2026-04-18 17:31:35.357322 | Job console starting 2026-04-18 17:31:35.373596 | Updating git repos 2026-04-18 17:31:35.563716 | Cloning repos into workspace 2026-04-18 17:31:50.833588 | Restoring repo states 2026-04-18 17:31:50.854540 | Merging changes 2026-04-18 17:31:52.167415 | Checking out repos 2026-04-18 17:31:52.361650 | Preparing playbooks 2026-04-18 17:32:26.719206 | Running Ansible setup 2026-04-18 17:32:32.683446 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-04-18 17:32:33.365164 | 2026-04-18 17:32:33.365307 | PLAY [localhost] 2026-04-18 17:32:33.374541 | 2026-04-18 17:32:33.374641 | TASK [Gathering Facts] 2026-04-18 17:32:34.366192 | localhost | ok 2026-04-18 17:32:34.373449 | 2026-04-18 17:32:34.373533 | TASK [Setup log path fact] 2026-04-18 17:32:34.396695 | localhost | ok 2026-04-18 17:32:34.411262 | 2026-04-18 17:32:34.411376 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-18 17:32:34.463490 | localhost | ok 2026-04-18 17:32:34.471014 | 2026-04-18 17:32:34.471091 | TASK [emit-job-header : Print job information] 2026-04-18 17:32:34.514144 | # Job Information 2026-04-18 17:32:34.514391 | Ansible Version: 2.16.16 2026-04-18 17:32:34.514466 | Job: ansible-collection-kubernetes-molecule-helm 2026-04-18 17:32:34.514515 | Pipeline: check 2026-04-18 17:32:34.514560 | Executor: 0a8996d2b663 2026-04-18 17:32:34.514604 | Triggered by: https://github.com/vexxhost/ansible-collection-kubernetes/pull/257 2026-04-18 17:32:34.514651 | Event ID: a3a4eef0-3b4a-11f1-8954-de302ad0b341 2026-04-18 17:32:34.519607 | 2026-04-18 17:32:34.519681 | LOOP [emit-job-header : Print node information] 2026-04-18 17:32:34.614876 | localhost | ok: 2026-04-18 17:32:34.615109 | localhost | # Node Information 2026-04-18 17:32:34.615140 | localhost | Inventory Hostname: controller 2026-04-18 17:32:34.615162 | localhost | Hostname: np0000167524 2026-04-18 17:32:34.615182 | localhost | Username: zuul 2026-04-18 17:32:34.615205 | localhost | Distro: Debian 13.4 2026-04-18 17:32:34.615225 | localhost | Provider: yul1 2026-04-18 17:32:34.615243 | localhost | Region: ca-ymq-1 2026-04-18 17:32:34.615262 | localhost | Label: debian-trixie 2026-04-18 17:32:34.615280 | localhost | Product Name: OpenStack Nova 2026-04-18 17:32:34.615298 | localhost | Interface IP: 199.204.45.157 2026-04-18 17:32:34.630922 | 2026-04-18 17:32:34.631098 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-04-18 17:32:35.105033 | localhost -> localhost | changed 2026-04-18 17:32:35.110224 | 2026-04-18 17:32:35.110294 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-04-18 17:32:36.923397 | localhost -> localhost | changed 2026-04-18 17:32:36.929708 | 2026-04-18 17:32:36.929879 | PLAY [all] 2026-04-18 17:32:36.938759 | 2026-04-18 17:32:36.938891 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-04-18 17:32:37.225140 | controller -> localhost | ok 2026-04-18 17:32:37.854182 | 2026-04-18 17:32:37.854304 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-04-18 17:32:37.886262 | controller | ok 2026-04-18 17:32:37.899664 | controller | included: /var/lib/zuul/builds/e47280e7907e418e8fac2fd0dcace248/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-04-18 17:32:37.904469 | 2026-04-18 17:32:37.904531 | TASK [add-build-sshkey : Create Temp SSH key] 2026-04-18 17:32:39.903374 | controller -> localhost | Generating public/private rsa key pair. 2026-04-18 17:32:39.903532 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/e47280e7907e418e8fac2fd0dcace248/work/e47280e7907e418e8fac2fd0dcace248_id_rsa 2026-04-18 17:32:39.903561 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/e47280e7907e418e8fac2fd0dcace248/work/e47280e7907e418e8fac2fd0dcace248_id_rsa.pub 2026-04-18 17:32:39.903583 | controller -> localhost | The key fingerprint is: 2026-04-18 17:32:39.903608 | controller -> localhost | SHA256:ueULECQK2cAZikNPKZziqMhGDxMo394Cc8fHsVT/v34 zuul-build-sshkey 2026-04-18 17:32:39.903647 | controller -> localhost | The key's randomart image is: 2026-04-18 17:32:39.903668 | controller -> localhost | +---[RSA 3072]----+ 2026-04-18 17:32:39.903692 | controller -> localhost | |**+.o . .. | 2026-04-18 17:32:39.903714 | controller -> localhost | |OO+o o o . | 2026-04-18 17:32:39.903743 | controller -> localhost | |O.+o ..o o . | 2026-04-18 17:32:39.903770 | controller -> localhost | |.*+ o o.+. . | 2026-04-18 17:32:39.903791 | controller -> localhost | |= += o..S . . | 2026-04-18 17:32:39.903810 | controller -> localhost | |oo .o .. + . | 2026-04-18 17:32:39.903828 | controller -> localhost | |. . o . .| 2026-04-18 17:32:39.903847 | controller -> localhost | | . . E| 2026-04-18 17:32:39.903869 | controller -> localhost | | . .o.| 2026-04-18 17:32:39.903888 | controller -> localhost | +----[SHA256]-----+ 2026-04-18 17:32:39.903939 | controller -> localhost | ok: Runtime: 0:00:01.540549 2026-04-18 17:32:39.911801 | 2026-04-18 17:32:39.911875 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-04-18 17:32:39.949081 | controller | ok 2026-04-18 17:32:39.969545 | controller | included: /var/lib/zuul/builds/e47280e7907e418e8fac2fd0dcace248/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-04-18 17:32:39.980401 | 2026-04-18 17:32:39.980490 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-04-18 17:32:40.006520 | controller | skipping: Conditional result was False 2026-04-18 17:32:40.014701 | 2026-04-18 17:32:40.014798 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-04-18 17:32:40.540297 | controller | changed 2026-04-18 17:32:40.547823 | 2026-04-18 17:32:40.547908 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-04-18 17:32:40.761826 | controller | ok 2026-04-18 17:32:40.767708 | 2026-04-18 17:32:40.767799 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-04-18 17:32:41.321901 | controller | changed 2026-04-18 17:32:41.327813 | 2026-04-18 17:32:41.327908 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-04-18 17:32:41.885005 | controller | changed 2026-04-18 17:32:41.892642 | 2026-04-18 17:32:41.892742 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-04-18 17:32:41.918432 | controller | skipping: Conditional result was False 2026-04-18 17:32:41.926613 | 2026-04-18 17:32:41.926707 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-04-18 17:32:52.777785 | controller -> localhost | changed 2026-04-18 17:32:52.789641 | 2026-04-18 17:32:52.789771 | TASK [add-build-sshkey : Add back temp key] 2026-04-18 17:32:53.092420 | controller -> localhost | Identity added: /var/lib/zuul/builds/e47280e7907e418e8fac2fd0dcace248/work/e47280e7907e418e8fac2fd0dcace248_id_rsa (zuul-build-sshkey) 2026-04-18 17:32:53.092608 | controller -> localhost | ok: Runtime: 0:00:00.014841 2026-04-18 17:32:53.097073 | 2026-04-18 17:32:53.097182 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-04-18 17:32:53.460736 | controller | ok 2026-04-18 17:32:53.464453 | 2026-04-18 17:32:53.464516 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-04-18 17:32:53.504294 | controller | skipping: Conditional result was False 2026-04-18 17:32:53.527088 | 2026-04-18 17:32:53.527329 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-04-18 17:32:53.930651 | controller | ok 2026-04-18 17:32:53.935625 | 2026-04-18 17:32:53.935687 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-04-18 17:35:58.323452 | controller | Output suppressed because no_log was given 2026-04-18 17:35:58.332653 | 2026-04-18 17:35:58.332718 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-04-18 17:36:14.272855 | controller | ok: "logs" 2026-04-18 17:36:14.273270 | controller | ok: All items complete 2026-04-18 17:36:14.273334 | 2026-04-18 17:36:14.470240 | controller | ok: "artifacts" 2026-04-18 17:36:14.644357 | controller | ok: "docs" 2026-04-18 17:36:14.654949 | 2026-04-18 17:36:14.655084 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-04-18 17:36:14.881962 | controller | changed: "logs" 2026-04-18 17:36:15.057461 | controller | changed: "artifacts" 2026-04-18 17:36:15.246211 | controller | changed: "docs" 2026-04-18 17:36:15.252683 | 2026-04-18 17:36:15.252736 | PLAY RECAP 2026-04-18 17:36:15.252780 | controller | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-04-18 17:36:15.252808 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-18 17:36:15.252828 | 2026-04-18 17:36:15.386570 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-04-18 17:36:15.389298 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-04-18 17:36:15.957908 | 2026-04-18 17:36:15.958023 | PLAY [all] 2026-04-18 17:36:15.975531 | 2026-04-18 17:36:15.976026 | TASK [setup-uv : Extract archive] 2026-04-18 17:36:18.597717 | controller | changed 2026-04-18 17:36:23.923529 | 2026-04-18 17:36:23.923699 | TASK [setup-uv : Print version] 2026-04-18 17:36:24.285549 | controller | uv 0.8.13 2026-04-18 17:36:24.457998 | controller | ok: Runtime: 0:00:00.013133 2026-04-18 17:36:24.464893 | 2026-04-18 17:36:24.464978 | PLAY RECAP 2026-04-18 17:36:24.465044 | controller | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-18 17:36:24.465083 | 2026-04-18 17:36:24.610817 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-04-18 17:36:24.613194 | PRE-RUN START: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/pre.yml@main] 2026-04-18 17:36:25.319419 | 2026-04-18 17:36:25.319563 | PLAY [all] 2026-04-18 17:36:25.330186 | 2026-04-18 17:36:25.330287 | TASK [Update apt cache] 2026-04-18 17:36:29.966519 | controller | ok 2026-04-18 17:36:30.899936 | 2026-04-18 17:36:30.900068 | TASK [Disable firewalld] 2026-04-18 17:36:30.924264 | controller | skipping: Conditional result was False 2026-04-18 17:36:30.929858 | 2026-04-18 17:36:30.929933 | TASK [Install "jq" for log collection] 2026-04-18 17:36:32.907114 | controller | changed 2026-04-18 17:36:32.909430 | 2026-04-18 17:36:32.909498 | PLAY RECAP 2026-04-18 17:36:32.909556 | controller | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-04-18 17:36:32.909588 | 2026-04-18 17:36:33.012100 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/pre.yml@main] 2026-04-18 17:36:33.015221 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-04-18 17:36:33.595933 | 2026-04-18 17:36:34.014557 | PLAY [all] 2026-04-18 17:36:34.040914 | 2026-04-18 17:36:34.041100 | TASK [Copy inventory file for Zuul] 2026-04-18 17:36:35.267087 | controller | changed 2026-04-18 17:36:35.274894 | 2026-04-18 17:36:35.274976 | TASK [Switch "ansible_host" to private IP] 2026-04-18 17:36:35.561780 | controller | changed: 1 replacements made 2026-04-18 17:36:35.567660 | 2026-04-18 17:36:35.567734 | TASK [Run Molecule scenario] 2026-04-18 17:36:35.987796 | controller | Using CPython 3.13.5 interpreter at: /usr/bin/python3 2026-04-18 17:36:35.987897 | controller | Creating virtual environment at: .venv 2026-04-18 17:36:36.256374 | controller | Downloading cryptography (4.3MiB) 2026-04-18 17:36:36.259230 | controller | Downloading pygments (1.2MiB) 2026-04-18 17:36:36.276736 | controller | Downloading ansible-core (2.3MiB) 2026-04-18 17:36:36.414602 | controller | Downloading cryptography 2026-04-18 17:36:36.464792 | controller | Downloading pygments 2026-04-18 17:36:36.626601 | controller | Downloading ansible-core 2026-04-18 17:36:36.673594 | controller | Installed 36 packages in 46ms 2026-04-18 17:36:37.361521 | controller | INFO Collection 'vexxhost.kubernetes' detected. 2026-04-18 17:36:37.361581 | controller | INFO Scenarios will be used from 'extensions/molecule' 2026-04-18 17:36:38.136950 | controller | :1: SyntaxWarning: invalid decimal literal 2026-04-18 17:36:38.242348 | controller | CRITICAL 'extensions/molecule/default/molecule.yml' glob failed. Exiting. 2026-04-18 17:36:38.242383 | controller | INFO default scenario not found, disabling shared state. 2026-04-18 17:36:38.242707 | controller | INFO [helm > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy 2026-04-18 17:36:38.242810 | controller | INFO [helm > prerun] Performing prerun with role_name_check=0... 2026-04-18 17:36:52.970393 | controller | INFO [helm > dependency] Executing 2026-04-18 17:36:52.970458 | controller | WARNING [helm > dependency] Missing roles requirements file: requirements.yml 2026-04-18 17:36:52.970468 | controller | WARNING [helm > dependency] Missing collections requirements file: collections.yml 2026-04-18 17:36:52.970504 | controller | WARNING [helm > dependency] Executed: 2 missing (Remove from test_sequence to suppress) 2026-04-18 17:36:52.975689 | controller | INFO [helm > cleanup] Executing 2026-04-18 17:36:52.976014 | controller | WARNING [helm > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-04-18 17:36:52.981361 | controller | INFO [helm > destroy] Executing 2026-04-18 17:36:52.981380 | controller | WARNING [helm > destroy] Skipping, '--destroy=never' requested. 2026-04-18 17:36:52.981472 | controller | INFO [helm > destroy] Executed: Successful 2026-04-18 17:36:52.986718 | controller | INFO [helm > syntax] Executing 2026-04-18 17:36:53.904891 | controller | 2026-04-18 17:36:53.904951 | controller | playbook: /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/extensions/molecule/helm/converge.yml 2026-04-18 17:36:54.012300 | controller | INFO [helm > syntax] Executed: Successful 2026-04-18 17:36:54.023185 | controller | INFO [helm > create] Executing 2026-04-18 17:36:54.024641 | controller | WARNING [helm > create] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-04-18 17:36:54.030113 | controller | INFO [helm > prepare] Executing 2026-04-18 17:36:54.860061 | controller | 2026-04-18 17:36:54.860235 | controller | PLAY [Prepare] ***************************************************************** 2026-04-18 17:36:54.860418 | controller | 2026-04-18 17:36:54.860619 | controller | TASK [Gathering Facts] ********************************************************* 2026-04-18 17:36:54.860822 | controller | Saturday 18 April 2026 17:36:54 +0000 (0:00:00.028) 0:00:00.028 ******** 2026-04-18 17:36:56.110008 | 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-04-18 17:36:56.115662 | controller | ok: [controller] 2026-04-18 17:36:56.115812 | controller | 2026-04-18 17:36:56.116015 | controller | TASK [Install Debian packages] ************************************************* 2026-04-18 17:36:56.116211 | controller | Saturday 18 April 2026 17:36:56 +0000 (0:00:01.256) 0:00:01.284 ******** 2026-04-18 17:36:57.679054 | controller | ok: [controller] 2026-04-18 17:36:57.679251 | controller | 2026-04-18 17:36:57.679452 | controller | PLAY RECAP ********************************************************************* 2026-04-18 17:36:57.679667 | controller | controller : ok=2 changed=0 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-04-18 17:36:57.679839 | controller | 2026-04-18 17:36:57.680011 | controller | 2026-04-18 17:36:57.680205 | controller | TASKS RECAP ******************************************************************** 2026-04-18 17:36:57.680447 | controller | Saturday 18 April 2026 17:36:57 +0000 (0:00:01.563) 0:00:02.848 ******** 2026-04-18 17:36:57.680625 | controller | =============================================================================== 2026-04-18 17:36:57.680816 | controller | Install Debian packages ------------------------------------------------- 1.56s 2026-04-18 17:36:57.681008 | controller | Gathering Facts --------------------------------------------------------- 1.26s 2026-04-18 17:36:57.775440 | controller | INFO [helm > prepare] Executed: Successful 2026-04-18 17:36:57.783448 | controller | INFO [helm > converge] Executing 2026-04-18 17:36:58.667160 | controller | 2026-04-18 17:36:58.667506 | controller | PLAY [Converge] **************************************************************** 2026-04-18 17:36:58.667720 | controller | 2026-04-18 17:36:58.668018 | controller | TASK [Gathering Facts] ********************************************************* 2026-04-18 17:36:58.668262 | controller | Saturday 18 April 2026 17:36:58 +0000 (0:00:00.012) 0:00:00.012 ******** 2026-04-18 17:36:59.757424 | 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-04-18 17:36:59.761617 | controller | ok: [controller] 2026-04-18 17:36:59.761797 | controller | 2026-04-18 17:36:59.762005 | controller | TASK [vexxhost.containers.forget_package : Forget package] ********************* 2026-04-18 17:36:59.762217 | controller | Saturday 18 April 2026 17:36:59 +0000 (0:00:01.094) 0:00:01.107 ******** 2026-04-18 17:37:00.248140 | controller | ok: [controller] 2026-04-18 17:37:00.248311 | controller | 2026-04-18 17:37:00.248544 | controller | TASK [vexxhost.containers.package : Update state for tar] ********************** 2026-04-18 17:37:00.248754 | controller | Saturday 18 April 2026 17:37:00 +0000 (0:00:00.486) 0:00:01.593 ******** 2026-04-18 17:37:01.297991 | controller | ok: [controller] 2026-04-18 17:37:01.298186 | controller | 2026-04-18 17:37:01.298410 | controller | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] *** 2026-04-18 17:37:01.298623 | controller | Saturday 18 April 2026 17:37:01 +0000 (0:00:01.049) 0:00:02.643 ******** 2026-04-18 17:37:01.703618 | controller | changed: [controller] 2026-04-18 17:37:01.703658 | controller | 2026-04-18 17:37:01.703761 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-04-18 17:37:01.703850 | controller | Saturday 18 April 2026 17:37:01 +0000 (0:00:00.406) 0:00:03.049 ******** 2026-04-18 17:37:01.746981 | controller | ok: [controller] => { 2026-04-18 17:37:01.747216 | controller | "msg": "https://get.helm.sh/helm-v3.11.2-linux-amd64.tar.gz" 2026-04-18 17:37:01.747409 | controller | } 2026-04-18 17:37:01.747587 | controller | 2026-04-18 17:37:01.747786 | controller | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-04-18 17:37:01.747976 | controller | Saturday 18 April 2026 17:37:01 +0000 (0:00:00.043) 0:00:03.092 ******** 2026-04-18 17:37:02.545045 | 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-04-18 17:37:02.552929 | controller | changed: [controller] 2026-04-18 17:37:02.553114 | controller | 2026-04-18 17:37:02.553318 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-04-18 17:37:02.553511 | controller | Saturday 18 April 2026 17:37:02 +0000 (0:00:00.806) 0:00:03.898 ******** 2026-04-18 17:37:04.574729 | controller | changed: [controller] 2026-04-18 17:37:04.574770 | controller | 2026-04-18 17:37:04.574901 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm repository] *********************** 2026-04-18 17:37:04.574987 | controller | Saturday 18 April 2026 17:37:04 +0000 (0:00:02.021) 0:00:05.920 ******** 2026-04-18 17:37:05.087556 | controller | ok: [controller] 2026-04-18 17:37:05.087677 | controller | 2026-04-18 17:37:05.087817 | controller | TASK [vexxhost.kubernetes.helm : Add bash autocomplete for helm] *************** 2026-04-18 17:37:05.087941 | controller | Saturday 18 April 2026 17:37:05 +0000 (0:00:00.512) 0:00:06.432 ******** 2026-04-18 17:37:05.535118 | controller | changed: [controller] 2026-04-18 17:37:05.535173 | controller | 2026-04-18 17:37:05.535227 | controller | TASK [vexxhost.kubernetes.helm : Get Helm plugins dir] ************************* 2026-04-18 17:37:05.535365 | controller | Saturday 18 April 2026 17:37:05 +0000 (0:00:00.444) 0:00:06.877 ******** 2026-04-18 17:37:06.052114 | controller | ok: [controller] 2026-04-18 17:37:06.052252 | controller | 2026-04-18 17:37:06.052481 | controller | TASK [vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist] *** 2026-04-18 17:37:06.052682 | controller | Saturday 18 April 2026 17:37:06 +0000 (0:00:00.519) 0:00:07.396 ******** 2026-04-18 17:37:06.363039 | controller | changed: [controller] 2026-04-18 17:37:06.363325 | controller | 2026-04-18 17:37:06.363684 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin] *** 2026-04-18 17:37:06.363971 | controller | Saturday 18 April 2026 17:37:06 +0000 (0:00:00.309) 0:00:07.706 ******** 2026-04-18 17:37:06.666300 | controller | ok: [controller] 2026-04-18 17:37:06.666458 | controller | 2026-04-18 17:37:06.666677 | controller | TASK [Install plugin] ********************************************************** 2026-04-18 17:37:06.666892 | controller | Saturday 18 April 2026 17:37:06 +0000 (0:00:00.304) 0:00:08.011 ******** 2026-04-18 17:37:06.727344 | controller | included: vexxhost.containers.download_artifact for controller 2026-04-18 17:37:06.727528 | controller | 2026-04-18 17:37:06.727751 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-04-18 17:37:06.727986 | controller | Saturday 18 April 2026 17:37:06 +0000 (0:00:00.061) 0:00:08.072 ******** 2026-04-18 17:37:06.781285 | controller | ok: [controller] => { 2026-04-18 17:37:06.781507 | controller | "msg": "https://github.com/databus23/helm-diff/releases/download/v3.8.1/helm-diff-linux-amd64.tgz" 2026-04-18 17:37:06.781699 | controller | } 2026-04-18 17:37:06.781873 | controller | 2026-04-18 17:37:06.782111 | controller | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-04-18 17:37:06.782330 | controller | Saturday 18 April 2026 17:37:06 +0000 (0:00:00.053) 0:00:08.125 ******** 2026-04-18 17:37:07.607428 | controller | changed: [controller] 2026-04-18 17:37:07.607566 | controller | 2026-04-18 17:37:07.607789 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-04-18 17:37:07.607999 | controller | Saturday 18 April 2026 17:37:07 +0000 (0:00:00.826) 0:00:08.952 ******** 2026-04-18 17:37:09.730591 | controller | changed: [controller] 2026-04-18 17:37:09.730635 | controller | 2026-04-18 17:37:09.730644 | controller | PLAY RECAP ********************************************************************* 2026-04-18 17:37:09.730655 | controller | controller : ok=16 changed=7 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-04-18 17:37:09.730663 | controller | 2026-04-18 17:37:09.730670 | controller | 2026-04-18 17:37:09.730677 | controller | TASKS RECAP ******************************************************************** 2026-04-18 17:37:09.730685 | controller | Saturday 18 April 2026 17:37:09 +0000 (0:00:02.119) 0:00:11.072 ******** 2026-04-18 17:37:09.730693 | controller | =============================================================================== 2026-04-18 17:37:09.730700 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 2.12s 2026-04-18 17:37:09.730707 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 2.02s 2026-04-18 17:37:09.730715 | controller | Gathering Facts --------------------------------------------------------- 1.09s 2026-04-18 17:37:09.730722 | controller | vexxhost.containers.package : Update state for tar ---------------------- 1.05s 2026-04-18 17:37:09.730729 | controller | vexxhost.containers.download_artifact : Download item ------------------- 0.83s 2026-04-18 17:37:09.730736 | controller | vexxhost.containers.download_artifact : Download item ------------------- 0.81s 2026-04-18 17:37:09.730743 | controller | vexxhost.kubernetes.helm : Get Helm plugins dir ------------------------- 0.52s 2026-04-18 17:37:09.730750 | controller | vexxhost.kubernetes.helm : Remove Helm repository ----------------------- 0.51s 2026-04-18 17:37:09.730757 | controller | vexxhost.containers.forget_package : Forget package --------------------- 0.49s 2026-04-18 17:37:09.730778 | controller | vexxhost.kubernetes.helm : Add bash autocomplete for helm --------------- 0.44s 2026-04-18 17:37:09.730785 | controller | vexxhost.containers.directory : Create directory (/var/lib/downloads) --- 0.41s 2026-04-18 17:37:09.730792 | controller | vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist --- 0.31s 2026-04-18 17:37:09.730800 | controller | vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin --- 0.30s 2026-04-18 17:37:09.730807 | controller | Install plugin ---------------------------------------------------------- 0.06s 2026-04-18 17:37:09.730814 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.05s 2026-04-18 17:37:09.730822 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.04s 2026-04-18 17:37:09.836551 | controller | INFO [helm > converge] Executed: Successful 2026-04-18 17:37:09.846550 | controller | INFO [helm > idempotence] Executing 2026-04-18 17:37:10.677536 | controller | 2026-04-18 17:37:10.677881 | controller | PLAY [Converge] **************************************************************** 2026-04-18 17:37:10.678120 | controller | 2026-04-18 17:37:10.678379 | controller | TASK [Gathering Facts] ********************************************************* 2026-04-18 17:37:10.678634 | controller | Saturday 18 April 2026 17:37:10 +0000 (0:00:00.012) 0:00:00.012 ******** 2026-04-18 17:37:11.765854 | 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-04-18 17:37:11.769631 | controller | ok: [controller] 2026-04-18 17:37:11.769921 | controller | 2026-04-18 17:37:11.770180 | controller | TASK [vexxhost.containers.forget_package : Forget package] ********************* 2026-04-18 17:37:11.770430 | controller | Saturday 18 April 2026 17:37:11 +0000 (0:00:01.092) 0:00:01.104 ******** 2026-04-18 17:37:12.198578 | controller | ok: [controller] 2026-04-18 17:37:12.198895 | controller | 2026-04-18 17:37:12.199312 | controller | TASK [vexxhost.containers.package : Update state for tar] ********************** 2026-04-18 17:37:12.199580 | controller | Saturday 18 April 2026 17:37:12 +0000 (0:00:00.428) 0:00:01.533 ******** 2026-04-18 17:37:13.284785 | controller | ok: [controller] 2026-04-18 17:37:13.285126 | controller | 2026-04-18 17:37:13.285423 | controller | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] *** 2026-04-18 17:37:13.285690 | controller | Saturday 18 April 2026 17:37:13 +0000 (0:00:01.084) 0:00:02.618 ******** 2026-04-18 17:37:13.737680 | controller | ok: [controller] 2026-04-18 17:37:13.737974 | controller | 2026-04-18 17:37:13.738248 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-04-18 17:37:13.738562 | controller | Saturday 18 April 2026 17:37:13 +0000 (0:00:00.453) 0:00:03.072 ******** 2026-04-18 17:37:13.787791 | controller | ok: [controller] => { 2026-04-18 17:37:13.788063 | controller | "msg": "https://get.helm.sh/helm-v3.11.2-linux-amd64.tar.gz" 2026-04-18 17:37:13.788302 | controller | } 2026-04-18 17:37:13.788531 | controller | 2026-04-18 17:37:13.788788 | controller | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-04-18 17:37:13.789039 | controller | Saturday 18 April 2026 17:37:13 +0000 (0:00:00.050) 0:00:03.122 ******** 2026-04-18 17:37:14.341315 | controller | ok: [controller] 2026-04-18 17:37:14.341636 | controller | 2026-04-18 17:37:14.341920 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-04-18 17:37:14.342175 | controller | Saturday 18 April 2026 17:37:14 +0000 (0:00:00.552) 0:00:03.675 ******** 2026-04-18 17:37:16.008454 | controller | ok: [controller] 2026-04-18 17:37:16.008796 | controller | 2026-04-18 17:37:16.009130 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm repository] *********************** 2026-04-18 17:37:16.009424 | controller | Saturday 18 April 2026 17:37:15 +0000 (0:00:01.667) 0:00:05.342 ******** 2026-04-18 17:37:16.554803 | controller | ok: [controller] 2026-04-18 17:37:16.554879 | controller | 2026-04-18 17:37:16.554892 | controller | TASK [vexxhost.kubernetes.helm : Add bash autocomplete for helm] *************** 2026-04-18 17:37:16.555005 | controller | Saturday 18 April 2026 17:37:16 +0000 (0:00:00.546) 0:00:05.889 ******** 2026-04-18 17:37:16.978823 | controller | ok: [controller] 2026-04-18 17:37:16.978874 | controller | 2026-04-18 17:37:16.978972 | controller | TASK [vexxhost.kubernetes.helm : Get Helm plugins dir] ************************* 2026-04-18 17:37:16.979065 | controller | Saturday 18 April 2026 17:37:16 +0000 (0:00:00.424) 0:00:06.313 ******** 2026-04-18 17:37:17.451686 | controller | ok: [controller] 2026-04-18 17:37:17.451746 | controller | 2026-04-18 17:37:17.451866 | controller | TASK [vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist] *** 2026-04-18 17:37:17.452039 | controller | Saturday 18 April 2026 17:37:17 +0000 (0:00:00.468) 0:00:06.782 ******** 2026-04-18 17:37:17.750965 | controller | ok: [controller] 2026-04-18 17:37:17.751160 | controller | 2026-04-18 17:37:17.751655 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin] *** 2026-04-18 17:37:17.751698 | controller | Saturday 18 April 2026 17:37:17 +0000 (0:00:00.302) 0:00:07.085 ******** 2026-04-18 17:37:18.050725 | controller | ok: [controller] 2026-04-18 17:37:18.050784 | controller | 2026-04-18 17:37:18.050847 | controller | TASK [Install plugin] ********************************************************** 2026-04-18 17:37:18.050936 | controller | Saturday 18 April 2026 17:37:18 +0000 (0:00:00.300) 0:00:07.385 ******** 2026-04-18 17:37:18.110899 | controller | included: vexxhost.containers.download_artifact for controller 2026-04-18 17:37:18.110930 | controller | 2026-04-18 17:37:18.111009 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-04-18 17:37:18.111111 | controller | Saturday 18 April 2026 17:37:18 +0000 (0:00:00.060) 0:00:07.445 ******** 2026-04-18 17:37:18.163565 | controller | ok: [controller] => { 2026-04-18 17:37:18.163624 | controller | "msg": "https://github.com/databus23/helm-diff/releases/download/v3.8.1/helm-diff-linux-amd64.tgz" 2026-04-18 17:37:18.163687 | controller | } 2026-04-18 17:37:18.166917 | controller | 2026-04-18 17:37:18.166938 | controller | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-04-18 17:37:18.166946 | controller | Saturday 18 April 2026 17:37:18 +0000 (0:00:00.051) 0:00:07.497 ******** 2026-04-18 17:37:18.623538 | controller | ok: [controller] 2026-04-18 17:37:18.623600 | controller | 2026-04-18 17:37:18.623658 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-04-18 17:37:18.623777 | controller | Saturday 18 April 2026 17:37:18 +0000 (0:00:00.457) 0:00:07.954 ******** 2026-04-18 17:37:22.283311 | controller | ok: [controller] 2026-04-18 17:37:22.283359 | controller | 2026-04-18 17:37:22.283369 | controller | PLAY RECAP ********************************************************************* 2026-04-18 17:37:22.283379 | controller | controller : ok=16 changed=0 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-04-18 17:37:22.283387 | controller | 2026-04-18 17:37:22.283394 | controller | 2026-04-18 17:37:22.283410 | controller | TASKS RECAP ******************************************************************** 2026-04-18 17:37:22.286694 | controller | Saturday 18 April 2026 17:37:22 +0000 (0:00:03.662) 0:00:11.616 ******** 2026-04-18 17:37:22.286747 | controller | =============================================================================== 2026-04-18 17:37:22.286755 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 3.66s 2026-04-18 17:37:22.286762 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 1.67s 2026-04-18 17:37:22.286770 | controller | Gathering Facts --------------------------------------------------------- 1.09s 2026-04-18 17:37:22.286778 | controller | vexxhost.containers.package : Update state for tar ---------------------- 1.08s 2026-04-18 17:37:22.286785 | controller | vexxhost.containers.download_artifact : Download item ------------------- 0.55s 2026-04-18 17:37:22.286792 | controller | vexxhost.kubernetes.helm : Remove Helm repository ----------------------- 0.55s 2026-04-18 17:37:22.286799 | controller | vexxhost.kubernetes.helm : Get Helm plugins dir ------------------------- 0.47s 2026-04-18 17:37:22.286806 | controller | vexxhost.containers.download_artifact : Download item ------------------- 0.46s 2026-04-18 17:37:22.286815 | controller | vexxhost.containers.directory : Create directory (/var/lib/downloads) --- 0.45s 2026-04-18 17:37:22.286840 | controller | vexxhost.containers.forget_package : Forget package --------------------- 0.43s 2026-04-18 17:37:22.286848 | controller | vexxhost.kubernetes.helm : Add bash autocomplete for helm --------------- 0.42s 2026-04-18 17:37:22.286855 | controller | vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist --- 0.30s 2026-04-18 17:37:22.286863 | controller | vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin --- 0.30s 2026-04-18 17:37:22.286870 | controller | Install plugin ---------------------------------------------------------- 0.06s 2026-04-18 17:37:22.286877 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.05s 2026-04-18 17:37:22.286887 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.05s 2026-04-18 17:37:22.374189 | controller | INFO [helm > idempotence] Executed: Successful 2026-04-18 17:37:22.384842 | controller | INFO [helm > side_effect] Executing 2026-04-18 17:37:22.385212 | controller | WARNING [helm > side_effect] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-04-18 17:37:22.390506 | controller | INFO [helm > verify] Executing 2026-04-18 17:37:23.203039 | controller | 2026-04-18 17:37:23.203355 | controller | PLAY [Verify] ****************************************************************** 2026-04-18 17:37:23.203517 | controller | 2026-04-18 17:37:23.203678 | controller | TASK [Gathering Facts] ********************************************************* 2026-04-18 17:37:23.203838 | controller | Saturday 18 April 2026 17:37:23 +0000 (0:00:00.009) 0:00:00.009 ******** 2026-04-18 17:37:24.279045 | 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-04-18 17:37:24.289406 | controller | ok: [controller] 2026-04-18 17:37:24.289523 | controller | 2026-04-18 17:37:24.289640 | controller | TASK [Get the current Helm version] ******************************************** 2026-04-18 17:37:24.289756 | controller | Saturday 18 April 2026 17:37:24 +0000 (0:00:01.087) 0:00:01.096 ******** 2026-04-18 17:37:24.736969 | controller | changed: [controller] 2026-04-18 17:37:24.737184 | controller | 2026-04-18 17:37:24.737357 | controller | TASK [Assert Helm version] ***************************************************** 2026-04-18 17:37:24.737540 | controller | Saturday 18 April 2026 17:37:24 +0000 (0:00:00.447) 0:00:01.543 ******** 2026-04-18 17:37:24.767468 | controller | ok: [controller] => { 2026-04-18 17:37:24.767740 | controller | "changed": false, 2026-04-18 17:37:24.767997 | controller | "msg": "All assertions passed" 2026-04-18 17:37:24.768244 | controller | } 2026-04-18 17:37:24.768513 | controller | 2026-04-18 17:37:24.768778 | controller | PLAY RECAP ********************************************************************* 2026-04-18 17:37:24.769061 | controller | controller : ok=3 changed=1 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-04-18 17:37:24.769298 | controller | 2026-04-18 17:37:24.769559 | controller | 2026-04-18 17:37:24.769852 | controller | TASKS RECAP ******************************************************************** 2026-04-18 17:37:24.770115 | controller | Saturday 18 April 2026 17:37:24 +0000 (0:00:00.031) 0:00:01.575 ******** 2026-04-18 17:37:24.770371 | controller | =============================================================================== 2026-04-18 17:37:24.770630 | controller | Gathering Facts --------------------------------------------------------- 1.09s 2026-04-18 17:37:24.770973 | controller | Get the current Helm version -------------------------------------------- 0.45s 2026-04-18 17:37:24.771243 | controller | Assert Helm version ----------------------------------------------------- 0.03s 2026-04-18 17:37:24.878246 | controller | INFO [helm > verify] Executed: Successful 2026-04-18 17:37:24.889788 | controller | INFO [helm > cleanup] Executing 2026-04-18 17:37:24.890144 | controller | WARNING [helm > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-04-18 17:37:24.895479 | controller | INFO [helm > destroy] Executing 2026-04-18 17:37:24.895599 | controller | WARNING [helm > destroy] Skipping, '--destroy=never' requested. 2026-04-18 17:37:24.895697 | controller | INFO [helm > destroy] Executed: Successful 2026-04-18 17:37:24.895999 | controller | WARNING Molecule executed 1 scenario (1 missing files) 2026-04-18 17:37:28.191199 | controller | ok: Runtime: 0:00:49.077774 2026-04-18 17:37:28.202741 | 2026-04-18 17:37:28.202901 | PLAY RECAP 2026-04-18 17:37:28.202970 | controller | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-18 17:37:28.203002 | 2026-04-18 17:37:28.374442 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-04-18 17:37:35.301749 | POST-RUN START: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/post.yml@main] 2026-04-18 17:37:35.993245 | 2026-04-18 17:37:38.227130 | PLAY [all] 2026-04-18 17:37:38.245088 | 2026-04-18 17:37:38.245200 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects] 2026-04-18 17:37:42.793178 | controller | changed 2026-04-18 17:37:45.994903 | 2026-04-18 17:37:45.995043 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects] 2026-04-18 17:37:46.393716 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-04-18 17:37:46.393832 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-04-18 17:37:46.399872 | controller | environment: line 1: kubectl: command not found 2026-04-18 17:37:46.400565 | controller | environment: line 1: kubectl: command not found 2026-04-18 17:37:46.401827 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-04-18 17:37:46.402297 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-04-18 17:37:46.405266 | controller | environment: line 1: kubectl: command not found 2026-04-18 17:37:46.406617 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-04-18 17:37:46.408613 | controller | environment: line 1: kubectl: command not found 2026-04-18 17:37:46.410246 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-04-18 17:37:46.412554 | controller | environment: line 1: kubectl: command not found 2026-04-18 17:37:46.413595 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-04-18 17:37:49.380118 | controller | ok: Runtime: 0:00:00.032069 2026-04-18 17:37:49.384847 | 2026-04-18 17:37:49.384974 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects] 2026-04-18 17:37:49.619245 | controller | changed 2026-04-18 17:37:53.596291 | 2026-04-18 17:37:53.596444 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects] 2026-04-18 17:37:53.865873 | controller | environment: line 5: kubectl: command not found 2026-04-18 17:37:53.866516 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-04-18 17:37:53.867141 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-04-18 17:37:53.867490 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-04-18 17:38:00.920585 | controller | ok: Runtime: 0:00:00.014654 2026-04-18 17:38:00.928631 | 2026-04-18 17:38:00.928694 | TASK [describe-kubernetes-objects : Downloads logs to executor] 2026-04-18 17:38:01.721461 | controller | changed: 2026-04-18 17:38:01.721778 | controller | created directory /var/lib/zuul/builds/e47280e7907e418e8fac2fd0dcace248/work/logs/controller 2026-04-18 17:38:01.721849 | controller | cd+++++++++ objects/ 2026-04-18 17:38:01.721898 | controller | cd+++++++++ objects/cluster/ 2026-04-18 17:38:01.721947 | controller | cd+++++++++ objects/namespaced/ 2026-04-18 17:38:01.729307 | 2026-04-18 17:38:01.729373 | TASK [gather-pod-logs : creating directory for pod logs] 2026-04-18 17:38:01.958505 | controller | changed 2026-04-18 17:38:01.962343 | 2026-04-18 17:38:01.962406 | TASK [gather-pod-logs : creating directory for failed pod logs] 2026-04-18 17:38:02.195668 | controller | changed 2026-04-18 17:38:02.201035 | 2026-04-18 17:38:02.201128 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)] 2026-04-18 17:38:02.437081 | controller | environment: line 3: kubectl: command not found 2026-04-18 17:38:09.275106 | controller | ok: Runtime: 0:00:00.014404 2026-04-18 17:38:09.280819 | 2026-04-18 17:38:09.280927 | TASK [gather-pod-logs : Downloads pod logs to executor] 2026-04-18 17:38:14.279964 | controller | changed: 2026-04-18 17:38:14.280124 | controller | cd+++++++++ pod-logs/ 2026-04-18 17:38:29.639145 | controller | cd+++++++++ pod-logs/failed-pods/ 2026-04-18 17:38:29.653848 | 2026-04-18 17:38:29.653964 | LOOP [helm-release-status : creating directory for helm release status] 2026-04-18 17:38:29.901214 | controller | changed: "values" 2026-04-18 17:38:30.128357 | controller | changed: "releases" 2026-04-18 17:38:30.141210 | 2026-04-18 17:38:30.141345 | TASK [helm-release-status : Gather get release status for helm charts] 2026-04-18 17:38:30.369965 | controller | /bin/bash: line 3: kubectl: command not found 2026-04-18 17:38:30.669799 | controller | ok: Runtime: 0:00:00.009530 2026-04-18 17:38:30.673890 | 2026-04-18 17:38:30.673953 | TASK [helm-release-status : Downloads logs to executor] 2026-04-18 17:38:31.179226 | controller | changed: 2026-04-18 17:38:31.179398 | controller | cd+++++++++ helm/ 2026-04-18 17:38:31.179427 | controller | cd+++++++++ helm/releases/ 2026-04-18 17:38:31.179453 | controller | cd+++++++++ helm/values/ 2026-04-18 17:38:31.185762 | 2026-04-18 17:38:31.185931 | PLAY RECAP 2026-04-18 17:38:31.186026 | controller | ok: 12 changed: 12 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-18 17:38:31.186076 | 2026-04-18 17:38:31.294127 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/post.yml@main] 2026-04-18 17:38:31.295505 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-04-18 17:38:31.847809 | 2026-04-18 17:38:31.847919 | PLAY [all] 2026-04-18 17:38:31.857506 | 2026-04-18 17:38:31.857581 | TASK [fetch-output : Set log path for multiple nodes] 2026-04-18 17:38:31.901816 | controller | skipping: Conditional result was False 2026-04-18 17:38:31.911814 | 2026-04-18 17:38:31.911973 | TASK [fetch-output : Set log path for single node] 2026-04-18 17:38:31.963546 | controller | ok 2026-04-18 17:38:31.968446 | 2026-04-18 17:38:31.968534 | LOOP [fetch-output : Ensure local output dirs] 2026-04-18 17:38:32.326671 | controller -> localhost | ok: "/var/lib/zuul/builds/e47280e7907e418e8fac2fd0dcace248/work/logs" 2026-04-18 17:38:32.533252 | controller -> localhost | changed: "/var/lib/zuul/builds/e47280e7907e418e8fac2fd0dcace248/work/artifacts" 2026-04-18 17:38:32.754620 | controller -> localhost | changed: "/var/lib/zuul/builds/e47280e7907e418e8fac2fd0dcace248/work/docs" 2026-04-18 17:38:32.770921 | 2026-04-18 17:38:32.771016 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-04-18 17:38:33.425267 | controller | changed: .d..t...... ./ 2026-04-18 17:38:33.425777 | controller | changed: All items complete 2026-04-18 17:38:33.425807 | 2026-04-18 17:38:33.931085 | controller | changed: .d..t...... ./ 2026-04-18 17:38:45.264445 | controller | changed: .d..t...... ./ 2026-04-18 17:38:45.284370 | 2026-04-18 17:38:45.284517 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-04-18 17:38:45.712854 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.007799 2026-04-18 17:38:45.924904 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.008048 2026-04-18 17:38:45.942111 | 2026-04-18 17:38:45.942189 | PLAY [all] 2026-04-18 17:38:45.947067 | 2026-04-18 17:38:45.947137 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-04-18 17:38:46.639668 | controller | changed 2026-04-18 17:38:46.645613 | 2026-04-18 17:38:46.645860 | PLAY RECAP 2026-04-18 17:38:46.645917 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-04-18 17:38:46.645947 | 2026-04-18 17:38:46.773244 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-04-18 17:38:46.774929 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-04-18 17:38:47.335895 | 2026-04-18 17:38:47.869080 | PLAY [localhost] 2026-04-18 17:38:47.889791 | 2026-04-18 17:38:47.889944 | TASK [Generate Zuul manifest] 2026-04-18 17:38:47.911150 | localhost | ok 2026-04-18 17:38:47.927218 | 2026-04-18 17:38:47.927300 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-04-18 17:38:48.279429 | localhost | changed 2026-04-18 17:38:48.289201 | 2026-04-18 17:38:48.289274 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-04-18 17:38:48.635603 | localhost | ok 2026-04-18 17:38:48.642217 | 2026-04-18 17:38:48.642307 | TASK [Upload logs] 2026-04-18 17:38:48.666068 | localhost | ok 2026-04-18 17:38:48.741611 | 2026-04-18 17:38:48.741742 | TASK [Set zuul-log-path fact] 2026-04-18 17:38:48.765770 | localhost | ok 2026-04-18 17:38:48.782160 | 2026-04-18 17:38:48.782315 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-18 17:38:48.812550 | localhost | ok 2026-04-18 17:38:48.821933 | 2026-04-18 17:38:48.822094 | TASK [upload-logs : Create log directories] 2026-04-18 17:38:54.185888 | localhost | changed 2026-04-18 17:38:59.997653 | 2026-04-18 17:38:59.997784 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-04-18 17:39:08.122837 | localhost -> localhost | ok: Runtime: 0:00:00.005851 2026-04-18 17:39:08.129757 | 2026-04-18 17:39:08.129828 | TASK [upload-logs : Upload logs to log server] 2026-04-18 17:39:25.661130 | localhost | Output suppressed because no_log was given 2026-04-18 17:39:25.666358 | 2026-04-18 17:39:25.666433 | LOOP [upload-logs : Compress console log and json output] 2026-04-18 17:39:25.723172 | localhost | skipping: Conditional result was False 2026-04-18 17:39:25.731560 | localhost | skipping: Conditional result was False 2026-04-18 17:39:25.751509 | 2026-04-18 17:39:25.751639 | LOOP [upload-logs : Upload compressed console log and json output] 2026-04-18 17:39:25.792395 | localhost | skipping: Conditional result was False 2026-04-18 17:39:25.792781 | 2026-04-18 17:39:25.796759 | localhost | skipping: Conditional result was False 2026-04-18 17:39:25.814915 | 2026-04-18 17:39:25.815031 | LOOP [upload-logs : Upload console log and json output]