2026-06-17 18:47:17.997428 | Job console starting
2026-06-17 18:47:18.013043 | Updating git repos
2026-06-17 18:47:18.064142 | Cloning repos into workspace
2026-06-17 18:47:18.127414 | Restoring repo states
2026-06-17 18:47:18.179695 | Merging changes
2026-06-17 18:47:18.962537 | Checking out repos
2026-06-17 18:47:19.218468 | Preparing playbooks
2026-06-17 18:47:23.232472 | Running Ansible setup
2026-06-17 18:47:27.266075 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-06-17 18:47:27.932864 | 
2026-06-17 18:47:27.933142 | PLAY [localhost]
2026-06-17 18:47:27.942928 | 
2026-06-17 18:47:27.943079 | TASK [Gathering Facts]
2026-06-17 18:47:29.264276 | localhost | ok
2026-06-17 18:47:29.272332 | 
2026-06-17 18:47:29.272414 | TASK [Setup log path fact]
2026-06-17 18:47:29.292877 | localhost | ok
2026-06-17 18:47:29.309366 | 
2026-06-17 18:47:29.309463 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-06-17 18:47:29.350950 | localhost | ok
2026-06-17 18:47:29.358383 | 
2026-06-17 18:47:29.358463 | TASK [emit-job-header : Print job information]
2026-06-17 18:47:29.411018 | # Job Information
2026-06-17 18:47:29.411250 | Ansible Version: 2.16.18
2026-06-17 18:47:29.411324 | Job: ansible-collection-kubernetes-molecule-cert-manager-v1.15.5
2026-06-17 18:47:29.411376 | Pipeline: check
2026-06-17 18:47:29.411421 | Executor: 2d72f0692154
2026-06-17 18:47:29.411467 | Triggered by: https://github.com/vexxhost/ansible-collection-kubernetes/pull/284
2026-06-17 18:47:29.411520 | Event ID: d07fc740-6a7c-11f1-8b15-c170421f36dc
2026-06-17 18:47:29.414968 | 
2026-06-17 18:47:29.415042 | LOOP [emit-job-header : Print node information]
2026-06-17 18:47:29.499467 | localhost | ok:
2026-06-17 18:47:29.499653 | localhost | # Node Information
2026-06-17 18:47:29.499682 | localhost | Inventory Hostname: controller
2026-06-17 18:47:29.499704 | localhost | Hostname: np0000181284
2026-06-17 18:47:29.499724 | localhost | Username: zuul
2026-06-17 18:47:29.499747 | localhost | Distro: Debian 13.5
2026-06-17 18:47:29.499767 | localhost | Provider: yul1
2026-06-17 18:47:29.499787 | localhost | Region: ca-ymq-1
2026-06-17 18:47:29.499805 | localhost | Label: debian-trixie
2026-06-17 18:47:29.499824 | localhost | Product Name: OpenStack Nova
2026-06-17 18:47:29.499842 | localhost | Interface IP: 199.204.45.248
2026-06-17 18:47:29.513753 | 
2026-06-17 18:47:29.513924 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-06-17 18:47:30.121089 | localhost -> localhost | changed
2026-06-17 18:47:30.129811 | 
2026-06-17 18:47:30.129888 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-06-17 18:47:31.096334 | localhost -> localhost | changed
2026-06-17 18:47:31.105124 | 
2026-06-17 18:47:31.105196 | PLAY [all]
2026-06-17 18:47:31.112159 | 
2026-06-17 18:47:31.112243 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-06-17 18:47:31.381936 | controller -> localhost | ok
2026-06-17 18:47:31.388845 | 
2026-06-17 18:47:31.388955 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-06-17 18:47:31.422075 | controller | ok
2026-06-17 18:47:31.443500 | controller | included: /var/lib/zuul/builds/4c01542c482b4ef993f0b7dad8ce9ef1/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-06-17 18:47:31.448950 | 
2026-06-17 18:47:31.449296 | TASK [add-build-sshkey : Create Temp SSH key]
2026-06-17 18:47:32.646587 | controller -> localhost | Generating public/private rsa key pair.
2026-06-17 18:47:32.646814 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/4c01542c482b4ef993f0b7dad8ce9ef1/work/4c01542c482b4ef993f0b7dad8ce9ef1_id_rsa
2026-06-17 18:47:32.646858 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/4c01542c482b4ef993f0b7dad8ce9ef1/work/4c01542c482b4ef993f0b7dad8ce9ef1_id_rsa.pub
2026-06-17 18:47:32.646895 | controller -> localhost | The key fingerprint is:
2026-06-17 18:47:32.646927 | controller -> localhost | SHA256:qrWuwrjQ7bisY+NZjJ4GRwPek84octkbwWQLOsnH2WU zuul-build-sshkey
2026-06-17 18:47:32.646983 | controller -> localhost | The key's randomart image is:
2026-06-17 18:47:32.647019 | controller -> localhost | +---[RSA 3072]----+
2026-06-17 18:47:32.647059 | controller -> localhost | |                 |
2026-06-17 18:47:32.647096 | controller -> localhost | |. . o  E         |
2026-06-17 18:47:32.647129 | controller -> localhost | |o+o=+.o          |
2026-06-17 18:47:32.647161 | controller -> localhost | |+ooB+.           |
2026-06-17 18:47:32.647192 | controller -> localhost | | o=+..  S        |
2026-06-17 18:47:32.647224 | controller -> localhost | |+o*+o  .         |
2026-06-17 18:47:32.647253 | controller -> localhost | |=*.o.oo          |
2026-06-17 18:47:32.647282 | controller -> localhost | |==*o.o .         |
2026-06-17 18:47:32.647313 | controller -> localhost | |*O=o+oo          |
2026-06-17 18:47:32.647342 | controller -> localhost | +----[SHA256]-----+
2026-06-17 18:47:32.647414 | controller -> localhost | ok: Runtime: 0:00:00.702148
2026-06-17 18:47:32.653706 | 
2026-06-17 18:47:32.653794 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-06-17 18:47:32.685965 | controller | ok
2026-06-17 18:47:32.695986 | controller | included: /var/lib/zuul/builds/4c01542c482b4ef993f0b7dad8ce9ef1/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-06-17 18:47:32.702890 | 
2026-06-17 18:47:32.702966 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-06-17 18:47:32.727073 | controller | skipping: Conditional result was False
2026-06-17 18:47:32.735484 | 
2026-06-17 18:47:32.735598 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-06-17 18:47:33.295564 | controller | changed
2026-06-17 18:47:33.303406 | 
2026-06-17 18:47:33.303509 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-06-17 18:47:33.509970 | controller | ok
2026-06-17 18:47:33.516504 | 
2026-06-17 18:47:33.516610 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-06-17 18:47:34.037897 | controller | changed
2026-06-17 18:47:34.042118 | 
2026-06-17 18:47:34.042191 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-06-17 18:47:34.545012 | controller | changed
2026-06-17 18:47:34.549015 | 
2026-06-17 18:47:34.549085 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-06-17 18:47:34.573124 | controller | skipping: Conditional result was False
2026-06-17 18:47:34.577826 | 
2026-06-17 18:47:34.577897 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-06-17 18:47:34.956808 | controller -> localhost | changed
2026-06-17 18:47:34.966312 | 
2026-06-17 18:47:34.966405 | TASK [add-build-sshkey : Add back temp key]
2026-06-17 18:47:35.235087 | controller -> localhost | Identity added: /var/lib/zuul/builds/4c01542c482b4ef993f0b7dad8ce9ef1/work/4c01542c482b4ef993f0b7dad8ce9ef1_id_rsa (zuul-build-sshkey)
2026-06-17 18:47:35.235390 | controller -> localhost | ok: Runtime: 0:00:00.012074
2026-06-17 18:47:35.241263 | 
2026-06-17 18:47:35.241329 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-06-17 18:47:35.594799 | controller | ok
2026-06-17 18:47:35.598715 | 
2026-06-17 18:47:35.598780 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-06-17 18:47:35.623253 | controller | skipping: Conditional result was False
2026-06-17 18:47:35.632772 | 
2026-06-17 18:47:35.632838 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-06-17 18:47:35.994878 | controller | ok
2026-06-17 18:47:35.999527 | 
2026-06-17 18:47:35.999591 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-06-17 18:47:37.011151 | controller | Output suppressed because no_log was given
2026-06-17 18:47:37.018434 | 
2026-06-17 18:47:37.018502 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-06-17 18:47:37.227487 | controller | ok: "logs"
2026-06-17 18:47:37.227882 | controller | ok: All items complete
2026-06-17 18:47:37.227932 | 
2026-06-17 18:47:37.422090 | controller | ok: "artifacts"
2026-06-17 18:47:37.608281 | controller | ok: "docs"
2026-06-17 18:47:37.814647 | 
2026-06-17 18:47:37.814827 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-06-17 18:47:38.023790 | controller | changed: "logs"
2026-06-17 18:47:38.210095 | controller | changed: "artifacts"
2026-06-17 18:47:38.389888 | controller | changed: "docs"
2026-06-17 18:47:38.404631 | 
2026-06-17 18:47:38.404733 | PLAY RECAP
2026-06-17 18:47:38.404783 | controller | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-06-17 18:47:38.404816 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-06-17 18:47:38.404840 | 
2026-06-17 18:47:38.576277 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-06-17 18:47:38.577738 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-06-17 18:47:39.235518 | 
2026-06-17 18:47:39.235694 | PLAY [all]
2026-06-17 18:47:39.246645 | 
2026-06-17 18:47:39.246799 | TASK [setup-uv : Extract archive]
2026-06-17 18:47:41.658034 | controller | changed
2026-06-17 18:47:41.663226 | 
2026-06-17 18:47:41.663364 | TASK [setup-uv : Print version]
2026-06-17 18:47:41.938664 | controller | uv 0.8.13
2026-06-17 18:47:42.187467 | controller | ok: Runtime: 0:00:00.015115
2026-06-17 18:47:42.195379 | 
2026-06-17 18:47:42.195443 | TASK [Install Ansible collection siblings]
2026-06-17 18:47:42.603067 | controller | ok:
2026-06-17 18:47:42.603326 | controller | Skipping ansible.posix: no checked-out sibling
2026-06-17 18:47:42.603389 | controller | Skipping community.crypto: no checked-out sibling
2026-06-17 18:47:42.603436 | controller | Skipping community.general: no checked-out sibling
2026-06-17 18:47:42.603480 | controller | Skipping kubernetes.core: no checked-out sibling
2026-06-17 18:47:42.603523 | controller | Skipping vexxhost.containers: no checked-out sibling
2026-06-17 18:47:42.606441 | 
2026-06-17 18:47:42.606487 | PLAY RECAP
2026-06-17 18:47:42.606531 | controller | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-06-17 18:47:42.606553 | 
2026-06-17 18:47:42.742068 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-06-17 18:47:42.743964 | PRE-RUN START: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/pre.yml@main]
2026-06-17 18:47:43.399004 | 
2026-06-17 18:47:43.399198 | PLAY [all]
2026-06-17 18:47:43.416508 | 
2026-06-17 18:47:43.416703 | TASK [Update apt cache]
2026-06-17 18:47:48.155531 | controller | ok
2026-06-17 18:47:48.161264 | 
2026-06-17 18:47:48.161510 | TASK [Disable firewalld]
2026-06-17 18:47:48.187308 | controller | skipping: Conditional result was False
2026-06-17 18:47:48.196479 | 
2026-06-17 18:47:48.196624 | TASK [Install "jq" for log collection]
2026-06-17 18:47:50.977614 | controller | changed
2026-06-17 18:47:50.979276 | 
2026-06-17 18:47:50.979324 | PLAY RECAP
2026-06-17 18:47:50.979364 | controller | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-06-17 18:47:50.979386 | 
2026-06-17 18:47:51.099480 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/pre.yml@main]
2026-06-17 18:47:51.102630 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-06-17 18:47:51.711765 | 
2026-06-17 18:47:51.711912 | PLAY [all]
2026-06-17 18:47:51.722740 | 
2026-06-17 18:47:51.722846 | LOOP [Check inventory directories]
2026-06-17 18:47:52.116318 | controller | ok: "src/github.com/vexxhost/ansible-collection-kubernetes"
2026-06-17 18:47:52.341742 | controller | ok: "src/github.com/vexxhost/ansible-collection-kubernetes/molecule/cert-manager/inventory"
2026-06-17 18:47:52.531005 | controller | ok: "src/github.com/vexxhost/ansible-collection-kubernetes/extensions/molecule/cert-manager/inventory"
2026-06-17 18:47:52.543805 | 
2026-06-17 18:47:52.544032 | LOOP [Copy inventory file for Zuul]
2026-06-17 18:47:53.425316 | controller | changed:
2026-06-17 18:47:53.425495 | controller | {
2026-06-17 18:47:53.425525 | controller |   "ansible_loop_var": "item",
2026-06-17 18:47:53.425549 | controller |   "changed": false,
2026-06-17 18:47:53.425569 | controller |   "failed": false,
2026-06-17 18:47:53.425591 | controller |   "invocation": {
2026-06-17 18:47:53.425610 | controller |     "module_args": {
2026-06-17 18:47:53.425630 | controller |       "checksum_algorithm": "sha1",
2026-06-17 18:47:53.425648 | controller |       "follow": true,
2026-06-17 18:47:53.425666 | controller |       "get_attributes": true,
2026-06-17 18:47:53.425685 | controller |       "get_checksum": true,
2026-06-17 18:47:53.425704 | controller |       "get_mime": true,
2026-06-17 18:47:53.425721 | controller |       "path": "src/github.com/vexxhost/ansible-collection-kubernetes"
2026-06-17 18:47:53.425739 | controller |     }
2026-06-17 18:47:53.425757 | controller |   },
2026-06-17 18:47:53.425774 | controller |   "item": "src/github.com/vexxhost/ansible-collection-kubernetes",
2026-06-17 18:47:53.425791 | controller |   "stat": {
2026-06-17 18:47:53.425809 | controller |     "atime": 1781722056.6933289,
2026-06-17 18:47:53.425827 | controller |     "attr_flags": "e",
2026-06-17 18:47:53.425844 | controller |     "attributes": [
2026-06-17 18:47:53.425861 | controller |       "extents"
2026-06-17 18:47:53.425878 | controller |     ],
2026-06-17 18:47:53.425896 | controller |     "block_size": 4096,
2026-06-17 18:47:53.425913 | controller |     "blocks": 8,
2026-06-17 18:47:53.425931 | controller |     "charset": "unknown",
2026-06-17 18:47:53.425949 | controller |     "ctime": 1781722056.7138433,
2026-06-17 18:47:53.425966 | controller |     "dev": 65025,
2026-06-17 18:47:53.425984 | controller |     "device_type": 0,
2026-06-17 18:47:53.426001 | controller |     "executable": true,
2026-06-17 18:47:53.426019 | controller |     "exists": true,
2026-06-17 18:47:53.426036 | controller |     "gid": 1000,
2026-06-17 18:47:53.426054 | controller |     "gr_name": "zuul",
2026-06-17 18:47:53.426071 | controller |     "inode": 519959,
2026-06-17 18:47:53.426089 | controller |     "isblk": false,
2026-06-17 18:47:53.426106 | controller |     "ischr": false,
2026-06-17 18:47:53.426124 | controller |     "isdir": true,
2026-06-17 18:47:53.426141 | controller |     "isfifo": false,
2026-06-17 18:47:53.426158 | controller |     "isgid": false,
2026-06-17 18:47:53.426175 | controller |     "islnk": false,
2026-06-17 18:47:53.426192 | controller |     "isreg": false,
2026-06-17 18:47:53.426209 | controller |     "issock": false,
2026-06-17 18:47:53.426226 | controller |     "isuid": false,
2026-06-17 18:47:53.426244 | controller |     "mimetype": "unknown",
2026-06-17 18:47:53.426261 | controller |     "mode": "0755",
2026-06-17 18:47:53.426278 | controller |     "mtime": 1781722038.810416,
2026-06-17 18:47:53.426296 | controller |     "nlink": 14,
2026-06-17 18:47:53.426316 | controller |     "path": "src/github.com/vexxhost/ansible-collection-kubernetes",
2026-06-17 18:47:53.426336 | controller |     "pw_name": "zuul",
2026-06-17 18:47:53.426358 | controller |     "readable": true,
2026-06-17 18:47:53.426376 | controller |     "rgrp": true,
2026-06-17 18:47:53.426393 | controller |     "roth": true,
2026-06-17 18:47:53.426411 | controller |     "rusr": true,
2026-06-17 18:47:53.426428 | controller |     "size": 4096,
2026-06-17 18:47:53.426445 | controller |     "uid": 1000,
2026-06-17 18:47:53.426462 | controller |     "version": "325253763",
2026-06-17 18:47:53.426480 | controller |     "wgrp": false,
2026-06-17 18:47:53.426498 | controller |     "woth": false,
2026-06-17 18:47:53.426516 | controller |     "writeable": true,
2026-06-17 18:47:53.426533 | controller |     "wusr": true,
2026-06-17 18:47:53.426550 | controller |     "xgrp": true,
2026-06-17 18:47:53.426567 | controller |     "xoth": true,
2026-06-17 18:47:53.426583 | controller |     "xusr": true
2026-06-17 18:47:53.426614 | controller |   }
2026-06-17 18:47:53.426632 | controller | }
2026-06-17 18:47:53.426664 | controller | ok: All items complete
2026-06-17 18:47:53.426685 | 
2026-06-17 18:47:53.427892 | controller | skipping: Conditional result was False
2026-06-17 18:47:53.431296 | controller | skipping: Conditional result was False
2026-06-17 18:47:53.440062 | 
2026-06-17 18:47:53.440204 | LOOP [Switch "ansible_host" to private IP]
2026-06-17 18:47:53.797812 | controller | changed: 1 replacements made
2026-06-17 18:47:53.799533 | controller | skipping: Conditional result was False
2026-06-17 18:47:53.803413 | controller | skipping: Conditional result was False
2026-06-17 18:47:53.820748 | 
2026-06-17 18:47:53.821017 | TASK [Run Molecule scenario]
2026-06-17 18:47:54.251555 | controller | Using CPython 3.13.5 interpreter at: /usr/bin/python3
2026-06-17 18:47:54.251636 | controller | Creating virtual environment at: .venv
2026-06-17 18:47:54.527226 | controller | Downloading pygments (1.2MiB)
2026-06-17 18:47:54.527979 | controller | Downloading cryptography (4.3MiB)
2026-06-17 18:47:54.528423 | controller | Downloading ansible-core (2.3MiB)
2026-06-17 18:47:54.688166 | controller |  Downloading cryptography
2026-06-17 18:47:54.737627 | controller |  Downloading pygments
2026-06-17 18:47:54.880559 | controller |  Downloading ansible-core
2026-06-17 18:47:54.929892 | controller | Installed 36 packages in 48ms
2026-06-17 18:47:55.639361 | controller | INFO     Collection 'vexxhost.kubernetes' detected.
2026-06-17 18:47:55.639411 | controller | INFO     Scenarios will be used from 'extensions/molecule'
2026-06-17 18:47:56.545042 | controller | CRITICAL 'extensions/molecule/default/molecule.yml' glob failed.  Exiting.
2026-06-17 18:47:56.545138 | controller | INFO     default scenario not found, disabling shared state.
2026-06-17 18:47:56.545513 | controller | INFO     [cert-manager > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy
2026-06-17 18:47:56.545683 | controller | INFO     [cert-manager > prerun] Performing prerun with role_name_check=0...
2026-06-17 18:48:14.234983 | controller | INFO     [cert-manager > dependency] Executing
2026-06-17 18:48:14.235175 | controller | WARNING  [cert-manager > dependency] Missing roles requirements file: requirements.yml
2026-06-17 18:48:14.235361 | controller | WARNING  [cert-manager > dependency] Missing collections requirements file: collections.yml
2026-06-17 18:48:14.235526 | controller | WARNING  [cert-manager > dependency] Executed: 2 missing (Remove from test_sequence to suppress)
2026-06-17 18:48:14.241718 | controller | INFO     [cert-manager > cleanup] Executing
2026-06-17 18:48:14.242026 | controller | WARNING  [cert-manager > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-06-17 18:48:14.247763 | controller | INFO     [cert-manager > destroy] Executing
2026-06-17 18:48:14.247791 | controller | WARNING  [cert-manager > destroy] Skipping, '--destroy=never' requested.
2026-06-17 18:48:14.247881 | controller | INFO     [cert-manager > destroy] Executed: Successful
2026-06-17 18:48:14.253491 | controller | INFO     [cert-manager > syntax] Executing
2026-06-17 18:48:15.150813 | controller |
2026-06-17 18:48:15.151017 | controller | playbook: /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/extensions/molecule/cert-manager/converge.yml
2026-06-17 18:48:15.219119 | controller | INFO     [cert-manager > syntax] Executed: Successful
2026-06-17 18:48:15.231415 | controller | INFO     [cert-manager > create] Executing
2026-06-17 18:48:15.233501 | controller | WARNING  [cert-manager > create] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-06-17 18:48:15.241046 | controller | INFO     [cert-manager > prepare] Executing
2026-06-17 18:48:16.144628 | controller |
2026-06-17 18:48:16.144818 | controller | PLAY [Prepare] *****************************************************************
2026-06-17 18:48:16.145153 | controller |
2026-06-17 18:48:16.145378 | controller | TASK [Gathering Facts] *********************************************************
2026-06-17 18:48:16.145621 | controller | Wednesday 17 June 2026  18:48:16 +0000 (0:00:00.031)       0:00:00.031 ********
2026-06-17 18:48:17.377346 | 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-06-17 18:48:17.387580 | controller | ok: [controller]
2026-06-17 18:48:17.387716 | controller |
2026-06-17 18:48:17.387862 | controller | TASK [Install Debian packages] *************************************************
2026-06-17 18:48:17.387996 | controller | Wednesday 17 June 2026  18:48:17 +0000 (0:00:01.241)       0:00:01.272 ********
2026-06-17 18:48:18.804156 | controller | ok: [controller]
2026-06-17 18:48:18.804262 | controller |
2026-06-17 18:48:18.804430 | controller | PLAY RECAP *********************************************************************
2026-06-17 18:48:18.804585 | controller | controller                 : ok=2    changed=0    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2026-06-17 18:48:18.804717 | controller |
2026-06-17 18:48:18.804844 | controller |
2026-06-17 18:48:18.804983 | controller | TASKS RECAP ********************************************************************
2026-06-17 18:48:18.805132 | controller | Wednesday 17 June 2026  18:48:18 +0000 (0:00:01.419)       0:00:02.691 ********
2026-06-17 18:48:18.805276 | controller | ===============================================================================
2026-06-17 18:48:18.805410 | controller | Install Debian packages ------------------------------------------------- 1.42s
2026-06-17 18:48:18.805533 | controller | Gathering Facts --------------------------------------------------------- 1.24s
2026-06-17 18:48:18.980037 | controller | INFO     [cert-manager > prepare] Executed: Successful
2026-06-17 18:48:18.992414 | controller | INFO     [cert-manager > converge] Executing
2026-06-17 18:48:19.849511 | controller |
2026-06-17 18:48:19.849706 | controller | PLAY [Converge] ****************************************************************
2026-06-17 18:48:19.849889 | controller |
2026-06-17 18:48:19.850104 | controller | TASK [Gathering Facts] *********************************************************
2026-06-17 18:48:19.850363 | controller | Wednesday 17 June 2026  18:48:19 +0000 (0:00:00.015)       0:00:00.015 ********
2026-06-17 18:48:21.907867 | 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-06-17 18:48:21.920835 | controller | ok: [controller]
2026-06-17 18:48:21.920938 | controller |
2026-06-17 18:48:21.921069 | controller | TASK [vexxhost.kubernetes.cert_manager : Load version-specific Helm values] ****
2026-06-17 18:48:21.921192 | controller | Wednesday 17 June 2026  18:48:21 +0000 (0:00:02.071)       0:00:02.086 ********
2026-06-17 18:48:21.969672 | controller | ok: [controller]
2026-06-17 18:48:21.969817 | controller |
2026-06-17 18:48:21.970020 | controller | TASK [vexxhost.kubernetes.cert_manager : Deploy Helm chart] ********************
2026-06-17 18:48:21.970251 | controller | Wednesday 17 June 2026  18:48:21 +0000 (0:00:00.048)       0:00:02.135 ********
2026-06-17 18:48:22.589121 | controller | [ERROR]: Task failed: Module failed: Failed to find required executable "helm" in paths: /usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin
2026-06-17 18:48:22.589307 | controller | Origin: /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes/roles/cert_manager/tasks/main.yml:13:3
2026-06-17 18:48:22.589430 | controller |
2026-06-17 18:48:22.589502 | controller | 11       - "{{ role_path }}/vars/default.yml"
2026-06-17 18:48:22.589605 | controller | 12
2026-06-17 18:48:22.589700 | controller | 13 - name: Deploy Helm chart
2026-06-17 18:48:22.589792 | controller |      ^ column 3
2026-06-17 18:48:22.589882 | controller |
2026-06-17 18:48:22.590766 | controller | fatal: [controller]: FAILED! => {"changed": false, "msg": "Failed to find required executable \"helm\" in paths: /usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin"}
2026-06-17 18:48:22.592109 | controller |
2026-06-17 18:48:22.592297 | controller | PLAY RECAP *********************************************************************
2026-06-17 18:48:22.592518 | controller | controller                 : ok=2    changed=0    unreachable=0    failed=1    skipped=0    rescued=0    ignored=0
2026-06-17 18:48:22.592695 | controller |
2026-06-17 18:48:22.592897 | controller |
2026-06-17 18:48:22.593118 | controller | TASKS RECAP ********************************************************************
2026-06-17 18:48:22.593309 | controller | Wednesday 17 June 2026  18:48:22 +0000 (0:00:00.623)       0:00:02.758 ********
2026-06-17 18:48:22.593496 | controller | ===============================================================================
2026-06-17 18:48:22.593861 | controller | Gathering Facts --------------------------------------------------------- 2.07s
2026-06-17 18:48:22.594080 | controller | vexxhost.kubernetes.cert_manager : Deploy Helm chart -------------------- 0.62s
2026-06-17 18:48:22.594417 | controller | vexxhost.kubernetes.cert_manager : Load version-specific Helm values ---- 0.05s
2026-06-17 18:48:22.681109 | controller | CRITICAL Ansible return code was 2, command was: ansible-playbook --inventory /home/zuul/.ansible/tmp/molecule.Bs5j.cert-manager/inventory --skip-tags molecule-notest,notest --inventory=/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/inventory.yaml /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/extensions/molecule/cert-manager/converge.yml
2026-06-17 18:48:22.681227 | controller | ERROR    [cert-manager > converge] Executed: Failed
2026-06-17 18:48:22.681375 | controller | ERROR    Ansible return code was 2, command was: ansible-playbook --inventory /home/zuul/.ansible/tmp/molecule.Bs5j.cert-manager/inventory --skip-tags molecule-notest,notest --inventory=/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/inventory.yaml /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/extensions/molecule/cert-manager/converge.yml
2026-06-17 18:48:22.849677 | controller | ERROR
2026-06-17 18:48:22.849930 | controller | {
2026-06-17 18:48:22.849972 | controller |   "delta": "0:00:28.593197",
2026-06-17 18:48:22.850002 | controller |   "end": "2026-06-17 18:48:22.766877",
2026-06-17 18:48:22.850029 | controller |   "msg": "non-zero return code",
2026-06-17 18:48:22.850055 | controller |   "rc": 2,
2026-06-17 18:48:22.850080 | controller |   "start": "2026-06-17 18:47:54.173680"
2026-06-17 18:48:22.850104 | controller | }
failure
2026-06-17 18:48:22.853387 | 
2026-06-17 18:48:22.853456 | PLAY RECAP
2026-06-17 18:48:22.853515 | controller | ok: 3 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-06-17 18:48:22.853546 | 
2026-06-17 18:48:23.079351 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-06-17 18:48:23.080900 | POST-RUN START: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/post.yml@main]
2026-06-17 18:48:23.717338 | 
2026-06-17 18:48:23.717476 | PLAY [all]
2026-06-17 18:48:23.728354 | 
2026-06-17 18:48:23.728440 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects]
2026-06-17 18:48:24.120299 | controller | changed
2026-06-17 18:48:24.125556 | 
2026-06-17 18:48:24.125667 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects]
2026-06-17 18:48:24.507213 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-06-17 18:48:24.507313 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-06-17 18:48:24.515157 | controller | environment: line 1: kubectl: command not found
2026-06-17 18:48:24.517261 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-06-17 18:48:24.519812 | controller | environment: line 1: kubectl: command not found
2026-06-17 18:48:24.521811 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-06-17 18:48:24.521851 | controller | environment: line 1: kubectl: command not found
2026-06-17 18:48:24.523379 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-06-17 18:48:24.524865 | controller | environment: line 1: kubectl: command not found
2026-06-17 18:48:24.526324 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-06-17 18:48:24.526704 | controller | environment: line 1: kubectl: command not found
2026-06-17 18:48:24.528516 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-06-17 18:48:24.655241 | controller | ok: Runtime: 0:00:00.034188
2026-06-17 18:48:24.661045 | 
2026-06-17 18:48:24.661152 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects]
2026-06-17 18:48:24.907048 | controller | changed
2026-06-17 18:48:24.912406 | 
2026-06-17 18:48:24.912513 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects]
2026-06-17 18:48:25.182315 | controller | environment: line 5: kubectl: command not found
2026-06-17 18:48:25.183492 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-06-17 18:48:25.183731 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-06-17 18:48:25.183779 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-06-17 18:48:25.442010 | controller | ok: Runtime: 0:00:00.014638
2026-06-17 18:48:25.448621 | 
2026-06-17 18:48:25.448744 | TASK [describe-kubernetes-objects : Downloads logs to executor]
2026-06-17 18:48:26.129245 | controller | changed:
2026-06-17 18:48:26.129468 | controller | created directory /var/lib/zuul/builds/4c01542c482b4ef993f0b7dad8ce9ef1/work/logs/controller
2026-06-17 18:48:26.129498 | controller | cd+++++++++ objects/
2026-06-17 18:48:26.129521 | controller | cd+++++++++ objects/cluster/
2026-06-17 18:48:26.129544 | controller | cd+++++++++ objects/namespaced/
2026-06-17 18:48:26.136181 | 
2026-06-17 18:48:26.136246 | TASK [gather-pod-logs : creating directory for pod logs]
2026-06-17 18:48:26.382643 | controller | changed
2026-06-17 18:48:26.389894 | 
2026-06-17 18:48:26.389961 | TASK [gather-pod-logs : creating directory for failed pod logs]
2026-06-17 18:48:26.636238 | controller | changed
2026-06-17 18:48:26.641776 | 
2026-06-17 18:48:26.641879 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)]
2026-06-17 18:48:26.879915 | controller | environment: line 3: kubectl: command not found
2026-06-17 18:48:27.174629 | controller | ok: Runtime: 0:00:00.011416
2026-06-17 18:48:27.180384 | 
2026-06-17 18:48:27.180492 | TASK [gather-pod-logs : Downloads pod logs to executor]
2026-06-17 18:48:27.689793 | controller | changed:
2026-06-17 18:48:27.690054 | controller | cd+++++++++ pod-logs/
2026-06-17 18:48:27.690113 | controller | cd+++++++++ pod-logs/failed-pods/
2026-06-17 18:48:27.698562 | 
2026-06-17 18:48:27.698631 | LOOP [helm-release-status : creating directory for helm release status]
2026-06-17 18:48:27.928780 | controller | changed: "values"
2026-06-17 18:48:28.126745 | controller | changed: "releases"
2026-06-17 18:48:28.139227 | 
2026-06-17 18:48:28.139510 | TASK [helm-release-status : Gather get release status for helm charts]
2026-06-17 18:48:28.376143 | controller | /bin/bash: line 3: kubectl: command not found
2026-06-17 18:48:28.668515 | controller | ok: Runtime: 0:00:00.008015
2026-06-17 18:48:28.674309 | 
2026-06-17 18:48:28.674401 | TASK [helm-release-status : Downloads logs to executor]
2026-06-17 18:48:29.170021 | controller | changed:
2026-06-17 18:48:29.170252 | controller | cd+++++++++ helm/
2026-06-17 18:48:29.170294 | controller | cd+++++++++ helm/releases/
2026-06-17 18:48:29.170330 | controller | cd+++++++++ helm/values/
2026-06-17 18:48:29.176813 | 
2026-06-17 18:48:29.176897 | PLAY RECAP
2026-06-17 18:48:29.176990 | controller | ok: 12 changed: 12 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-06-17 18:48:29.177028 | 
2026-06-17 18:48:29.343895 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/post.yml@main]
2026-06-17 18:48:29.346835 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-06-17 18:48:29.988382 | 
2026-06-17 18:48:29.988536 | PLAY [all]
2026-06-17 18:48:30.000493 | 
2026-06-17 18:48:30.000622 | TASK [fetch-output : Set log path for multiple nodes]
2026-06-17 18:48:30.046028 | controller | skipping: Conditional result was False
2026-06-17 18:48:30.055672 | 
2026-06-17 18:48:30.055861 | TASK [fetch-output : Set log path for single node]
2026-06-17 18:48:30.098627 | controller | ok
2026-06-17 18:48:30.104299 | 
2026-06-17 18:48:30.104450 | LOOP [fetch-output : Ensure local output dirs]
2026-06-17 18:48:30.518203 | controller -> localhost | ok: "/var/lib/zuul/builds/4c01542c482b4ef993f0b7dad8ce9ef1/work/logs"
2026-06-17 18:48:30.725372 | controller -> localhost | changed: "/var/lib/zuul/builds/4c01542c482b4ef993f0b7dad8ce9ef1/work/artifacts"
2026-06-17 18:48:30.939609 | controller -> localhost | changed: "/var/lib/zuul/builds/4c01542c482b4ef993f0b7dad8ce9ef1/work/docs"
2026-06-17 18:48:30.955417 | 
2026-06-17 18:48:30.955588 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-06-17 18:48:31.584978 | controller | changed: .d..t...... ./
2026-06-17 18:48:31.585240 | controller | changed: All items complete
2026-06-17 18:48:31.585277 | 
2026-06-17 18:48:32.023401 | controller | changed: .d..t...... ./
2026-06-17 18:48:32.487554 | controller | changed: .d..t...... ./
2026-06-17 18:48:32.504369 | 
2026-06-17 18:48:32.504531 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-06-17 18:48:32.931557 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.008879
2026-06-17 18:48:33.164856 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.005781
2026-06-17 18:48:33.182824 | 
2026-06-17 18:48:33.182920 | PLAY [all]
2026-06-17 18:48:33.188242 | 
2026-06-17 18:48:33.188327 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-06-17 18:48:33.647570 | controller | changed
2026-06-17 18:48:33.654152 | 
2026-06-17 18:48:33.654221 | PLAY RECAP
2026-06-17 18:48:33.654287 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-06-17 18:48:33.654326 | 
2026-06-17 18:48:33.807369 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-06-17 18:48:33.810567 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-06-17 18:48:34.387078 | 
2026-06-17 18:48:34.387204 | PLAY [localhost]
2026-06-17 18:48:34.397486 | 
2026-06-17 18:48:34.397593 | TASK [Generate Zuul manifest]
2026-06-17 18:48:34.416586 | localhost | ok
2026-06-17 18:48:34.434395 | 
2026-06-17 18:48:34.434474 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-06-17 18:48:34.811073 | localhost | changed
2026-06-17 18:48:34.823639 | 
2026-06-17 18:48:34.823736 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-06-17 18:48:34.854764 | localhost | ok
2026-06-17 18:48:34.863561 | 
2026-06-17 18:48:34.863646 | TASK [Upload logs]
2026-06-17 18:48:34.884713 | localhost | ok
2026-06-17 18:48:34.940496 | 
2026-06-17 18:48:34.940606 | TASK [Set zuul-log-path fact]
2026-06-17 18:48:34.961875 | localhost | ok
2026-06-17 18:48:34.975429 | 
2026-06-17 18:48:34.975496 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-06-17 18:48:35.007506 | localhost | ok
2026-06-17 18:48:35.015921 | 
2026-06-17 18:48:35.016000 | TASK [upload-logs : Create log directories]
2026-06-17 18:48:35.451507 | localhost | changed
2026-06-17 18:48:35.458972 | 
2026-06-17 18:48:35.459079 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-06-17 18:48:35.867774 | localhost -> localhost | ok: Runtime: 0:00:00.005788
2026-06-17 18:48:35.873869 | 
2026-06-17 18:48:35.873956 | TASK [upload-logs : Upload logs to log server]
2026-06-17 18:48:36.362312 | localhost | Output suppressed because no_log was given
2026-06-17 18:48:36.367498 | 
2026-06-17 18:48:36.367585 | LOOP [upload-logs : Compress console log and json output]
2026-06-17 18:48:36.416745 | localhost | skipping: Conditional result was False
2026-06-17 18:48:36.470914 | localhost | skipping: Conditional result was False
2026-06-17 18:48:36.482976 | 
2026-06-17 18:48:36.483152 | LOOP [upload-logs : Upload compressed console log and json output]
2026-06-17 18:48:36.523386 | localhost | skipping: Conditional result was False
2026-06-17 18:48:36.523781 | 
2026-06-17 18:48:36.534547 | localhost | skipping: Conditional result was False
2026-06-17 18:48:36.546979 | 
2026-06-17 18:48:36.547195 | LOOP [upload-logs : Upload console log and json output]