2026-09-15 16:49:10.170321 | Job console starting
2026-09-15 16:49:10.179364 | Updating git repos
2026-09-15 16:49:10.339529 | Cloning repos into workspace
2026-09-15 16:49:10.384548 | Restoring repo states
2026-09-15 16:49:10.460074 | Merging changes
2026-09-15 16:49:11.261694 | Checking out repos
2026-09-15 16:49:11.405341 | Preparing playbooks
2026-09-15 16:49:14.649402 | Running Ansible setup
2026-09-15 16:49:18.464555 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-09-15 16:49:19.066159 | 
2026-09-15 16:49:19.066316 | PLAY [localhost]
2026-09-15 16:49:19.073450 | 
2026-09-15 16:49:19.073515 | TASK [Gathering Facts]
2026-09-15 16:49:19.995100 | localhost | ok
2026-09-15 16:49:20.009150 | 
2026-09-15 16:49:20.009361 | TASK [Setup log path fact]
2026-09-15 16:49:20.030728 | localhost | ok
2026-09-15 16:49:20.048394 | 
2026-09-15 16:49:20.048488 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-09-15 16:49:20.100385 | localhost | ok
2026-09-15 16:49:20.113212 | 
2026-09-15 16:49:20.113391 | TASK [emit-job-header : Print job information]
2026-09-15 16:49:20.158227 | # Job Information
2026-09-15 16:49:20.158480 | Ansible Version: 2.16.18
2026-09-15 16:49:20.158546 | Job: ansible-collection-kubernetes-molecule-aio-ubuntu-noble
2026-09-15 16:49:20.158591 | Pipeline: check
2026-09-15 16:49:20.158630 | Executor: 2d72f0692154
2026-09-15 16:49:20.158669 | Triggered by: https://github.com/vexxhost/ansible-collection-kubernetes/pull/291
2026-09-15 16:49:20.158712 | Event ID: 7e3b23a0-b124-11f1-9be4-5c390cfddffc
2026-09-15 16:49:20.164542 | 
2026-09-15 16:49:20.164679 | LOOP [emit-job-header : Print node information]
2026-09-15 16:49:20.267885 | localhost | ok:
2026-09-15 16:49:20.268611 | localhost | # Node Information
2026-09-15 16:49:20.268698 | localhost | Inventory Hostname: controller
2026-09-15 16:49:20.268748 | localhost | Hostname: np0000192849
2026-09-15 16:49:20.268790 | localhost | Username: zuul
2026-09-15 16:49:20.268838 | localhost | Distro: Ubuntu 24.04
2026-09-15 16:49:20.268880 | localhost | Provider: yul1
2026-09-15 16:49:20.268923 | localhost | Region: ca-ymq-1
2026-09-15 16:49:20.268963 | localhost | Label: ubuntu-noble
2026-09-15 16:49:20.269000 | localhost | Product Name: OpenStack Nova
2026-09-15 16:49:20.269039 | localhost | Interface IP: 199.204.45.131
2026-09-15 16:49:20.287467 | 
2026-09-15 16:49:20.287700 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-09-15 16:49:20.669239 | localhost -> localhost | changed
2026-09-15 16:49:20.679047 | 
2026-09-15 16:49:20.679190 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-09-15 16:49:21.577231 | localhost -> localhost | changed
2026-09-15 16:49:21.588134 | 
2026-09-15 16:49:21.588239 | PLAY [all]
2026-09-15 16:49:21.603055 | 
2026-09-15 16:49:21.603192 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-09-15 16:49:21.850311 | controller -> localhost | ok
2026-09-15 16:49:21.856200 | 
2026-09-15 16:49:21.856275 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-09-15 16:49:21.886717 | controller | ok
2026-09-15 16:49:21.901055 | controller | included: /var/lib/zuul/builds/949e4c21d5ab48f89affda2024675766/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-09-15 16:49:21.906611 | 
2026-09-15 16:49:21.906666 | TASK [add-build-sshkey : Create Temp SSH key]
2026-09-15 16:49:23.133166 | controller -> localhost | Generating public/private rsa key pair.
2026-09-15 16:49:23.133664 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/949e4c21d5ab48f89affda2024675766/work/949e4c21d5ab48f89affda2024675766_id_rsa
2026-09-15 16:49:23.133695 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/949e4c21d5ab48f89affda2024675766/work/949e4c21d5ab48f89affda2024675766_id_rsa.pub
2026-09-15 16:49:23.133716 | controller -> localhost | The key fingerprint is:
2026-09-15 16:49:23.133735 | controller -> localhost | SHA256:e0UNTpum4PzAl/LgHeR+ablMgdhRhKVIoTIKmHUsO/A zuul-build-sshkey
2026-09-15 16:49:23.133766 | controller -> localhost | The key's randomart image is:
2026-09-15 16:49:23.133785 | controller -> localhost | +---[RSA 3072]----+
2026-09-15 16:49:23.133808 | controller -> localhost | |  ...   o. +*    |
2026-09-15 16:49:23.133828 | controller -> localhost | |oo...  o ..= =   |
2026-09-15 16:49:23.133846 | controller -> localhost | |+o oo . o + * .  |
2026-09-15 16:49:23.133864 | controller -> localhost | | .E. o + * B     |
2026-09-15 16:49:23.133881 | controller -> localhost | |  ..    S O o    |
2026-09-15 16:49:23.133898 | controller -> localhost | |       . @ o +   |
2026-09-15 16:49:23.133915 | controller -> localhost | |        o * *    |
2026-09-15 16:49:23.133932 | controller -> localhost | |         . = .   |
2026-09-15 16:49:23.133951 | controller -> localhost | |            o    |
2026-09-15 16:49:23.133969 | controller -> localhost | +----[SHA256]-----+
2026-09-15 16:49:23.134025 | controller -> localhost | ok: Runtime: 0:00:00.804385
2026-09-15 16:49:23.140179 | 
2026-09-15 16:49:23.140315 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-09-15 16:49:23.169124 | controller | ok
2026-09-15 16:49:23.176922 | controller | included: /var/lib/zuul/builds/949e4c21d5ab48f89affda2024675766/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-09-15 16:49:23.183741 | 
2026-09-15 16:49:23.183812 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-09-15 16:49:23.207195 | controller | skipping: Conditional result was False
2026-09-15 16:49:23.215100 | 
2026-09-15 16:49:23.215214 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-09-15 16:49:23.848881 | controller | changed
2026-09-15 16:49:23.862231 | 
2026-09-15 16:49:23.862393 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-09-15 16:49:24.138366 | controller | ok
2026-09-15 16:49:24.154976 | 
2026-09-15 16:49:24.155123 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-09-15 16:49:24.855051 | controller | changed
2026-09-15 16:49:24.869309 | 
2026-09-15 16:49:24.869455 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-09-15 16:49:25.509140 | controller | changed
2026-09-15 16:49:25.519134 | 
2026-09-15 16:49:25.519322 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-09-15 16:49:25.544436 | controller | skipping: Conditional result was False
2026-09-15 16:49:25.561663 | 
2026-09-15 16:49:25.561882 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-09-15 16:49:25.934853 | controller -> localhost | changed
2026-09-15 16:49:25.951783 | 
2026-09-15 16:49:25.951957 | TASK [add-build-sshkey : Add back temp key]
2026-09-15 16:49:26.197163 | controller -> localhost | Identity added: /var/lib/zuul/builds/949e4c21d5ab48f89affda2024675766/work/949e4c21d5ab48f89affda2024675766_id_rsa (zuul-build-sshkey)
2026-09-15 16:49:26.197338 | controller -> localhost | ok: Runtime: 0:00:00.013153
2026-09-15 16:49:26.201986 | 
2026-09-15 16:49:26.202046 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-09-15 16:49:26.574297 | controller | ok
2026-09-15 16:49:26.584876 | 
2026-09-15 16:49:26.585057 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-09-15 16:49:26.618955 | controller | skipping: Conditional result was False
2026-09-15 16:49:26.644317 | 
2026-09-15 16:49:26.644541 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-09-15 16:49:27.137342 | controller | ok
2026-09-15 16:49:27.142832 | 
2026-09-15 16:49:27.142904 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-09-15 16:49:28.412115 | controller | Output suppressed because no_log was given
2026-09-15 16:49:28.433124 | 
2026-09-15 16:49:28.433245 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-09-15 16:49:28.725002 | controller | ok: "logs"
2026-09-15 16:49:28.725482 | controller | ok: All items complete
2026-09-15 16:49:28.725570 | 
2026-09-15 16:49:28.983288 | controller | ok: "artifacts"
2026-09-15 16:49:29.220603 | controller | ok: "docs"
2026-09-15 16:49:29.236276 | 
2026-09-15 16:49:29.236787 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-09-15 16:49:29.530459 | controller | changed: "logs"
2026-09-15 16:49:29.746560 | controller | changed: "artifacts"
2026-09-15 16:49:29.975354 | controller | changed: "docs"
2026-09-15 16:49:29.997038 | 
2026-09-15 16:49:29.997216 | PLAY RECAP
2026-09-15 16:49:29.997332 | controller | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-09-15 16:49:29.997392 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-15 16:49:29.997436 | 
2026-09-15 16:49:30.157667 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-09-15 16:49:30.160593 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-09-15 16:49:30.673665 | 
2026-09-15 16:49:30.674296 | PLAY [all]
2026-09-15 16:49:30.684317 | 
2026-09-15 16:49:30.684389 | TASK [setup-uv : Extract archive]
2026-09-15 16:49:33.213703 | controller | changed
2026-09-15 16:49:33.219155 | 
2026-09-15 16:49:33.219238 | TASK [setup-uv : Print version]
2026-09-15 16:49:33.646348 | controller | uv 0.8.13
2026-09-15 16:49:33.755172 | controller | ok: Runtime: 0:00:00.021223
2026-09-15 16:49:33.774470 | 
2026-09-15 16:49:33.774596 | TASK [Install Ansible collection siblings]
2026-09-15 16:49:34.218786 | controller | ok:
2026-09-15 16:49:34.219044 | controller | Skipping ansible.posix: no checked-out sibling
2026-09-15 16:49:34.219101 | controller | Skipping community.crypto: no checked-out sibling
2026-09-15 16:49:34.219143 | controller | Skipping community.general: no checked-out sibling
2026-09-15 16:49:34.219182 | controller | Skipping kubernetes.core: no checked-out sibling
2026-09-15 16:49:34.219220 | controller | Skipping vexxhost.containers: no checked-out sibling
2026-09-15 16:49:34.222383 | 
2026-09-15 16:49:34.222478 | PLAY RECAP
2026-09-15 16:49:34.222562 | controller | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-15 16:49:34.222604 | 
2026-09-15 16:49:34.365511 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-09-15 16:49:34.369235 | PRE-RUN START: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/pre.yml@main]
2026-09-15 16:49:34.914957 | 
2026-09-15 16:49:34.915079 | PLAY [all]
2026-09-15 16:49:34.925471 | 
2026-09-15 16:49:34.925541 | TASK [Update apt cache]
2026-09-15 16:49:40.084341 | controller | changed
2026-09-15 16:49:40.095369 | 
2026-09-15 16:49:40.095576 | TASK [Disable firewalld]
2026-09-15 16:49:40.121339 | controller | skipping: Conditional result was False
2026-09-15 16:49:40.136976 | 
2026-09-15 16:49:40.137160 | TASK [Install "jq" for log collection]
2026-09-15 16:49:44.398168 | controller | changed
2026-09-15 16:49:44.402064 | 
2026-09-15 16:49:44.402174 | PLAY RECAP
2026-09-15 16:49:44.402292 | controller | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-09-15 16:49:44.402341 | 
2026-09-15 16:49:44.573469 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/pre.yml@main]
2026-09-15 16:49:44.576069 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-09-15 16:49:45.162543 | 
2026-09-15 16:49:45.162674 | PLAY [all]
2026-09-15 16:49:45.173586 | 
2026-09-15 16:49:45.173659 | LOOP [Check inventory directories]
2026-09-15 16:49:45.617951 | controller | ok: "src/github.com/vexxhost/ansible-collection-kubernetes"
2026-09-15 16:49:45.804984 | controller | ok: "src/github.com/vexxhost/ansible-collection-kubernetes/molecule/aio/inventory"
2026-09-15 16:49:45.989261 | controller | ok: "src/github.com/vexxhost/ansible-collection-kubernetes/extensions/molecule/aio/inventory"
2026-09-15 16:49:46.003258 | 
2026-09-15 16:49:46.003463 | LOOP [Copy inventory file for Zuul]
2026-09-15 16:49:47.059319 | controller | changed:
2026-09-15 16:49:47.059583 | controller | {
2026-09-15 16:49:47.059624 | controller |   "ansible_loop_var": "item",
2026-09-15 16:49:47.059656 | controller |   "changed": false,
2026-09-15 16:49:47.059682 | controller |   "failed": false,
2026-09-15 16:49:47.059709 | controller |   "invocation": {
2026-09-15 16:49:47.059735 | controller |     "module_args": {
2026-09-15 16:49:47.059758 | controller |       "checksum_algorithm": "sha1",
2026-09-15 16:49:47.059781 | controller |       "follow": true,
2026-09-15 16:49:47.059805 | controller |       "get_attributes": true,
2026-09-15 16:49:47.059830 | controller |       "get_checksum": true,
2026-09-15 16:49:47.059853 | controller |       "get_mime": true,
2026-09-15 16:49:47.059876 | controller |       "path": "src/github.com/vexxhost/ansible-collection-kubernetes"
2026-09-15 16:49:47.059898 | controller |     }
2026-09-15 16:49:47.059921 | controller |   },
2026-09-15 16:49:47.059943 | controller |   "item": "src/github.com/vexxhost/ansible-collection-kubernetes",
2026-09-15 16:49:47.059967 | controller |   "stat": {
2026-09-15 16:49:47.059991 | controller |     "atime": 1789490968.228189,
2026-09-15 16:49:47.060015 | controller |     "attr_flags": "e",
2026-09-15 16:49:47.060076 | controller |     "attributes": [
2026-09-15 16:49:47.060120 | controller |       "extents"
2026-09-15 16:49:47.060143 | controller |     ],
2026-09-15 16:49:47.060166 | controller |     "block_size": 4096,
2026-09-15 16:49:47.060187 | controller |     "blocks": 8,
2026-09-15 16:49:47.060209 | controller |     "charset": "unknown",
2026-09-15 16:49:47.060259 | controller |     "ctime": 1789490968.2711875,
2026-09-15 16:49:47.060305 | controller |     "dev": 64769,
2026-09-15 16:49:47.060340 | controller |     "device_type": 0,
2026-09-15 16:49:47.060374 | controller |     "executable": true,
2026-09-15 16:49:47.060398 | controller |     "exists": true,
2026-09-15 16:49:47.060419 | controller |     "gid": 1000,
2026-09-15 16:49:47.060441 | controller |     "gr_name": "zuul",
2026-09-15 16:49:47.060463 | controller |     "inode": 1034784,
2026-09-15 16:49:47.060485 | controller |     "isblk": false,
2026-09-15 16:49:47.060550 | controller |     "ischr": false,
2026-09-15 16:49:47.060598 | controller |     "isdir": true,
2026-09-15 16:49:47.060634 | controller |     "isfifo": false,
2026-09-15 16:49:47.060672 | controller |     "isgid": false,
2026-09-15 16:49:47.060717 | controller |     "islnk": false,
2026-09-15 16:49:47.060751 | controller |     "isreg": false,
2026-09-15 16:49:47.060775 | controller |     "issock": false,
2026-09-15 16:49:47.072544 | controller |     "isuid": false,
2026-09-15 16:49:47.072636 | controller |     "mimetype": "unknown",
2026-09-15 16:49:47.072658 | controller |     "mode": "0755",
2026-09-15 16:49:47.072674 | controller |     "mtime": 1789490951.1878786,
2026-09-15 16:49:47.072689 | controller |     "nlink": 14,
2026-09-15 16:49:47.072703 | controller |     "path": "src/github.com/vexxhost/ansible-collection-kubernetes",
2026-09-15 16:49:47.072718 | controller |     "pw_name": "zuul",
2026-09-15 16:49:47.072736 | controller |     "readable": true,
2026-09-15 16:49:47.072750 | controller |     "rgrp": true,
2026-09-15 16:49:47.072764 | controller |     "roth": true,
2026-09-15 16:49:47.072778 | controller |     "rusr": true,
2026-09-15 16:49:47.072793 | controller |     "size": 4096,
2026-09-15 16:49:47.072807 | controller |     "uid": 1000,
2026-09-15 16:49:47.072822 | controller |     "version": "713660421",
2026-09-15 16:49:47.072836 | controller |     "wgrp": false,
2026-09-15 16:49:47.072853 | controller |     "woth": false,
2026-09-15 16:49:47.072868 | controller |     "writeable": true,
2026-09-15 16:49:47.072883 | controller |     "wusr": true,
2026-09-15 16:49:47.072897 | controller |     "xgrp": true,
2026-09-15 16:49:47.072911 | controller |     "xoth": true,
2026-09-15 16:49:47.072925 | controller |     "xusr": true
2026-09-15 16:49:47.072954 | controller |   }
2026-09-15 16:49:47.072969 | controller | }
2026-09-15 16:49:47.073025 | controller | ok: All items complete
2026-09-15 16:49:47.073045 | 
2026-09-15 16:49:47.074652 | controller | skipping: Conditional result was False
2026-09-15 16:49:47.075191 | controller | skipping: Conditional result was False
2026-09-15 16:49:47.088505 | 
2026-09-15 16:49:47.088672 | LOOP [Switch "ansible_host" to private IP]
2026-09-15 16:49:47.563075 | controller | changed: 1 replacements made
2026-09-15 16:49:47.566047 | controller | skipping: Conditional result was False
2026-09-15 16:49:47.567429 | controller | skipping: Conditional result was False
2026-09-15 16:49:47.585176 | 
2026-09-15 16:49:47.585442 | TASK [Run Molecule scenario]
2026-09-15 16:49:48.105745 | controller | Using CPython 3.12.3 interpreter at: /usr/bin/python3
2026-09-15 16:49:48.105931 | controller | Creating virtual environment at: .venv
2026-09-15 16:49:48.153192 | controller | Downloading ansible-core (2.3MiB)
2026-09-15 16:49:48.153467 | controller | Downloading pygments (1.2MiB)
2026-09-15 16:49:48.154715 | controller | Downloading cryptography (4.3MiB)
2026-09-15 16:49:48.328077 | controller |  Downloading cryptography
2026-09-15 16:49:48.374858 | controller |  Downloading pygments
2026-09-15 16:49:48.578158 | controller |  Downloading ansible-core
2026-09-15 16:49:48.636536 | controller | Installed 37 packages in 57ms
2026-09-15 16:49:49.484016 | controller | INFO     Collection 'vexxhost.kubernetes' detected.
2026-09-15 16:49:49.484133 | controller | INFO     Scenarios will be used from 'extensions/molecule'
2026-09-15 16:49:50.489250 | controller | CRITICAL 'extensions/molecule/default/molecule.yml' glob failed.  Exiting.
2026-09-15 16:49:50.489362 | controller | INFO     default scenario not found, disabling shared state.
2026-09-15 16:49:50.489375 | controller | INFO     [aio > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy
2026-09-15 16:49:50.489398 | controller | INFO     [aio > prerun] Performing prerun with role_name_check=0...
2026-09-15 17:01:14.149035 | controller | WARNING  Retrying execution failure 1 of: ansible-galaxy collection install -vvv community.general:>=4.5.0
2026-09-15 17:01:14.149171 | controller | ERROR    Command ansible-galaxy collection install -vvv community.general:>=4.5.0, returned 1 code:
2026-09-15 17:01:14.149220 | controller | Starting galaxy collection install process
2026-09-15 17:01:14.149232 | controller | Process install dependency map
2026-09-15 17:01:14.149244 | controller | Starting collection install process
2026-09-15 17:01:14.149257 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/community-general-13.4.0.tar.gz to /home/zuul/.ansible/tmp/ansible-local-137673wu6z3p/tmpzfmd3bp0/community-general-13.4.0-6_8xkrrp
2026-09-15 17:01:14.149280 | controller |
2026-09-15 17:01:14.149292 | controller | ansible-galaxy
2026-09-15 17:01:14.149304 | controller |   config file = None
2026-09-15 17:01:14.149315 | controller |   configured module search path =
2026-09-15 17:01:14.149327 | controller |   ansible python module location = /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.12/site-packages/ansible
2026-09-15 17:01:14.149343 | controller |   ansible collection location = /home/zuul/.ansible/collections:/usr/share/ansible/collections:/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.12/dist-packages:/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3/dist-packages:/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/local/lib/python3.12/dist-packages:/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.12/site-packages
2026-09-15 17:01:14.149358 | controller |   executable location = /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/bin/ansible-galaxy
2026-09-15 17:01:14.149369 | controller |   python version = 3.12.3 (main, Jul 15 2026, 23:46:41)  (/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/bin/python)
2026-09-15 17:01:14.149380 | controller |   jinja version = 3.1.6
2026-09-15 17:01:14.149392 | controller |   pyyaml version = 6.0.3 (with libyaml v0.2.5)
2026-09-15 17:01:14.149403 | controller | No config file found; using defaults
2026-09-15 17:01:14.149421 | controller | Found installed collection ansible.posix:2.2.2 at '/home/zuul/.ansible/collections/ansible_collections/ansible/posix'
2026-09-15 17:01:14.149432 | controller | Found installed collection community.crypto:3.4.0 at '/home/zuul/.ansible/collections/ansible_collections/community/crypto'
2026-09-15 17:01:14.149443 | controller | Opened /home/zuul/.ansible/galaxy_token
2026-09-15 17:01:14.149455 | controller | : Failed to download collection tar from 'default' due to the following unforeseen error: The read operation timed out
2026-09-15 17:01:14.149466 | controller |
2026-09-15 17:01:14.149571 | controller | Traceback (most recent call last):
2026-09-15 17:01:14.149908 | controller |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/bin/molecule", line 10, in <module>
2026-09-15 17:01:14.150346 | controller |     sys.exit(main())
2026-09-15 17:01:14.150605 | controller |              ^^^^^^
2026-09-15 17:01:14.150851 | controller |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.12/site-packages/click/core.py", line 1485, in __call__
2026-09-15 17:01:14.151571 | controller |     return self.main(*args, **kwargs)
2026-09-15 17:01:14.151866 | controller |            ^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-15 17:01:14.152072 | controller |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.12/site-packages/click/core.py", line 1406, in main
2026-09-15 17:01:14.152755 | controller |     rv = self.invoke(ctx)
2026-09-15 17:01:14.152997 | controller |          ^^^^^^^^^^^^^^^^
2026-09-15 17:01:14.153239 | controller |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.12/site-packages/click/core.py", line 1873, in invoke
2026-09-15 17:01:14.154040 | controller |     return _process_result(sub_ctx.command.invoke(sub_ctx))
2026-09-15 17:01:14.154346 | controller |                            ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-15 17:01:14.154538 | controller |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.12/site-packages/click/core.py", line 1269, in invoke
2026-09-15 17:01:14.155180 | controller |     return ctx.invoke(self.callback, **ctx.params)
2026-09-15 17:01:14.155451 | controller |            ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-15 17:01:14.155648 | controller |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.12/site-packages/click/core.py", line 824, in invoke
2026-09-15 17:01:14.156159 | controller |     return callback(*args, **kwargs)
2026-09-15 17:01:14.156419 | controller |            ^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-15 17:01:14.156616 | controller |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.12/site-packages/molecule/click_cfg.py", line 583, in wrapper
2026-09-15 17:01:14.157067 | controller |     return func(*args, **kwargs)
2026-09-15 17:01:14.157368 | controller |            ^^^^^^^^^^^^^^^^^^^^^
2026-09-15 17:01:14.157562 | controller |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.12/site-packages/click/decorators.py", line 34, in new_func
2026-09-15 17:01:14.157890 | controller |     return f(get_current_context(), *args, **kwargs)
2026-09-15 17:01:14.158196 | controller |            ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-15 17:01:14.158406 | controller |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.12/site-packages/molecule/click_cfg.py", line 418, in wrapper
2026-09-15 17:01:14.159796 | controller |     return func(ctx)
2026-09-15 17:01:14.159896 | controller |            ^^^^^^^^^
2026-09-15 17:01:14.159905 | controller |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.12/site-packages/molecule/command/test.py", line 81, in test
2026-09-15 17:01:14.160006 | controller |     base.execute_cmdline_scenarios(scenario_name, args, command_args, ansible_args, exclude)
2026-09-15 17:01:14.160471 | controller |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.12/site-packages/molecule/command/base.py", line 169, in execute_cmdline_scenarios
2026-09-15 17:01:14.160955 | controller |     _run_scenarios(scenarios, command_args, default_config)
2026-09-15 17:01:14.161295 | controller |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.12/site-packages/molecule/command/base.py", line 240, in _run_scenarios
2026-09-15 17:01:14.161669 | controller |     scenario.config.runtime.prepare_environment(
2026-09-15 17:01:14.161935 | controller |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.12/site-packages/ansible_compat/runtime.py", line 752, in prepare_environment
2026-09-15 17:01:14.162453 | controller |     self.install_collection(
2026-09-15 17:01:14.162663 | controller |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.12/site-packages/ansible_compat/runtime.py", line 609, in install_collection
2026-09-15 17:01:14.163142 | controller |     raise InvalidPrerequisiteError(msg)
2026-09-15 17:01:14.164586 | controller | ansible_compat.errors.InvalidPrerequisiteError: Command ansible-galaxy collection install -vvv community.general:>=4.5.0, returned 1 code:
2026-09-15 17:01:14.164642 | controller | Starting galaxy collection install process
2026-09-15 17:01:14.164654 | controller | Process install dependency map
2026-09-15 17:01:14.164665 | controller | Starting collection install process
2026-09-15 17:01:14.164716 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/community-general-13.4.0.tar.gz to /home/zuul/.ansible/tmp/ansible-local-137673wu6z3p/tmpzfmd3bp0/community-general-13.4.0-6_8xkrrp
2026-09-15 17:01:14.164731 | controller |
2026-09-15 17:01:14.164743 | controller | ansible-galaxy [core 2.21.4]
2026-09-15 17:01:14.164754 | controller |   config file = None
2026-09-15 17:01:14.164764 | controller |   configured module search path = ['/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/plugins/modules', '/home/zuul/.ansible/plugins/modules', '/usr/share/ansible/plugins/modules']
2026-09-15 17:01:14.164776 | controller |   ansible python module location = /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.12/site-packages/ansible
2026-09-15 17:01:14.164789 | controller |   ansible collection location = /home/zuul/.ansible/collections:/usr/share/ansible/collections:/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.12/dist-packages:/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3/dist-packages:/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/local/lib/python3.12/dist-packages:/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.12/site-packages
2026-09-15 17:01:14.164802 | controller |   executable location = /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/bin/ansible-galaxy
2026-09-15 17:01:14.164811 | controller |   python version = 3.12.3 (main, Jul 15 2026, 23:46:41) [GCC 13.3.0] (/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/bin/python)
2026-09-15 17:01:14.164819 | controller |   jinja version = 3.1.6
2026-09-15 17:01:14.164827 | controller |   pyyaml version = 6.0.3 (with libyaml v0.2.5)
2026-09-15 17:01:14.164835 | controller | No config file found; using defaults
2026-09-15 17:01:14.164844 | controller | Found installed collection ansible.posix:2.2.2 at '/home/zuul/.ansible/collections/ansible_collections/ansible/posix'
2026-09-15 17:01:14.164852 | controller | Found installed collection community.crypto:3.4.0 at '/home/zuul/.ansible/collections/ansible_collections/community/crypto'
2026-09-15 17:01:14.164860 | controller | Opened /home/zuul/.ansible/galaxy_token
2026-09-15 17:01:14.164868 | controller | [ERROR]: Failed to download collection tar from 'default' due to the following unforeseen error: The read operation timed out
2026-09-15 17:01:14.164882 | controller |
2026-09-15 17:01:14.604888 | controller | ERROR
2026-09-15 17:01:14.605205 | controller | {
2026-09-15 17:01:14.605266 | controller |   "delta": "0:11:26.275344",
2026-09-15 17:01:14.605310 | controller |   "end": "2026-09-15 17:01:14.286733",
2026-09-15 17:01:14.605348 | controller |   "msg": "non-zero return code",
2026-09-15 17:01:14.605386 | controller |   "rc": 1,
2026-09-15 17:01:14.605423 | controller |   "start": "2026-09-15 16:49:48.011389"
2026-09-15 17:01:14.605459 | controller | }
failure
2026-09-15 17:01:14.609294 | 
2026-09-15 17:01:14.609394 | PLAY RECAP
2026-09-15 17:01:14.609484 | controller | ok: 3 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-09-15 17:01:14.609536 | 
2026-09-15 17:01:14.781315 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-09-15 17:01:14.784009 | POST-RUN START: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/post.yml@main]
2026-09-15 17:01:15.374208 | 
2026-09-15 17:01:15.374738 | PLAY [all]
2026-09-15 17:01:15.387313 | 
2026-09-15 17:01:15.387412 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects]
2026-09-15 17:01:15.856432 | controller | changed
2026-09-15 17:01:15.868810 | 
2026-09-15 17:01:15.868972 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects]
2026-09-15 17:01:16.313721 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-15 17:01:16.313806 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-15 17:01:16.322603 | controller | environment: line 1: kubectl: command not found
2026-09-15 17:01:16.324306 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-15 17:01:16.326249 | controller | environment: line 1: kubectl: command not found
2026-09-15 17:01:16.328489 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-15 17:01:16.330080 | controller | environment: line 1: kubectl: command not found
2026-09-15 17:01:16.332435 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-15 17:01:16.333423 | controller | environment: line 1: kubectl: command not found
2026-09-15 17:01:16.335923 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-15 17:01:16.337479 | controller | environment: line 1: kubectl: command not found
2026-09-15 17:01:16.339688 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-15 17:01:16.423271 | controller | ok: Runtime: 0:00:00.042964
2026-09-15 17:01:16.436316 | 
2026-09-15 17:01:16.436684 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects]
2026-09-15 17:01:16.735566 | controller | changed
2026-09-15 17:01:16.748361 | 
2026-09-15 17:01:16.748544 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects]
2026-09-15 17:01:17.062169 | controller | environment: line 5: kubectl: command not found
2026-09-15 17:01:17.063409 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-15 17:01:17.064169 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-15 17:01:17.064418 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-15 17:01:17.300019 | controller | ok: Runtime: 0:00:00.014871
2026-09-15 17:01:17.311868 | 
2026-09-15 17:01:17.311989 | TASK [describe-kubernetes-objects : Downloads logs to executor]
2026-09-15 17:01:18.025152 | controller | changed:
2026-09-15 17:01:18.025383 | controller | created directory /var/lib/zuul/builds/949e4c21d5ab48f89affda2024675766/work/logs/controller
2026-09-15 17:01:18.025420 | controller | cd+++++++++ objects/
2026-09-15 17:01:18.025444 | controller | cd+++++++++ objects/cluster/
2026-09-15 17:01:18.025471 | controller | cd+++++++++ objects/namespaced/
2026-09-15 17:01:18.039033 | 
2026-09-15 17:01:18.039118 | TASK [gather-pod-logs : creating directory for pod logs]
2026-09-15 17:01:18.313767 | controller | changed
2026-09-15 17:01:18.320549 | 
2026-09-15 17:01:18.320646 | TASK [gather-pod-logs : creating directory for failed pod logs]
2026-09-15 17:01:18.599221 | controller | changed
2026-09-15 17:01:18.609100 | 
2026-09-15 17:01:18.609218 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)]
2026-09-15 17:01:18.926187 | controller | environment: line 3: kubectl: command not found
2026-09-15 17:01:19.165448 | controller | ok: Runtime: 0:00:00.014721
2026-09-15 17:01:19.218700 | 
2026-09-15 17:01:19.218892 | TASK [gather-pod-logs : Downloads pod logs to executor]
2026-09-15 17:01:19.787702 | controller | changed:
2026-09-15 17:01:19.788009 | controller | cd+++++++++ pod-logs/
2026-09-15 17:01:19.788064 | controller | cd+++++++++ pod-logs/failed-pods/
2026-09-15 17:01:19.803491 | 
2026-09-15 17:01:19.803595 | LOOP [helm-release-status : creating directory for helm release status]
2026-09-15 17:01:20.097978 | controller | changed: "values"
2026-09-15 17:01:20.334706 | controller | changed: "releases"
2026-09-15 17:01:20.356380 | 
2026-09-15 17:01:20.356704 | TASK [helm-release-status : Gather get release status for helm charts]
2026-09-15 17:01:20.646283 | controller | /bin/bash: line 3: kubectl: command not found
2026-09-15 17:01:20.911683 | controller | ok: Runtime: 0:00:00.010001
2026-09-15 17:01:20.922723 | 
2026-09-15 17:01:20.922855 | TASK [helm-release-status : Downloads logs to executor]
2026-09-15 17:01:21.463174 | controller | changed:
2026-09-15 17:01:21.463451 | controller | cd+++++++++ helm/
2026-09-15 17:01:21.463509 | controller | cd+++++++++ helm/releases/
2026-09-15 17:01:21.463555 | controller | cd+++++++++ helm/values/
2026-09-15 17:01:21.475720 | 
2026-09-15 17:01:21.475861 | PLAY RECAP
2026-09-15 17:01:21.476056 | controller | ok: 12 changed: 12 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-15 17:01:21.476106 | 
2026-09-15 17:01:21.622835 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/post.yml@main]
2026-09-15 17:01:21.625074 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-09-15 17:01:22.214809 | 
2026-09-15 17:01:22.214980 | PLAY [all]
2026-09-15 17:01:22.226168 | 
2026-09-15 17:01:22.226244 | TASK [fetch-output : Set log path for multiple nodes]
2026-09-15 17:01:22.281085 | controller | skipping: Conditional result was False
2026-09-15 17:01:22.288566 | 
2026-09-15 17:01:22.288685 | TASK [fetch-output : Set log path for single node]
2026-09-15 17:01:22.344876 | controller | ok
2026-09-15 17:01:22.353497 | 
2026-09-15 17:01:22.353584 | LOOP [fetch-output : Ensure local output dirs]
2026-09-15 17:01:22.736333 | controller -> localhost | ok: "/var/lib/zuul/builds/949e4c21d5ab48f89affda2024675766/work/logs"
2026-09-15 17:01:22.954045 | controller -> localhost | changed: "/var/lib/zuul/builds/949e4c21d5ab48f89affda2024675766/work/artifacts"
2026-09-15 17:01:23.179696 | controller -> localhost | changed: "/var/lib/zuul/builds/949e4c21d5ab48f89affda2024675766/work/docs"
2026-09-15 17:01:23.209799 | 
2026-09-15 17:01:23.210039 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-09-15 17:01:23.902241 | controller | changed: .d..t...... ./
2026-09-15 17:01:23.902440 | controller | changed: All items complete
2026-09-15 17:01:23.902465 | 
2026-09-15 17:01:24.438382 | controller | changed: .d..t...... ./
2026-09-15 17:01:24.957120 | controller | changed: .d..t...... ./
2026-09-15 17:01:24.988449 | 
2026-09-15 17:01:24.988729 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-09-15 17:01:25.486516 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.040873
2026-09-15 17:01:25.741625 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.006507
2026-09-15 17:01:25.755426 | 
2026-09-15 17:01:25.755478 | PLAY [all]
2026-09-15 17:01:25.760868 | 
2026-09-15 17:01:25.760927 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-09-15 17:01:26.264187 | controller | changed
2026-09-15 17:01:26.280193 | 
2026-09-15 17:01:26.280322 | PLAY RECAP
2026-09-15 17:01:26.280439 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-09-15 17:01:26.280536 | 
2026-09-15 17:01:26.415088 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-09-15 17:01:26.416959 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-09-15 17:01:26.916485 | 
2026-09-15 17:01:26.916620 | PLAY [localhost]
2026-09-15 17:01:26.925527 | 
2026-09-15 17:01:26.925594 | TASK [Generate Zuul manifest]
2026-09-15 17:01:26.942420 | localhost | ok
2026-09-15 17:01:26.986695 | 
2026-09-15 17:01:26.986776 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-09-15 17:01:27.410261 | localhost | changed
2026-09-15 17:01:27.426690 | 
2026-09-15 17:01:27.426800 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-09-15 17:01:27.456460 | localhost | ok
2026-09-15 17:01:27.465017 | 
2026-09-15 17:01:27.465104 | TASK [Upload logs]
2026-09-15 17:01:27.482690 | localhost | ok
2026-09-15 17:01:27.543519 | 
2026-09-15 17:01:27.543650 | TASK [Set zuul-log-path fact]
2026-09-15 17:01:27.560602 | localhost | ok
2026-09-15 17:01:27.570483 | 
2026-09-15 17:01:27.570548 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-09-15 17:01:27.608119 | localhost | ok
2026-09-15 17:01:27.617521 | 
2026-09-15 17:01:27.617604 | TASK [upload-logs : Create log directories]
2026-09-15 17:01:28.064902 | localhost | changed
2026-09-15 17:01:28.075286 | 
2026-09-15 17:01:28.075438 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-09-15 17:01:28.451716 | localhost -> localhost | ok: Runtime: 0:00:00.004679
2026-09-15 17:01:28.461879 | 
2026-09-15 17:01:28.462348 | TASK [upload-logs : Upload logs to log server]
2026-09-15 17:01:29.000683 | localhost | Output suppressed because no_log was given
2026-09-15 17:01:29.007503 | 
2026-09-15 17:01:29.007662 | LOOP [upload-logs : Compress console log and json output]
2026-09-15 17:01:29.072484 | localhost | skipping: Conditional result was False
2026-09-15 17:01:29.123568 | localhost | skipping: Conditional result was False
2026-09-15 17:01:29.132767 | 
2026-09-15 17:01:29.132956 | LOOP [upload-logs : Upload compressed console log and json output]
2026-09-15 17:01:29.193758 | localhost | skipping: Conditional result was False
2026-09-15 17:01:29.194135 | 
2026-09-15 17:01:29.199172 | localhost | skipping: Conditional result was False
2026-09-15 17:01:29.216295 | 
2026-09-15 17:01:29.216557 | LOOP [upload-logs : Upload console log and json output]