2026-09-07 01:52:20.570424 | Job console starting
2026-09-07 01:52:20.582145 | Updating git repos
2026-09-07 01:52:20.638632 | Cloning repos into workspace
2026-09-07 01:52:20.688877 | Restoring repo states
2026-09-07 01:52:20.714830 | Merging changes
2026-09-07 01:52:21.699817 | Checking out repos
2026-09-07 01:52:21.839460 | Preparing playbooks
2026-09-07 01:52:25.088250 | Running Ansible setup
2026-09-07 01:52:28.697105 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-09-07 01:52:29.290691 | 
2026-09-07 01:52:29.290855 | PLAY [localhost]
2026-09-07 01:52:29.298624 | 
2026-09-07 01:52:29.298691 | TASK [Gathering Facts]
2026-09-07 01:52:30.252880 | localhost | ok
2026-09-07 01:52:30.265983 | 
2026-09-07 01:52:30.266129 | TASK [Setup log path fact]
2026-09-07 01:52:30.292469 | localhost | ok
2026-09-07 01:52:30.312169 | 
2026-09-07 01:52:30.312251 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-09-07 01:52:30.352257 | localhost | ok
2026-09-07 01:52:30.364098 | 
2026-09-07 01:52:30.364236 | TASK [emit-job-header : Print job information]
2026-09-07 01:52:30.417120 | # Job Information
2026-09-07 01:52:30.417400 | Ansible Version: 2.16.18
2026-09-07 01:52:30.417467 | Job: ansible-collection-kubernetes-molecule-upload-helm-chart
2026-09-07 01:52:30.417511 | Pipeline: check
2026-09-07 01:52:30.417550 | Executor: 2d72f0692154
2026-09-07 01:52:30.417588 | Triggered by: https://github.com/vexxhost/ansible-collection-kubernetes/pull/300
2026-09-07 01:52:30.417656 | Event ID: 1a3e6320-aa5e-11f1-83e1-f73eae392cf5
2026-09-07 01:52:30.423570 | 
2026-09-07 01:52:30.423734 | LOOP [emit-job-header : Print node information]
2026-09-07 01:52:30.525229 | localhost | ok:
2026-09-07 01:52:30.525464 | localhost | # Node Information
2026-09-07 01:52:30.525491 | localhost | Inventory Hostname: controller
2026-09-07 01:52:30.525511 | localhost | Hostname: np0000192000
2026-09-07 01:52:30.525535 | localhost | Username: zuul
2026-09-07 01:52:30.525564 | localhost | Distro: Debian 13.6
2026-09-07 01:52:30.525592 | localhost | Provider: yul1
2026-09-07 01:52:30.525627 | localhost | Region: ca-ymq-1
2026-09-07 01:52:30.525644 | localhost | Label: debian-trixie
2026-09-07 01:52:30.525660 | localhost | Product Name: OpenStack Nova
2026-09-07 01:52:30.525676 | localhost | Interface IP: 162.253.55.210
2026-09-07 01:52:30.538508 | 
2026-09-07 01:52:30.538574 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-09-07 01:52:30.965245 | localhost -> localhost | changed
2026-09-07 01:52:30.971427 | 
2026-09-07 01:52:30.971519 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-09-07 01:52:31.979259 | localhost -> localhost | changed
2026-09-07 01:52:31.991614 | 
2026-09-07 01:52:31.991722 | PLAY [all]
2026-09-07 01:52:32.005374 | 
2026-09-07 01:52:32.005518 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-09-07 01:52:32.254417 | controller -> localhost | ok
2026-09-07 01:52:32.263478 | 
2026-09-07 01:52:32.263648 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-09-07 01:52:32.313057 | controller | ok
2026-09-07 01:52:32.335956 | controller | included: /var/lib/zuul/builds/54288937300c4355bdb8828b0d5946f7/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-09-07 01:52:32.341813 | 
2026-09-07 01:52:32.341892 | TASK [add-build-sshkey : Create Temp SSH key]
2026-09-07 01:52:33.354796 | controller -> localhost | Generating public/private rsa key pair.
2026-09-07 01:52:33.355002 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/54288937300c4355bdb8828b0d5946f7/work/54288937300c4355bdb8828b0d5946f7_id_rsa
2026-09-07 01:52:33.355040 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/54288937300c4355bdb8828b0d5946f7/work/54288937300c4355bdb8828b0d5946f7_id_rsa.pub
2026-09-07 01:52:33.355068 | controller -> localhost | The key fingerprint is:
2026-09-07 01:52:33.355095 | controller -> localhost | SHA256:uZcAg67j3KR2/3ZVx68ynm+2Ab/V0oPQVg/hlDx48WA zuul-build-sshkey
2026-09-07 01:52:33.355139 | controller -> localhost | The key's randomart image is:
2026-09-07 01:52:33.355670 | controller -> localhost | +---[RSA 3072]----+
2026-09-07 01:52:33.355726 | controller -> localhost | |             oEo |
2026-09-07 01:52:33.355756 | controller -> localhost | |     .      .+=+ |
2026-09-07 01:52:33.355781 | controller -> localhost | |    . o      .o+.|
2026-09-07 01:52:33.355804 | controller -> localhost | |   .   o .  . o.+|
2026-09-07 01:52:33.355827 | controller -> localhost | |    .   S  ..+ .o|
2026-09-07 01:52:33.355850 | controller -> localhost | |   .     o .+o..o|
2026-09-07 01:52:33.355873 | controller -> localhost | |  o .   . o. .+o+|
2026-09-07 01:52:33.355896 | controller -> localhost | | o.=.   ... o.+=.|
2026-09-07 01:52:33.355922 | controller -> localhost | | .+....o.. .o*+. |
2026-09-07 01:52:33.355946 | controller -> localhost | +----[SHA256]-----+
2026-09-07 01:52:33.356008 | controller -> localhost | ok: Runtime: 0:00:00.559769
2026-09-07 01:52:33.361311 | 
2026-09-07 01:52:33.361434 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-09-07 01:52:33.390870 | controller | ok
2026-09-07 01:52:33.397978 | controller | included: /var/lib/zuul/builds/54288937300c4355bdb8828b0d5946f7/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-09-07 01:52:33.403484 | 
2026-09-07 01:52:33.403543 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-09-07 01:52:33.427921 | controller | skipping: Conditional result was False
2026-09-07 01:52:33.432808 | 
2026-09-07 01:52:33.432870 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-09-07 01:52:33.969756 | controller | changed
2026-09-07 01:52:33.973304 | 
2026-09-07 01:52:33.973363 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-09-07 01:52:34.194282 | controller | ok
2026-09-07 01:52:34.199131 | 
2026-09-07 01:52:34.199207 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-09-07 01:52:34.764447 | controller | changed
2026-09-07 01:52:34.771965 | 
2026-09-07 01:52:34.772048 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-09-07 01:52:35.394875 | controller | changed
2026-09-07 01:52:35.407163 | 
2026-09-07 01:52:35.407321 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-09-07 01:52:35.435565 | controller | skipping: Conditional result was False
2026-09-07 01:52:35.446831 | 
2026-09-07 01:52:35.446974 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-09-07 01:52:35.874906 | controller -> localhost | changed
2026-09-07 01:52:35.892227 | 
2026-09-07 01:52:35.892369 | TASK [add-build-sshkey : Add back temp key]
2026-09-07 01:52:36.231887 | controller -> localhost | Identity added: /var/lib/zuul/builds/54288937300c4355bdb8828b0d5946f7/work/54288937300c4355bdb8828b0d5946f7_id_rsa (zuul-build-sshkey)
2026-09-07 01:52:36.232253 | controller -> localhost | ok: Runtime: 0:00:00.017366
2026-09-07 01:52:36.239669 | 
2026-09-07 01:52:36.239751 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-09-07 01:52:36.628129 | controller | ok
2026-09-07 01:52:36.634303 | 
2026-09-07 01:52:36.634384 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-09-07 01:52:36.669637 | controller | skipping: Conditional result was False
2026-09-07 01:52:36.679357 | 
2026-09-07 01:52:36.679440 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-09-07 01:52:37.093409 | controller | ok
2026-09-07 01:52:37.102876 | 
2026-09-07 01:52:37.102988 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-09-07 01:52:38.236416 | controller | Output suppressed because no_log was given
2026-09-07 01:52:38.245562 | 
2026-09-07 01:52:38.245663 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-09-07 01:52:38.486817 | controller | ok: "logs"
2026-09-07 01:52:38.487214 | controller | ok: All items complete
2026-09-07 01:52:38.487293 | 
2026-09-07 01:52:38.667606 | controller | ok: "artifacts"
2026-09-07 01:52:38.852983 | controller | ok: "docs"
2026-09-07 01:52:38.867978 | 
2026-09-07 01:52:38.868199 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-09-07 01:52:39.087325 | controller | changed: "logs"
2026-09-07 01:52:39.256662 | controller | changed: "artifacts"
2026-09-07 01:52:39.434723 | controller | changed: "docs"
2026-09-07 01:52:39.449312 | 
2026-09-07 01:52:39.449486 | PLAY RECAP
2026-09-07 01:52:39.449575 | controller | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-09-07 01:52:39.449656 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-07 01:52:39.449697 | 
2026-09-07 01:52:39.682889 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-09-07 01:52:39.689786 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-09-07 01:52:40.319746 | 
2026-09-07 01:52:40.320525 | PLAY [all]
2026-09-07 01:52:40.330888 | 
2026-09-07 01:52:40.330984 | TASK [setup-uv : Extract archive]
2026-09-07 01:52:42.813622 | controller | changed
2026-09-07 01:52:42.823974 | 
2026-09-07 01:52:42.824152 | TASK [setup-uv : Print version]
2026-09-07 01:52:43.234657 | controller | uv 0.8.13
2026-09-07 01:52:43.362893 | controller | ok: Runtime: 0:00:00.014232
2026-09-07 01:52:43.376743 | 
2026-09-07 01:52:43.376880 | TASK [Install Ansible collection siblings]
2026-09-07 01:52:43.833358 | controller | ok:
2026-09-07 01:52:43.833660 | controller | Skipping ansible.posix: no checked-out sibling
2026-09-07 01:52:43.833724 | controller | Skipping community.crypto: no checked-out sibling
2026-09-07 01:52:43.833770 | controller | Skipping community.general: no checked-out sibling
2026-09-07 01:52:43.833812 | controller | Skipping kubernetes.core: no checked-out sibling
2026-09-07 01:52:43.833852 | controller | Skipping vexxhost.containers: no checked-out sibling
2026-09-07 01:52:43.836828 | 
2026-09-07 01:52:43.836919 | PLAY RECAP
2026-09-07 01:52:43.837015 | controller | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-07 01:52:43.837061 | 
2026-09-07 01:52:44.015132 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-09-07 01:52:44.018129 | PRE-RUN START: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/pre.yml@main]
2026-09-07 01:52:44.634203 | 
2026-09-07 01:52:44.634355 | PLAY [all]
2026-09-07 01:52:44.644339 | 
2026-09-07 01:52:44.644406 | TASK [Update apt cache]
2026-09-07 01:52:49.724753 | controller | ok
2026-09-07 01:52:49.732458 | 
2026-09-07 01:52:49.732664 | TASK [Disable firewalld]
2026-09-07 01:52:49.758413 | controller | skipping: Conditional result was False
2026-09-07 01:52:49.766345 | 
2026-09-07 01:52:49.766478 | TASK [Install "jq" for log collection]
2026-09-07 01:52:52.569840 | controller | changed
2026-09-07 01:52:52.572983 | 
2026-09-07 01:52:52.573075 | PLAY RECAP
2026-09-07 01:52:52.573160 | controller | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-09-07 01:52:52.573202 | 
2026-09-07 01:52:52.711647 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/pre.yml@main]
2026-09-07 01:52:52.714357 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-09-07 01:52:53.260975 | 
2026-09-07 01:52:53.261093 | PLAY [all]
2026-09-07 01:52:53.270271 | 
2026-09-07 01:52:53.270345 | LOOP [Check inventory directories]
2026-09-07 01:52:53.623627 | controller | ok: "src/github.com/vexxhost/ansible-collection-kubernetes"
2026-09-07 01:52:53.820605 | controller | ok: "src/github.com/vexxhost/ansible-collection-kubernetes/molecule/upload-helm-chart/inventory"
2026-09-07 01:52:54.012176 | controller | ok: "src/github.com/vexxhost/ansible-collection-kubernetes/extensions/molecule/upload-helm-chart/inventory"
2026-09-07 01:52:54.033704 | 
2026-09-07 01:52:54.034016 | LOOP [Copy inventory file for Zuul]
2026-09-07 01:52:54.967187 | controller | changed:
2026-09-07 01:52:54.967495 | controller | {
2026-09-07 01:52:54.967542 | controller |   "ansible_loop_var": "item",
2026-09-07 01:52:54.967591 | controller |   "changed": false,
2026-09-07 01:52:54.967618 | controller |   "failed": false,
2026-09-07 01:52:54.967646 | controller |   "invocation": {
2026-09-07 01:52:54.967670 | controller |     "module_args": {
2026-09-07 01:52:54.967693 | controller |       "checksum_algorithm": "sha1",
2026-09-07 01:52:54.967715 | controller |       "follow": true,
2026-09-07 01:52:54.967737 | controller |       "get_attributes": true,
2026-09-07 01:52:54.967762 | controller |       "get_checksum": true,
2026-09-07 01:52:54.967784 | controller |       "get_mime": true,
2026-09-07 01:52:54.967806 | controller |       "path": "src/github.com/vexxhost/ansible-collection-kubernetes"
2026-09-07 01:52:54.967830 | controller |     }
2026-09-07 01:52:54.967852 | controller |   },
2026-09-07 01:52:54.967874 | controller |   "item": "src/github.com/vexxhost/ansible-collection-kubernetes",
2026-09-07 01:52:54.967896 | controller |   "stat": {
2026-09-07 01:52:54.967918 | controller |     "atime": 1788745958.0766408,
2026-09-07 01:52:54.967944 | controller |     "attr_flags": "e",
2026-09-07 01:52:54.967967 | controller |     "attributes": [
2026-09-07 01:52:54.967989 | controller |       "extents"
2026-09-07 01:52:54.968011 | controller |     ],
2026-09-07 01:52:54.968033 | controller |     "block_size": 4096,
2026-09-07 01:52:54.968055 | controller |     "blocks": 8,
2026-09-07 01:52:54.968077 | controller |     "charset": "unknown",
2026-09-07 01:52:54.968100 | controller |     "ctime": 1788745958.1166449,
2026-09-07 01:52:54.968122 | controller |     "dev": 65025,
2026-09-07 01:52:54.968144 | controller |     "device_type": 0,
2026-09-07 01:52:54.968165 | controller |     "executable": true,
2026-09-07 01:52:54.968187 | controller |     "exists": true,
2026-09-07 01:52:54.968208 | controller |     "gid": 1000,
2026-09-07 01:52:54.968230 | controller |     "gr_name": "zuul",
2026-09-07 01:52:54.968251 | controller |     "inode": 521495,
2026-09-07 01:52:54.968273 | controller |     "isblk": false,
2026-09-07 01:52:54.968295 | controller |     "ischr": false,
2026-09-07 01:52:54.968316 | controller |     "isdir": true,
2026-09-07 01:52:54.968338 | controller |     "isfifo": false,
2026-09-07 01:52:54.968359 | controller |     "isgid": false,
2026-09-07 01:52:54.968381 | controller |     "islnk": false,
2026-09-07 01:52:54.968402 | controller |     "isreg": false,
2026-09-07 01:52:54.968423 | controller |     "issock": false,
2026-09-07 01:52:54.968443 | controller |     "isuid": false,
2026-09-07 01:52:54.968464 | controller |     "mimetype": "unknown",
2026-09-07 01:52:54.968485 | controller |     "mode": "0755",
2026-09-07 01:52:54.968534 | controller |     "mtime": 1788745941.1692293,
2026-09-07 01:52:54.968557 | controller |     "nlink": 14,
2026-09-07 01:52:54.968593 | controller |     "path": "src/github.com/vexxhost/ansible-collection-kubernetes",
2026-09-07 01:52:54.968615 | controller |     "pw_name": "zuul",
2026-09-07 01:52:54.968640 | controller |     "readable": true,
2026-09-07 01:52:54.968663 | controller |     "rgrp": true,
2026-09-07 01:52:54.968685 | controller |     "roth": true,
2026-09-07 01:52:54.968707 | controller |     "rusr": true,
2026-09-07 01:52:54.968729 | controller |     "size": 4096,
2026-09-07 01:52:54.968750 | controller |     "uid": 1000,
2026-09-07 01:52:54.968780 | controller |     "version": "1025833725",
2026-09-07 01:52:54.968803 | controller |     "wgrp": false,
2026-09-07 01:52:54.968826 | controller |     "woth": false,
2026-09-07 01:52:54.968848 | controller |     "writeable": true,
2026-09-07 01:52:54.968870 | controller |     "wusr": true,
2026-09-07 01:52:54.968891 | controller |     "xgrp": true,
2026-09-07 01:52:54.968913 | controller |     "xoth": true,
2026-09-07 01:52:54.968934 | controller |     "xusr": true
2026-09-07 01:52:54.968969 | controller |   }
2026-09-07 01:52:54.968992 | controller | }
2026-09-07 01:52:54.969037 | controller | ok: All items complete
2026-09-07 01:52:54.969063 | 
2026-09-07 01:52:54.976070 | controller | skipping: Conditional result was False
2026-09-07 01:52:54.981217 | controller | skipping: Conditional result was False
2026-09-07 01:52:54.994120 | 
2026-09-07 01:52:54.994306 | LOOP [Switch "ansible_host" to private IP]
2026-09-07 01:52:55.358718 | controller | changed: 1 replacements made
2026-09-07 01:52:55.361014 | controller | skipping: Conditional result was False
2026-09-07 01:52:55.362256 | controller | skipping: Conditional result was False
2026-09-07 01:52:55.379213 | 
2026-09-07 01:52:55.379440 | TASK [Run Molecule scenario]
2026-09-07 01:52:55.875296 | controller | Using CPython 3.13.5 interpreter at: /usr/bin/python3
2026-09-07 01:52:55.875449 | controller | Creating virtual environment at: .venv
2026-09-07 01:52:56.175615 | controller | Downloading cryptography (4.3MiB)
2026-09-07 01:52:56.176087 | controller | Downloading pygments (1.2MiB)
2026-09-07 01:52:56.176301 | controller | Downloading ansible-core (2.3MiB)
2026-09-07 01:52:56.376674 | controller |  Downloading pygments
2026-09-07 01:53:26.333869 | controller |   × Failed to download `cryptography==46.0.3`
2026-09-07 01:53:26.333932 | controller |   ├─▶ Failed to extract archive:
2026-09-07 01:53:26.333943 | controller |   │   cryptography-46.0.3-cp311-abi3-manylinux_2_34_x86_64.whl
2026-09-07 01:53:26.333952 | controller |   ├─▶ I/O operation failed during extraction
2026-09-07 01:53:26.333963 | controller |   ╰─▶ Failed to download distribution due to network timeout. Try increasing
2026-09-07 01:53:26.333987 | controller |       UV_HTTP_TIMEOUT (current value: 30s).
2026-09-07 01:53:26.333996 | controller |   help: `cryptography` (v46.0.3) was included because
2026-09-07 01:53:26.334004 | controller |         `ansible-collection-kubernetes` (v3.3.0) depends on `ansible-core`
2026-09-07 01:53:26.334012 | controller |         (v2.20.1) which depends on `cryptography`
2026-09-07 01:53:26.435940 | controller | ERROR
2026-09-07 01:53:26.436238 | controller | {
2026-09-07 01:53:26.436298 | controller |   "delta": "0:00:30.584784",
2026-09-07 01:53:26.436340 | controller |   "end": "2026-09-07 01:53:26.344923",
2026-09-07 01:53:26.436377 | controller |   "msg": "non-zero return code",
2026-09-07 01:53:26.436412 | controller |   "rc": 1,
2026-09-07 01:53:26.436448 | controller |   "start": "2026-09-07 01:52:55.760139"
2026-09-07 01:53:26.436483 | controller | }
failure
2026-09-07 01:53:26.439487 | 
2026-09-07 01:53:26.439612 | PLAY RECAP
2026-09-07 01:53:26.439698 | controller | ok: 3 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-09-07 01:53:26.439741 | 
2026-09-07 01:53:26.655296 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-09-07 01:53:26.656813 | POST-RUN START: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/post.yml@main]
2026-09-07 01:53:27.215148 | 
2026-09-07 01:53:27.215282 | PLAY [all]
2026-09-07 01:53:27.225292 | 
2026-09-07 01:53:27.225367 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects]
2026-09-07 01:53:27.638292 | controller | changed
2026-09-07 01:53:27.643933 | 
2026-09-07 01:53:27.644032 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects]
2026-09-07 01:53:28.040782 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-07 01:53:28.040885 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-07 01:53:28.048099 | controller | environment: line 1: kubectl: command not found
2026-09-07 01:53:28.050421 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-07 01:53:28.053951 | controller | environment: line 1: kubectl: command not found
2026-09-07 01:53:28.054790 | controller | environment: line 1: kubectl: command not found
2026-09-07 01:53:28.058844 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-07 01:53:28.058902 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-07 01:53:28.060681 | controller | environment: line 1: kubectl: command not found
2026-09-07 01:53:28.062618 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-07 01:53:28.062712 | controller | environment: line 1: kubectl: command not found
2026-09-07 01:53:28.065114 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-07 01:53:28.173910 | controller | ok: Runtime: 0:00:00.035549
2026-09-07 01:53:28.182359 | 
2026-09-07 01:53:28.182490 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects]
2026-09-07 01:53:28.428741 | controller | changed
2026-09-07 01:53:28.436555 | 
2026-09-07 01:53:28.436706 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects]
2026-09-07 01:53:28.725634 | controller | environment: line 5: kubectl: command not found
2026-09-07 01:53:28.727826 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-07 01:53:28.727844 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-07 01:53:28.727856 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-07 01:53:28.976189 | controller | ok: Runtime: 0:00:00.016033
2026-09-07 01:53:28.985558 | 
2026-09-07 01:53:28.985693 | TASK [describe-kubernetes-objects : Downloads logs to executor]
2026-09-07 01:53:29.723749 | controller | changed:
2026-09-07 01:53:29.723987 | controller | created directory /var/lib/zuul/builds/54288937300c4355bdb8828b0d5946f7/work/logs/controller
2026-09-07 01:53:29.724061 | controller | cd+++++++++ objects/
2026-09-07 01:53:29.724106 | controller | cd+++++++++ objects/cluster/
2026-09-07 01:53:29.724151 | controller | cd+++++++++ objects/namespaced/
2026-09-07 01:53:29.737991 | 
2026-09-07 01:53:29.738086 | TASK [gather-pod-logs : creating directory for pod logs]
2026-09-07 01:53:29.992719 | controller | changed
2026-09-07 01:53:30.002409 | 
2026-09-07 01:53:30.002571 | TASK [gather-pod-logs : creating directory for failed pod logs]
2026-09-07 01:53:30.264045 | controller | changed
2026-09-07 01:53:30.273154 | 
2026-09-07 01:53:30.273304 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)]
2026-09-07 01:53:30.563236 | controller | environment: line 3: kubectl: command not found
2026-09-07 01:53:30.807573 | controller | ok: Runtime: 0:00:00.012564
2026-09-07 01:53:30.816735 | 
2026-09-07 01:53:30.816876 | TASK [gather-pod-logs : Downloads pod logs to executor]
2026-09-07 01:53:31.327301 | controller | changed:
2026-09-07 01:53:31.327582 | controller | cd+++++++++ pod-logs/
2026-09-07 01:53:31.327644 | controller | cd+++++++++ pod-logs/failed-pods/
2026-09-07 01:53:31.344083 | 
2026-09-07 01:53:31.344217 | LOOP [helm-release-status : creating directory for helm release status]
2026-09-07 01:53:31.598923 | controller | changed: "values"
2026-09-07 01:53:31.792025 | controller | changed: "releases"
2026-09-07 01:53:31.808927 | 
2026-09-07 01:53:31.809115 | TASK [helm-release-status : Gather get release status for helm charts]
2026-09-07 01:53:32.097859 | controller | /bin/bash: line 3: kubectl: command not found
2026-09-07 01:53:32.352154 | controller | ok: Runtime: 0:00:00.008640
2026-09-07 01:53:32.361769 | 
2026-09-07 01:53:32.361924 | TASK [helm-release-status : Downloads logs to executor]
2026-09-07 01:53:32.899859 | controller | changed:
2026-09-07 01:53:32.900110 | controller | cd+++++++++ helm/
2026-09-07 01:53:32.900165 | controller | cd+++++++++ helm/releases/
2026-09-07 01:53:32.900212 | controller | cd+++++++++ helm/values/
2026-09-07 01:53:32.908645 | 
2026-09-07 01:53:32.908738 | PLAY RECAP
2026-09-07 01:53:32.908826 | controller | ok: 12 changed: 12 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-07 01:53:32.908867 | 
2026-09-07 01:53:33.032173 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/post.yml@main]
2026-09-07 01:53:33.033949 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-09-07 01:53:33.593237 | 
2026-09-07 01:53:33.593374 | PLAY [all]
2026-09-07 01:53:33.602364 | 
2026-09-07 01:53:33.602432 | TASK [fetch-output : Set log path for multiple nodes]
2026-09-07 01:53:33.666491 | controller | skipping: Conditional result was False
2026-09-07 01:53:33.672103 | 
2026-09-07 01:53:33.672199 | TASK [fetch-output : Set log path for single node]
2026-09-07 01:53:33.722523 | controller | ok
2026-09-07 01:53:33.729452 | 
2026-09-07 01:53:33.729597 | LOOP [fetch-output : Ensure local output dirs]
2026-09-07 01:53:34.141622 | controller -> localhost | ok: "/var/lib/zuul/builds/54288937300c4355bdb8828b0d5946f7/work/logs"
2026-09-07 01:53:34.359935 | controller -> localhost | changed: "/var/lib/zuul/builds/54288937300c4355bdb8828b0d5946f7/work/artifacts"
2026-09-07 01:53:34.589105 | controller -> localhost | changed: "/var/lib/zuul/builds/54288937300c4355bdb8828b0d5946f7/work/docs"
2026-09-07 01:53:34.606617 | 
2026-09-07 01:53:34.606837 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-09-07 01:53:35.324985 | controller | changed: .d..t...... ./
2026-09-07 01:53:35.325191 | controller | changed: All items complete
2026-09-07 01:53:35.325216 | 
2026-09-07 01:53:35.884030 | controller | changed: .d..t...... ./
2026-09-07 01:53:36.381924 | controller | changed: .d..t...... ./
2026-09-07 01:53:36.401153 | 
2026-09-07 01:53:36.401299 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-09-07 01:53:36.880130 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.008350
2026-09-07 01:53:37.176644 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.009442
2026-09-07 01:53:37.192685 | 
2026-09-07 01:53:37.192888 | PLAY [all]
2026-09-07 01:53:37.204228 | 
2026-09-07 01:53:37.204381 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-09-07 01:53:37.688670 | controller | changed
2026-09-07 01:53:37.697088 | 
2026-09-07 01:53:37.697222 | PLAY RECAP
2026-09-07 01:53:37.697308 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-09-07 01:53:37.697350 | 
2026-09-07 01:53:37.894035 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-09-07 01:53:37.897156 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-09-07 01:53:38.469068 | 
2026-09-07 01:53:38.469192 | PLAY [localhost]
2026-09-07 01:53:38.478920 | 
2026-09-07 01:53:38.478989 | TASK [Generate Zuul manifest]
2026-09-07 01:53:38.495374 | localhost | ok
2026-09-07 01:53:38.508324 | 
2026-09-07 01:53:38.508406 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-09-07 01:53:39.029747 | localhost | changed
2026-09-07 01:53:39.062136 | 
2026-09-07 01:53:39.062948 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-09-07 01:53:39.107340 | localhost | ok
2026-09-07 01:53:39.116470 | 
2026-09-07 01:53:39.116586 | TASK [Upload logs]
2026-09-07 01:53:39.136898 | localhost | ok
2026-09-07 01:53:39.195644 | 
2026-09-07 01:53:39.195785 | TASK [Set zuul-log-path fact]
2026-09-07 01:53:39.213124 | localhost | ok
2026-09-07 01:53:39.223615 | 
2026-09-07 01:53:39.223728 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-09-07 01:53:39.262490 | localhost | ok
2026-09-07 01:53:39.271997 | 
2026-09-07 01:53:39.272085 | TASK [upload-logs : Create log directories]
2026-09-07 01:53:39.745288 | localhost | changed
2026-09-07 01:53:39.757364 | 
2026-09-07 01:53:39.757452 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-09-07 01:53:40.184830 | localhost -> localhost | ok: Runtime: 0:00:00.004475
2026-09-07 01:53:40.194340 | 
2026-09-07 01:53:40.194472 | TASK [upload-logs : Upload logs to log server]
2026-09-07 01:53:40.733673 | localhost | Output suppressed because no_log was given
2026-09-07 01:53:40.741076 | 
2026-09-07 01:53:40.741211 | LOOP [upload-logs : Compress console log and json output]
2026-09-07 01:53:40.802375 | localhost | skipping: Conditional result was False
2026-09-07 01:53:40.853187 | localhost | skipping: Conditional result was False
2026-09-07 01:53:40.863965 | 
2026-09-07 01:53:40.864197 | LOOP [upload-logs : Upload compressed console log and json output]
2026-09-07 01:53:40.906670 | localhost | skipping: Conditional result was False
2026-09-07 01:53:40.907148 | 
2026-09-07 01:53:40.909621 | localhost | skipping: Conditional result was False
2026-09-07 01:53:40.920024 | 
2026-09-07 01:53:40.920232 | LOOP [upload-logs : Upload console log and json output]