2026-04-22 02:26:51.837159 | Job console starting 2026-04-22 02:26:51.851863 | Updating git repos 2026-04-22 02:26:51.896929 | Cloning repos into workspace 2026-04-22 02:26:53.836700 | Restoring repo states 2026-04-22 02:26:53.896292 | Merging changes 2026-04-22 02:26:54.514231 | Checking out repos 2026-04-22 02:26:54.807223 | Preparing playbooks 2026-04-22 02:27:03.066624 | Running Ansible setup 2026-04-22 02:27:06.884072 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-04-22 02:27:07.471372 | 2026-04-22 02:27:07.471513 | PLAY [localhost] 2026-04-22 02:27:07.478950 | 2026-04-22 02:27:07.479022 | TASK [Gathering Facts] 2026-04-22 02:27:08.357760 | localhost | ok 2026-04-22 02:27:08.367012 | 2026-04-22 02:27:08.367095 | TASK [Setup log path fact] 2026-04-22 02:27:08.387967 | localhost | ok 2026-04-22 02:27:08.538103 | 2026-04-22 02:27:08.538306 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-22 02:27:08.569064 | localhost | ok 2026-04-22 02:27:08.576660 | 2026-04-22 02:27:08.576730 | TASK [emit-job-header : Print job information] 2026-04-22 02:27:08.618397 | # Job Information 2026-04-22 02:27:08.618597 | Ansible Version: 2.16.16 2026-04-22 02:27:08.618646 | Job: ansible-collection-kubernetes-molecule-helm 2026-04-22 02:27:08.618679 | Pipeline: check 2026-04-22 02:27:08.618709 | Executor: 0a8996d2b663 2026-04-22 02:27:08.618738 | Triggered by: https://github.com/vexxhost/ansible-collection-kubernetes/pull/267 2026-04-22 02:27:08.618770 | Event ID: 98177490-3df2-11f1-97dd-708141ef92ff 2026-04-22 02:27:08.622910 | 2026-04-22 02:27:08.623005 | LOOP [emit-job-header : Print node information] 2026-04-22 02:27:08.705387 | localhost | ok: 2026-04-22 02:27:08.705698 | localhost | # Node Information 2026-04-22 02:27:08.705759 | localhost | Inventory Hostname: controller 2026-04-22 02:27:08.705805 | localhost | Hostname: np0000169097 2026-04-22 02:27:08.705849 | localhost | Username: zuul 2026-04-22 02:27:08.705898 | localhost | Distro: Debian 13.4 2026-04-22 02:27:08.705941 | localhost | Provider: yul1 2026-04-22 02:27:08.705983 | localhost | Region: ca-ymq-1 2026-04-22 02:27:08.706024 | localhost | Label: debian-trixie 2026-04-22 02:27:08.706065 | localhost | Product Name: OpenStack Nova 2026-04-22 02:27:08.706105 | localhost | Interface IP: 199.19.213.225 2026-04-22 02:27:08.721424 | 2026-04-22 02:27:08.721648 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-04-22 02:27:09.134386 | localhost -> localhost | changed 2026-04-22 02:27:09.144341 | 2026-04-22 02:27:09.144439 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-04-22 02:27:10.181560 | localhost -> localhost | changed 2026-04-22 02:27:10.189161 | 2026-04-22 02:27:10.189232 | PLAY [all] 2026-04-22 02:27:10.196137 | 2026-04-22 02:27:10.196208 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-04-22 02:27:10.423175 | controller -> localhost | ok 2026-04-22 02:27:10.428881 | 2026-04-22 02:27:10.428953 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-04-22 02:27:10.459702 | controller | ok 2026-04-22 02:27:10.472433 | controller | included: /var/lib/zuul/builds/0c2e5e854d7d4f2d9f716ec5779399ed/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-04-22 02:27:10.477147 | 2026-04-22 02:27:10.477214 | TASK [add-build-sshkey : Create Temp SSH key] 2026-04-22 02:27:11.743297 | controller -> localhost | Generating public/private rsa key pair. 2026-04-22 02:27:11.743702 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/0c2e5e854d7d4f2d9f716ec5779399ed/work/0c2e5e854d7d4f2d9f716ec5779399ed_id_rsa 2026-04-22 02:27:11.743795 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/0c2e5e854d7d4f2d9f716ec5779399ed/work/0c2e5e854d7d4f2d9f716ec5779399ed_id_rsa.pub 2026-04-22 02:27:11.743863 | controller -> localhost | The key fingerprint is: 2026-04-22 02:27:11.743912 | controller -> localhost | SHA256:LzJD6oFSz6UjQjDT1Y8ZXMXQccJBNp4FYPKrJAizaFs zuul-build-sshkey 2026-04-22 02:27:11.743985 | controller -> localhost | The key's randomart image is: 2026-04-22 02:27:11.744032 | controller -> localhost | +---[RSA 3072]----+ 2026-04-22 02:27:11.744087 | controller -> localhost | | .o..=OX+o | 2026-04-22 02:27:11.744135 | controller -> localhost | | . . ++ o+* | 2026-04-22 02:27:11.744182 | controller -> localhost | |* . =. o | 2026-04-22 02:27:11.744228 | controller -> localhost | |o* . o .. | 2026-04-22 02:27:11.744273 | controller -> localhost | |oooE. + S | 2026-04-22 02:27:11.744317 | controller -> localhost | |o.o+ B . . | 2026-04-22 02:27:11.744361 | controller -> localhost | |o.o B = . . | 2026-04-22 02:27:11.744404 | controller -> localhost | | o o o + . | 2026-04-22 02:27:11.744454 | controller -> localhost | | . | 2026-04-22 02:27:11.744499 | controller -> localhost | +----[SHA256]-----+ 2026-04-22 02:27:11.744601 | controller -> localhost | ok: Runtime: 0:00:00.822095 2026-04-22 02:27:11.750688 | 2026-04-22 02:27:11.750758 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-04-22 02:27:11.772660 | controller | ok 2026-04-22 02:27:11.781725 | controller | included: /var/lib/zuul/builds/0c2e5e854d7d4f2d9f716ec5779399ed/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-04-22 02:27:11.788702 | 2026-04-22 02:27:11.788764 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-04-22 02:27:11.803637 | controller | skipping: Conditional result was False 2026-04-22 02:27:11.809473 | 2026-04-22 02:27:11.809561 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-04-22 02:27:12.325017 | controller | changed 2026-04-22 02:27:12.329023 | 2026-04-22 02:27:12.329087 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-04-22 02:27:12.544157 | controller | ok 2026-04-22 02:27:12.550048 | 2026-04-22 02:27:12.550144 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-04-22 02:27:13.125274 | controller | changed 2026-04-22 02:27:13.129447 | 2026-04-22 02:27:13.129516 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-04-22 02:27:13.656498 | controller | changed 2026-04-22 02:27:13.665968 | 2026-04-22 02:27:13.666057 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-04-22 02:27:13.691737 | controller | skipping: Conditional result was False 2026-04-22 02:27:13.697594 | 2026-04-22 02:27:13.697677 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-04-22 02:27:14.089725 | controller -> localhost | changed 2026-04-22 02:27:14.102934 | 2026-04-22 02:27:14.103030 | TASK [add-build-sshkey : Add back temp key] 2026-04-22 02:27:14.387569 | controller -> localhost | Identity added: /var/lib/zuul/builds/0c2e5e854d7d4f2d9f716ec5779399ed/work/0c2e5e854d7d4f2d9f716ec5779399ed_id_rsa (zuul-build-sshkey) 2026-04-22 02:27:14.387840 | controller -> localhost | ok: Runtime: 0:00:00.012215 2026-04-22 02:27:14.395006 | 2026-04-22 02:27:14.395091 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-04-22 02:27:14.737900 | controller | ok 2026-04-22 02:27:14.742635 | 2026-04-22 02:27:14.742715 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-04-22 02:27:14.768176 | controller | skipping: Conditional result was False 2026-04-22 02:27:14.781127 | 2026-04-22 02:27:14.781252 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-04-22 02:27:15.170369 | controller | ok 2026-04-22 02:27:15.212896 | 2026-04-22 02:27:15.213021 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-04-22 02:27:16.262618 | controller | Output suppressed because no_log was given 2026-04-22 02:27:16.280618 | 2026-04-22 02:27:16.280711 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-04-22 02:27:16.491259 | controller | ok: "logs" 2026-04-22 02:27:16.491713 | controller | ok: All items complete 2026-04-22 02:27:16.491765 | 2026-04-22 02:27:16.688793 | controller | ok: "artifacts" 2026-04-22 02:27:16.884289 | controller | ok: "docs" 2026-04-22 02:27:16.899670 | 2026-04-22 02:27:16.899835 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-04-22 02:27:17.133273 | controller | changed: "logs" 2026-04-22 02:27:17.323887 | controller | changed: "artifacts" 2026-04-22 02:27:17.510854 | controller | changed: "docs" 2026-04-22 02:27:17.524141 | 2026-04-22 02:27:17.524279 | PLAY RECAP 2026-04-22 02:27:17.524335 | controller | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-04-22 02:27:17.524364 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-22 02:27:17.524384 | 2026-04-22 02:27:17.680382 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-04-22 02:27:17.681918 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-04-22 02:27:18.254278 | 2026-04-22 02:27:18.254417 | PLAY [all] 2026-04-22 02:27:18.271902 | 2026-04-22 02:27:18.272054 | TASK [setup-uv : Extract archive] 2026-04-22 02:27:20.746442 | controller | changed 2026-04-22 02:27:20.755601 | 2026-04-22 02:27:20.755785 | TASK [setup-uv : Print version] 2026-04-22 02:27:21.132350 | controller | uv 0.8.13 2026-04-22 02:27:21.295487 | controller | ok: Runtime: 0:00:00.014851 2026-04-22 02:27:21.301772 | 2026-04-22 02:27:21.301828 | PLAY RECAP 2026-04-22 02:27:21.301878 | controller | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-22 02:27:21.301903 | 2026-04-22 02:27:21.468575 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-04-22 02:27:21.471272 | PRE-RUN START: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/pre.yml@main] 2026-04-22 02:27:22.141348 | 2026-04-22 02:27:22.141496 | PLAY [all] 2026-04-22 02:27:22.152232 | 2026-04-22 02:27:22.152318 | TASK [Update apt cache] 2026-04-22 02:27:27.050356 | controller | ok 2026-04-22 02:27:27.055212 | 2026-04-22 02:27:27.055284 | TASK [Disable firewalld] 2026-04-22 02:27:27.080113 | controller | skipping: Conditional result was False 2026-04-22 02:27:27.084492 | 2026-04-22 02:27:27.084556 | TASK [Install "jq" for log collection] 2026-04-22 02:27:29.198123 | controller | changed 2026-04-22 02:27:29.201427 | 2026-04-22 02:27:29.201502 | PLAY RECAP 2026-04-22 02:27:29.201568 | controller | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-04-22 02:27:29.201601 | 2026-04-22 02:27:29.324991 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/pre.yml@main] 2026-04-22 02:27:29.326782 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-04-22 02:27:29.950538 | 2026-04-22 02:27:29.950677 | PLAY [all] 2026-04-22 02:27:29.960483 | 2026-04-22 02:27:29.960558 | TASK [Copy inventory file for Zuul] 2026-04-22 02:27:30.919369 | controller | changed 2026-04-22 02:27:30.925092 | 2026-04-22 02:27:30.925175 | TASK [Switch "ansible_host" to private IP] 2026-04-22 02:27:31.224523 | controller | changed: 1 replacements made 2026-04-22 02:27:31.229279 | 2026-04-22 02:27:31.229349 | TASK [Run Molecule scenario] 2026-04-22 02:27:31.683199 | controller | Using CPython 3.13.5 interpreter at: /usr/bin/python3 2026-04-22 02:27:31.683300 | controller | Creating virtual environment at: .venv 2026-04-22 02:27:31.969871 | controller | Downloading pygments (1.2MiB) 2026-04-22 02:27:31.970864 | controller | Downloading ansible-core (2.3MiB) 2026-04-22 02:27:31.971099 | controller | Downloading cryptography (4.3MiB) 2026-04-22 02:27:32.144026 | controller | Downloading cryptography 2026-04-22 02:27:32.203080 | controller | Downloading pygments 2026-04-22 02:27:32.408764 | controller | Downloading ansible-core 2026-04-22 02:27:32.460244 | controller | Installed 36 packages in 50ms 2026-04-22 02:27:33.176460 | controller | INFO Collection 'vexxhost.kubernetes' detected. 2026-04-22 02:27:33.176519 | controller | INFO Scenarios will be used from 'extensions/molecule' 2026-04-22 02:27:34.087331 | controller | CRITICAL 'extensions/molecule/default/molecule.yml' glob failed. Exiting. 2026-04-22 02:27:34.087399 | controller | INFO default scenario not found, disabling shared state. 2026-04-22 02:27:34.087416 | controller | INFO [helm > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy 2026-04-22 02:27:34.087489 | controller | INFO [helm > prerun] Performing prerun with role_name_check=0... 2026-04-22 02:27:50.362211 | controller | INFO [helm > dependency] Executing 2026-04-22 02:27:50.362498 | controller | WARNING [helm > dependency] Missing roles requirements file: requirements.yml 2026-04-22 02:27:50.363114 | controller | WARNING [helm > dependency] Missing collections requirements file: collections.yml 2026-04-22 02:27:50.363360 | controller | WARNING [helm > dependency] Executed: 2 missing (Remove from test_sequence to suppress) 2026-04-22 02:27:50.369190 | controller | INFO [helm > cleanup] Executing 2026-04-22 02:27:50.369492 | controller | WARNING [helm > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-04-22 02:27:50.374912 | controller | INFO [helm > destroy] Executing 2026-04-22 02:27:50.374940 | controller | WARNING [helm > destroy] Skipping, '--destroy=never' requested. 2026-04-22 02:27:50.375032 | controller | INFO [helm > destroy] Executed: Successful 2026-04-22 02:27:50.380407 | controller | INFO [helm > syntax] Executing 2026-04-22 02:27:51.285037 | controller | 2026-04-22 02:27:51.285307 | controller | playbook: /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/extensions/molecule/helm/converge.yml 2026-04-22 02:27:51.343495 | controller | INFO [helm > syntax] Executed: Successful 2026-04-22 02:27:51.352581 | controller | INFO [helm > create] Executing 2026-04-22 02:27:51.354677 | controller | WARNING [helm > create] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-04-22 02:27:51.360736 | controller | INFO [helm > prepare] Executing 2026-04-22 02:27:52.222388 | controller | 2026-04-22 02:27:52.222578 | controller | PLAY [Prepare] ***************************************************************** 2026-04-22 02:27:52.222854 | controller | 2026-04-22 02:27:52.223082 | controller | TASK [Gathering Facts] ********************************************************* 2026-04-22 02:27:52.223294 | controller | Wednesday 22 April 2026 02:27:52 +0000 (0:00:00.029) 0:00:00.029 ******* 2026-04-22 02:27:53.501839 | 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-22 02:27:53.509056 | controller | ok: [controller] 2026-04-22 02:27:53.509173 | controller | 2026-04-22 02:27:53.509302 | controller | TASK [Install Debian packages] ************************************************* 2026-04-22 02:27:53.512950 | controller | Wednesday 22 April 2026 02:27:53 +0000 (0:00:01.287) 0:00:01.316 ******* 2026-04-22 02:27:54.931846 | controller | ok: [controller] 2026-04-22 02:27:54.931873 | controller | 2026-04-22 02:27:54.931879 | controller | PLAY RECAP ********************************************************************* 2026-04-22 02:27:54.931886 | controller | controller : ok=2 changed=0 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-04-22 02:27:54.931891 | controller | 2026-04-22 02:27:54.931896 | controller | 2026-04-22 02:27:54.931901 | controller | TASKS RECAP ******************************************************************** 2026-04-22 02:27:54.931916 | controller | Wednesday 22 April 2026 02:27:54 +0000 (0:00:01.421) 0:00:02.738 ******* 2026-04-22 02:27:54.931921 | controller | =============================================================================== 2026-04-22 02:27:54.931926 | controller | Install Debian packages ------------------------------------------------- 1.42s 2026-04-22 02:27:54.931931 | controller | Gathering Facts --------------------------------------------------------- 1.29s 2026-04-22 02:27:55.015416 | controller | INFO [helm > prepare] Executed: Successful 2026-04-22 02:27:55.022833 | controller | INFO [helm > converge] Executing 2026-04-22 02:27:55.852797 | controller | 2026-04-22 02:27:55.853194 | controller | PLAY [Converge] **************************************************************** 2026-04-22 02:27:55.853459 | controller | 2026-04-22 02:27:55.853784 | controller | TASK [Gathering Facts] ********************************************************* 2026-04-22 02:27:55.854143 | controller | Wednesday 22 April 2026 02:27:55 +0000 (0:00:00.013) 0:00:00.013 ******* 2026-04-22 02:27:56.908825 | 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-22 02:27:56.913832 | controller | ok: [controller] 2026-04-22 02:27:56.914117 | controller | 2026-04-22 02:27:56.914389 | controller | TASK [vexxhost.containers.forget_package : Forget package] ********************* 2026-04-22 02:27:56.914648 | controller | Wednesday 22 April 2026 02:27:56 +0000 (0:00:01.061) 0:00:01.074 ******* 2026-04-22 02:27:57.431734 | controller | ok: [controller] 2026-04-22 02:27:57.432089 | controller | 2026-04-22 02:27:57.432371 | controller | TASK [vexxhost.containers.package : Update state for tar] ********************** 2026-04-22 02:27:57.432632 | controller | Wednesday 22 April 2026 02:27:57 +0000 (0:00:00.517) 0:00:01.591 ******* 2026-04-22 02:27:58.442312 | controller | ok: [controller] 2026-04-22 02:27:58.442692 | controller | 2026-04-22 02:27:58.443092 | controller | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] *** 2026-04-22 02:27:58.443465 | controller | Wednesday 22 April 2026 02:27:58 +0000 (0:00:01.010) 0:00:02.602 ******* 2026-04-22 02:27:58.863838 | controller | changed: [controller] 2026-04-22 02:27:58.864116 | controller | 2026-04-22 02:27:58.864394 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-04-22 02:27:58.864658 | controller | Wednesday 22 April 2026 02:27:58 +0000 (0:00:00.421) 0:00:03.024 ******* 2026-04-22 02:27:58.907928 | controller | ok: [controller] => { 2026-04-22 02:27:58.908258 | controller | "msg": "https://get.helm.sh/helm-v3.11.2-linux-amd64.tar.gz" 2026-04-22 02:27:58.908548 | controller | } 2026-04-22 02:27:58.908829 | controller | 2026-04-22 02:27:58.909195 | controller | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-04-22 02:27:58.909501 | controller | Wednesday 22 April 2026 02:27:58 +0000 (0:00:00.043) 0:00:03.067 ******* 2026-04-22 02:27:59.866741 | 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-22 02:27:59.876739 | controller | changed: [controller] 2026-04-22 02:27:59.876761 | controller | 2026-04-22 02:27:59.876771 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-04-22 02:27:59.876779 | controller | Wednesday 22 April 2026 02:27:59 +0000 (0:00:00.968) 0:00:04.036 ******* 2026-04-22 02:28:01.949798 | controller | changed: [controller] 2026-04-22 02:28:01.950108 | controller | 2026-04-22 02:28:01.950409 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm repository] *********************** 2026-04-22 02:28:01.950694 | controller | Wednesday 22 April 2026 02:28:01 +0000 (0:00:02.073) 0:00:06.110 ******* 2026-04-22 02:28:02.464148 | controller | ok: [controller] 2026-04-22 02:28:02.464299 | controller | 2026-04-22 02:28:02.464440 | controller | TASK [vexxhost.kubernetes.helm : Add bash autocomplete for helm] *************** 2026-04-22 02:28:02.464580 | controller | Wednesday 22 April 2026 02:28:02 +0000 (0:00:00.513) 0:00:06.624 ******* 2026-04-22 02:28:02.863600 | controller | changed: [controller] 2026-04-22 02:28:02.863928 | controller | 2026-04-22 02:28:02.864241 | controller | TASK [vexxhost.kubernetes.helm : Get Helm plugins dir] ************************* 2026-04-22 02:28:02.864630 | controller | Wednesday 22 April 2026 02:28:02 +0000 (0:00:00.399) 0:00:07.023 ******* 2026-04-22 02:28:03.318944 | controller | ok: [controller] 2026-04-22 02:28:03.319132 | controller | 2026-04-22 02:28:03.319315 | controller | TASK [vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist] *** 2026-04-22 02:28:03.319484 | controller | Wednesday 22 April 2026 02:28:03 +0000 (0:00:00.455) 0:00:07.478 ******* 2026-04-22 02:28:03.624006 | controller | changed: [controller] 2026-04-22 02:28:03.624295 | controller | 2026-04-22 02:28:03.624589 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin] *** 2026-04-22 02:28:03.624855 | controller | Wednesday 22 April 2026 02:28:03 +0000 (0:00:00.304) 0:00:07.783 ******* 2026-04-22 02:28:03.939523 | controller | ok: [controller] 2026-04-22 02:28:03.939714 | controller | 2026-04-22 02:28:03.939897 | controller | TASK [Install plugin] ********************************************************** 2026-04-22 02:28:03.940062 | controller | Wednesday 22 April 2026 02:28:03 +0000 (0:00:00.315) 0:00:08.099 ******* 2026-04-22 02:28:03.995116 | controller | included: vexxhost.containers.download_artifact for controller 2026-04-22 02:28:03.995473 | controller | 2026-04-22 02:28:03.995784 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-04-22 02:28:03.996072 | controller | Wednesday 22 April 2026 02:28:03 +0000 (0:00:00.055) 0:00:08.155 ******* 2026-04-22 02:28:04.040170 | controller | ok: [controller] => { 2026-04-22 02:28:04.040409 | controller | "msg": "https://github.com/databus23/helm-diff/releases/download/v3.8.1/helm-diff-linux-amd64.tgz" 2026-04-22 02:28:04.040597 | controller | } 2026-04-22 02:28:04.040777 | controller | 2026-04-22 02:28:04.041008 | controller | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-04-22 02:28:04.041205 | controller | Wednesday 22 April 2026 02:28:04 +0000 (0:00:00.044) 0:00:08.200 ******* 2026-04-22 02:28:04.793424 | controller | changed: [controller] 2026-04-22 02:28:04.793714 | controller | 2026-04-22 02:28:04.793999 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-04-22 02:28:04.794271 | controller | Wednesday 22 April 2026 02:28:04 +0000 (0:00:00.753) 0:00:08.953 ******* 2026-04-22 02:28:06.843670 | controller | changed: [controller] 2026-04-22 02:28:06.843970 | controller | 2026-04-22 02:28:06.844250 | controller | PLAY RECAP ********************************************************************* 2026-04-22 02:28:06.844533 | controller | controller : ok=16 changed=7 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-04-22 02:28:06.844770 | controller | 2026-04-22 02:28:06.845080 | controller | 2026-04-22 02:28:06.845347 | controller | TASKS RECAP ******************************************************************** 2026-04-22 02:28:06.845604 | controller | Wednesday 22 April 2026 02:28:06 +0000 (0:00:02.050) 0:00:11.004 ******* 2026-04-22 02:28:06.845843 | controller | =============================================================================== 2026-04-22 02:28:06.846099 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 2.07s 2026-04-22 02:28:06.846372 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 2.05s 2026-04-22 02:28:06.846624 | controller | Gathering Facts --------------------------------------------------------- 1.06s 2026-04-22 02:28:06.846873 | controller | vexxhost.containers.package : Update state for tar ---------------------- 1.01s 2026-04-22 02:28:06.847331 | controller | vexxhost.containers.download_artifact : Download item ------------------- 0.97s 2026-04-22 02:28:06.847597 | controller | vexxhost.containers.download_artifact : Download item ------------------- 0.75s 2026-04-22 02:28:06.847843 | controller | vexxhost.containers.forget_package : Forget package --------------------- 0.52s 2026-04-22 02:28:06.848092 | controller | vexxhost.kubernetes.helm : Remove Helm repository ----------------------- 0.51s 2026-04-22 02:28:06.848351 | controller | vexxhost.kubernetes.helm : Get Helm plugins dir ------------------------- 0.46s 2026-04-22 02:28:06.848611 | controller | vexxhost.containers.directory : Create directory (/var/lib/downloads) --- 0.42s 2026-04-22 02:28:06.848939 | controller | vexxhost.kubernetes.helm : Add bash autocomplete for helm --------------- 0.40s 2026-04-22 02:28:06.849217 | controller | vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin --- 0.32s 2026-04-22 02:28:06.849502 | controller | vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist --- 0.30s 2026-04-22 02:28:06.849757 | controller | Install plugin ---------------------------------------------------------- 0.06s 2026-04-22 02:28:06.850009 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.04s 2026-04-22 02:28:06.850256 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.04s 2026-04-22 02:28:06.958938 | controller | INFO [helm > converge] Executed: Successful 2026-04-22 02:28:06.970830 | controller | INFO [helm > idempotence] Executing 2026-04-22 02:28:07.849828 | controller | 2026-04-22 02:28:07.850153 | controller | PLAY [Converge] **************************************************************** 2026-04-22 02:28:07.850394 | controller | 2026-04-22 02:28:07.850654 | controller | TASK [Gathering Facts] ********************************************************* 2026-04-22 02:28:07.850917 | controller | Wednesday 22 April 2026 02:28:07 +0000 (0:00:00.013) 0:00:00.013 ******* 2026-04-22 02:28:09.905836 | 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-22 02:28:09.911568 | controller | ok: [controller] 2026-04-22 02:28:09.911599 | controller | 2026-04-22 02:28:09.911608 | controller | TASK [vexxhost.containers.forget_package : Forget package] ********************* 2026-04-22 02:28:09.911617 | controller | Wednesday 22 April 2026 02:28:09 +0000 (0:00:02.061) 0:00:02.075 ******* 2026-04-22 02:28:10.353251 | controller | ok: [controller] 2026-04-22 02:28:10.353594 | controller | 2026-04-22 02:28:10.353898 | controller | TASK [vexxhost.containers.package : Update state for tar] ********************** 2026-04-22 02:28:10.354241 | controller | Wednesday 22 April 2026 02:28:10 +0000 (0:00:00.442) 0:00:02.517 ******* 2026-04-22 02:28:11.352212 | controller | ok: [controller] 2026-04-22 02:28:11.352542 | controller | 2026-04-22 02:28:11.352853 | controller | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] *** 2026-04-22 02:28:11.353199 | controller | Wednesday 22 April 2026 02:28:11 +0000 (0:00:00.998) 0:00:03.516 ******* 2026-04-22 02:28:11.768425 | controller | ok: [controller] 2026-04-22 02:28:11.768450 | controller | 2026-04-22 02:28:11.768456 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-04-22 02:28:11.768461 | controller | Wednesday 22 April 2026 02:28:11 +0000 (0:00:00.413) 0:00:03.929 ******* 2026-04-22 02:28:11.811761 | controller | ok: [controller] => { 2026-04-22 02:28:11.811792 | controller | "msg": "https://get.helm.sh/helm-v3.11.2-linux-amd64.tar.gz" 2026-04-22 02:28:11.811798 | controller | } 2026-04-22 02:28:11.811803 | controller | 2026-04-22 02:28:11.811808 | controller | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-04-22 02:28:11.811813 | controller | Wednesday 22 April 2026 02:28:11 +0000 (0:00:00.045) 0:00:03.975 ******* 2026-04-22 02:28:12.325366 | controller | ok: [controller] 2026-04-22 02:28:12.325391 | controller | 2026-04-22 02:28:12.325396 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-04-22 02:28:12.325402 | controller | Wednesday 22 April 2026 02:28:12 +0000 (0:00:00.511) 0:00:04.486 ******* 2026-04-22 02:28:15.010410 | controller | ok: [controller] 2026-04-22 02:28:15.010741 | controller | 2026-04-22 02:28:15.011115 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm repository] *********************** 2026-04-22 02:28:15.011482 | controller | Wednesday 22 April 2026 02:28:15 +0000 (0:00:02.687) 0:00:07.174 ******* 2026-04-22 02:28:15.516387 | controller | ok: [controller] 2026-04-22 02:28:15.516430 | controller | 2026-04-22 02:28:15.516534 | controller | TASK [vexxhost.kubernetes.helm : Add bash autocomplete for helm] *************** 2026-04-22 02:28:15.516684 | controller | Wednesday 22 April 2026 02:28:15 +0000 (0:00:00.499) 0:00:07.674 ******* 2026-04-22 02:28:15.935372 | controller | ok: [controller] 2026-04-22 02:28:15.935422 | controller | 2026-04-22 02:28:15.935513 | controller | TASK [vexxhost.kubernetes.helm : Get Helm plugins dir] ************************* 2026-04-22 02:28:15.935601 | controller | Wednesday 22 April 2026 02:28:15 +0000 (0:00:00.425) 0:00:08.099 ******* 2026-04-22 02:28:16.392311 | controller | ok: [controller] 2026-04-22 02:28:16.392367 | controller | 2026-04-22 02:28:16.392746 | controller | TASK [vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist] *** 2026-04-22 02:28:16.392780 | controller | Wednesday 22 April 2026 02:28:16 +0000 (0:00:00.456) 0:00:08.556 ******* 2026-04-22 02:28:16.689323 | controller | ok: [controller] 2026-04-22 02:28:16.689404 | controller | 2026-04-22 02:28:16.689719 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin] *** 2026-04-22 02:28:16.689748 | controller | Wednesday 22 April 2026 02:28:16 +0000 (0:00:00.294) 0:00:08.851 ******* 2026-04-22 02:28:16.980658 | controller | ok: [controller] 2026-04-22 02:28:16.980711 | controller | 2026-04-22 02:28:16.980905 | controller | TASK [Install plugin] ********************************************************** 2026-04-22 02:28:16.981080 | controller | Wednesday 22 April 2026 02:28:16 +0000 (0:00:00.293) 0:00:09.144 ******* 2026-04-22 02:28:17.037345 | controller | included: vexxhost.containers.download_artifact for controller 2026-04-22 02:28:17.037402 | controller | 2026-04-22 02:28:17.040201 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-04-22 02:28:17.040227 | controller | Wednesday 22 April 2026 02:28:17 +0000 (0:00:00.055) 0:00:09.200 ******* 2026-04-22 02:28:17.085236 | controller | ok: [controller] => { 2026-04-22 02:28:17.085382 | controller | "msg": "https://github.com/databus23/helm-diff/releases/download/v3.8.1/helm-diff-linux-amd64.tgz" 2026-04-22 02:28:17.085496 | controller | } 2026-04-22 02:28:17.085627 | controller | 2026-04-22 02:28:17.085748 | controller | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-04-22 02:28:17.085864 | controller | Wednesday 22 April 2026 02:28:17 +0000 (0:00:00.047) 0:00:09.247 ******* 2026-04-22 02:28:17.509159 | controller | ok: [controller] 2026-04-22 02:28:17.509264 | controller | 2026-04-22 02:28:17.509393 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-04-22 02:28:17.509519 | controller | Wednesday 22 April 2026 02:28:17 +0000 (0:00:00.425) 0:00:09.673 ******* 2026-04-22 02:28:19.131241 | controller | ok: [controller] 2026-04-22 02:28:19.131266 | controller | 2026-04-22 02:28:19.131270 | controller | PLAY RECAP ********************************************************************* 2026-04-22 02:28:19.131274 | controller | controller : ok=16 changed=0 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-04-22 02:28:19.131278 | controller | 2026-04-22 02:28:19.131281 | controller | 2026-04-22 02:28:19.131284 | controller | TASKS RECAP ******************************************************************** 2026-04-22 02:28:19.131288 | controller | Wednesday 22 April 2026 02:28:19 +0000 (0:00:01.616) 0:00:11.290 ******* 2026-04-22 02:28:19.131291 | controller | =============================================================================== 2026-04-22 02:28:19.131295 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 2.69s 2026-04-22 02:28:19.131298 | controller | Gathering Facts --------------------------------------------------------- 2.06s 2026-04-22 02:28:19.131301 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 1.62s 2026-04-22 02:28:19.131304 | controller | vexxhost.containers.package : Update state for tar ---------------------- 1.00s 2026-04-22 02:28:19.131307 | controller | vexxhost.containers.download_artifact : Download item ------------------- 0.51s 2026-04-22 02:28:19.131310 | controller | vexxhost.kubernetes.helm : Remove Helm repository ----------------------- 0.50s 2026-04-22 02:28:19.131313 | controller | vexxhost.kubernetes.helm : Get Helm plugins dir ------------------------- 0.46s 2026-04-22 02:28:19.131316 | controller | vexxhost.containers.forget_package : Forget package --------------------- 0.44s 2026-04-22 02:28:19.131320 | controller | vexxhost.kubernetes.helm : Add bash autocomplete for helm --------------- 0.43s 2026-04-22 02:28:19.131324 | controller | vexxhost.containers.download_artifact : Download item ------------------- 0.43s 2026-04-22 02:28:19.131335 | controller | vexxhost.containers.directory : Create directory (/var/lib/downloads) --- 0.41s 2026-04-22 02:28:19.131338 | controller | vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist --- 0.29s 2026-04-22 02:28:19.131341 | controller | vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin --- 0.29s 2026-04-22 02:28:19.131344 | controller | Install plugin ---------------------------------------------------------- 0.06s 2026-04-22 02:28:19.131348 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.05s 2026-04-22 02:28:19.131352 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.05s 2026-04-22 02:28:19.219980 | controller | INFO [helm > idempotence] Executed: Successful 2026-04-22 02:28:19.228823 | controller | INFO [helm > side_effect] Executing 2026-04-22 02:28:19.229307 | controller | WARNING [helm > side_effect] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-04-22 02:28:19.236082 | controller | INFO [helm > verify] Executing 2026-04-22 02:28:20.066355 | controller | 2026-04-22 02:28:20.066677 | controller | PLAY [Verify] ****************************************************************** 2026-04-22 02:28:20.067052 | controller | 2026-04-22 02:28:20.067346 | controller | TASK [Gathering Facts] ********************************************************* 2026-04-22 02:28:20.067615 | controller | Wednesday 22 April 2026 02:28:20 +0000 (0:00:00.009) 0:00:00.009 ******* 2026-04-22 02:28:21.140910 | 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-22 02:28:21.147468 | controller | ok: [controller] 2026-04-22 02:28:21.147743 | controller | 2026-04-22 02:28:21.148013 | controller | TASK [Get the current Helm version] ******************************************** 2026-04-22 02:28:21.148276 | controller | Wednesday 22 April 2026 02:28:21 +0000 (0:00:01.081) 0:00:01.091 ******* 2026-04-22 02:28:21.581821 | controller | changed: [controller] 2026-04-22 02:28:21.582117 | controller | 2026-04-22 02:28:21.582397 | controller | TASK [Assert Helm version] ***************************************************** 2026-04-22 02:28:21.582665 | controller | Wednesday 22 April 2026 02:28:21 +0000 (0:00:00.434) 0:00:01.525 ******* 2026-04-22 02:28:21.610044 | controller | ok: [controller] => { 2026-04-22 02:28:21.610323 | controller | "changed": false, 2026-04-22 02:28:21.610579 | controller | "msg": "All assertions passed" 2026-04-22 02:28:21.610910 | controller | } 2026-04-22 02:28:21.611166 | controller | 2026-04-22 02:28:21.611459 | controller | PLAY RECAP ********************************************************************* 2026-04-22 02:28:21.611962 | controller | controller : ok=3 changed=1 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-04-22 02:28:21.612259 | controller | 2026-04-22 02:28:21.612451 | controller | 2026-04-22 02:28:21.612661 | controller | TASKS RECAP ******************************************************************** 2026-04-22 02:28:21.612916 | controller | Wednesday 22 April 2026 02:28:21 +0000 (0:00:00.029) 0:00:01.554 ******* 2026-04-22 02:28:21.613118 | controller | =============================================================================== 2026-04-22 02:28:21.613325 | controller | Gathering Facts --------------------------------------------------------- 1.08s 2026-04-22 02:28:21.613499 | controller | Get the current Helm version -------------------------------------------- 0.43s 2026-04-22 02:28:21.613672 | controller | Assert Helm version ----------------------------------------------------- 0.03s 2026-04-22 02:28:21.703037 | controller | INFO [helm > verify] Executed: Successful 2026-04-22 02:28:21.713381 | controller | INFO [helm > cleanup] Executing 2026-04-22 02:28:21.713890 | controller | WARNING [helm > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-04-22 02:28:21.721334 | controller | INFO [helm > destroy] Executing 2026-04-22 02:28:21.721628 | controller | WARNING [helm > destroy] Skipping, '--destroy=never' requested. 2026-04-22 02:28:21.721721 | controller | INFO [helm > destroy] Executed: Successful 2026-04-22 02:28:21.722003 | controller | WARNING Molecule executed 1 scenario (1 missing files) 2026-04-22 02:28:22.298531 | controller | ok: Runtime: 0:00:50.205359 2026-04-22 02:28:22.305546 | 2026-04-22 02:28:22.305663 | PLAY RECAP 2026-04-22 02:28:22.305714 | controller | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-22 02:28:22.305737 | 2026-04-22 02:28:22.420248 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-04-22 02:28:22.423034 | POST-RUN START: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/post.yml@main] 2026-04-22 02:28:23.058953 | 2026-04-22 02:28:23.059078 | PLAY [all] 2026-04-22 02:28:23.070113 | 2026-04-22 02:28:23.070191 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects] 2026-04-22 02:28:23.459219 | controller | changed 2026-04-22 02:28:23.463732 | 2026-04-22 02:28:23.463818 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects] 2026-04-22 02:28:23.880717 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-04-22 02:28:23.880843 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-04-22 02:28:23.888138 | controller | environment: line 1: kubectl: command not found 2026-04-22 02:28:23.889947 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-04-22 02:28:23.890124 | controller | environment: line 1: kubectl: command not found 2026-04-22 02:28:23.892030 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-04-22 02:28:23.894336 | controller | environment: line 1: kubectl: command not found 2026-04-22 02:28:23.895606 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-04-22 02:28:23.898454 | controller | environment: line 1: kubectl: command not found 2026-04-22 02:28:23.900630 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-04-22 02:28:23.902398 | controller | environment: line 1: kubectl: command not found 2026-04-22 02:28:23.904154 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-04-22 02:28:24.005812 | controller | ok: Runtime: 0:00:00.037088 2026-04-22 02:28:24.010119 | 2026-04-22 02:28:24.010195 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects] 2026-04-22 02:28:24.272678 | controller | changed 2026-04-22 02:28:24.277849 | 2026-04-22 02:28:24.277932 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects] 2026-04-22 02:28:24.542703 | controller | environment: line 5: kubectl: command not found 2026-04-22 02:28:24.544248 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-04-22 02:28:24.544283 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-04-22 02:28:24.544546 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-04-22 02:28:24.805773 | controller | ok: Runtime: 0:00:00.012446 2026-04-22 02:28:24.813913 | 2026-04-22 02:28:24.813989 | TASK [describe-kubernetes-objects : Downloads logs to executor] 2026-04-22 02:28:25.544455 | controller | changed: 2026-04-22 02:28:25.544650 | controller | created directory /var/lib/zuul/builds/0c2e5e854d7d4f2d9f716ec5779399ed/work/logs/controller 2026-04-22 02:28:25.544683 | controller | cd+++++++++ objects/ 2026-04-22 02:28:25.544706 | controller | cd+++++++++ objects/cluster/ 2026-04-22 02:28:25.544728 | controller | cd+++++++++ objects/namespaced/ 2026-04-22 02:28:25.551641 | 2026-04-22 02:28:25.551715 | TASK [gather-pod-logs : creating directory for pod logs] 2026-04-22 02:28:25.804082 | controller | changed 2026-04-22 02:28:25.808920 | 2026-04-22 02:28:25.809014 | TASK [gather-pod-logs : creating directory for failed pod logs] 2026-04-22 02:28:26.058224 | controller | changed 2026-04-22 02:28:26.066450 | 2026-04-22 02:28:26.066660 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)] 2026-04-22 02:28:26.339530 | controller | environment: line 3: kubectl: command not found 2026-04-22 02:28:26.602743 | controller | ok: Runtime: 0:00:00.012095 2026-04-22 02:28:26.607768 | 2026-04-22 02:28:26.607864 | TASK [gather-pod-logs : Downloads pod logs to executor] 2026-04-22 02:28:27.196713 | controller | changed: 2026-04-22 02:28:27.197027 | controller | cd+++++++++ pod-logs/ 2026-04-22 02:28:27.197095 | controller | cd+++++++++ pod-logs/failed-pods/ 2026-04-22 02:28:27.207203 | 2026-04-22 02:28:27.207269 | LOOP [helm-release-status : creating directory for helm release status] 2026-04-22 02:28:27.439801 | controller | changed: "values" 2026-04-22 02:28:27.653261 | controller | changed: "releases" 2026-04-22 02:28:27.664026 | 2026-04-22 02:28:27.664169 | TASK [helm-release-status : Gather get release status for helm charts] 2026-04-22 02:28:27.913423 | controller | /bin/bash: line 3: kubectl: command not found 2026-04-22 02:28:28.189525 | controller | ok: Runtime: 0:00:00.009755 2026-04-22 02:28:28.193740 | 2026-04-22 02:28:28.193805 | TASK [helm-release-status : Downloads logs to executor] 2026-04-22 02:28:28.724739 | controller | changed: 2026-04-22 02:28:28.725061 | controller | cd+++++++++ helm/ 2026-04-22 02:28:28.725102 | controller | cd+++++++++ helm/releases/ 2026-04-22 02:28:28.725135 | controller | cd+++++++++ helm/values/ 2026-04-22 02:28:28.731324 | 2026-04-22 02:28:28.731386 | PLAY RECAP 2026-04-22 02:28:28.731445 | controller | ok: 12 changed: 12 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-22 02:28:28.731474 | 2026-04-22 02:28:28.846119 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/post.yml@main] 2026-04-22 02:28:28.847540 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-04-22 02:28:29.470315 | 2026-04-22 02:28:29.470945 | PLAY [all] 2026-04-22 02:28:29.482728 | 2026-04-22 02:28:29.482903 | TASK [fetch-output : Set log path for multiple nodes] 2026-04-22 02:28:29.528589 | controller | skipping: Conditional result was False 2026-04-22 02:28:29.540298 | 2026-04-22 02:28:29.540465 | TASK [fetch-output : Set log path for single node] 2026-04-22 02:28:29.579613 | controller | ok 2026-04-22 02:28:29.583405 | 2026-04-22 02:28:29.583478 | LOOP [fetch-output : Ensure local output dirs] 2026-04-22 02:28:29.989417 | controller -> localhost | ok: "/var/lib/zuul/builds/0c2e5e854d7d4f2d9f716ec5779399ed/work/logs" 2026-04-22 02:28:30.191500 | controller -> localhost | changed: "/var/lib/zuul/builds/0c2e5e854d7d4f2d9f716ec5779399ed/work/artifacts" 2026-04-22 02:28:30.428074 | controller -> localhost | changed: "/var/lib/zuul/builds/0c2e5e854d7d4f2d9f716ec5779399ed/work/docs" 2026-04-22 02:28:30.442164 | 2026-04-22 02:28:30.442325 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-04-22 02:28:31.322014 | controller | changed: .d..t...... ./ 2026-04-22 02:28:31.322235 | controller | changed: All items complete 2026-04-22 02:28:31.322265 | 2026-04-22 02:28:31.806206 | controller | changed: .d..t...... ./ 2026-04-22 02:28:32.280478 | controller | changed: .d..t...... ./ 2026-04-22 02:28:32.300583 | 2026-04-22 02:28:32.300719 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-04-22 02:28:32.732435 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.019823 2026-04-22 02:28:32.969256 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.007523 2026-04-22 02:28:32.982126 | 2026-04-22 02:28:32.982211 | PLAY [all] 2026-04-22 02:28:32.988431 | 2026-04-22 02:28:32.988515 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-04-22 02:28:33.453255 | controller | changed 2026-04-22 02:28:33.459099 | 2026-04-22 02:28:33.459211 | PLAY RECAP 2026-04-22 02:28:33.459269 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-04-22 02:28:33.459300 | 2026-04-22 02:28:33.577125 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-04-22 02:28:33.579789 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-04-22 02:28:34.140890 | 2026-04-22 02:28:34.141424 | PLAY [localhost] 2026-04-22 02:28:34.151482 | 2026-04-22 02:28:34.151555 | TASK [Generate Zuul manifest] 2026-04-22 02:28:34.184518 | localhost | ok 2026-04-22 02:28:34.318167 | 2026-04-22 02:28:34.318275 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-04-22 02:28:34.665679 | localhost | changed 2026-04-22 02:28:34.681599 | 2026-04-22 02:28:34.681709 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-04-22 02:28:34.714615 | localhost | ok 2026-04-22 02:28:34.723678 | 2026-04-22 02:28:34.723765 | TASK [Upload logs] 2026-04-22 02:28:34.743450 | localhost | ok 2026-04-22 02:28:34.800577 | 2026-04-22 02:28:34.800680 | TASK [Set zuul-log-path fact] 2026-04-22 02:28:34.819360 | localhost | ok 2026-04-22 02:28:34.839406 | 2026-04-22 02:28:34.839622 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-22 02:28:34.873320 | localhost | ok 2026-04-22 02:28:34.884460 | 2026-04-22 02:28:34.884671 | TASK [upload-logs : Create log directories] 2026-04-22 02:28:35.315402 | localhost | changed 2026-04-22 02:28:35.324520 | 2026-04-22 02:28:35.324770 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-04-22 02:28:35.744379 | localhost -> localhost | ok: Runtime: 0:00:00.006808 2026-04-22 02:28:35.751537 | 2026-04-22 02:28:35.751632 | TASK [upload-logs : Upload logs to log server] 2026-04-22 02:28:36.358584 | localhost | Output suppressed because no_log was given 2026-04-22 02:28:36.363367 | 2026-04-22 02:28:36.363454 | LOOP [upload-logs : Compress console log and json output] 2026-04-22 02:28:36.410834 | localhost | skipping: Conditional result was False 2026-04-22 02:28:36.417379 | localhost | skipping: Conditional result was False 2026-04-22 02:28:36.431431 | 2026-04-22 02:28:36.431539 | LOOP [upload-logs : Upload compressed console log and json output] 2026-04-22 02:28:36.477681 | localhost | skipping: Conditional result was False 2026-04-22 02:28:36.478147 | 2026-04-22 02:28:36.481890 | localhost | skipping: Conditional result was False 2026-04-22 02:28:36.496023 | 2026-04-22 02:28:36.496151 | LOOP [upload-logs : Upload console log and json output]