2026-09-07 01:50:32.267314 | Job console starting
2026-09-07 01:50:32.316832 | Updating git repos
2026-09-07 01:50:32.397607 | Cloning repos into workspace
2026-09-07 01:50:32.445911 | Restoring repo states
2026-09-07 01:50:32.459892 | Merging changes
2026-09-07 01:50:33.089583 | Checking out repos
2026-09-07 01:50:33.189877 | Preparing playbooks
2026-09-07 01:50:35.741661 | Running Ansible setup
2026-09-07 01:50:43.189944 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-09-07 01:50:44.105388 | 
2026-09-07 01:50:44.105618 | PLAY [localhost]
2026-09-07 01:50:44.114753 | 
2026-09-07 01:50:44.114839 | TASK [Gathering Facts]
2026-09-07 01:50:45.052222 | localhost | ok
2026-09-07 01:50:45.067009 | 
2026-09-07 01:50:45.067196 | TASK [Setup log path fact]
2026-09-07 01:50:45.085843 | localhost | ok
2026-09-07 01:50:45.098021 | 
2026-09-07 01:50:45.098110 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-09-07 01:50:45.125860 | localhost | ok
2026-09-07 01:50:45.133314 | 
2026-09-07 01:50:45.133504 | TASK [emit-job-header : Print job information]
2026-09-07 01:50:45.174711 | # Job Information
2026-09-07 01:50:45.175041 | Ansible Version: 2.16.18
2026-09-07 01:50:45.175139 | Job: ansible-collection-kubernetes-molecule-ha
2026-09-07 01:50:45.175188 | Pipeline: check
2026-09-07 01:50:45.175228 | Executor: 2d72f0692154
2026-09-07 01:50:45.175265 | Triggered by: https://github.com/vexxhost/ansible-collection-kubernetes/pull/300
2026-09-07 01:50:45.175308 | Event ID: 1a3e6320-aa5e-11f1-83e1-f73eae392cf5
2026-09-07 01:50:45.181503 | 
2026-09-07 01:50:45.181658 | LOOP [emit-job-header : Print node information]
2026-09-07 01:50:45.270313 | localhost | ok:
2026-09-07 01:50:45.270749 | localhost | # Node Information
2026-09-07 01:50:45.270845 | localhost | Inventory Hostname: controller-1
2026-09-07 01:50:45.270899 | localhost | Hostname: np0000191990
2026-09-07 01:50:45.270947 | localhost | Username: zuul
2026-09-07 01:50:45.271001 | localhost | Distro: Debian 13.6
2026-09-07 01:50:45.271049 | localhost | Provider: yul1
2026-09-07 01:50:45.271094 | localhost | Region: ca-ymq-1
2026-09-07 01:50:45.271138 | localhost | Label: debian-trixie
2026-09-07 01:50:45.271183 | localhost | Product Name: OpenStack Nova
2026-09-07 01:50:45.271226 | localhost | Interface IP: 162.253.55.161
2026-09-07 01:50:45.295357 | localhost | ok:
2026-09-07 01:50:45.295464 | localhost | # Node Information
2026-09-07 01:50:45.295517 | localhost | Inventory Hostname: controller-2
2026-09-07 01:50:45.295558 | localhost | Hostname: np0000191991
2026-09-07 01:50:45.295596 | localhost | Username: zuul
2026-09-07 01:50:45.295633 | localhost | Distro: Debian 13.6
2026-09-07 01:50:45.295668 | localhost | Provider: yul1
2026-09-07 01:50:45.295704 | localhost | Region: ca-ymq-1
2026-09-07 01:50:45.295740 | localhost | Label: debian-trixie
2026-09-07 01:50:45.295796 | localhost | Product Name: OpenStack Nova
2026-09-07 01:50:45.295836 | localhost | Interface IP: 162.253.55.33
2026-09-07 01:50:45.325669 | localhost | ok:
2026-09-07 01:50:45.325793 | localhost | # Node Information
2026-09-07 01:50:45.325848 | localhost | Inventory Hostname: controller-3
2026-09-07 01:50:45.325891 | localhost | Hostname: np0000191992
2026-09-07 01:50:45.325932 | localhost | Username: zuul
2026-09-07 01:50:45.325972 | localhost | Distro: Debian 13.6
2026-09-07 01:50:45.326009 | localhost | Provider: yul1
2026-09-07 01:50:45.326057 | localhost | Region: ca-ymq-1
2026-09-07 01:50:45.326113 | localhost | Label: debian-trixie
2026-09-07 01:50:45.326154 | localhost | Product Name: OpenStack Nova
2026-09-07 01:50:45.326201 | localhost | Interface IP: 162.253.55.166
2026-09-07 01:50:45.346919 | 
2026-09-07 01:50:45.347138 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-09-07 01:50:45.723915 | localhost -> localhost | changed
2026-09-07 01:50:45.734425 | 
2026-09-07 01:50:45.734560 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-09-07 01:50:46.704955 | localhost -> localhost | changed
2026-09-07 01:50:46.721586 | 
2026-09-07 01:50:46.721716 | PLAY [all]
2026-09-07 01:50:46.732816 | 
2026-09-07 01:50:46.732908 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-09-07 01:50:46.955002 | controller-1 -> localhost | ok
2026-09-07 01:50:46.959345 | 
2026-09-07 01:50:46.959404 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-09-07 01:50:46.999702 | controller-1 | ok
2026-09-07 01:50:47.025611 | controller-2 | ok
2026-09-07 01:50:47.058616 | controller-3 | ok
2026-09-07 01:50:47.091391 | controller-1 | included: /var/lib/zuul/builds/ea72482e21fe4024bdb93cf14327f000/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-09-07 01:50:47.091517 | controller-2 | included: /var/lib/zuul/builds/ea72482e21fe4024bdb93cf14327f000/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-09-07 01:50:47.091550 | controller-3 | included: /var/lib/zuul/builds/ea72482e21fe4024bdb93cf14327f000/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-09-07 01:50:47.097074 | 
2026-09-07 01:50:47.097134 | TASK [add-build-sshkey : Create Temp SSH key]
2026-09-07 01:50:48.105128 | controller-1 -> localhost | Generating public/private rsa key pair.
2026-09-07 01:50:48.105318 | controller-1 -> localhost | Your identification has been saved in /var/lib/zuul/builds/ea72482e21fe4024bdb93cf14327f000/work/ea72482e21fe4024bdb93cf14327f000_id_rsa
2026-09-07 01:50:48.105358 | controller-1 -> localhost | Your public key has been saved in /var/lib/zuul/builds/ea72482e21fe4024bdb93cf14327f000/work/ea72482e21fe4024bdb93cf14327f000_id_rsa.pub
2026-09-07 01:50:48.105389 | controller-1 -> localhost | The key fingerprint is:
2026-09-07 01:50:48.105418 | controller-1 -> localhost | SHA256:e7ihRzj4/0sNOu+CeeTWCMBF57dctxDWC/CZSzC16ew zuul-build-sshkey
2026-09-07 01:50:48.105447 | controller-1 -> localhost | The key's randomart image is:
2026-09-07 01:50:48.105475 | controller-1 -> localhost | +---[RSA 3072]----+
2026-09-07 01:50:48.105505 | controller-1 -> localhost | |     .. . +o+.   |
2026-09-07 01:50:48.105534 | controller-1 -> localhost | |      .o   =.*.  |
2026-09-07 01:50:48.105561 | controller-1 -> localhost | |   . .  . . X... |
2026-09-07 01:50:48.105588 | controller-1 -> localhost | |    o    o * +.. |
2026-09-07 01:50:48.105615 | controller-1 -> localhost | |     o .S + + .  |
2026-09-07 01:50:48.105642 | controller-1 -> localhost | |    . + o+ +     |
2026-09-07 01:50:48.105668 | controller-1 -> localhost | |     . O*oo E    |
2026-09-07 01:50:48.105695 | controller-1 -> localhost | |      +.BB.      |
2026-09-07 01:50:48.105722 | controller-1 -> localhost | |      .=o+=.     |
2026-09-07 01:50:48.105749 | controller-1 -> localhost | +----[SHA256]-----+
2026-09-07 01:50:48.105826 | controller-1 -> localhost | ok: Runtime: 0:00:00.581629
2026-09-07 01:50:48.111114 | 
2026-09-07 01:50:48.111194 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-09-07 01:50:48.139050 | controller-1 | ok
2026-09-07 01:50:48.141882 | controller-2 | ok
2026-09-07 01:50:48.154889 | controller-3 | ok
2026-09-07 01:50:48.166957 | controller-1 | included: /var/lib/zuul/builds/ea72482e21fe4024bdb93cf14327f000/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-09-07 01:50:48.167003 | controller-2 | included: /var/lib/zuul/builds/ea72482e21fe4024bdb93cf14327f000/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-09-07 01:50:48.167025 | controller-3 | included: /var/lib/zuul/builds/ea72482e21fe4024bdb93cf14327f000/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-09-07 01:50:48.177562 | 
2026-09-07 01:50:48.177625 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-09-07 01:50:48.198150 | controller-1 | skipping: Conditional result was False
2026-09-07 01:50:48.209423 | controller-2 | skipping: Conditional result was False
2026-09-07 01:50:48.209633 | controller-3 | skipping: Conditional result was False
2026-09-07 01:50:48.215120 | 
2026-09-07 01:50:48.215185 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-09-07 01:50:48.807795 | controller-2 | changed
2026-09-07 01:50:48.821559 | controller-1 | changed
2026-09-07 01:50:48.824362 | controller-3 | changed
2026-09-07 01:50:48.827662 | 
2026-09-07 01:50:48.827721 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-09-07 01:50:49.052152 | controller-2 | ok
2026-09-07 01:50:49.064218 | controller-1 | ok
2026-09-07 01:50:49.071045 | controller-3 | ok
2026-09-07 01:50:49.078746 | 
2026-09-07 01:50:49.078963 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-09-07 01:50:49.645816 | controller-1 | changed
2026-09-07 01:50:49.649275 | controller-3 | changed
2026-09-07 01:50:49.672343 | controller-2 | changed
2026-09-07 01:50:49.676016 | 
2026-09-07 01:50:49.676075 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-09-07 01:50:50.214941 | controller-2 | changed
2026-09-07 01:50:50.233298 | controller-1 | changed
2026-09-07 01:50:50.260522 | controller-3 | changed
2026-09-07 01:50:50.268104 | 
2026-09-07 01:50:50.268232 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-09-07 01:50:50.298432 | controller-1 | skipping: Conditional result was False
2026-09-07 01:50:50.309775 | controller-2 | skipping: Conditional result was False
2026-09-07 01:50:50.320253 | controller-3 | skipping: Conditional result was False
2026-09-07 01:50:50.328484 | 
2026-09-07 01:50:50.328592 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-09-07 01:50:50.680229 | controller-1 -> localhost | changed
2026-09-07 01:50:50.696123 | 
2026-09-07 01:50:50.696268 | TASK [add-build-sshkey : Add back temp key]
2026-09-07 01:50:50.992694 | controller-1 -> localhost | Identity added: /var/lib/zuul/builds/ea72482e21fe4024bdb93cf14327f000/work/ea72482e21fe4024bdb93cf14327f000_id_rsa (zuul-build-sshkey)
2026-09-07 01:50:50.993030 | controller-1 -> localhost | ok: Runtime: 0:00:00.017647
2026-09-07 01:50:51.002136 | 
2026-09-07 01:50:51.002294 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-09-07 01:50:51.299378 | controller-1 | ok
2026-09-07 01:50:51.551133 | controller-3 | ok
2026-09-07 01:50:51.564321 | controller-2 | ok
2026-09-07 01:50:51.576245 | 
2026-09-07 01:50:51.576474 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-09-07 01:50:51.610991 | controller-1 | skipping: Conditional result was False
2026-09-07 01:50:51.623070 | controller-2 | skipping: Conditional result was False
2026-09-07 01:50:51.633537 | controller-3 | skipping: Conditional result was False
2026-09-07 01:50:51.654726 | 
2026-09-07 01:50:51.654829 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-09-07 01:50:51.990338 | controller-3 | ok
2026-09-07 01:50:52.004880 | controller-1 | ok
2026-09-07 01:50:52.011591 | controller-2 | ok
2026-09-07 01:50:52.016012 | 
2026-09-07 01:50:52.016202 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-09-07 01:50:53.083472 | controller-2 | Output suppressed because no_log was given
2026-09-07 01:50:53.115298 | controller-3 | Output suppressed because no_log was given
2026-09-07 01:50:53.133635 | controller-1 | Output suppressed because no_log was given
2026-09-07 01:50:53.161496 | 
2026-09-07 01:50:53.161590 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-09-07 01:50:53.390122 | controller-2 | ok: "logs"
2026-09-07 01:50:53.390746 | controller-2 | ok: All items complete
2026-09-07 01:50:53.394121 | 
2026-09-07 01:50:53.401077 | controller-1 | ok: "logs"
2026-09-07 01:50:53.419710 | controller-3 | ok: "logs"
2026-09-07 01:50:53.580069 | controller-1 | ok: "artifacts"
2026-09-07 01:50:53.600725 | controller-2 | ok: "artifacts"
2026-09-07 01:50:53.618554 | controller-3 | ok: "artifacts"
2026-09-07 01:50:53.778964 | controller-2 | ok: "docs"
2026-09-07 01:50:53.798244 | controller-1 | ok: "docs"
2026-09-07 01:50:53.806192 | controller-3 | ok: "docs"
2026-09-07 01:50:53.817641 | 
2026-09-07 01:50:53.817805 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-09-07 01:50:54.060383 | controller-2 | changed: "logs"
2026-09-07 01:50:54.060836 | controller-2 | ok: All items complete
2026-09-07 01:50:54.060898 | 
2026-09-07 01:50:54.067778 | controller-1 | changed: "logs"
2026-09-07 01:50:54.085892 | controller-3 | changed: "logs"
2026-09-07 01:50:54.239003 | controller-2 | changed: "artifacts"
2026-09-07 01:50:54.277470 | controller-1 | changed: "artifacts"
2026-09-07 01:50:54.291747 | controller-3 | changed: "artifacts"
2026-09-07 01:50:54.421933 | controller-2 | changed: "docs"
2026-09-07 01:50:54.476327 | controller-3 | changed: "docs"
2026-09-07 01:50:54.484365 | controller-1 | changed: "docs"
2026-09-07 01:50:54.514999 | 
2026-09-07 01:50:54.515160 | PLAY RECAP
2026-09-07 01:50:54.515254 | controller-1 | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-09-07 01:50:54.515310 | controller-2 | ok: 11 changed: 5 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-09-07 01:50:54.515359 | controller-3 | ok: 11 changed: 5 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-09-07 01:50:54.515405 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-07 01:50:54.515440 | 
2026-09-07 01:50:54.665438 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-09-07 01:50:54.671048 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-09-07 01:50:55.315742 | 
2026-09-07 01:50:55.315900 | PLAY [all]
2026-09-07 01:50:55.327760 | 
2026-09-07 01:50:55.327854 | TASK [setup-uv : Extract archive]
2026-09-07 01:50:57.792953 | controller-2 | changed
2026-09-07 01:50:57.846082 | controller-3 | changed
2026-09-07 01:50:57.853880 | controller-1 | changed
2026-09-07 01:50:57.863983 | 
2026-09-07 01:50:57.864165 | TASK [setup-uv : Print version]
2026-09-07 01:50:58.310843 | controller-3 | uv 0.8.13
2026-09-07 01:50:58.292808 | controller-1 | uv 0.8.13
2026-09-07 01:50:58.293992 | controller-2 | uv 0.8.13
2026-09-07 01:50:58.446811 | controller-2 | ok: Runtime: 0:00:00.017071
2026-09-07 01:50:58.459689 | controller-1 | ok: Runtime: 0:00:00.017134
2026-09-07 01:50:58.472712 | controller-3 | ok: Runtime: 0:00:00.017260
2026-09-07 01:50:58.490123 | 
2026-09-07 01:50:58.490207 | TASK [Install Ansible collection siblings]
2026-09-07 01:50:58.886388 | controller-1 | ok:
2026-09-07 01:50:58.886627 | controller-1 | Skipping ansible.posix: no checked-out sibling
2026-09-07 01:50:58.886691 | controller-1 | Skipping community.crypto: no checked-out sibling
2026-09-07 01:50:58.886764 | controller-1 | Skipping community.general: no checked-out sibling
2026-09-07 01:50:58.886807 | controller-1 | Skipping kubernetes.core: no checked-out sibling
2026-09-07 01:50:58.886848 | controller-1 | Skipping vexxhost.containers: no checked-out sibling
2026-09-07 01:50:58.889674 | 
2026-09-07 01:50:58.889781 | PLAY RECAP
2026-09-07 01:50:58.889861 | controller-1 | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-07 01:50:58.889928 | controller-2 | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-07 01:50:58.890004 | controller-3 | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-07 01:50:58.890046 | 
2026-09-07 01:50:59.009108 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-09-07 01:50:59.014871 | PRE-RUN START: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/pre.yml@main]
2026-09-07 01:50:59.572609 | 
2026-09-07 01:50:59.572735 | PLAY [all]
2026-09-07 01:50:59.583349 | 
2026-09-07 01:50:59.583442 | TASK [Update apt cache]
2026-09-07 01:51:05.491418 | controller-1 | ok
2026-09-07 01:51:05.499192 | controller-3 | ok
2026-09-07 01:51:05.699181 | controller-2 | ok
2026-09-07 01:51:05.706610 | 
2026-09-07 01:51:05.706810 | TASK [Disable firewalld]
2026-09-07 01:51:05.744394 | controller-1 | skipping: Conditional result was False
2026-09-07 01:51:05.756461 | controller-2 | skipping: Conditional result was False
2026-09-07 01:51:05.757132 | controller-3 | skipping: Conditional result was False
2026-09-07 01:51:05.765479 | 
2026-09-07 01:51:05.765664 | TASK [Install "jq" for log collection]
2026-09-07 01:51:08.394204 | controller-2 | changed
2026-09-07 01:51:08.524339 | controller-3 | changed
2026-09-07 01:51:08.555511 | controller-1 | changed
2026-09-07 01:51:08.558852 | 
2026-09-07 01:51:08.558973 | PLAY RECAP
2026-09-07 01:51:08.559062 | controller-1 | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-09-07 01:51:08.559116 | controller-2 | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-09-07 01:51:08.559163 | controller-3 | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-09-07 01:51:08.559198 | 
2026-09-07 01:51:08.737698 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/pre.yml@main]
2026-09-07 01:51:08.743303 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-09-07 01:51:09.473306 | 
2026-09-07 01:51:09.473423 | PLAY [all]
2026-09-07 01:51:09.483574 | 
2026-09-07 01:51:09.483648 | LOOP [Check inventory directories]
2026-09-07 01:51:09.885981 | controller-1 | ok: "src/github.com/vexxhost/ansible-collection-kubernetes"
2026-09-07 01:51:10.079254 | controller-1 | ok: "src/github.com/vexxhost/ansible-collection-kubernetes/molecule/ha/inventory"
2026-09-07 01:51:10.279602 | controller-1 | ok: "src/github.com/vexxhost/ansible-collection-kubernetes/extensions/molecule/ha/inventory"
2026-09-07 01:51:10.301249 | 
2026-09-07 01:51:10.301901 | LOOP [Copy inventory file for Zuul]
2026-09-07 01:51:11.260978 | controller-1 | changed:
2026-09-07 01:51:11.261221 | controller-1 | {
2026-09-07 01:51:11.261248 | controller-1 |   "ansible_loop_var": "item",
2026-09-07 01:51:11.261270 | controller-1 |   "changed": false,
2026-09-07 01:51:11.261289 | controller-1 |   "failed": false,
2026-09-07 01:51:11.261311 | controller-1 |   "invocation": {
2026-09-07 01:51:11.261328 | controller-1 |     "module_args": {
2026-09-07 01:51:11.261346 | controller-1 |       "checksum_algorithm": "sha1",
2026-09-07 01:51:11.261363 | controller-1 |       "follow": true,
2026-09-07 01:51:11.261381 | controller-1 |       "get_attributes": true,
2026-09-07 01:51:11.261398 | controller-1 |       "get_checksum": true,
2026-09-07 01:51:11.261415 | controller-1 |       "get_mime": true,
2026-09-07 01:51:11.261431 | controller-1 |       "path": "src/github.com/vexxhost/ansible-collection-kubernetes"
2026-09-07 01:51:11.261447 | controller-1 |     }
2026-09-07 01:51:11.261463 | controller-1 |   },
2026-09-07 01:51:11.261479 | controller-1 |   "item": "src/github.com/vexxhost/ansible-collection-kubernetes",
2026-09-07 01:51:11.261495 | controller-1 |   "stat": {
2026-09-07 01:51:11.261511 | controller-1 |     "atime": 1788745852.9586353,
2026-09-07 01:51:11.261527 | controller-1 |     "attr_flags": "e",
2026-09-07 01:51:11.261543 | controller-1 |     "attributes": [
2026-09-07 01:51:11.261559 | controller-1 |       "extents"
2026-09-07 01:51:11.261575 | controller-1 |     ],
2026-09-07 01:51:11.261591 | controller-1 |     "block_size": 4096,
2026-09-07 01:51:11.261606 | controller-1 |     "blocks": 8,
2026-09-07 01:51:11.261622 | controller-1 |     "charset": "unknown",
2026-09-07 01:51:11.261639 | controller-1 |     "ctime": 1788745852.9946356,
2026-09-07 01:51:11.261655 | controller-1 |     "dev": 65025,
2026-09-07 01:51:11.261671 | controller-1 |     "device_type": 0,
2026-09-07 01:51:11.261687 | controller-1 |     "executable": true,
2026-09-07 01:51:11.261703 | controller-1 |     "exists": true,
2026-09-07 01:51:11.261731 | controller-1 |     "gid": 1000,
2026-09-07 01:51:11.261750 | controller-1 |     "gr_name": "zuul",
2026-09-07 01:51:11.261787 | controller-1 |     "inode": 521495,
2026-09-07 01:51:11.261804 | controller-1 |     "isblk": false,
2026-09-07 01:51:11.261820 | controller-1 |     "ischr": false,
2026-09-07 01:51:11.261836 | controller-1 |     "isdir": true,
2026-09-07 01:51:11.261852 | controller-1 |     "isfifo": false,
2026-09-07 01:51:11.261868 | controller-1 |     "isgid": false,
2026-09-07 01:51:11.261884 | controller-1 |     "islnk": false,
2026-09-07 01:51:11.261899 | controller-1 |     "isreg": false,
2026-09-07 01:51:11.261914 | controller-1 |     "issock": false,
2026-09-07 01:51:11.261930 | controller-1 |     "isuid": false,
2026-09-07 01:51:11.261946 | controller-1 |     "mimetype": "unknown",
2026-09-07 01:51:11.261961 | controller-1 |     "mode": "0755",
2026-09-07 01:51:11.261977 | controller-1 |     "mtime": 1788745832.5628557,
2026-09-07 01:51:11.261992 | controller-1 |     "nlink": 14,
2026-09-07 01:51:11.262008 | controller-1 |     "path": "src/github.com/vexxhost/ansible-collection-kubernetes",
2026-09-07 01:51:11.262024 | controller-1 |     "pw_name": "zuul",
2026-09-07 01:51:11.262041 | controller-1 |     "readable": true,
2026-09-07 01:51:11.262056 | controller-1 |     "rgrp": true,
2026-09-07 01:51:11.262072 | controller-1 |     "roth": true,
2026-09-07 01:51:11.262088 | controller-1 |     "rusr": true,
2026-09-07 01:51:11.262104 | controller-1 |     "size": 4096,
2026-09-07 01:51:11.262120 | controller-1 |     "uid": 1000,
2026-09-07 01:51:11.262147 | controller-1 |     "version": "1114873411",
2026-09-07 01:51:11.262170 | controller-1 |     "wgrp": false,
2026-09-07 01:51:11.262193 | controller-1 |     "woth": false,
2026-09-07 01:51:11.262215 | controller-1 |     "writeable": true,
2026-09-07 01:51:11.262236 | controller-1 |     "wusr": true,
2026-09-07 01:51:11.262258 | controller-1 |     "xgrp": true,
2026-09-07 01:51:11.262279 | controller-1 |     "xoth": true,
2026-09-07 01:51:11.262299 | controller-1 |     "xusr": true
2026-09-07 01:51:11.262321 | controller-1 |   }
2026-09-07 01:51:11.262342 | controller-1 | }
2026-09-07 01:51:11.262401 | controller-1 | ok: All items complete
2026-09-07 01:51:11.262427 | 
2026-09-07 01:51:11.265034 | controller-1 | skipping: Conditional result was False
2026-09-07 01:51:11.272258 | controller-1 | skipping: Conditional result was False
2026-09-07 01:51:11.285254 | 
2026-09-07 01:51:11.285476 | LOOP [Switch "ansible_host" to private IP]
2026-09-07 01:51:11.648249 | controller-1 | changed: 3 replacements made
2026-09-07 01:51:11.650670 | controller-1 | skipping: Conditional result was False
2026-09-07 01:51:11.657601 | controller-1 | skipping: Conditional result was False
2026-09-07 01:51:11.676714 | 
2026-09-07 01:51:11.676933 | TASK [Run Molecule scenario]
2026-09-07 01:51:12.219886 | controller-1 | Using CPython 3.13.5 interpreter at: /usr/bin/python3
2026-09-07 01:51:12.219955 | controller-1 | Creating virtual environment at: .venv
2026-09-07 01:51:13.458983 | controller-1 | Downloading ansible-core (2.3MiB)
2026-09-07 01:51:13.460064 | controller-1 | Downloading pygments (1.2MiB)
2026-09-07 01:51:13.467814 | controller-1 | Downloading cryptography (4.3MiB)
2026-09-07 01:51:13.620808 | controller-1 |  Downloading cryptography
2026-09-07 01:51:13.677094 | controller-1 |  Downloading pygments
2026-09-07 01:51:13.837098 | controller-1 |  Downloading ansible-core
2026-09-07 01:51:13.897463 | controller-1 | Installed 36 packages in 59ms
2026-09-07 01:51:14.539165 | controller-1 | INFO     Collection 'vexxhost.kubernetes' detected.
2026-09-07 01:51:14.539262 | controller-1 | INFO     Scenarios will be used from 'extensions/molecule'
2026-09-07 01:51:15.432090 | controller-1 | CRITICAL 'extensions/molecule/default/molecule.yml' glob failed.  Exiting.
2026-09-07 01:51:15.432154 | controller-1 | INFO     default scenario not found, disabling shared state.
2026-09-07 01:51:15.432246 | controller-1 | INFO     [ha > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy
2026-09-07 01:51:15.432320 | controller-1 | INFO     [ha > prerun] Performing prerun with role_name_check=0...
2026-09-07 01:54:51.148897 | controller-1 | WARNING  Retrying execution failure 1 of: ansible-galaxy collection install -vvv community.crypto:>=2.2.3
2026-09-07 01:54:51.148985 | controller-1 | ERROR    Command ansible-galaxy collection install -vvv community.crypto:>=2.2.3, returned 1 code:
2026-09-07 01:54:51.149041 | controller-1 | Starting galaxy collection install process
2026-09-07 01:54:51.149049 | controller-1 | Process install dependency map
2026-09-07 01:54:51.149057 | controller-1 | Starting collection install process
2026-09-07 01:54:51.149065 | controller-1 | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/community-crypto-3.4.0.tar.gz to /home/zuul/.ansible/tmp/ansible-local-1494elryjoyq/tmp1lykmyrz/community-crypto-3.4.0-707xoj2q
2026-09-07 01:54:51.149073 | controller-1 |
2026-09-07 01:54:51.149082 | controller-1 | ansible-galaxy
2026-09-07 01:54:51.149089 | controller-1 |   config file = None
2026-09-07 01:54:51.149096 | controller-1 |   configured module search path =
2026-09-07 01:54:51.149104 | controller-1 |   ansible python module location = /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible
2026-09-07 01:54:51.149120 | controller-1 |   ansible collection location = /home/zuul/.ansible/collections:/usr/share/ansible/collections:/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/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.13/dist-packages:/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages
2026-09-07 01:54:51.149130 | controller-1 |   executable location = /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/bin/ansible-galaxy
2026-09-07 01:54:51.149138 | controller-1 |   python version = 3.13.5 (main, Jul 15 2026, 20:25:40)  (/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/bin/python)
2026-09-07 01:54:51.149145 | controller-1 |   jinja version = 3.1.6
2026-09-07 01:54:51.149152 | controller-1 |   pyyaml version = 6.0.3 (with libyaml v0.2.5)
2026-09-07 01:54:51.149159 | controller-1 | No config file found; using defaults
2026-09-07 01:54:51.149166 | controller-1 | Found installed collection ansible.posix:2.2.2 at '/home/zuul/.ansible/collections/ansible_collections/ansible/posix'
2026-09-07 01:54:51.149173 | controller-1 | Opened /home/zuul/.ansible/galaxy_token
2026-09-07 01:54:51.149180 | controller-1 | : Failed to download collection tar from 'default': <urlopen error _ssl.c:1012: The handshake operation timed out>
2026-09-07 01:54:51.149187 | controller-1 |
2026-09-07 01:54:51.149195 | controller-1 | Failed to download collection tar from 'default': <urlopen error _ssl.c:1012: The handshake operation timed out>
2026-09-07 01:54:51.149202 | controller-1 |
2026-09-07 01:54:51.149209 | controller-1 | <<< caused by >>>
2026-09-07 01:54:51.149216 | controller-1 |
2026-09-07 01:54:51.149229 | controller-1 | <urlopen error _ssl.c:1012: The handshake operation timed out>
2026-09-07 01:54:51.149237 | controller-1 |
2026-09-07 01:54:51.149244 | controller-1 |
2026-09-07 01:54:51.149277 | controller-1 | Traceback (most recent call last):
2026-09-07 01:54:51.149716 | controller-1 |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/bin/molecule", line 10, in <module>
2026-09-07 01:54:51.149725 | controller-1 |     sys.exit(main())
2026-09-07 01:54:51.149732 | controller-1 |              ~~~~^^
2026-09-07 01:54:51.149859 | controller-1 |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/click/core.py", line 1485, in __call__
2026-09-07 01:54:51.149869 | controller-1 |     return self.main(*args, **kwargs)
2026-09-07 01:54:51.149876 | controller-1 |            ~~~~~~~~~^^^^^^^^^^^^^^^^^
2026-09-07 01:54:51.149983 | controller-1 |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/click/core.py", line 1406, in main
2026-09-07 01:54:51.149993 | controller-1 |     rv = self.invoke(ctx)
2026-09-07 01:54:51.150108 | controller-1 |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/click/core.py", line 1873, in invoke
2026-09-07 01:54:51.150117 | controller-1 |     return _process_result(sub_ctx.command.invoke(sub_ctx))
2026-09-07 01:54:51.150124 | controller-1 |                            ~~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^
2026-09-07 01:54:51.150276 | controller-1 |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/click/core.py", line 1269, in invoke
2026-09-07 01:54:51.150286 | controller-1 |     return ctx.invoke(self.callback, **ctx.params)
2026-09-07 01:54:51.150294 | controller-1 |            ~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-07 01:54:51.150344 | controller-1 |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/click/core.py", line 824, in invoke
2026-09-07 01:54:51.150352 | controller-1 |     return callback(*args, **kwargs)
2026-09-07 01:54:51.150445 | controller-1 |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/molecule/click_cfg.py", line 583, in wrapper
2026-09-07 01:54:51.150453 | controller-1 |     return func(*args, **kwargs)
2026-09-07 01:54:51.150551 | controller-1 |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/click/decorators.py", line 34, in new_func
2026-09-07 01:54:51.150559 | controller-1 |     return f(get_current_context(), *args, **kwargs)
2026-09-07 01:54:51.150657 | controller-1 |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/molecule/click_cfg.py", line 418, in wrapper
2026-09-07 01:54:51.150665 | controller-1 |     return func(ctx)
2026-09-07 01:54:51.150796 | controller-1 |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/molecule/command/test.py", line 81, in test
2026-09-07 01:54:51.150805 | controller-1 |     base.execute_cmdline_scenarios(scenario_name, args, command_args, ansible_args, exclude)
2026-09-07 01:54:51.150812 | controller-1 |     ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-07 01:54:51.150906 | controller-1 |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/molecule/command/base.py", line 169, in execute_cmdline_scenarios
2026-09-07 01:54:51.150914 | controller-1 |     _run_scenarios(scenarios, command_args, default_config)
2026-09-07 01:54:51.150921 | controller-1 |     ~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-07 01:54:51.151108 | controller-1 |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/molecule/command/base.py", line 240, in _run_scenarios
2026-09-07 01:54:51.151117 | controller-1 |     scenario.config.runtime.prepare_environment(
2026-09-07 01:54:51.151124 | controller-1 |     ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^
2026-09-07 01:54:51.151131 | controller-1 |         install_local=True,
2026-09-07 01:54:51.151138 | controller-1 |         ^^^^^^^^^^^^^^^^^^^
2026-09-07 01:54:51.151145 | controller-1 |         role_name_check=role_name_check,
2026-09-07 01:54:51.151151 | controller-1 |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-07 01:54:51.151159 | controller-1 |     )
2026-09-07 01:54:51.151166 | controller-1 |     ^
2026-09-07 01:54:51.151293 | controller-1 |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible_compat/runtime.py", line 752, in prepare_environment
2026-09-07 01:54:51.151308 | controller-1 |     self.install_collection(
2026-09-07 01:54:51.151316 | controller-1 |     ~~~~~~~~~~~~~~~~~~~~~~~^
2026-09-07 01:54:51.151323 | controller-1 |         f"{name}{',' if is_url(name) else ':'}{required_version}",
2026-09-07 01:54:51.151330 | controller-1 |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-07 01:54:51.151337 | controller-1 |         destination=destination,
2026-09-07 01:54:51.151343 | controller-1 |         ^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-07 01:54:51.151350 | controller-1 |     )
2026-09-07 01:54:51.151357 | controller-1 |     ^
2026-09-07 01:54:51.151401 | controller-1 |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible_compat/runtime.py", line 609, in install_collection
2026-09-07 01:54:51.151409 | controller-1 |     raise InvalidPrerequisiteError(msg)
2026-09-07 01:54:51.151948 | controller-1 | ansible_compat.errors.InvalidPrerequisiteError: Command ansible-galaxy collection install -vvv community.crypto:>=2.2.3, returned 1 code:
2026-09-07 01:54:51.151957 | controller-1 | Starting galaxy collection install process
2026-09-07 01:54:51.151964 | controller-1 | Process install dependency map
2026-09-07 01:54:51.151971 | controller-1 | Starting collection install process
2026-09-07 01:54:51.151987 | controller-1 | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/community-crypto-3.4.0.tar.gz to /home/zuul/.ansible/tmp/ansible-local-1494elryjoyq/tmp1lykmyrz/community-crypto-3.4.0-707xoj2q
2026-09-07 01:54:51.151994 | controller-1 |
2026-09-07 01:54:51.152002 | controller-1 | ansible-galaxy [core 2.20.1]
2026-09-07 01:54:51.152008 | controller-1 |   config file = None
2026-09-07 01:54:51.152015 | controller-1 |   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-07 01:54:51.152022 | controller-1 |   ansible python module location = /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible
2026-09-07 01:54:51.152030 | controller-1 |   ansible collection location = /home/zuul/.ansible/collections:/usr/share/ansible/collections:/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/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.13/dist-packages:/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages
2026-09-07 01:54:51.152038 | controller-1 |   executable location = /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/bin/ansible-galaxy
2026-09-07 01:54:51.152045 | controller-1 |   python version = 3.13.5 (main, Jul 15 2026, 20:25:40) [GCC 14.2.0] (/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/bin/python)
2026-09-07 01:54:51.152052 | controller-1 |   jinja version = 3.1.6
2026-09-07 01:54:51.152059 | controller-1 |   pyyaml version = 6.0.3 (with libyaml v0.2.5)
2026-09-07 01:54:51.152065 | controller-1 | No config file found; using defaults
2026-09-07 01:54:51.152072 | controller-1 | Found installed collection ansible.posix:2.2.2 at '/home/zuul/.ansible/collections/ansible_collections/ansible/posix'
2026-09-07 01:54:51.152079 | controller-1 | Opened /home/zuul/.ansible/galaxy_token
2026-09-07 01:54:51.152086 | controller-1 | [ERROR]: Failed to download collection tar from 'default': <urlopen error _ssl.c:1012: The handshake operation timed out>
2026-09-07 01:54:51.152093 | controller-1 |
2026-09-07 01:54:51.152100 | controller-1 | Failed to download collection tar from 'default': <urlopen error _ssl.c:1012: The handshake operation timed out>
2026-09-07 01:54:51.152107 | controller-1 |
2026-09-07 01:54:51.152113 | controller-1 | <<< caused by >>>
2026-09-07 01:54:51.152120 | controller-1 |
2026-09-07 01:54:51.152127 | controller-1 | <urlopen error _ssl.c:1012: The handshake operation timed out>
2026-09-07 01:54:51.152134 | controller-1 |
2026-09-07 01:54:51.152141 | controller-1 |
2026-09-07 01:54:51.392755 | controller-1 | ERROR
2026-09-07 01:54:51.392976 | controller-1 | {
2026-09-07 01:54:51.393034 | controller-1 |   "delta": "0:03:39.120554",
2026-09-07 01:54:51.393074 | controller-1 |   "end": "2026-09-07 01:54:51.229625",
2026-09-07 01:54:51.393183 | controller-1 |   "msg": "non-zero return code",
2026-09-07 01:54:51.393230 | controller-1 |   "rc": 1,
2026-09-07 01:54:51.393266 | controller-1 |   "start": "2026-09-07 01:51:12.109071"
2026-09-07 01:54:51.393300 | controller-1 | }
failure
2026-09-07 01:54:51.399061 | 
2026-09-07 01:54:51.399177 | PLAY RECAP
2026-09-07 01:54:51.399339 | controller-1 | ok: 3 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-09-07 01:54:51.399436 | 
2026-09-07 01:54:51.587884 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-09-07 01:54:51.594066 | POST-RUN START: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/post.yml@main]
2026-09-07 01:54:52.232489 | 
2026-09-07 01:54:52.232804 | PLAY [all]
2026-09-07 01:54:52.254011 | 
2026-09-07 01:54:52.254128 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects]
2026-09-07 01:54:52.674362 | controller-1 | changed
2026-09-07 01:54:52.845668 | controller-3 | changed
2026-09-07 01:54:52.882724 | controller-2 | changed
2026-09-07 01:54:52.890371 | 
2026-09-07 01:54:52.890575 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects]
2026-09-07 01:54:53.377303 | controller-2 | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-07 01:54:53.377404 | controller-2 | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-07 01:54:53.386042 | controller-2 | environment: line 1: kubectl: command not found
2026-09-07 01:54:53.388890 | controller-2 | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-07 01:54:53.390082 | controller-2 | environment: line 1: kubectl: command not found
2026-09-07 01:54:53.392209 | controller-2 | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-07 01:54:53.394256 | controller-2 | environment: line 1: kubectl: command not found
2026-09-07 01:54:53.396438 | controller-2 | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-07 01:54:53.397445 | controller-2 | environment: line 1: kubectl: command not found
2026-09-07 01:54:53.400529 | controller-2 | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-07 01:54:53.402002 | controller-2 | environment: line 1: kubectl: command not found
2026-09-07 01:54:53.404224 | controller-2 | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-07 01:54:53.366908 | controller-3 | xargs: xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args valuewarning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-07 01:54:53.366987 | controller-3 |
2026-09-07 01:54:53.374987 | controller-3 | environment: line 1: kubectl: command not found
2026-09-07 01:54:53.377267 | controller-3 | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-07 01:54:53.378453 | controller-3 | environment: line 1: kubectl: command not found
2026-09-07 01:54:53.381044 | controller-3 | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-07 01:54:53.385730 | controller-3 | environment: line 1: kubectl: command not found
2026-09-07 01:54:53.388175 | controller-3 | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-07 01:54:53.389429 | controller-3 | environment: line 1: kubectl: command not found
2026-09-07 01:54:53.392149 | controller-3 | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-07 01:54:53.394509 | controller-3 | environment: line 1: kubectl: command not found
2026-09-07 01:54:53.396507 | controller-3 | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-07 01:54:53.367325 | controller-1 | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-07 01:54:53.367447 | controller-1 | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-07 01:54:53.375571 | controller-1 | environment: line 1: kubectl: command not found
2026-09-07 01:54:53.377858 | controller-1 | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-07 01:54:53.379365 | controller-1 | environment: line 1: kubectl: command not found
2026-09-07 01:54:53.382125 | controller-1 | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-07 01:54:53.384609 | controller-1 | environment: line 1: kubectl: command not found
2026-09-07 01:54:53.386238 | controller-1 | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-07 01:54:53.388438 | controller-1 | environment: line 1: kubectl: command not found
2026-09-07 01:54:53.389734 | controller-1 | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-07 01:54:53.392238 | controller-1 | environment: line 1: kubectl: command not found
2026-09-07 01:54:53.394160 | controller-1 | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-07 01:54:53.474744 | controller-1 | ok: Runtime: 0:00:00.041535
2026-09-07 01:54:53.487382 | controller-3 | ok: Runtime: 0:00:00.044885
2026-09-07 01:54:53.500905 | controller-2 | ok: Runtime: 0:00:00.041887
2026-09-07 01:54:53.509342 | 
2026-09-07 01:54:53.509449 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects]
2026-09-07 01:54:53.770891 | controller-1 | changed
2026-09-07 01:54:53.798276 | controller-2 | changed
2026-09-07 01:54:53.824872 | controller-3 | changed
2026-09-07 01:54:53.833210 | 
2026-09-07 01:54:53.833351 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects]
2026-09-07 01:54:54.162692 | controller-2 | environment: line 5: kubectl: command not found
2026-09-07 01:54:54.164003 | controller-2 | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-07 01:54:54.164348 | controller-2 | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-07 01:54:54.164815 | controller-2 | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-07 01:54:54.194317 | controller-3 | environment: line 5: kubectl: command not found
2026-09-07 01:54:54.196020 | controller-3 | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-07 01:54:54.196136 | controller-3 | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-07 01:54:54.196742 | controller-3 | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-07 01:54:54.162913 | controller-1 | environment: line 5: kubectl: command not found
2026-09-07 01:54:54.164227 | controller-1 | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-07 01:54:54.164258 | controller-1 | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-07 01:54:54.165337 | controller-1 | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-07 01:54:54.407564 | controller-2 | ok: Runtime: 0:00:00.014160
2026-09-07 01:54:54.467596 | controller-1 | ok: Runtime: 0:00:00.013556
2026-09-07 01:54:54.473675 | controller-3 | ok: Runtime: 0:00:00.013786
2026-09-07 01:54:54.478160 | 
2026-09-07 01:54:54.478239 | TASK [describe-kubernetes-objects : Downloads logs to executor]
2026-09-07 01:54:55.113892 | controller-2 | changed:
2026-09-07 01:54:55.114246 | controller-2 | created directory /var/lib/zuul/builds/ea72482e21fe4024bdb93cf14327f000/work/logs/controller-2
2026-09-07 01:54:55.114306 | controller-2 | cd+++++++++ objects/
2026-09-07 01:54:55.114438 | controller-2 | cd+++++++++ objects/cluster/
2026-09-07 01:54:55.114618 | controller-2 | cd+++++++++ objects/namespaced/
2026-09-07 01:54:55.133329 | controller-3 | changed:
2026-09-07 01:54:55.133473 | controller-3 | created directory /var/lib/zuul/builds/ea72482e21fe4024bdb93cf14327f000/work/logs/controller-3
2026-09-07 01:54:55.133541 | controller-3 | cd+++++++++ objects/
2026-09-07 01:54:55.133591 | controller-3 | cd+++++++++ objects/cluster/
2026-09-07 01:54:55.133640 | controller-3 | cd+++++++++ objects/namespaced/
2026-09-07 01:54:55.139976 | controller-1 | changed:
2026-09-07 01:54:55.140063 | controller-1 | created directory /var/lib/zuul/builds/ea72482e21fe4024bdb93cf14327f000/work/logs/controller-1
2026-09-07 01:54:55.140110 | controller-1 | cd+++++++++ objects/
2026-09-07 01:54:55.140149 | controller-1 | cd+++++++++ objects/cluster/
2026-09-07 01:54:55.140186 | controller-1 | cd+++++++++ objects/namespaced/
2026-09-07 01:54:55.163279 | 
2026-09-07 01:54:55.163365 | TASK [gather-pod-logs : creating directory for pod logs]
2026-09-07 01:54:55.418101 | controller-1 | changed
2026-09-07 01:54:55.436679 | controller-2 | changed
2026-09-07 01:54:55.443119 | controller-3 | changed
2026-09-07 01:54:55.450235 | 
2026-09-07 01:54:55.450360 | TASK [gather-pod-logs : creating directory for failed pod logs]
2026-09-07 01:54:55.703917 | controller-1 | changed
2026-09-07 01:54:55.754069 | controller-3 | changed
2026-09-07 01:54:55.782680 | controller-2 | changed
2026-09-07 01:54:55.793564 | 
2026-09-07 01:54:55.793712 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)]
2026-09-07 01:54:56.110002 | controller-2 | environment: line 3: kubectl: command not found
2026-09-07 01:54:56.142479 | controller-3 | environment: line 3: kubectl: command not found
2026-09-07 01:54:56.109860 | controller-1 | environment: line 3: kubectl: command not found
2026-09-07 01:54:56.362808 | controller-2 | ok: Runtime: 0:00:00.013910
2026-09-07 01:54:56.374100 | controller-1 | ok: Runtime: 0:00:00.013104
2026-09-07 01:54:56.385055 | controller-3 | ok: Runtime: 0:00:00.014255
2026-09-07 01:54:56.392226 | 
2026-09-07 01:54:56.392354 | TASK [gather-pod-logs : Downloads pod logs to executor]
2026-09-07 01:54:56.927043 | controller-1 | changed:
2026-09-07 01:54:56.927469 | controller-1 | cd+++++++++ pod-logs/
2026-09-07 01:54:56.927530 | controller-1 | cd+++++++++ pod-logs/failed-pods/
2026-09-07 01:54:56.944515 | controller-2 | changed:
2026-09-07 01:54:56.944620 | controller-2 | cd+++++++++ pod-logs/
2026-09-07 01:54:56.944668 | controller-2 | cd+++++++++ pod-logs/failed-pods/
2026-09-07 01:54:57.004463 | controller-3 | changed:
2026-09-07 01:54:57.004596 | controller-3 | cd+++++++++ pod-logs/
2026-09-07 01:54:57.004648 | controller-3 | cd+++++++++ pod-logs/failed-pods/
2026-09-07 01:54:57.028994 | 
2026-09-07 01:54:57.029119 | LOOP [helm-release-status : creating directory for helm release status]
2026-09-07 01:54:57.293390 | controller-1 | changed: "values"
2026-09-07 01:54:57.301474 | controller-2 | changed: "values"
2026-09-07 01:54:57.315345 | controller-3 | changed: "values"
2026-09-07 01:54:57.526610 | controller-1 | changed: "releases"
2026-09-07 01:54:57.542848 | controller-2 | changed: "releases"
2026-09-07 01:54:57.542973 | controller-2 | changed: All items complete
2026-09-07 01:54:57.543022 | 
2026-09-07 01:54:57.550121 | controller-3 | changed: "releases"
2026-09-07 01:54:57.562548 | 
2026-09-07 01:54:57.562785 | TASK [helm-release-status : Gather get release status for helm charts]
2026-09-07 01:54:57.899180 | controller-2 | /bin/bash: line 3: kubectl: command not found
2026-09-07 01:54:57.909909 | controller-3 | /bin/bash: line 3: kubectl: command not found
2026-09-07 01:54:57.886979 | controller-1 | /bin/bash: line 3: kubectl: command not found
2026-09-07 01:54:58.140845 | controller-1 | ok: Runtime: 0:00:00.009946
2026-09-07 01:54:58.153083 | controller-2 | ok: Runtime: 0:00:00.008553
2026-09-07 01:54:58.166069 | controller-3 | ok: Runtime: 0:00:00.009841
2026-09-07 01:54:58.173252 | 
2026-09-07 01:54:58.173378 | TASK [helm-release-status : Downloads logs to executor]
2026-09-07 01:54:58.731682 | controller-1 | changed:
2026-09-07 01:54:58.732083 | controller-1 | cd+++++++++ helm/
2026-09-07 01:54:58.732171 | controller-1 | cd+++++++++ helm/releases/
2026-09-07 01:54:58.732245 | controller-1 | cd+++++++++ helm/values/
2026-09-07 01:54:58.740135 | controller-2 | changed:
2026-09-07 01:54:58.740244 | controller-2 | cd+++++++++ helm/
2026-09-07 01:54:58.740293 | controller-2 | cd+++++++++ helm/releases/
2026-09-07 01:54:58.740332 | controller-2 | cd+++++++++ helm/values/
2026-09-07 01:54:58.757492 | controller-3 | changed:
2026-09-07 01:54:58.757588 | controller-3 | cd+++++++++ helm/
2026-09-07 01:54:58.757636 | controller-3 | cd+++++++++ helm/releases/
2026-09-07 01:54:58.757676 | controller-3 | cd+++++++++ helm/values/
2026-09-07 01:54:58.779216 | 
2026-09-07 01:54:58.779280 | PLAY RECAP
2026-09-07 01:54:58.779333 | controller-1 | ok: 12 changed: 12 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-07 01:54:58.779366 | controller-2 | ok: 12 changed: 12 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-07 01:54:58.779395 | controller-3 | ok: 12 changed: 12 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-07 01:54:58.779437 | 
2026-09-07 01:54:58.904940 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/post.yml@main]
2026-09-07 01:54:58.910548 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-09-07 01:54:59.465720 | 
2026-09-07 01:54:59.465838 | PLAY [all]
2026-09-07 01:54:59.475370 | 
2026-09-07 01:54:59.475454 | TASK [fetch-output : Set log path for multiple nodes]
2026-09-07 01:54:59.521168 | controller-1 | ok
2026-09-07 01:54:59.556315 | controller-2 | ok
2026-09-07 01:54:59.559886 | controller-3 | ok
2026-09-07 01:54:59.565262 | 
2026-09-07 01:54:59.565377 | TASK [fetch-output : Set log path for single node]
2026-09-07 01:54:59.601093 | controller-1 | skipping: Conditional result was False
2026-09-07 01:54:59.612062 | controller-2 | skipping: Conditional result was False
2026-09-07 01:54:59.622476 | controller-3 | skipping: Conditional result was False
2026-09-07 01:54:59.627661 | 
2026-09-07 01:54:59.627750 | LOOP [fetch-output : Ensure local output dirs]
2026-09-07 01:55:00.020366 | controller-1 -> localhost | ok: "/var/lib/zuul/builds/ea72482e21fe4024bdb93cf14327f000/work/logs/controller-1"
2026-09-07 01:55:00.021586 | controller-2 -> localhost | ok: "/var/lib/zuul/builds/ea72482e21fe4024bdb93cf14327f000/work/logs/controller-2"
2026-09-07 01:55:00.025271 | controller-3 -> localhost | ok: "/var/lib/zuul/builds/ea72482e21fe4024bdb93cf14327f000/work/logs/controller-3"
2026-09-07 01:55:00.226953 | controller-2 -> localhost | changed: "/var/lib/zuul/builds/ea72482e21fe4024bdb93cf14327f000/work/artifacts"
2026-09-07 01:55:00.232705 | controller-3 -> localhost | ok: "/var/lib/zuul/builds/ea72482e21fe4024bdb93cf14327f000/work/artifacts"
2026-09-07 01:55:00.282157 | controller-1 -> localhost | ok: "/var/lib/zuul/builds/ea72482e21fe4024bdb93cf14327f000/work/artifacts"
2026-09-07 01:55:00.454079 | controller-3 -> localhost | changed: "/var/lib/zuul/builds/ea72482e21fe4024bdb93cf14327f000/work/docs"
2026-09-07 01:55:00.463821 | controller-2 -> localhost | ok: "/var/lib/zuul/builds/ea72482e21fe4024bdb93cf14327f000/work/docs"
2026-09-07 01:55:00.463919 | controller-2 -> localhost | changed: All items complete
2026-09-07 01:55:00.463956 | 
2026-09-07 01:55:00.511178 | controller-1 -> localhost | ok: "/var/lib/zuul/builds/ea72482e21fe4024bdb93cf14327f000/work/docs"
2026-09-07 01:55:00.528141 | 
2026-09-07 01:55:00.528326 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-09-07 01:55:01.235652 | controller-3 | changed: .d..t...... ./
2026-09-07 01:55:01.235966 | controller-3 | ok: All items complete
2026-09-07 01:55:01.236022 | 
2026-09-07 01:55:01.263950 | controller-2 | changed: .d..t...... ./
2026-09-07 01:55:01.271559 | controller-1 | changed: .d..t...... ./
2026-09-07 01:55:01.762031 | controller-1 | changed: .d..t...... ./
2026-09-07 01:55:01.778940 | controller-2 | ok
2026-09-07 01:55:01.812962 | controller-3 | ok
2026-09-07 01:55:02.245848 | controller-1 | changed: .d..t...... ./
2026-09-07 01:55:02.270444 | controller-2 | ok
2026-09-07 01:55:02.358246 | controller-3 | ok
2026-09-07 01:55:02.358354 | controller-3 | changed: All items complete
2026-09-07 01:55:02.358438 | 
2026-09-07 01:55:02.388252 | 
2026-09-07 01:55:02.388481 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-09-07 01:55:02.829717 | controller-1 -> localhost | ok: Item: artifacts Runtime: 0:00:00.008014
2026-09-07 01:55:03.075083 | controller-1 -> localhost | ok: Item: docs Runtime: 0:00:00.007017
2026-09-07 01:55:03.102219 | 
2026-09-07 01:55:03.102344 | PLAY [all]
2026-09-07 01:55:03.108585 | 
2026-09-07 01:55:03.108666 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-09-07 01:55:03.581666 | controller-1 | changed
2026-09-07 01:55:03.762275 | controller-3 | changed
2026-09-07 01:55:03.768332 | controller-2 | changed
2026-09-07 01:55:03.790277 | 
2026-09-07 01:55:03.790384 | PLAY RECAP
2026-09-07 01:55:03.790601 | controller-1 | ok: 5 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-09-07 01:55:03.790662 | controller-2 | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-09-07 01:55:03.790710 | controller-3 | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-09-07 01:55:03.790745 | 
2026-09-07 01:55:03.916927 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-09-07 01:55:03.922478 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-09-07 01:55:04.478886 | 
2026-09-07 01:55:04.479146 | PLAY [localhost]
2026-09-07 01:55:04.492544 | 
2026-09-07 01:55:04.492702 | TASK [Generate Zuul manifest]
2026-09-07 01:55:04.510306 | localhost | ok
2026-09-07 01:55:04.525991 | 
2026-09-07 01:55:04.526077 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-09-07 01:55:04.958531 | localhost | changed
2026-09-07 01:55:04.967583 | 
2026-09-07 01:55:04.967657 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-09-07 01:55:05.005940 | localhost | ok
2026-09-07 01:55:05.011756 | 
2026-09-07 01:55:05.011815 | TASK [Upload logs]
2026-09-07 01:55:05.048614 | localhost | ok
2026-09-07 01:55:05.116594 | 
2026-09-07 01:55:05.116696 | TASK [Set zuul-log-path fact]
2026-09-07 01:55:05.132898 | localhost | ok
2026-09-07 01:55:05.142326 | 
2026-09-07 01:55:05.142390 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-09-07 01:55:05.170427 | localhost | ok
2026-09-07 01:55:05.178250 | 
2026-09-07 01:55:05.178345 | TASK [upload-logs : Create log directories]
2026-09-07 01:55:05.613776 | localhost | changed
2026-09-07 01:55:05.624338 | 
2026-09-07 01:55:05.624528 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-09-07 01:55:06.094023 | localhost -> localhost | ok: Runtime: 0:00:00.005308
2026-09-07 01:55:06.099717 | 
2026-09-07 01:55:06.099804 | TASK [upload-logs : Upload logs to log server]
2026-09-07 01:55:06.574747 | localhost | Output suppressed because no_log was given
2026-09-07 01:55:06.581566 | 
2026-09-07 01:55:06.581693 | LOOP [upload-logs : Compress console log and json output]
2026-09-07 01:55:06.637323 | localhost | skipping: Conditional result was False
2026-09-07 01:55:06.644778 | localhost | skipping: Conditional result was False
2026-09-07 01:55:06.656928 | 
2026-09-07 01:55:06.657105 | LOOP [upload-logs : Upload compressed console log and json output]
2026-09-07 01:55:06.716565 | localhost | skipping: Conditional result was False
2026-09-07 01:55:06.716901 | 
2026-09-07 01:55:06.723131 | localhost | skipping: Conditional result was False
2026-09-07 01:55:06.733304 | 
2026-09-07 01:55:06.733499 | LOOP [upload-logs : Upload console log and json output]