2026-04-26 11:22:35.710012 | Job console starting 2026-04-26 11:22:35.724606 | Updating git repos 2026-04-26 11:22:35.760502 | Cloning repos into workspace 2026-04-26 11:22:36.672044 | Restoring repo states 2026-04-26 11:22:36.681008 | Merging changes 2026-04-26 11:22:36.988016 | Checking out repos 2026-04-26 11:22:37.130755 | Preparing playbooks 2026-04-26 11:22:42.938004 | Running Ansible setup 2026-04-26 11:22:47.330640 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-04-26 11:22:48.003224 | 2026-04-26 11:22:48.003431 | PLAY [localhost] 2026-04-26 11:22:48.013368 | 2026-04-26 11:22:48.013464 | TASK [Gathering Facts] 2026-04-26 11:22:49.651184 | localhost | ok 2026-04-26 11:22:49.660187 | 2026-04-26 11:22:49.660283 | TASK [Setup log path fact] 2026-04-26 11:22:49.682254 | localhost | ok 2026-04-26 11:22:49.696740 | 2026-04-26 11:22:49.696814 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-26 11:22:49.727590 | localhost | ok 2026-04-26 11:22:49.735288 | 2026-04-26 11:22:49.735383 | TASK [emit-job-header : Print job information] 2026-04-26 11:22:49.774974 | # Job Information 2026-04-26 11:22:49.775133 | Ansible Version: 2.16.16 2026-04-26 11:22:49.775179 | Job: ansible-collection-containers-molecule-containerd-rockylinux-9 2026-04-26 11:22:49.775211 | Pipeline: check 2026-04-26 11:22:49.775240 | Executor: 0a8996d2b663 2026-04-26 11:22:49.775268 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/122 2026-04-26 11:22:49.775300 | Event ID: 21689ae0-4162-11f1-9bf5-ceff5f6c5044 2026-04-26 11:22:49.779421 | 2026-04-26 11:22:49.779517 | LOOP [emit-job-header : Print node information] 2026-04-26 11:22:49.880771 | localhost | ok: 2026-04-26 11:22:49.881010 | localhost | # Node Information 2026-04-26 11:22:49.881074 | localhost | Inventory Hostname: rockylinux-9 2026-04-26 11:22:49.881116 | localhost | Hostname: np0000170265 2026-04-26 11:22:49.881224 | localhost | Username: zuul 2026-04-26 11:22:49.881265 | localhost | Distro: Rocky 9.7 2026-04-26 11:22:49.881294 | localhost | Provider: yul1 2026-04-26 11:22:49.881322 | localhost | Region: ca-ymq-1 2026-04-26 11:22:49.881349 | localhost | Label: rockylinux-9 2026-04-26 11:22:49.881374 | localhost | Product Name: OpenStack Nova 2026-04-26 11:22:49.881401 | localhost | Interface IP: 199.204.45.229 2026-04-26 11:22:49.899410 | 2026-04-26 11:22:49.899491 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-04-26 11:22:51.712196 | localhost -> localhost | changed 2026-04-26 11:22:51.717292 | 2026-04-26 11:22:51.717361 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-04-26 11:22:52.669622 | localhost -> localhost | changed 2026-04-26 11:22:52.677937 | 2026-04-26 11:22:52.678010 | PLAY [all] 2026-04-26 11:22:52.695590 | 2026-04-26 11:22:52.695715 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-04-26 11:22:52.923618 | rockylinux-9 -> localhost | ok 2026-04-26 11:22:52.933252 | 2026-04-26 11:22:52.933345 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-04-26 11:22:52.965362 | rockylinux-9 | ok 2026-04-26 11:22:52.981192 | rockylinux-9 | included: /var/lib/zuul/builds/bf733bdafb8340f78d49ac9a6a6bf072/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-04-26 11:22:52.986627 | 2026-04-26 11:22:52.986695 | TASK [add-build-sshkey : Create Temp SSH key] 2026-04-26 11:22:54.165910 | rockylinux-9 -> localhost | Generating public/private rsa key pair. 2026-04-26 11:22:54.166086 | rockylinux-9 -> localhost | Your identification has been saved in /var/lib/zuul/builds/bf733bdafb8340f78d49ac9a6a6bf072/work/bf733bdafb8340f78d49ac9a6a6bf072_id_rsa 2026-04-26 11:22:54.166116 | rockylinux-9 -> localhost | Your public key has been saved in /var/lib/zuul/builds/bf733bdafb8340f78d49ac9a6a6bf072/work/bf733bdafb8340f78d49ac9a6a6bf072_id_rsa.pub 2026-04-26 11:22:54.166139 | rockylinux-9 -> localhost | The key fingerprint is: 2026-04-26 11:22:54.166161 | rockylinux-9 -> localhost | SHA256:3Vh0uOG3hZUF2xOwafgBXYHsg5kIb5LlqAc8CRMVzng zuul-build-sshkey 2026-04-26 11:22:54.166188 | rockylinux-9 -> localhost | The key's randomart image is: 2026-04-26 11:22:54.166210 | rockylinux-9 -> localhost | +---[RSA 3072]----+ 2026-04-26 11:22:54.166236 | rockylinux-9 -> localhost | | .oo. .+o*==| 2026-04-26 11:22:54.166258 | rockylinux-9 -> localhost | | o+ . . .==o+o| 2026-04-26 11:22:54.166278 | rockylinux-9 -> localhost | | .+E. B .o*B.+.| 2026-04-26 11:22:54.166298 | rockylinux-9 -> localhost | | .= + * B=o+ o| 2026-04-26 11:22:54.166317 | rockylinux-9 -> localhost | | + S o .o.o | 2026-04-26 11:22:54.166337 | rockylinux-9 -> localhost | | . . . | 2026-04-26 11:22:54.166355 | rockylinux-9 -> localhost | | . | 2026-04-26 11:22:54.166375 | rockylinux-9 -> localhost | | | 2026-04-26 11:22:54.166396 | rockylinux-9 -> localhost | | | 2026-04-26 11:22:54.166416 | rockylinux-9 -> localhost | +----[SHA256]-----+ 2026-04-26 11:22:54.166462 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.720940 2026-04-26 11:22:54.171996 | 2026-04-26 11:22:54.172060 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-04-26 11:22:54.204811 | rockylinux-9 | ok 2026-04-26 11:22:54.214897 | rockylinux-9 | included: /var/lib/zuul/builds/bf733bdafb8340f78d49ac9a6a6bf072/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-04-26 11:22:54.223399 | 2026-04-26 11:22:54.223462 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-04-26 11:22:54.248172 | rockylinux-9 | skipping: Conditional result was False 2026-04-26 11:22:54.257414 | 2026-04-26 11:22:54.257598 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-04-26 11:22:54.707998 | rockylinux-9 | changed 2026-04-26 11:22:54.712610 | 2026-04-26 11:22:54.712670 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-04-26 11:22:54.970039 | rockylinux-9 | ok 2026-04-26 11:22:54.974747 | 2026-04-26 11:22:54.974806 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-04-26 11:22:55.846342 | rockylinux-9 | changed 2026-04-26 11:22:56.105368 | 2026-04-26 11:22:56.105512 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-04-26 11:22:56.967969 | rockylinux-9 | changed 2026-04-26 11:22:56.973449 | 2026-04-26 11:22:56.973544 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-04-26 11:22:56.997977 | rockylinux-9 | skipping: Conditional result was False 2026-04-26 11:22:57.008888 | 2026-04-26 11:22:57.008977 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-04-26 11:22:57.396686 | rockylinux-9 -> localhost | changed 2026-04-26 11:22:57.411323 | 2026-04-26 11:22:57.411389 | TASK [add-build-sshkey : Add back temp key] 2026-04-26 11:22:57.686101 | rockylinux-9 -> localhost | Identity added: /var/lib/zuul/builds/bf733bdafb8340f78d49ac9a6a6bf072/work/bf733bdafb8340f78d49ac9a6a6bf072_id_rsa (zuul-build-sshkey) 2026-04-26 11:22:57.686297 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.014153 2026-04-26 11:22:57.692800 | 2026-04-26 11:22:57.692861 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-04-26 11:22:58.075196 | rockylinux-9 | ok 2026-04-26 11:22:58.079687 | 2026-04-26 11:22:58.079747 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-04-26 11:22:58.104050 | rockylinux-9 | skipping: Conditional result was False 2026-04-26 11:22:58.115813 | 2026-04-26 11:22:58.115896 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-04-26 11:22:58.495156 | rockylinux-9 | ok 2026-04-26 11:22:58.502431 | 2026-04-26 11:22:58.502510 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-04-26 11:23:02.244985 | rockylinux-9 | Output suppressed because no_log was given 2026-04-26 11:23:02.267400 | 2026-04-26 11:23:02.267489 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-04-26 11:23:02.522583 | rockylinux-9 | ok: "logs" 2026-04-26 11:23:02.522889 | rockylinux-9 | ok: All items complete 2026-04-26 11:23:02.522931 | 2026-04-26 11:23:02.755034 | rockylinux-9 | ok: "artifacts" 2026-04-26 11:23:02.970158 | rockylinux-9 | ok: "docs" 2026-04-26 11:23:02.984384 | 2026-04-26 11:23:02.984521 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-04-26 11:23:03.233574 | rockylinux-9 | changed: "logs" 2026-04-26 11:23:03.457572 | rockylinux-9 | changed: "artifacts" 2026-04-26 11:23:03.678911 | rockylinux-9 | changed: "docs" 2026-04-26 11:23:03.699775 | 2026-04-26 11:23:03.699918 | PLAY RECAP 2026-04-26 11:23:03.699987 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-26 11:23:03.700033 | rockylinux-9 | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-04-26 11:23:03.700064 | 2026-04-26 11:23:03.857262 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-04-26 11:23:03.858535 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-04-26 11:23:04.516659 | 2026-04-26 11:23:04.516793 | PLAY [all] 2026-04-26 11:23:04.527697 | 2026-04-26 11:23:04.527775 | TASK [setup-uv : Extract archive] 2026-04-26 11:23:07.201705 | rockylinux-9 | changed 2026-04-26 11:23:07.208224 | 2026-04-26 11:23:07.208337 | TASK [setup-uv : Print version] 2026-04-26 11:23:07.229365 | [rockylinux-9] Waiting on logger 2026-04-26 11:23:07.746121 | rockylinux-9 | ok: Runtime: 0:00:00.013878 2026-04-26 11:23:07.935820 | 2026-04-26 11:23:07.935942 | PLAY RECAP 2026-04-26 11:23:07.935992 | rockylinux-9 | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-26 11:23:07.936019 | 2026-04-26 11:23:08.132015 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-04-26 11:23:08.133748 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-04-26 11:23:08.756835 | 2026-04-26 11:23:09.296364 | PLAY [all] 2026-04-26 11:23:09.316104 | 2026-04-26 11:23:09.316248 | TASK [Copy inventory file for Zuul] 2026-04-26 11:23:10.651120 | rockylinux-9 | changed 2026-04-26 11:23:10.660846 | 2026-04-26 11:23:10.660997 | TASK [Switch "ansible_host" to private IP] 2026-04-26 11:23:10.998413 | rockylinux-9 | changed: 1 replacements made 2026-04-26 11:23:11.174253 | 2026-04-26 11:23:11.174349 | TASK [Run Molecule scenario] 2026-04-26 11:23:11.206817 | [rockylinux-9] Waiting on logger 2026-04-26 11:23:18.315224 | [rockylinux-9] Waiting on logger 2026-04-26 11:23:28.486077 | [rockylinux-9] Waiting on logger 2026-04-26 11:23:38.662198 | [rockylinux-9] Waiting on logger 2026-04-26 11:23:48.845105 | [rockylinux-9] Waiting on logger 2026-04-26 11:23:59.016136 | [rockylinux-9] Waiting on logger 2026-04-26 11:24:08.236020 | [rockylinux-9] Waiting on logger 2026-04-26 11:24:19.046399 | [rockylinux-9] Waiting on logger 2026-04-26 11:24:29.226191 | [rockylinux-9] Waiting on logger 2026-04-26 11:24:38.374442 | [rockylinux-9] Waiting on logger 2026-04-26 11:24:48.966481 | [rockylinux-9] Waiting on logger 2026-04-26 11:24:59.142114 | [rockylinux-9] Waiting on logger 2026-04-26 11:25:08.422481 | [rockylinux-9] Waiting on logger 2026-04-26 11:25:18.697828 | [rockylinux-9] Waiting on logger 2026-04-26 11:25:28.870098 | [rockylinux-9] Waiting on logger 2026-04-26 11:25:39.206141 | [rockylinux-9] Waiting on logger 2026-04-26 11:25:48.361899 | [rockylinux-9] Waiting on logger 2026-04-26 11:25:58.598160 | [rockylinux-9] Waiting on logger 2026-04-26 11:26:08.518082 | [rockylinux-9] Waiting on logger 2026-04-26 11:26:18.698132 | [rockylinux-9] Waiting on logger 2026-04-26 11:26:28.870582 | [rockylinux-9] Waiting on logger 2026-04-26 11:26:39.046077 | [rockylinux-9] Waiting on logger 2026-04-26 11:26:49.222493 | [rockylinux-9] Waiting on logger 2026-04-26 11:26:58.374176 | [rockylinux-9] Waiting on logger 2026-04-26 11:27:08.550233 | [rockylinux-9] Waiting on logger 2026-04-26 11:27:18.918188 | [rockylinux-9] Waiting on logger 2026-04-26 11:27:29.094371 | [rockylinux-9] Waiting on logger 2026-04-26 11:27:31.439561 | rockylinux-9 | ok: Runtime: 0:04:19.120730 2026-04-26 11:27:31.449302 | 2026-04-26 11:27:31.449455 | PLAY RECAP 2026-04-26 11:27:31.449533 | rockylinux-9 | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-26 11:27:31.449570 | 2026-04-26 11:27:31.617926 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-04-26 11:27:31.620146 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-04-26 11:27:32.270025 | 2026-04-26 11:27:32.270157 | PLAY [all] 2026-04-26 11:27:32.280840 | 2026-04-26 11:27:32.280915 | TASK [fetch-output : Set log path for multiple nodes] 2026-04-26 11:27:32.326453 | rockylinux-9 | skipping: Conditional result was False 2026-04-26 11:27:32.336398 | 2026-04-26 11:27:32.336481 | TASK [fetch-output : Set log path for single node] 2026-04-26 11:27:32.378860 | rockylinux-9 | ok 2026-04-26 11:27:32.386231 | 2026-04-26 11:27:32.386323 | LOOP [fetch-output : Ensure local output dirs] 2026-04-26 11:27:32.776200 | rockylinux-9 -> localhost | ok: "/var/lib/zuul/builds/bf733bdafb8340f78d49ac9a6a6bf072/work/logs" 2026-04-26 11:27:32.989525 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/bf733bdafb8340f78d49ac9a6a6bf072/work/artifacts" 2026-04-26 11:27:33.222275 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/bf733bdafb8340f78d49ac9a6a6bf072/work/docs" 2026-04-26 11:27:33.240990 | 2026-04-26 11:27:33.241170 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-04-26 11:27:33.914441 | rockylinux-9 | changed: .d..t...... ./ 2026-04-26 11:27:33.914710 | rockylinux-9 | changed: All items complete 2026-04-26 11:27:33.914750 | 2026-04-26 11:27:34.413565 | rockylinux-9 | changed: .d..t...... ./ 2026-04-26 11:27:34.914850 | rockylinux-9 | changed: .d..t...... ./ 2026-04-26 11:27:34.940491 | 2026-04-26 11:27:34.940657 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-04-26 11:27:35.357895 | rockylinux-9 -> localhost | ok: Item: artifacts Runtime: 0:00:00.007638 2026-04-26 11:27:35.593230 | rockylinux-9 -> localhost | ok: Item: docs Runtime: 0:00:00.009280 2026-04-26 11:27:35.617300 | 2026-04-26 11:27:35.617432 | PLAY [all] 2026-04-26 11:27:35.623173 | 2026-04-26 11:27:35.623243 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-04-26 11:27:36.079889 | rockylinux-9 | changed 2026-04-26 11:27:36.085943 | 2026-04-26 11:27:36.086044 | PLAY RECAP 2026-04-26 11:27:36.086114 | rockylinux-9 | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-04-26 11:27:36.086149 | 2026-04-26 11:27:36.203956 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-04-26 11:27:36.206227 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-04-26 11:27:36.834114 | 2026-04-26 11:27:36.834236 | PLAY [localhost] 2026-04-26 11:27:36.844177 | 2026-04-26 11:27:36.844247 | TASK [Generate Zuul manifest] 2026-04-26 11:27:36.866705 | localhost | ok 2026-04-26 11:27:36.881673 | 2026-04-26 11:27:36.881750 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-04-26 11:27:37.206495 | localhost | changed 2026-04-26 11:27:37.218238 | 2026-04-26 11:27:37.218311 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-04-26 11:27:37.249506 | localhost | ok 2026-04-26 11:27:37.258467 | 2026-04-26 11:27:37.258550 | TASK [Upload logs] 2026-04-26 11:27:37.278012 | localhost | ok 2026-04-26 11:27:37.337172 | 2026-04-26 11:27:37.337263 | TASK [Set zuul-log-path fact] 2026-04-26 11:27:37.356133 | localhost | ok 2026-04-26 11:27:37.370152 | 2026-04-26 11:27:37.370226 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-26 11:27:37.401886 | localhost | ok 2026-04-26 11:27:37.411842 | 2026-04-26 11:27:37.411928 | TASK [upload-logs : Create log directories] 2026-04-26 11:27:37.821582 | localhost | changed 2026-04-26 11:27:37.827495 | 2026-04-26 11:27:37.827566 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-04-26 11:27:38.222731 | localhost -> localhost | ok: Runtime: 0:00:00.007414 2026-04-26 11:27:38.231203 | 2026-04-26 11:27:38.231296 | TASK [upload-logs : Upload logs to log server] 2026-04-26 11:27:38.716197 | localhost | Output suppressed because no_log was given 2026-04-26 11:27:38.721600 | 2026-04-26 11:27:38.721672 | LOOP [upload-logs : Compress console log and json output] 2026-04-26 11:27:38.769419 | localhost | skipping: Conditional result was False 2026-04-26 11:27:38.778497 | localhost | skipping: Conditional result was False 2026-04-26 11:27:38.798008 | 2026-04-26 11:27:38.798246 | LOOP [upload-logs : Upload compressed console log and json output] 2026-04-26 11:27:38.846670 | localhost | skipping: Conditional result was False 2026-04-26 11:27:38.847066 | 2026-04-26 11:27:38.851663 | localhost | skipping: Conditional result was False 2026-04-26 11:27:38.863679 | 2026-04-26 11:27:38.864129 | LOOP [upload-logs : Upload console log and json output]