2026-04-15 17:47:24.167121 | Job console starting 2026-04-15 17:47:24.178680 | Updating git repos 2026-04-15 17:47:24.217241 | Cloning repos into workspace 2026-04-15 17:47:24.269204 | Restoring repo states 2026-04-15 17:47:24.282848 | Merging changes 2026-04-15 17:47:25.828379 | Checking out repos 2026-04-15 17:47:25.873380 | Preparing playbooks 2026-04-15 17:47:27.652492 | Running Ansible setup 2026-04-15 17:47:31.360260 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-04-15 17:47:31.953369 | 2026-04-15 17:47:31.953509 | PLAY [localhost] 2026-04-15 17:47:31.961451 | 2026-04-15 17:47:31.961558 | TASK [Gathering Facts] 2026-04-15 17:47:32.831181 | localhost | ok 2026-04-15 17:47:32.844084 | 2026-04-15 17:47:32.844239 | TASK [Setup log path fact] 2026-04-15 17:47:32.867055 | localhost | ok 2026-04-15 17:47:32.881797 | 2026-04-15 17:47:32.881873 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-15 17:47:32.912762 | localhost | ok 2026-04-15 17:47:33.095851 | 2026-04-15 17:47:33.096006 | TASK [emit-job-header : Print job information] 2026-04-15 17:47:33.150356 | # Job Information 2026-04-15 17:47:33.150583 | Ansible Version: 2.16.16 2026-04-15 17:47:33.150625 | Job: ansible-collection-kubernetes-molecule-helm 2026-04-15 17:47:33.150650 | Pipeline: check 2026-04-15 17:47:33.150671 | Executor: 0a8996d2b663 2026-04-15 17:47:33.150692 | Triggered by: https://github.com/vexxhost/ansible-collection-kubernetes/pull/257 2026-04-15 17:47:33.150714 | Event ID: 09940520-38f3-11f1-8774-592ff867e9b2 2026-04-15 17:47:33.153794 | 2026-04-15 17:47:33.153880 | LOOP [emit-job-header : Print node information] 2026-04-15 17:47:33.240838 | localhost | ok: 2026-04-15 17:47:33.241085 | localhost | # Node Information 2026-04-15 17:47:33.241124 | localhost | Inventory Hostname: controller 2026-04-15 17:47:33.241247 | localhost | Hostname: np0000166479 2026-04-15 17:47:33.241276 | localhost | Username: zuul 2026-04-15 17:47:33.241300 | localhost | Distro: Debian 13.4 2026-04-15 17:47:33.241320 | localhost | Provider: yul1 2026-04-15 17:47:33.241338 | localhost | Region: ca-ymq-1 2026-04-15 17:47:33.241357 | localhost | Label: debian-trixie 2026-04-15 17:47:33.241376 | localhost | Product Name: OpenStack Nova 2026-04-15 17:47:33.241394 | localhost | Interface IP: 199.19.213.231 2026-04-15 17:47:33.263656 | 2026-04-15 17:47:33.263850 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-04-15 17:47:33.653363 | localhost -> localhost | changed 2026-04-15 17:47:33.660251 | 2026-04-15 17:47:33.660378 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-04-15 17:47:34.811540 | localhost -> localhost | changed 2026-04-15 17:47:34.820535 | 2026-04-15 17:47:34.820622 | PLAY [all] 2026-04-15 17:47:34.829639 | 2026-04-15 17:47:34.829921 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-04-15 17:47:35.067704 | controller -> localhost | ok 2026-04-15 17:47:35.077437 | 2026-04-15 17:47:35.077596 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-04-15 17:47:35.112022 | controller | ok 2026-04-15 17:47:35.127789 | controller | included: /var/lib/zuul/builds/50d7cb1cda9249f6bf80fa494b15c12e/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-04-15 17:47:35.133550 | 2026-04-15 17:47:35.133616 | TASK [add-build-sshkey : Create Temp SSH key] 2026-04-15 17:47:36.613500 | controller -> localhost | Generating public/private rsa key pair. 2026-04-15 17:47:36.613751 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/50d7cb1cda9249f6bf80fa494b15c12e/work/50d7cb1cda9249f6bf80fa494b15c12e_id_rsa 2026-04-15 17:47:36.613817 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/50d7cb1cda9249f6bf80fa494b15c12e/work/50d7cb1cda9249f6bf80fa494b15c12e_id_rsa.pub 2026-04-15 17:47:36.613868 | controller -> localhost | The key fingerprint is: 2026-04-15 17:47:36.613916 | controller -> localhost | SHA256:dX8JxsgjFF3qqzLzYtCIKC71YBN96CrWfUvbK4oIRc8 zuul-build-sshkey 2026-04-15 17:47:36.613978 | controller -> localhost | The key's randomart image is: 2026-04-15 17:47:36.614024 | controller -> localhost | +---[RSA 3072]----+ 2026-04-15 17:47:36.614071 | controller -> localhost | | oo .. | 2026-04-15 17:47:36.614118 | controller -> localhost | | . ..+ | 2026-04-15 17:47:36.614163 | controller -> localhost | | .. . ..=.+ | 2026-04-15 17:47:36.614207 | controller -> localhost | | ..oo . .o.o.. .| 2026-04-15 17:47:36.614251 | controller -> localhost | | ooE.o S . ...| 2026-04-15 17:47:36.614293 | controller -> localhost | |.o=..o . . . | 2026-04-15 17:47:36.614336 | controller -> localhost | |+o.=. .. . | 2026-04-15 17:47:36.614378 | controller -> localhost | |+oo.o..Oo . | 2026-04-15 17:47:36.614426 | controller -> localhost | |oo . .+oO=. | 2026-04-15 17:47:36.614469 | controller -> localhost | +----[SHA256]-----+ 2026-04-15 17:47:36.614560 | controller -> localhost | ok: Runtime: 0:00:01.030623 2026-04-15 17:47:36.620063 | 2026-04-15 17:47:36.620129 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-04-15 17:47:36.650719 | controller | ok 2026-04-15 17:47:36.662474 | controller | included: /var/lib/zuul/builds/50d7cb1cda9249f6bf80fa494b15c12e/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-04-15 17:47:36.671966 | 2026-04-15 17:47:36.672146 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-04-15 17:47:36.688397 | controller | skipping: Conditional result was False 2026-04-15 17:47:36.696002 | 2026-04-15 17:47:36.696188 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-04-15 17:47:37.364724 | controller | changed 2026-04-15 17:47:37.370579 | 2026-04-15 17:47:37.370710 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-04-15 17:47:37.573375 | controller | ok 2026-04-15 17:47:37.579202 | 2026-04-15 17:47:37.579295 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-04-15 17:47:38.145501 | controller | changed 2026-04-15 17:47:38.150935 | 2026-04-15 17:47:38.151029 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-04-15 17:47:38.676719 | controller | changed 2026-04-15 17:47:38.682211 | 2026-04-15 17:47:38.682277 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-04-15 17:47:38.705976 | controller | skipping: Conditional result was False 2026-04-15 17:47:38.714736 | 2026-04-15 17:47:38.714903 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-04-15 17:47:39.076153 | controller -> localhost | changed 2026-04-15 17:47:39.090892 | 2026-04-15 17:47:39.090992 | TASK [add-build-sshkey : Add back temp key] 2026-04-15 17:47:39.420634 | controller -> localhost | Identity added: /var/lib/zuul/builds/50d7cb1cda9249f6bf80fa494b15c12e/work/50d7cb1cda9249f6bf80fa494b15c12e_id_rsa (zuul-build-sshkey) 2026-04-15 17:47:39.420845 | controller -> localhost | ok: Runtime: 0:00:00.015347 2026-04-15 17:47:39.426999 | 2026-04-15 17:47:39.427091 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-04-15 17:47:39.793508 | controller | ok 2026-04-15 17:47:39.798226 | 2026-04-15 17:47:39.798310 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-04-15 17:47:39.822381 | controller | skipping: Conditional result was False 2026-04-15 17:47:39.836807 | 2026-04-15 17:47:39.837162 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-04-15 17:47:40.211111 | controller | ok 2026-04-15 17:47:40.216101 | 2026-04-15 17:47:40.216176 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-04-15 17:47:41.257138 | controller | Output suppressed because no_log was given 2026-04-15 17:47:41.267004 | 2026-04-15 17:47:41.267075 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-04-15 17:47:41.483002 | controller | ok: "logs" 2026-04-15 17:47:41.483381 | controller | ok: All items complete 2026-04-15 17:47:41.483443 | 2026-04-15 17:47:41.673310 | controller | ok: "artifacts" 2026-04-15 17:47:41.890779 | controller | ok: "docs" 2026-04-15 17:47:41.902305 | 2026-04-15 17:47:41.902452 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-04-15 17:47:42.116501 | controller | changed: "logs" 2026-04-15 17:47:42.302091 | controller | changed: "artifacts" 2026-04-15 17:47:42.493846 | controller | changed: "docs" 2026-04-15 17:47:42.512521 | 2026-04-15 17:47:42.512637 | PLAY RECAP 2026-04-15 17:47:42.512715 | controller | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-04-15 17:47:42.512759 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-15 17:47:42.512787 | 2026-04-15 17:47:42.677885 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-04-15 17:47:42.680922 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-04-15 17:47:43.279732 | 2026-04-15 17:47:43.279881 | PLAY [all] 2026-04-15 17:47:43.296654 | 2026-04-15 17:47:43.296792 | TASK [setup-uv : Extract archive] 2026-04-15 17:47:45.713721 | controller | changed 2026-04-15 17:47:45.722071 | 2026-04-15 17:47:45.722230 | TASK [setup-uv : Print version] 2026-04-15 17:47:45.933097 | controller | uv 0.8.13 2026-04-15 17:47:46.906968 | controller | ok: Runtime: 0:00:00.017061 2026-04-15 17:47:46.916042 | 2026-04-15 17:47:46.916093 | PLAY RECAP 2026-04-15 17:47:46.916142 | controller | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-15 17:47:46.916166 | 2026-04-15 17:47:47.148547 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-04-15 17:47:47.151252 | PRE-RUN START: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/pre.yml@main] 2026-04-15 17:47:47.786190 | 2026-04-15 17:47:47.786301 | PLAY [all] 2026-04-15 17:47:47.796678 | 2026-04-15 17:47:47.796782 | TASK [Update apt cache] 2026-04-15 17:47:56.502905 | controller | ok 2026-04-15 17:47:56.507836 | 2026-04-15 17:47:56.507909 | TASK [Disable firewalld] 2026-04-15 17:47:56.532451 | controller | skipping: Conditional result was False 2026-04-15 17:47:56.536565 | 2026-04-15 17:47:56.536632 | TASK [Install "jq" for log collection] 2026-04-15 17:47:58.715717 | controller | changed 2026-04-15 17:47:59.237673 | 2026-04-15 17:47:59.237804 | PLAY RECAP 2026-04-15 17:47:59.237896 | controller | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-04-15 17:47:59.237945 | 2026-04-15 17:47:59.356225 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/pre.yml@main] 2026-04-15 17:47:59.358739 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-04-15 17:47:59.918330 | 2026-04-15 17:47:59.918444 | PLAY [all] 2026-04-15 17:47:59.927864 | 2026-04-15 17:47:59.927935 | TASK [Copy inventory file for Zuul] 2026-04-15 17:48:00.924315 | controller | changed 2026-04-15 17:48:00.932742 | 2026-04-15 17:48:00.932820 | TASK [Switch "ansible_host" to private IP] 2026-04-15 17:48:01.224532 | controller | changed: 1 replacements made 2026-04-15 17:48:01.231967 | 2026-04-15 17:48:01.232036 | TASK [Run Molecule scenario] 2026-04-15 17:48:01.618958 | controller | Using CPython 3.13.5 interpreter at: /usr/bin/python3 2026-04-15 17:48:01.619057 | controller | Creating virtual environment at: .venv 2026-04-15 17:48:01.896727 | controller | Downloading cryptography (4.3MiB) 2026-04-15 17:48:01.896831 | controller | Downloading ansible-core (2.3MiB) 2026-04-15 17:48:01.909230 | controller | Downloading pygments (1.2MiB) 2026-04-15 17:48:02.049108 | controller | Downloading cryptography 2026-04-15 17:48:02.109178 | controller | Downloading pygments 2026-04-15 17:48:02.290466 | controller | Downloading ansible-core 2026-04-15 17:48:02.337793 | controller | Installed 36 packages in 46ms 2026-04-15 17:48:03.170841 | controller | INFO Collection 'vexxhost.kubernetes' detected. 2026-04-15 17:48:03.170893 | controller | INFO Scenarios will be used from 'extensions/molecule' 2026-04-15 17:48:04.108430 | controller | CRITICAL 'extensions/molecule/default/molecule.yml' glob failed. Exiting. 2026-04-15 17:48:04.108571 | controller | INFO default scenario not found, disabling shared state. 2026-04-15 17:48:04.108853 | controller | INFO [helm > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy 2026-04-15 17:48:04.108975 | controller | INFO [helm > prerun] Performing prerun with role_name_check=0... 2026-04-15 17:48:26.106811 | controller | INFO [helm > dependency] Executing 2026-04-15 17:48:26.107307 | controller | WARNING [helm > dependency] Missing roles requirements file: requirements.yml 2026-04-15 17:48:26.107780 | controller | WARNING [helm > dependency] Missing collections requirements file: collections.yml 2026-04-15 17:48:26.108006 | controller | WARNING [helm > dependency] Executed: 2 missing (Remove from test_sequence to suppress) 2026-04-15 17:48:26.114729 | controller | INFO [helm > cleanup] Executing 2026-04-15 17:48:26.115110 | controller | WARNING [helm > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-04-15 17:48:26.121640 | controller | INFO [helm > destroy] Executing 2026-04-15 17:48:26.121757 | controller | WARNING [helm > destroy] Skipping, '--destroy=never' requested. 2026-04-15 17:48:26.121851 | controller | INFO [helm > destroy] Executed: Successful 2026-04-15 17:48:26.131106 | controller | INFO [helm > syntax] Executing 2026-04-15 17:48:27.084299 | controller | 2026-04-15 17:48:27.084507 | controller | playbook: /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/extensions/molecule/helm/converge.yml 2026-04-15 17:48:27.149682 | controller | INFO [helm > syntax] Executed: Successful 2026-04-15 17:48:27.161736 | controller | INFO [helm > create] Executing 2026-04-15 17:48:27.163211 | controller | WARNING [helm > create] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-04-15 17:48:27.168598 | controller | INFO [helm > prepare] Executing 2026-04-15 17:48:28.136148 | controller | 2026-04-15 17:48:28.136646 | controller | PLAY [Prepare] ***************************************************************** 2026-04-15 17:48:28.136824 | controller | 2026-04-15 17:48:28.136952 | controller | TASK [Gathering Facts] ********************************************************* 2026-04-15 17:48:28.137032 | controller | Wednesday 15 April 2026 17:48:28 +0000 (0:00:00.032) 0:00:00.032 ******* 2026-04-15 17:48:29.395938 | 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-15 17:48:29.403931 | controller | ok: [controller] 2026-04-15 17:48:29.404098 | controller | 2026-04-15 17:48:29.404341 | controller | TASK [Install Debian packages] ************************************************* 2026-04-15 17:48:29.404542 | controller | Wednesday 15 April 2026 17:48:29 +0000 (0:00:01.265) 0:00:01.298 ******* 2026-04-15 17:48:31.034054 | controller | ok: [controller] 2026-04-15 17:48:31.034189 | controller | 2026-04-15 17:48:31.034404 | controller | PLAY RECAP ********************************************************************* 2026-04-15 17:48:31.034604 | controller | controller : ok=2 changed=0 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-04-15 17:48:31.034778 | controller | 2026-04-15 17:48:31.034957 | controller | 2026-04-15 17:48:31.035133 | controller | TASKS RECAP ******************************************************************** 2026-04-15 17:48:31.035327 | controller | Wednesday 15 April 2026 17:48:31 +0000 (0:00:01.633) 0:00:02.931 ******* 2026-04-15 17:48:31.035506 | controller | =============================================================================== 2026-04-15 17:48:31.035695 | controller | Install Debian packages ------------------------------------------------- 1.63s 2026-04-15 17:48:31.035888 | controller | Gathering Facts --------------------------------------------------------- 1.27s 2026-04-15 17:48:31.169124 | controller | INFO [helm > prepare] Executed: Successful 2026-04-15 17:48:31.179664 | controller | INFO [helm > converge] Executing 2026-04-15 17:48:32.071231 | controller | 2026-04-15 17:48:32.071319 | controller | PLAY [Converge] **************************************************************** 2026-04-15 17:48:32.071492 | controller | 2026-04-15 17:48:32.071705 | controller | TASK [Gathering Facts] ********************************************************* 2026-04-15 17:48:32.071905 | controller | Wednesday 15 April 2026 17:48:32 +0000 (0:00:00.014) 0:00:00.014 ******* 2026-04-15 17:48:33.169894 | 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-15 17:48:33.176205 | controller | ok: [controller] 2026-04-15 17:48:33.176410 | controller | 2026-04-15 17:48:33.176623 | controller | TASK [vexxhost.containers.forget_package : Forget package] ********************* 2026-04-15 17:48:33.176818 | controller | Wednesday 15 April 2026 17:48:33 +0000 (0:00:01.104) 0:00:01.119 ******* 2026-04-15 17:48:33.682232 | controller | ok: [controller] 2026-04-15 17:48:33.682305 | controller | 2026-04-15 17:48:33.682415 | controller | TASK [vexxhost.containers.package : Update state for tar] ********************** 2026-04-15 17:48:33.682534 | controller | Wednesday 15 April 2026 17:48:33 +0000 (0:00:00.505) 0:00:01.625 ******* 2026-04-15 17:48:34.923462 | controller | ok: [controller] 2026-04-15 17:48:34.923492 | controller | 2026-04-15 17:48:34.923496 | controller | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] *** 2026-04-15 17:48:34.923501 | controller | Wednesday 15 April 2026 17:48:34 +0000 (0:00:01.239) 0:00:02.864 ******* 2026-04-15 17:48:35.370703 | controller | changed: [controller] 2026-04-15 17:48:35.370913 | controller | 2026-04-15 17:48:35.371157 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-04-15 17:48:35.371386 | controller | Wednesday 15 April 2026 17:48:35 +0000 (0:00:00.449) 0:00:03.313 ******* 2026-04-15 17:48:35.413804 | controller | ok: [controller] => { 2026-04-15 17:48:35.414040 | controller | "msg": "https://get.helm.sh/helm-v3.11.2-linux-amd64.tar.gz" 2026-04-15 17:48:35.414241 | controller | } 2026-04-15 17:48:35.414422 | controller | 2026-04-15 17:48:35.414632 | controller | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-04-15 17:48:35.414874 | controller | Wednesday 15 April 2026 17:48:35 +0000 (0:00:00.043) 0:00:03.357 ******* 2026-04-15 17:48:36.420864 | 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-15 17:48:36.426801 | controller | changed: [controller] 2026-04-15 17:48:36.426875 | controller | 2026-04-15 17:48:36.426998 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-04-15 17:48:36.427065 | controller | Wednesday 15 April 2026 17:48:36 +0000 (0:00:01.013) 0:00:04.370 ******* 2026-04-15 17:48:38.488260 | controller | changed: [controller] 2026-04-15 17:48:38.488369 | controller | 2026-04-15 17:48:38.488599 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm repository] *********************** 2026-04-15 17:48:38.488765 | controller | Wednesday 15 April 2026 17:48:38 +0000 (0:00:02.059) 0:00:06.430 ******* 2026-04-15 17:48:39.039257 | controller | ok: [controller] 2026-04-15 17:48:39.039315 | controller | 2026-04-15 17:48:39.039449 | controller | TASK [vexxhost.kubernetes.helm : Add bash autocomplete for helm] *************** 2026-04-15 17:48:39.039575 | controller | Wednesday 15 April 2026 17:48:39 +0000 (0:00:00.551) 0:00:06.982 ******* 2026-04-15 17:48:39.470874 | controller | changed: [controller] 2026-04-15 17:48:39.470999 | controller | 2026-04-15 17:48:39.471198 | controller | TASK [vexxhost.kubernetes.helm : Get Helm plugins dir] ************************* 2026-04-15 17:48:39.471391 | controller | Wednesday 15 April 2026 17:48:39 +0000 (0:00:00.431) 0:00:07.414 ******* 2026-04-15 17:48:39.981295 | controller | ok: [controller] 2026-04-15 17:48:39.981454 | controller | 2026-04-15 17:48:39.981675 | controller | TASK [vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist] *** 2026-04-15 17:48:39.981898 | controller | Wednesday 15 April 2026 17:48:39 +0000 (0:00:00.510) 0:00:07.924 ******* 2026-04-15 17:48:40.295859 | controller | changed: [controller] 2026-04-15 17:48:40.296017 | controller | 2026-04-15 17:48:40.296286 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin] *** 2026-04-15 17:48:40.296481 | controller | Wednesday 15 April 2026 17:48:40 +0000 (0:00:00.314) 0:00:08.238 ******* 2026-04-15 17:48:40.612224 | controller | ok: [controller] 2026-04-15 17:48:40.612257 | controller | 2026-04-15 17:48:40.612263 | controller | TASK [Install plugin] ********************************************************** 2026-04-15 17:48:40.612269 | controller | Wednesday 15 April 2026 17:48:40 +0000 (0:00:00.314) 0:00:08.552 ******* 2026-04-15 17:48:40.666138 | controller | included: vexxhost.containers.download_artifact for controller 2026-04-15 17:48:40.666324 | controller | 2026-04-15 17:48:40.666542 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-04-15 17:48:40.666744 | controller | Wednesday 15 April 2026 17:48:40 +0000 (0:00:00.056) 0:00:08.609 ******* 2026-04-15 17:48:40.716892 | controller | ok: [controller] => { 2026-04-15 17:48:40.717123 | controller | "msg": "https://github.com/databus23/helm-diff/releases/download/v3.8.1/helm-diff-linux-amd64.tgz" 2026-04-15 17:48:40.717315 | controller | } 2026-04-15 17:48:40.717494 | controller | 2026-04-15 17:48:40.717700 | controller | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-04-15 17:48:40.717901 | controller | Wednesday 15 April 2026 17:48:40 +0000 (0:00:00.050) 0:00:08.660 ******* 2026-04-15 17:48:42.454349 | controller | changed: [controller] 2026-04-15 17:48:42.454634 | controller | 2026-04-15 17:48:42.454902 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-04-15 17:48:42.455164 | controller | Wednesday 15 April 2026 17:48:42 +0000 (0:00:01.737) 0:00:10.397 ******* 2026-04-15 17:48:44.613766 | controller | changed: [controller] 2026-04-15 17:48:44.614048 | controller | 2026-04-15 17:48:44.614304 | controller | PLAY RECAP ********************************************************************* 2026-04-15 17:48:44.614573 | controller | controller : ok=16 changed=7 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-04-15 17:48:44.614801 | controller | 2026-04-15 17:48:44.615023 | controller | 2026-04-15 17:48:44.615264 | controller | TASKS RECAP ******************************************************************** 2026-04-15 17:48:44.615512 | controller | Wednesday 15 April 2026 17:48:44 +0000 (0:00:02.160) 0:00:12.557 ******* 2026-04-15 17:48:44.615745 | controller | =============================================================================== 2026-04-15 17:48:44.615989 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 2.16s 2026-04-15 17:48:44.616333 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 2.06s 2026-04-15 17:48:44.616583 | controller | vexxhost.containers.download_artifact : Download item ------------------- 1.74s 2026-04-15 17:48:44.616825 | controller | vexxhost.containers.package : Update state for tar ---------------------- 1.24s 2026-04-15 17:48:44.617063 | controller | Gathering Facts --------------------------------------------------------- 1.10s 2026-04-15 17:48:44.617310 | controller | vexxhost.containers.download_artifact : Download item ------------------- 1.01s 2026-04-15 17:48:44.617554 | controller | vexxhost.kubernetes.helm : Remove Helm repository ----------------------- 0.55s 2026-04-15 17:48:44.617798 | controller | vexxhost.kubernetes.helm : Get Helm plugins dir ------------------------- 0.51s 2026-04-15 17:48:44.618040 | controller | vexxhost.containers.forget_package : Forget package --------------------- 0.51s 2026-04-15 17:48:44.618286 | controller | vexxhost.containers.directory : Create directory (/var/lib/downloads) --- 0.45s 2026-04-15 17:48:44.618550 | controller | vexxhost.kubernetes.helm : Add bash autocomplete for helm --------------- 0.43s 2026-04-15 17:48:44.618804 | controller | vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist --- 0.31s 2026-04-15 17:48:44.619057 | controller | vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin --- 0.31s 2026-04-15 17:48:44.619297 | controller | Install plugin ---------------------------------------------------------- 0.06s 2026-04-15 17:48:44.619564 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.05s 2026-04-15 17:48:44.619804 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.04s 2026-04-15 17:48:44.774283 | controller | INFO [helm > converge] Executed: Successful 2026-04-15 17:48:44.783096 | controller | INFO [helm > idempotence] Executing 2026-04-15 17:48:45.689949 | controller | 2026-04-15 17:48:45.690151 | controller | PLAY [Converge] **************************************************************** 2026-04-15 17:48:45.690284 | controller | 2026-04-15 17:48:45.690421 | controller | TASK [Gathering Facts] ********************************************************* 2026-04-15 17:48:45.690559 | controller | Wednesday 15 April 2026 17:48:45 +0000 (0:00:00.014) 0:00:00.014 ******* 2026-04-15 17:48:46.808717 | 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-15 17:48:46.815861 | controller | ok: [controller] 2026-04-15 17:48:46.816268 | controller | 2026-04-15 17:48:46.816301 | controller | TASK [vexxhost.containers.forget_package : Forget package] ********************* 2026-04-15 17:48:46.816307 | controller | Wednesday 15 April 2026 17:48:46 +0000 (0:00:01.125) 0:00:01.140 ******* 2026-04-15 17:48:47.300382 | controller | ok: [controller] 2026-04-15 17:48:47.300452 | controller | 2026-04-15 17:48:47.300464 | controller | TASK [vexxhost.containers.package : Update state for tar] ********************** 2026-04-15 17:48:47.300534 | controller | Wednesday 15 April 2026 17:48:47 +0000 (0:00:00.484) 0:00:01.624 ******* 2026-04-15 17:48:48.429943 | controller | ok: [controller] 2026-04-15 17:48:48.430005 | controller | 2026-04-15 17:48:48.430015 | controller | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] *** 2026-04-15 17:48:48.430024 | controller | Wednesday 15 April 2026 17:48:48 +0000 (0:00:01.126) 0:00:02.751 ******* 2026-04-15 17:48:48.864900 | controller | ok: [controller] 2026-04-15 17:48:48.864953 | controller | 2026-04-15 17:48:48.864962 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-04-15 17:48:48.865068 | controller | Wednesday 15 April 2026 17:48:48 +0000 (0:00:00.438) 0:00:03.189 ******* 2026-04-15 17:48:48.916253 | controller | ok: [controller] => { 2026-04-15 17:48:48.916328 | controller | "msg": "https://get.helm.sh/helm-v3.11.2-linux-amd64.tar.gz" 2026-04-15 17:48:48.916877 | controller | } 2026-04-15 17:48:48.916920 | controller | 2026-04-15 17:48:48.916927 | controller | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-04-15 17:48:48.916933 | controller | Wednesday 15 April 2026 17:48:48 +0000 (0:00:00.051) 0:00:03.240 ******* 2026-04-15 17:48:49.440646 | controller | ok: [controller] 2026-04-15 17:48:49.440791 | controller | 2026-04-15 17:48:49.440916 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-04-15 17:48:49.441036 | controller | Wednesday 15 April 2026 17:48:49 +0000 (0:00:00.522) 0:00:03.763 ******* 2026-04-15 17:48:51.208020 | controller | ok: [controller] 2026-04-15 17:48:51.208052 | controller | 2026-04-15 17:48:51.208060 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm repository] *********************** 2026-04-15 17:48:51.208066 | controller | Wednesday 15 April 2026 17:48:51 +0000 (0:00:01.765) 0:00:05.528 ******* 2026-04-15 17:48:51.739020 | controller | ok: [controller] 2026-04-15 17:48:51.739088 | controller | 2026-04-15 17:48:51.739180 | controller | TASK [vexxhost.kubernetes.helm : Add bash autocomplete for helm] *************** 2026-04-15 17:48:51.739270 | controller | Wednesday 15 April 2026 17:48:51 +0000 (0:00:00.534) 0:00:06.063 ******* 2026-04-15 17:48:52.148103 | controller | ok: [controller] 2026-04-15 17:48:52.148195 | controller | 2026-04-15 17:48:52.148488 | controller | TASK [vexxhost.kubernetes.helm : Get Helm plugins dir] ************************* 2026-04-15 17:48:52.148504 | controller | Wednesday 15 April 2026 17:48:52 +0000 (0:00:00.408) 0:00:06.471 ******* 2026-04-15 17:48:52.620653 | controller | ok: [controller] 2026-04-15 17:48:52.620822 | controller | 2026-04-15 17:48:52.621000 | controller | TASK [vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist] *** 2026-04-15 17:48:52.621162 | controller | Wednesday 15 April 2026 17:48:52 +0000 (0:00:00.470) 0:00:06.942 ******* 2026-04-15 17:48:52.932609 | controller | ok: [controller] 2026-04-15 17:48:52.932687 | controller | 2026-04-15 17:48:52.932774 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin] *** 2026-04-15 17:48:52.932875 | controller | Wednesday 15 April 2026 17:48:52 +0000 (0:00:00.313) 0:00:07.255 ******* 2026-04-15 17:48:53.246296 | controller | ok: [controller] 2026-04-15 17:48:53.248204 | controller | 2026-04-15 17:48:53.248253 | controller | TASK [Install plugin] ********************************************************** 2026-04-15 17:48:53.248258 | controller | Wednesday 15 April 2026 17:48:53 +0000 (0:00:00.315) 0:00:07.570 ******* 2026-04-15 17:48:53.317127 | controller | included: vexxhost.containers.download_artifact for controller 2026-04-15 17:48:53.317162 | controller | 2026-04-15 17:48:53.317169 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-04-15 17:48:53.317175 | controller | Wednesday 15 April 2026 17:48:53 +0000 (0:00:00.069) 0:00:07.639 ******* 2026-04-15 17:48:53.373118 | controller | ok: [controller] => { 2026-04-15 17:48:53.373152 | controller | "msg": "https://github.com/databus23/helm-diff/releases/download/v3.8.1/helm-diff-linux-amd64.tgz" 2026-04-15 17:48:53.373157 | controller | } 2026-04-15 17:48:53.373163 | controller | 2026-04-15 17:48:53.373168 | controller | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-04-15 17:48:53.373173 | controller | Wednesday 15 April 2026 17:48:53 +0000 (0:00:00.053) 0:00:07.693 ******* 2026-04-15 17:48:53.801019 | controller | ok: [controller] 2026-04-15 17:48:53.801123 | controller | 2026-04-15 17:48:53.801277 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-04-15 17:48:53.801418 | controller | Wednesday 15 April 2026 17:48:53 +0000 (0:00:00.431) 0:00:08.125 ******* 2026-04-15 17:48:55.448260 | controller | ok: [controller] 2026-04-15 17:48:55.448310 | controller | 2026-04-15 17:48:55.448320 | controller | PLAY RECAP ********************************************************************* 2026-04-15 17:48:55.448330 | controller | controller : ok=16 changed=0 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-04-15 17:48:55.448338 | controller | 2026-04-15 17:48:55.448346 | controller | 2026-04-15 17:48:55.448353 | controller | TASKS RECAP ******************************************************************** 2026-04-15 17:48:55.448361 | controller | Wednesday 15 April 2026 17:48:55 +0000 (0:00:01.645) 0:00:09.770 ******* 2026-04-15 17:48:55.448368 | controller | =============================================================================== 2026-04-15 17:48:55.448375 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 1.77s 2026-04-15 17:48:55.448382 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 1.65s 2026-04-15 17:48:55.448389 | controller | vexxhost.containers.package : Update state for tar ---------------------- 1.13s 2026-04-15 17:48:55.448539 | controller | Gathering Facts --------------------------------------------------------- 1.13s 2026-04-15 17:48:55.448821 | controller | vexxhost.kubernetes.helm : Remove Helm repository ----------------------- 0.53s 2026-04-15 17:48:55.449094 | controller | vexxhost.containers.download_artifact : Download item ------------------- 0.52s 2026-04-15 17:48:55.449376 | controller | vexxhost.containers.forget_package : Forget package --------------------- 0.48s 2026-04-15 17:48:55.449628 | controller | vexxhost.kubernetes.helm : Get Helm plugins dir ------------------------- 0.47s 2026-04-15 17:48:55.449911 | controller | vexxhost.containers.directory : Create directory (/var/lib/downloads) --- 0.44s 2026-04-15 17:48:55.450167 | controller | vexxhost.containers.download_artifact : Download item ------------------- 0.43s 2026-04-15 17:48:55.450443 | controller | vexxhost.kubernetes.helm : Add bash autocomplete for helm --------------- 0.41s 2026-04-15 17:48:55.450729 | controller | vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin --- 0.32s 2026-04-15 17:48:55.451004 | controller | vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist --- 0.31s 2026-04-15 17:48:55.451254 | controller | Install plugin ---------------------------------------------------------- 0.07s 2026-04-15 17:48:55.451507 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.05s 2026-04-15 17:48:55.451774 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.05s 2026-04-15 17:48:55.563590 | controller | INFO [helm > idempotence] Executed: Successful 2026-04-15 17:48:55.574981 | controller | INFO [helm > side_effect] Executing 2026-04-15 17:48:55.575361 | controller | WARNING [helm > side_effect] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-04-15 17:48:55.580628 | controller | INFO [helm > verify] Executing 2026-04-15 17:48:56.395519 | controller | 2026-04-15 17:48:56.395855 | controller | PLAY [Verify] ****************************************************************** 2026-04-15 17:48:56.396097 | controller | 2026-04-15 17:48:56.396488 | controller | TASK [Gathering Facts] ********************************************************* 2026-04-15 17:48:56.396815 | controller | Wednesday 15 April 2026 17:48:56 +0000 (0:00:00.009) 0:00:00.009 ******* 2026-04-15 17:48:57.466934 | 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-15 17:48:57.472926 | controller | ok: [controller] 2026-04-15 17:48:57.473184 | controller | 2026-04-15 17:48:57.473447 | controller | TASK [Get the current Helm version] ******************************************** 2026-04-15 17:48:57.473724 | controller | Wednesday 15 April 2026 17:48:57 +0000 (0:00:01.078) 0:00:01.088 ******* 2026-04-15 17:48:57.936571 | controller | changed: [controller] 2026-04-15 17:48:57.936859 | controller | 2026-04-15 17:48:57.937119 | controller | TASK [Assert Helm version] ***************************************************** 2026-04-15 17:48:57.937374 | controller | Wednesday 15 April 2026 17:48:57 +0000 (0:00:00.462) 0:00:01.551 ******* 2026-04-15 17:48:57.968211 | controller | ok: [controller] => { 2026-04-15 17:48:57.968474 | controller | "changed": false, 2026-04-15 17:48:57.968739 | controller | "msg": "All assertions passed" 2026-04-15 17:48:57.968989 | controller | } 2026-04-15 17:48:57.969225 | controller | 2026-04-15 17:48:57.969477 | controller | PLAY RECAP ********************************************************************* 2026-04-15 17:48:57.969758 | controller | controller : ok=3 changed=1 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-04-15 17:48:57.970030 | controller | 2026-04-15 17:48:57.970354 | controller | 2026-04-15 17:48:57.970646 | controller | TASKS RECAP ******************************************************************** 2026-04-15 17:48:57.970916 | controller | Wednesday 15 April 2026 17:48:57 +0000 (0:00:00.033) 0:00:01.584 ******* 2026-04-15 17:48:57.971164 | controller | =============================================================================== 2026-04-15 17:48:57.971431 | controller | Gathering Facts --------------------------------------------------------- 1.08s 2026-04-15 17:48:57.971733 | controller | Get the current Helm version -------------------------------------------- 0.46s 2026-04-15 17:48:57.971998 | controller | Assert Helm version ----------------------------------------------------- 0.03s 2026-04-15 17:48:58.103860 | controller | INFO [helm > verify] Executed: Successful 2026-04-15 17:48:58.110041 | controller | INFO [helm > cleanup] Executing 2026-04-15 17:48:58.110337 | controller | WARNING [helm > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-04-15 17:48:58.115622 | controller | INFO [helm > destroy] Executing 2026-04-15 17:48:58.120372 | controller | WARNING [helm > destroy] Skipping, '--destroy=never' requested. 2026-04-15 17:48:58.120397 | controller | INFO [helm > destroy] Executed: Successful 2026-04-15 17:48:58.120429 | controller | WARNING Molecule executed 1 scenario (1 missing files) 2026-04-15 17:48:58.287579 | controller | ok: Runtime: 0:00:56.690037 2026-04-15 17:48:58.292586 | 2026-04-15 17:48:58.292657 | PLAY RECAP 2026-04-15 17:48:58.292722 | controller | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-15 17:48:58.292752 | 2026-04-15 17:48:58.404861 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-04-15 17:48:58.407561 | POST-RUN START: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/post.yml@main] 2026-04-15 17:48:59.022676 | 2026-04-15 17:48:59.022860 | PLAY [all] 2026-04-15 17:48:59.034723 | 2026-04-15 17:48:59.034839 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects] 2026-04-15 17:48:59.465188 | controller | changed 2026-04-15 17:48:59.472066 | 2026-04-15 17:48:59.472148 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects] 2026-04-15 17:48:59.850154 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-04-15 17:48:59.850780 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-04-15 17:48:59.858779 | controller | environment: line 1: kubectl: command not found 2026-04-15 17:48:59.860879 | controller | environment: line 1: kubectl: command not found 2026-04-15 17:48:59.861184 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-04-15 17:48:59.862262 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-04-15 17:48:59.867699 | controller | environment: line 1: kubectl: command not found 2026-04-15 17:48:59.867723 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-04-15 17:48:59.867732 | controller | environment: line 1: kubectl: command not found 2026-04-15 17:48:59.869388 | controller | environment: line 1: kubectl: command not found 2026-04-15 17:48:59.870098 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-04-15 17:48:59.871317 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-04-15 17:49:00.007363 | controller | ok: Runtime: 0:00:00.033118 2026-04-15 17:49:00.014627 | 2026-04-15 17:49:00.014700 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects] 2026-04-15 17:49:00.242416 | controller | changed 2026-04-15 17:49:00.247570 | 2026-04-15 17:49:00.247664 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects] 2026-04-15 17:49:00.508361 | controller | environment: line 5: kubectl: command not found 2026-04-15 17:49:00.508445 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-04-15 17:49:00.508519 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-04-15 17:49:00.509492 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-04-15 17:49:00.787057 | controller | ok: Runtime: 0:00:00.014830 2026-04-15 17:49:00.794290 | 2026-04-15 17:49:00.794398 | TASK [describe-kubernetes-objects : Downloads logs to executor] 2026-04-15 17:49:01.497412 | controller | changed: 2026-04-15 17:49:01.497676 | controller | created directory /var/lib/zuul/builds/50d7cb1cda9249f6bf80fa494b15c12e/work/logs/controller 2026-04-15 17:49:01.497742 | controller | cd+++++++++ objects/ 2026-04-15 17:49:01.497791 | controller | cd+++++++++ objects/cluster/ 2026-04-15 17:49:01.497841 | controller | cd+++++++++ objects/namespaced/ 2026-04-15 17:49:01.506274 | 2026-04-15 17:49:01.506342 | TASK [gather-pod-logs : creating directory for pod logs] 2026-04-15 17:49:01.745790 | controller | changed 2026-04-15 17:49:01.750462 | 2026-04-15 17:49:01.750533 | TASK [gather-pod-logs : creating directory for failed pod logs] 2026-04-15 17:49:02.002429 | controller | changed 2026-04-15 17:49:02.009630 | 2026-04-15 17:49:02.009821 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)] 2026-04-15 17:49:02.263234 | controller | environment: line 3: kubectl: command not found 2026-04-15 17:49:02.553587 | controller | ok: Runtime: 0:00:00.015097 2026-04-15 17:49:02.558854 | 2026-04-15 17:49:02.558924 | TASK [gather-pod-logs : Downloads pod logs to executor] 2026-04-15 17:49:03.111319 | controller | changed: 2026-04-15 17:49:03.111525 | controller | cd+++++++++ pod-logs/ 2026-04-15 17:49:03.111553 | controller | cd+++++++++ pod-logs/failed-pods/ 2026-04-15 17:49:03.118530 | 2026-04-15 17:49:03.118595 | LOOP [helm-release-status : creating directory for helm release status] 2026-04-15 17:49:03.356668 | controller | changed: "values" 2026-04-15 17:49:03.550679 | controller | changed: "releases" 2026-04-15 17:49:03.561546 | 2026-04-15 17:49:03.562331 | TASK [helm-release-status : Gather get release status for helm charts] 2026-04-15 17:49:03.793672 | controller | /bin/bash: line 3: kubectl: command not found 2026-04-15 17:49:04.090809 | controller | ok: Runtime: 0:00:00.007668 2026-04-15 17:49:04.096281 | 2026-04-15 17:49:04.096367 | TASK [helm-release-status : Downloads logs to executor] 2026-04-15 17:49:04.766425 | controller | changed: 2026-04-15 17:49:04.766694 | controller | cd+++++++++ helm/ 2026-04-15 17:49:04.766760 | controller | cd+++++++++ helm/releases/ 2026-04-15 17:49:04.766818 | controller | cd+++++++++ helm/values/ 2026-04-15 17:49:04.772718 | 2026-04-15 17:49:04.772763 | PLAY RECAP 2026-04-15 17:49:04.772805 | controller | ok: 12 changed: 12 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-15 17:49:04.772827 | 2026-04-15 17:49:04.880877 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/post.yml@main] 2026-04-15 17:49:04.882863 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-04-15 17:49:05.490016 | 2026-04-15 17:49:05.490152 | PLAY [all] 2026-04-15 17:49:05.499989 | 2026-04-15 17:49:05.500064 | TASK [fetch-output : Set log path for multiple nodes] 2026-04-15 17:49:05.555643 | controller | skipping: Conditional result was False 2026-04-15 17:49:05.566353 | 2026-04-15 17:49:05.566454 | TASK [fetch-output : Set log path for single node] 2026-04-15 17:49:05.608079 | controller | ok 2026-04-15 17:49:05.613018 | 2026-04-15 17:49:05.613142 | LOOP [fetch-output : Ensure local output dirs] 2026-04-15 17:49:06.676939 | controller -> localhost | ok: "/var/lib/zuul/builds/50d7cb1cda9249f6bf80fa494b15c12e/work/logs" 2026-04-15 17:49:06.884238 | controller -> localhost | changed: "/var/lib/zuul/builds/50d7cb1cda9249f6bf80fa494b15c12e/work/artifacts" 2026-04-15 17:49:07.089333 | controller -> localhost | changed: "/var/lib/zuul/builds/50d7cb1cda9249f6bf80fa494b15c12e/work/docs" 2026-04-15 17:49:07.105165 | 2026-04-15 17:49:07.105366 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-04-15 17:49:07.760928 | controller | changed: .d..t...... ./ 2026-04-15 17:49:07.761343 | controller | changed: All items complete 2026-04-15 17:49:07.761407 | 2026-04-15 17:49:08.293722 | controller | changed: .d..t...... ./ 2026-04-15 17:49:08.772881 | controller | changed: .d..t...... ./ 2026-04-15 17:49:08.796558 | 2026-04-15 17:49:08.796717 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-04-15 17:49:09.249146 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.008508 2026-04-15 17:49:09.482650 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.009081 2026-04-15 17:49:09.501327 | 2026-04-15 17:49:09.501486 | PLAY [all] 2026-04-15 17:49:09.508837 | 2026-04-15 17:49:09.508904 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-04-15 17:49:09.982832 | controller | changed 2026-04-15 17:49:09.989249 | 2026-04-15 17:49:09.989327 | PLAY RECAP 2026-04-15 17:49:09.989385 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-04-15 17:49:09.989416 | 2026-04-15 17:49:10.124291 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-04-15 17:49:10.126309 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-04-15 17:49:10.806962 | 2026-04-15 17:49:10.807110 | PLAY [localhost] 2026-04-15 17:49:10.817610 | 2026-04-15 17:49:10.817684 | TASK [Generate Zuul manifest] 2026-04-15 17:49:10.837005 | localhost | ok 2026-04-15 17:49:10.854189 | 2026-04-15 17:49:10.854275 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-04-15 17:49:11.172459 | localhost | changed 2026-04-15 17:49:11.183088 | 2026-04-15 17:49:11.183182 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-04-15 17:49:11.214514 | localhost | ok 2026-04-15 17:49:11.222847 | 2026-04-15 17:49:11.222912 | TASK [Upload logs] 2026-04-15 17:49:11.242999 | localhost | ok 2026-04-15 17:49:11.302379 | 2026-04-15 17:49:11.302508 | TASK [Set zuul-log-path fact] 2026-04-15 17:49:11.324323 | localhost | ok 2026-04-15 17:49:11.337751 | 2026-04-15 17:49:11.337839 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-15 17:49:11.367703 | localhost | ok 2026-04-15 17:49:11.376197 | 2026-04-15 17:49:11.376262 | TASK [upload-logs : Create log directories] 2026-04-15 17:49:11.772489 | localhost | changed 2026-04-15 17:49:11.778760 | 2026-04-15 17:49:11.778823 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-04-15 17:49:12.163273 | localhost -> localhost | ok: Runtime: 0:00:00.005648 2026-04-15 17:49:12.169179 | 2026-04-15 17:49:12.169255 | TASK [upload-logs : Upload logs to log server] 2026-04-15 17:49:12.648114 | localhost | Output suppressed because no_log was given 2026-04-15 17:49:12.653059 | 2026-04-15 17:49:12.653181 | LOOP [upload-logs : Compress console log and json output] 2026-04-15 17:49:12.701215 | localhost | skipping: Conditional result was False 2026-04-15 17:49:12.708880 | localhost | skipping: Conditional result was False 2026-04-15 17:49:12.719472 | 2026-04-15 17:49:12.719624 | LOOP [upload-logs : Upload compressed console log and json output] 2026-04-15 17:49:12.765505 | localhost | skipping: Conditional result was False 2026-04-15 17:49:12.765925 | 2026-04-15 17:49:12.769904 | localhost | skipping: Conditional result was False 2026-04-15 17:49:12.787142 | 2026-04-15 17:49:12.787272 | LOOP [upload-logs : Upload console log and json output]