2026-05-21 22:44:06.841100 | Job console starting 2026-05-21 22:44:06.852705 | Updating git repos 2026-05-21 22:44:06.886089 | Cloning repos into workspace 2026-05-21 22:44:07.014977 | Restoring repo states 2026-05-21 22:44:07.022323 | Merging changes 2026-05-21 22:44:07.289744 | Checking out repos 2026-05-21 22:44:07.355646 | Preparing playbooks 2026-05-21 22:44:08.475336 | Running Ansible setup 2026-05-21 22:44:12.318558 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-05-21 22:44:12.920873 | 2026-05-21 22:44:12.921038 | PLAY [localhost] 2026-05-21 22:44:12.929144 | 2026-05-21 22:44:12.929217 | TASK [Gathering Facts] 2026-05-21 22:44:13.796563 | localhost | ok 2026-05-21 22:44:13.806191 | 2026-05-21 22:44:13.806278 | TASK [Setup log path fact] 2026-05-21 22:44:13.826233 | localhost | ok 2026-05-21 22:44:13.838173 | 2026-05-21 22:44:13.838243 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-21 22:44:13.868810 | localhost | ok 2026-05-21 22:44:13.877212 | 2026-05-21 22:44:13.877304 | TASK [emit-job-header : Print job information] 2026-05-21 22:44:13.932940 | # Job Information 2026-05-21 22:44:13.933098 | Ansible Version: 2.16.18 2026-05-21 22:44:13.933132 | Job: ansible-collection-containers-molecule-containerd-rockylinux-9 2026-05-21 22:44:13.933154 | Pipeline: check 2026-05-21 22:44:13.933174 | Executor: 2d72f0692154 2026-05-21 22:44:13.933194 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/130 2026-05-21 22:44:13.933217 | Event ID: 7e688440-5566-11f1-87a2-04a2a7b79ebe 2026-05-21 22:44:13.936024 | 2026-05-21 22:44:13.936091 | LOOP [emit-job-header : Print node information] 2026-05-21 22:44:14.042702 | localhost | ok: 2026-05-21 22:44:14.042867 | localhost | # Node Information 2026-05-21 22:44:14.042895 | localhost | Inventory Hostname: rockylinux-9 2026-05-21 22:44:14.042917 | localhost | Hostname: np0000176014 2026-05-21 22:44:14.042937 | localhost | Username: zuul 2026-05-21 22:44:14.042961 | localhost | Distro: Rocky 9.7 2026-05-21 22:44:14.042981 | localhost | Provider: yul1 2026-05-21 22:44:14.043000 | localhost | Region: ca-ymq-1 2026-05-21 22:44:14.043018 | localhost | Label: rockylinux-9 2026-05-21 22:44:14.043036 | localhost | Product Name: OpenStack Nova 2026-05-21 22:44:14.043055 | localhost | Interface IP: 199.204.45.156 2026-05-21 22:44:14.060039 | 2026-05-21 22:44:14.060252 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-05-21 22:44:14.455120 | localhost -> localhost | changed 2026-05-21 22:44:14.464783 | 2026-05-21 22:44:14.464867 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-05-21 22:44:15.348808 | localhost -> localhost | changed 2026-05-21 22:44:15.359691 | 2026-05-21 22:44:15.359792 | PLAY [all] 2026-05-21 22:44:15.368022 | 2026-05-21 22:44:15.368093 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-05-21 22:44:15.616195 | rockylinux-9 -> localhost | ok 2026-05-21 22:44:15.643947 | 2026-05-21 22:44:15.644167 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-05-21 22:44:15.683548 | rockylinux-9 | ok 2026-05-21 22:44:15.703156 | rockylinux-9 | included: /var/lib/zuul/builds/349b09d8f713467ba327ed6a87c0e375/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-05-21 22:44:15.710953 | 2026-05-21 22:44:15.711017 | TASK [add-build-sshkey : Create Temp SSH key] 2026-05-21 22:44:16.795478 | rockylinux-9 -> localhost | Generating public/private rsa key pair. 2026-05-21 22:44:16.795695 | rockylinux-9 -> localhost | Your identification has been saved in /var/lib/zuul/builds/349b09d8f713467ba327ed6a87c0e375/work/349b09d8f713467ba327ed6a87c0e375_id_rsa 2026-05-21 22:44:16.795737 | rockylinux-9 -> localhost | Your public key has been saved in /var/lib/zuul/builds/349b09d8f713467ba327ed6a87c0e375/work/349b09d8f713467ba327ed6a87c0e375_id_rsa.pub 2026-05-21 22:44:16.795768 | rockylinux-9 -> localhost | The key fingerprint is: 2026-05-21 22:44:16.795798 | rockylinux-9 -> localhost | SHA256:6+5l/F8ElxLaS5MfdJsUGIayOLXqMDMUsx7RgGm5xSI zuul-build-sshkey 2026-05-21 22:44:16.795838 | rockylinux-9 -> localhost | The key's randomart image is: 2026-05-21 22:44:16.795869 | rockylinux-9 -> localhost | +---[RSA 3072]----+ 2026-05-21 22:44:16.795906 | rockylinux-9 -> localhost | | =.o .+o+o| 2026-05-21 22:44:16.795937 | rockylinux-9 -> localhost | | E * * . o .+.= =| 2026-05-21 22:44:16.795964 | rockylinux-9 -> localhost | | o + = o +. *.=.| 2026-05-21 22:44:16.795993 | rockylinux-9 -> localhost | | . + o o . =o.| 2026-05-21 22:44:16.796021 | rockylinux-9 -> localhost | | o . S . ..| 2026-05-21 22:44:16.796048 | rockylinux-9 -> localhost | | * . o . | 2026-05-21 22:44:16.796075 | rockylinux-9 -> localhost | | * . + .| 2026-05-21 22:44:16.796103 | rockylinux-9 -> localhost | | o o . . | 2026-05-21 22:44:16.796134 | rockylinux-9 -> localhost | | o+ ... | 2026-05-21 22:44:16.796161 | rockylinux-9 -> localhost | +----[SHA256]-----+ 2026-05-21 22:44:16.796228 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.636336 2026-05-21 22:44:16.803205 | 2026-05-21 22:44:16.803293 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-05-21 22:44:16.836668 | rockylinux-9 | ok 2026-05-21 22:44:16.847844 | rockylinux-9 | included: /var/lib/zuul/builds/349b09d8f713467ba327ed6a87c0e375/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-05-21 22:44:16.855347 | 2026-05-21 22:44:16.855462 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-05-21 22:44:16.881034 | rockylinux-9 | skipping: Conditional result was False 2026-05-21 22:44:16.891848 | 2026-05-21 22:44:16.891938 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-05-21 22:44:17.411425 | rockylinux-9 | changed 2026-05-21 22:44:17.418107 | 2026-05-21 22:44:17.418203 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-05-21 22:44:17.696354 | rockylinux-9 | ok 2026-05-21 22:44:17.703364 | 2026-05-21 22:44:17.703463 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-05-21 22:44:18.709937 | rockylinux-9 | changed 2026-05-21 22:44:18.714614 | 2026-05-21 22:44:18.714682 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-05-21 22:44:19.660235 | rockylinux-9 | changed 2026-05-21 22:44:19.666703 | 2026-05-21 22:44:19.666796 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-05-21 22:44:19.692195 | rockylinux-9 | skipping: Conditional result was False 2026-05-21 22:44:19.700765 | 2026-05-21 22:44:19.700854 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-05-21 22:44:20.110149 | rockylinux-9 -> localhost | changed 2026-05-21 22:44:20.120401 | 2026-05-21 22:44:20.120479 | TASK [add-build-sshkey : Add back temp key] 2026-05-21 22:44:20.420709 | rockylinux-9 -> localhost | Identity added: /var/lib/zuul/builds/349b09d8f713467ba327ed6a87c0e375/work/349b09d8f713467ba327ed6a87c0e375_id_rsa (zuul-build-sshkey) 2026-05-21 22:44:20.420922 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.014009 2026-05-21 22:44:20.426959 | 2026-05-21 22:44:20.427040 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-05-21 22:44:20.815854 | rockylinux-9 | ok 2026-05-21 22:44:20.821747 | 2026-05-21 22:44:20.821809 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-05-21 22:44:20.846875 | rockylinux-9 | skipping: Conditional result was False 2026-05-21 22:44:20.855962 | 2026-05-21 22:44:20.856029 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-05-21 22:44:21.294815 | rockylinux-9 | ok 2026-05-21 22:44:21.457760 | 2026-05-21 22:44:21.457905 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-05-21 22:44:22.821537 | rockylinux-9 | Output suppressed because no_log was given 2026-05-21 22:44:22.829005 | 2026-05-21 22:44:22.829070 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-05-21 22:44:23.097969 | rockylinux-9 | ok: "logs" 2026-05-21 22:44:23.098251 | rockylinux-9 | ok: All items complete 2026-05-21 22:44:23.098282 | 2026-05-21 22:44:23.326571 | rockylinux-9 | ok: "artifacts" 2026-05-21 22:44:23.584065 | rockylinux-9 | ok: "docs" 2026-05-21 22:44:23.598503 | 2026-05-21 22:44:23.599082 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-05-21 22:44:23.865935 | rockylinux-9 | changed: "logs" 2026-05-21 22:44:24.154875 | rockylinux-9 | changed: "artifacts" 2026-05-21 22:44:24.365681 | rockylinux-9 | changed: "docs" 2026-05-21 22:44:24.390382 | 2026-05-21 22:44:24.390529 | PLAY RECAP 2026-05-21 22:44:24.390874 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-05-21 22:44:24.390928 | rockylinux-9 | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-05-21 22:44:24.390953 | 2026-05-21 22:44:24.634382 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-05-21 22:44:24.642904 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-05-21 22:44:25.352852 | 2026-05-21 22:44:25.479091 | PLAY [all] 2026-05-21 22:44:25.500202 | 2026-05-21 22:44:25.500347 | TASK [setup-uv : Extract archive] 2026-05-21 22:44:28.350994 | rockylinux-9 | changed 2026-05-21 22:44:28.358644 | 2026-05-21 22:44:28.358753 | TASK [setup-uv : Print version] 2026-05-21 22:44:28.393201 | [rockylinux-9] Waiting on logger 2026-05-21 22:44:28.908543 | rockylinux-9 | ok: Runtime: 0:00:00.015495 2026-05-21 22:44:28.921540 | 2026-05-21 22:44:28.921673 | TASK [Install Ansible collection siblings] 2026-05-21 22:44:29.400241 | rockylinux-9 | ok: 2026-05-21 22:44:29.400432 | rockylinux-9 | Skipping ansible.posix: no checked-out sibling 2026-05-21 22:44:29.400461 | rockylinux-9 | Skipping community.general: no checked-out sibling 2026-05-21 22:44:29.405320 | 2026-05-21 22:44:29.405901 | PLAY RECAP 2026-05-21 22:44:29.405977 | rockylinux-9 | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-05-21 22:44:29.406007 | 2026-05-21 22:44:29.605701 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-05-21 22:44:29.609117 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-05-21 22:44:30.248854 | 2026-05-21 22:44:30.308612 | PLAY [all] 2026-05-21 22:44:30.331772 | 2026-05-21 22:44:30.331964 | TASK [Copy inventory file for Zuul] 2026-05-21 22:44:31.739784 | rockylinux-9 | changed 2026-05-21 22:44:31.744598 | 2026-05-21 22:44:31.744677 | TASK [Switch "ansible_host" to private IP] 2026-05-21 22:44:32.219737 | rockylinux-9 | changed: 1 replacements made 2026-05-21 22:44:32.225621 | 2026-05-21 22:44:32.225729 | TASK [Run Molecule scenario] 2026-05-21 22:44:32.255101 | [rockylinux-9] Waiting on logger 2026-05-21 22:44:40.390184 | [rockylinux-9] Waiting on logger 2026-05-21 22:44:49.542022 | [rockylinux-9] Waiting on logger 2026-05-21 22:44:59.717975 | [rockylinux-9] Waiting on logger 2026-05-21 22:45:09.893998 | [rockylinux-9] Waiting on logger 2026-05-21 22:45:20.070164 | [rockylinux-9] Waiting on logger 2026-05-21 22:45:30.250103 | [rockylinux-9] Waiting on logger 2026-05-21 22:45:39.404514 | [rockylinux-9] Waiting on logger 2026-05-21 22:45:49.574038 | [rockylinux-9] Waiting on logger 2026-05-21 22:45:59.751883 | [rockylinux-9] Waiting on logger 2026-05-21 22:46:09.926205 | [rockylinux-9] Waiting on logger 2026-05-21 22:46:20.102175 | [rockylinux-9] Waiting on logger 2026-05-21 22:46:30.278501 | [rockylinux-9] Waiting on logger 2026-05-21 22:46:39.438630 | [rockylinux-9] Waiting on logger 2026-05-21 22:46:49.606353 | [rockylinux-9] Waiting on logger 2026-05-21 22:46:59.786116 | [rockylinux-9] Waiting on logger 2026-05-21 22:47:09.958116 | [rockylinux-9] Waiting on logger 2026-05-21 22:47:20.133960 | [rockylinux-9] Waiting on logger 2026-05-21 22:47:30.310132 | [rockylinux-9] Waiting on logger 2026-05-21 22:47:39.467565 | [rockylinux-9] Waiting on logger 2026-05-21 22:47:49.637825 | [rockylinux-9] Waiting on logger 2026-05-21 22:47:59.813916 | [rockylinux-9] Waiting on logger 2026-05-21 22:48:09.989934 | [rockylinux-9] Waiting on logger 2026-05-21 22:48:20.166153 | [rockylinux-9] Waiting on logger 2026-05-21 22:48:30.345978 | [rockylinux-9] Waiting on logger 2026-05-21 22:48:39.502023 | [rockylinux-9] Waiting on logger 2026-05-21 22:48:49.671284 | [rockylinux-9] Waiting on logger 2026-05-21 22:48:59.846251 | [rockylinux-9] Waiting on logger 2026-05-21 22:49:10.021794 | [rockylinux-9] Waiting on logger 2026-05-21 22:49:20.201904 | [rockylinux-9] Waiting on logger 2026-05-21 22:49:30.374152 | [rockylinux-9] Waiting on logger 2026-05-21 22:49:39.527549 | [rockylinux-9] Waiting on logger 2026-05-21 22:49:40.652858 | rockylinux-9 | ok: Runtime: 0:05:06.820744 2026-05-21 22:49:40.661012 | 2026-05-21 22:49:40.661070 | PLAY RECAP 2026-05-21 22:49:40.661119 | rockylinux-9 | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-05-21 22:49:40.661221 | 2026-05-21 22:49:40.796950 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-05-21 22:49:40.799698 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-05-21 22:49:41.368183 | 2026-05-21 22:49:41.368322 | PLAY [all] 2026-05-21 22:49:41.379376 | 2026-05-21 22:49:41.379454 | TASK [fetch-output : Set log path for multiple nodes] 2026-05-21 22:49:41.425180 | rockylinux-9 | skipping: Conditional result was False 2026-05-21 22:49:41.434530 | 2026-05-21 22:49:41.434613 | TASK [fetch-output : Set log path for single node] 2026-05-21 22:49:41.478355 | rockylinux-9 | ok 2026-05-21 22:49:41.482537 | 2026-05-21 22:49:41.482607 | LOOP [fetch-output : Ensure local output dirs] 2026-05-21 22:49:41.997826 | rockylinux-9 -> localhost | ok: "/var/lib/zuul/builds/349b09d8f713467ba327ed6a87c0e375/work/logs" 2026-05-21 22:49:42.216055 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/349b09d8f713467ba327ed6a87c0e375/work/artifacts" 2026-05-21 22:49:42.464440 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/349b09d8f713467ba327ed6a87c0e375/work/docs" 2026-05-21 22:49:42.483604 | 2026-05-21 22:49:42.483747 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-05-21 22:49:43.204471 | rockylinux-9 | changed: .d..t...... ./ 2026-05-21 22:49:43.204705 | rockylinux-9 | changed: All items complete 2026-05-21 22:49:43.204733 | 2026-05-21 22:49:43.763008 | rockylinux-9 | changed: .d..t...... ./ 2026-05-21 22:49:44.314587 | rockylinux-9 | changed: .d..t...... ./ 2026-05-21 22:49:44.338624 | 2026-05-21 22:49:44.339211 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-05-21 22:49:44.975038 | rockylinux-9 -> localhost | ok: Item: artifacts Runtime: 0:00:00.007766 2026-05-21 22:49:45.216573 | rockylinux-9 -> localhost | ok: Item: docs Runtime: 0:00:00.009273 2026-05-21 22:49:45.226166 | 2026-05-21 22:49:45.226333 | PLAY [all] 2026-05-21 22:49:45.233567 | 2026-05-21 22:49:45.233634 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-05-21 22:49:45.711568 | rockylinux-9 | changed 2026-05-21 22:49:45.723851 | 2026-05-21 22:49:45.724031 | PLAY RECAP 2026-05-21 22:49:45.724132 | rockylinux-9 | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-05-21 22:49:45.724182 | 2026-05-21 22:49:45.903186 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-05-21 22:49:45.905742 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-05-21 22:49:46.681324 | 2026-05-21 22:49:47.047063 | PLAY [localhost] 2026-05-21 22:49:47.072574 | 2026-05-21 22:49:47.072810 | TASK [Generate Zuul manifest] 2026-05-21 22:49:47.095375 | localhost | ok 2026-05-21 22:49:47.114367 | 2026-05-21 22:49:47.114487 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-05-21 22:49:47.533246 | localhost | changed 2026-05-21 22:49:47.548079 | 2026-05-21 22:49:47.548177 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-05-21 22:49:47.582212 | localhost | ok 2026-05-21 22:49:47.591936 | 2026-05-21 22:49:47.592027 | TASK [Upload logs] 2026-05-21 22:49:47.613855 | localhost | ok 2026-05-21 22:49:47.676384 | 2026-05-21 22:49:47.676547 | TASK [Set zuul-log-path fact] 2026-05-21 22:49:47.699042 | localhost | ok 2026-05-21 22:49:47.714681 | 2026-05-21 22:49:47.714780 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-21 22:49:47.745508 | localhost | ok 2026-05-21 22:49:47.752727 | 2026-05-21 22:49:47.752804 | TASK [upload-logs : Create log directories] 2026-05-21 22:49:48.163929 | localhost | changed 2026-05-21 22:49:48.169804 | 2026-05-21 22:49:48.169875 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-05-21 22:49:48.611945 | localhost -> localhost | ok: Runtime: 0:00:00.004779 2026-05-21 22:49:48.617408 | 2026-05-21 22:49:48.617479 | TASK [upload-logs : Upload logs to log server] 2026-05-21 22:49:49.263116 | localhost | Output suppressed because no_log was given 2026-05-21 22:49:49.270074 | 2026-05-21 22:49:49.270169 | LOOP [upload-logs : Compress console log and json output] 2026-05-21 22:49:49.316788 | localhost | skipping: Conditional result was False 2026-05-21 22:49:49.324223 | localhost | skipping: Conditional result was False 2026-05-21 22:49:49.334278 | 2026-05-21 22:49:49.334378 | LOOP [upload-logs : Upload compressed console log and json output] 2026-05-21 22:49:49.384313 | localhost | skipping: Conditional result was False 2026-05-21 22:49:49.384553 | 2026-05-21 22:49:49.388861 | localhost | skipping: Conditional result was False 2026-05-21 22:49:49.395299 | 2026-05-21 22:49:49.395371 | LOOP [upload-logs : Upload console log and json output]