2026-03-18 09:41:55.158263 | Job console starting 2026-03-18 09:41:55.198040 | Updating git repos 2026-03-18 09:41:55.225487 | Cloning repos into workspace 2026-03-18 09:41:55.308284 | Restoring repo states 2026-03-18 09:41:55.315415 | Merging changes 2026-03-18 09:41:55.645110 | Checking out repos 2026-03-18 09:41:55.686802 | Preparing playbooks 2026-03-18 09:41:56.921741 | Running Ansible setup 2026-03-18 09:42:01.234008 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-03-18 09:42:01.822524 | 2026-03-18 09:42:01.822975 | PLAY [localhost] 2026-03-18 09:42:01.831418 | 2026-03-18 09:42:01.831522 | TASK [Gathering Facts] 2026-03-18 09:42:02.754774 | localhost | ok 2026-03-18 09:42:02.765778 | 2026-03-18 09:42:02.765926 | TASK [Setup log path fact] 2026-03-18 09:42:02.788984 | localhost | ok 2026-03-18 09:42:02.809746 | 2026-03-18 09:42:02.809937 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-03-18 09:42:02.839680 | localhost | ok 2026-03-18 09:42:02.849223 | 2026-03-18 09:42:02.849372 | TASK [emit-job-header : Print job information] 2026-03-18 09:42:02.892394 | # Job Information 2026-03-18 09:42:02.892674 | Ansible Version: 2.16.16 2026-03-18 09:42:02.892727 | Job: ansible-collection-containers-molecule-containerd-rockylinux-9 2026-03-18 09:42:02.892762 | Pipeline: check 2026-03-18 09:42:02.892793 | Executor: 0a8996d2b663 2026-03-18 09:42:02.892822 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/104 2026-03-18 09:42:02.892855 | Event ID: 77e49900-22ae-11f1-94ea-44190962b9a6 2026-03-18 09:42:02.897324 | 2026-03-18 09:42:02.897454 | LOOP [emit-job-header : Print node information] 2026-03-18 09:42:03.027344 | localhost | ok: 2026-03-18 09:42:03.027619 | localhost | # Node Information 2026-03-18 09:42:03.027665 | localhost | Inventory Hostname: rockylinux-9 2026-03-18 09:42:03.027702 | localhost | Hostname: np0000159758 2026-03-18 09:42:03.027733 | localhost | Username: zuul 2026-03-18 09:42:03.027765 | localhost | Distro: Rocky 9.7 2026-03-18 09:42:03.027793 | localhost | Provider: yul1 2026-03-18 09:42:03.027819 | localhost | Region: ca-ymq-1 2026-03-18 09:42:03.027845 | localhost | Label: rockylinux-9 2026-03-18 09:42:03.027890 | localhost | Product Name: OpenStack Nova 2026-03-18 09:42:03.027920 | localhost | Interface IP: 199.204.45.140 2026-03-18 09:42:03.042667 | 2026-03-18 09:42:03.042834 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-03-18 09:42:03.432199 | localhost -> localhost | changed 2026-03-18 09:42:03.445395 | 2026-03-18 09:42:03.445497 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-03-18 09:42:04.384162 | localhost -> localhost | changed 2026-03-18 09:42:04.393994 | 2026-03-18 09:42:04.394074 | PLAY [all] 2026-03-18 09:42:04.404731 | 2026-03-18 09:42:04.404819 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-03-18 09:42:04.631211 | rockylinux-9 -> localhost | ok 2026-03-18 09:42:04.636407 | 2026-03-18 09:42:04.636478 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-03-18 09:42:04.668385 | rockylinux-9 | ok 2026-03-18 09:42:04.751086 | rockylinux-9 | included: /var/lib/zuul/builds/587c0cff2f6f4e8c9c998831ce787162/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-03-18 09:42:04.758039 | 2026-03-18 09:42:04.758103 | TASK [add-build-sshkey : Create Temp SSH key] 2026-03-18 09:42:05.846454 | rockylinux-9 -> localhost | Generating public/private rsa key pair. 2026-03-18 09:42:05.846658 | rockylinux-9 -> localhost | Your identification has been saved in /var/lib/zuul/builds/587c0cff2f6f4e8c9c998831ce787162/work/587c0cff2f6f4e8c9c998831ce787162_id_rsa 2026-03-18 09:42:05.846704 | rockylinux-9 -> localhost | Your public key has been saved in /var/lib/zuul/builds/587c0cff2f6f4e8c9c998831ce787162/work/587c0cff2f6f4e8c9c998831ce787162_id_rsa.pub 2026-03-18 09:42:05.846737 | rockylinux-9 -> localhost | The key fingerprint is: 2026-03-18 09:42:05.846769 | rockylinux-9 -> localhost | SHA256:7Jj5Qmf6KkjiFA24VmEH7dHJtpxIAC8JKtya52TIoVY zuul-build-sshkey 2026-03-18 09:42:05.846817 | rockylinux-9 -> localhost | The key's randomart image is: 2026-03-18 09:42:05.846848 | rockylinux-9 -> localhost | +---[RSA 3072]----+ 2026-03-18 09:42:05.846880 | rockylinux-9 -> localhost | |o..==.o . | 2026-03-18 09:42:05.846912 | rockylinux-9 -> localhost | |=.=..+ = | 2026-03-18 09:42:05.846943 | rockylinux-9 -> localhost | |o*+Eo = o | 2026-03-18 09:42:05.846973 | rockylinux-9 -> localhost | |=oB. o = | 2026-03-18 09:42:05.847002 | rockylinux-9 -> localhost | |o*.+ S | 2026-03-18 09:42:05.847031 | rockylinux-9 -> localhost | |o.* .=o | 2026-03-18 09:42:05.847059 | rockylinux-9 -> localhost | |oo o .++. | 2026-03-18 09:42:05.847088 | rockylinux-9 -> localhost | | .. . o. | 2026-03-18 09:42:05.847121 | rockylinux-9 -> localhost | | ..+o | 2026-03-18 09:42:05.847150 | rockylinux-9 -> localhost | +----[SHA256]-----+ 2026-03-18 09:42:05.847213 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.621839 2026-03-18 09:42:05.854154 | 2026-03-18 09:42:05.854729 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-03-18 09:42:05.874508 | rockylinux-9 | ok 2026-03-18 09:42:05.885163 | rockylinux-9 | included: /var/lib/zuul/builds/587c0cff2f6f4e8c9c998831ce787162/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-03-18 09:42:05.895519 | 2026-03-18 09:42:05.895643 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-03-18 09:42:05.928505 | rockylinux-9 | skipping: Conditional result was False 2026-03-18 09:42:05.937407 | 2026-03-18 09:42:05.937510 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-03-18 09:42:06.535237 | rockylinux-9 | changed 2026-03-18 09:42:06.567788 | 2026-03-18 09:42:06.567966 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-03-18 09:42:06.825832 | rockylinux-9 | ok 2026-03-18 09:42:06.832147 | 2026-03-18 09:42:06.832301 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-03-18 09:42:07.838173 | rockylinux-9 | changed 2026-03-18 09:42:07.956270 | 2026-03-18 09:42:07.956353 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-03-18 09:42:08.904996 | rockylinux-9 | changed 2026-03-18 09:42:08.911271 | 2026-03-18 09:42:08.911356 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-03-18 09:42:08.935587 | rockylinux-9 | skipping: Conditional result was False 2026-03-18 09:42:08.943463 | 2026-03-18 09:42:08.943572 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-03-18 09:42:09.576162 | rockylinux-9 -> localhost | changed 2026-03-18 09:42:09.591658 | 2026-03-18 09:42:09.591778 | TASK [add-build-sshkey : Add back temp key] 2026-03-18 09:42:09.886601 | rockylinux-9 -> localhost | Identity added: /var/lib/zuul/builds/587c0cff2f6f4e8c9c998831ce787162/work/587c0cff2f6f4e8c9c998831ce787162_id_rsa (zuul-build-sshkey) 2026-03-18 09:42:09.886936 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.030338 2026-03-18 09:42:09.899582 | 2026-03-18 09:42:09.899740 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-03-18 09:42:10.319358 | rockylinux-9 | ok 2026-03-18 09:42:10.335604 | 2026-03-18 09:42:10.335746 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-03-18 09:42:10.361380 | rockylinux-9 | skipping: Conditional result was False 2026-03-18 09:42:10.383381 | 2026-03-18 09:42:10.383445 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-03-18 09:42:10.769596 | rockylinux-9 | ok 2026-03-18 09:42:10.804982 | 2026-03-18 09:42:10.805125 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-03-18 09:42:12.224453 | rockylinux-9 | Output suppressed because no_log was given 2026-03-18 09:42:12.237081 | 2026-03-18 09:42:12.237169 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-03-18 09:42:12.483851 | rockylinux-9 | ok: "logs" 2026-03-18 09:42:12.505046 | rockylinux-9 | ok: All items complete 2026-03-18 09:42:12.505103 | 2026-03-18 09:42:12.707829 | rockylinux-9 | ok: "artifacts" 2026-03-18 09:42:12.939954 | rockylinux-9 | ok: "docs" 2026-03-18 09:42:12.962913 | 2026-03-18 09:42:12.963233 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-03-18 09:42:13.227026 | rockylinux-9 | changed: "logs" 2026-03-18 09:42:13.443780 | rockylinux-9 | changed: "artifacts" 2026-03-18 09:42:13.693741 | rockylinux-9 | changed: "docs" 2026-03-18 09:42:13.837228 | 2026-03-18 09:42:13.837381 | PLAY RECAP 2026-03-18 09:42:13.837454 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-03-18 09:42:13.837502 | rockylinux-9 | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-03-18 09:42:13.837557 | 2026-03-18 09:42:13.973178 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-03-18 09:42:13.974627 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-03-18 09:42:14.543733 | 2026-03-18 09:42:14.543869 | PLAY [all] 2026-03-18 09:42:14.555351 | 2026-03-18 09:42:14.555478 | TASK [setup-uv : Extract archive] 2026-03-18 09:42:17.347835 | rockylinux-9 | changed 2026-03-18 09:42:17.354593 | 2026-03-18 09:42:17.354737 | TASK [setup-uv : Print version] 2026-03-18 09:42:17.383303 | [rockylinux-9] Waiting on logger 2026-03-18 09:42:17.895866 | rockylinux-9 | ok: Runtime: 0:00:00.013716 2026-03-18 09:42:17.989217 | 2026-03-18 09:42:17.989320 | PLAY RECAP 2026-03-18 09:42:17.989381 | rockylinux-9 | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-03-18 09:42:17.989415 | 2026-03-18 09:42:18.122683 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-03-18 09:42:18.131787 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-03-18 09:42:18.792181 | 2026-03-18 09:42:18.792532 | PLAY [all] 2026-03-18 09:42:18.825079 | 2026-03-18 09:42:18.825466 | TASK [Copy inventory file for Zuul] 2026-03-18 09:42:20.150164 | rockylinux-9 | changed 2026-03-18 09:42:20.163422 | 2026-03-18 09:42:20.163583 | TASK [Switch "ansible_host" to private IP] 2026-03-18 09:42:20.521294 | rockylinux-9 | changed: 1 replacements made 2026-03-18 09:42:20.530649 | 2026-03-18 09:42:20.530798 | TASK [Run Molecule scenario] 2026-03-18 09:42:20.557201 | [rockylinux-9] Waiting on logger 2026-03-18 09:42:28.713915 | [rockylinux-9] Waiting on logger 2026-03-18 09:42:38.886238 | [rockylinux-9] Waiting on logger 2026-03-18 09:42:49.066605 | [rockylinux-9] Waiting on logger 2026-03-18 09:42:59.238008 | [rockylinux-9] Waiting on logger 2026-03-18 09:43:08.395451 | [rockylinux-9] Waiting on logger 2026-03-18 09:43:19.014151 | [rockylinux-9] Waiting on logger 2026-03-18 09:43:29.190074 | [rockylinux-9] Waiting on logger 2026-03-18 09:43:39.302135 | [rockylinux-9] Waiting on logger 2026-03-18 09:43:48.459037 | [rockylinux-9] Waiting on logger 2026-03-18 09:43:58.886197 | [rockylinux-9] Waiting on logger 2026-03-18 09:44:09.061955 | [rockylinux-9] Waiting on logger 2026-03-18 09:44:19.238082 | [rockylinux-9] Waiting on logger 2026-03-18 09:44:28.395270 | [rockylinux-9] Waiting on logger 2026-03-18 09:44:38.568584 | [rockylinux-9] Waiting on logger 2026-03-18 09:44:48.742402 | [rockylinux-9] Waiting on logger 2026-03-18 09:44:58.922100 | [rockylinux-9] Waiting on logger 2026-03-18 09:45:09.095328 | [rockylinux-9] Waiting on logger 2026-03-18 09:45:19.269989 | [rockylinux-9] Waiting on logger 2026-03-18 09:45:28.427627 | [rockylinux-9] Waiting on logger 2026-03-18 09:45:38.598108 | [rockylinux-9] Waiting on logger 2026-03-18 09:45:48.774322 | [rockylinux-9] Waiting on logger 2026-03-18 09:45:58.950207 | [rockylinux-9] Waiting on logger 2026-03-18 09:46:09.126873 | [rockylinux-9] Waiting on logger 2026-03-18 09:46:19.302267 | [rockylinux-9] Waiting on logger 2026-03-18 09:46:28.463564 | [rockylinux-9] Waiting on logger 2026-03-18 09:46:38.630156 | [rockylinux-9] Waiting on logger 2026-03-18 09:46:48.805789 | [rockylinux-9] Waiting on logger 2026-03-18 09:46:55.629920 | rockylinux-9 | ok: Runtime: 0:04:33.907942 2026-03-18 09:46:55.637294 | 2026-03-18 09:46:55.637350 | PLAY RECAP 2026-03-18 09:46:55.637397 | rockylinux-9 | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-03-18 09:46:55.637421 | 2026-03-18 09:46:55.727442 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-03-18 09:46:55.733131 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-03-18 09:46:56.340949 | 2026-03-18 09:46:56.341160 | PLAY [all] 2026-03-18 09:46:56.353564 | 2026-03-18 09:46:56.353748 | TASK [fetch-output : Set log path for multiple nodes] 2026-03-18 09:46:56.400169 | rockylinux-9 | skipping: Conditional result was False 2026-03-18 09:46:56.411007 | 2026-03-18 09:46:56.411199 | TASK [fetch-output : Set log path for single node] 2026-03-18 09:46:56.457395 | rockylinux-9 | ok 2026-03-18 09:46:56.465194 | 2026-03-18 09:46:56.465266 | LOOP [fetch-output : Ensure local output dirs] 2026-03-18 09:46:56.830887 | rockylinux-9 -> localhost | ok: "/var/lib/zuul/builds/587c0cff2f6f4e8c9c998831ce787162/work/logs" 2026-03-18 09:46:57.037653 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/587c0cff2f6f4e8c9c998831ce787162/work/artifacts" 2026-03-18 09:46:57.252040 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/587c0cff2f6f4e8c9c998831ce787162/work/docs" 2026-03-18 09:46:57.266674 | 2026-03-18 09:46:57.266769 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-03-18 09:46:57.964775 | rockylinux-9 | ok 2026-03-18 09:46:57.965009 | rockylinux-9 | changed: All items complete 2026-03-18 09:46:57.965049 | 2026-03-18 09:46:58.481376 | rockylinux-9 | changed: .d..t...... ./ 2026-03-18 09:46:58.997010 | rockylinux-9 | changed: .d..t...... ./ 2026-03-18 09:46:59.015903 | 2026-03-18 09:46:59.016415 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-03-18 09:46:59.445736 | rockylinux-9 -> localhost | ok: Item: artifacts Runtime: 0:00:00.006844 2026-03-18 09:46:59.667959 | rockylinux-9 -> localhost | ok: Item: docs Runtime: 0:00:00.008785 2026-03-18 09:46:59.682311 | 2026-03-18 09:46:59.682511 | PLAY [all] 2026-03-18 09:46:59.690210 | 2026-03-18 09:46:59.690308 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-03-18 09:47:00.140641 | rockylinux-9 | changed 2026-03-18 09:47:00.147773 | 2026-03-18 09:47:00.147820 | PLAY RECAP 2026-03-18 09:47:00.147863 | rockylinux-9 | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-03-18 09:47:00.147885 | 2026-03-18 09:47:00.234303 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-03-18 09:47:00.235909 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-03-18 09:47:00.783343 | 2026-03-18 09:47:00.783475 | PLAY [localhost] 2026-03-18 09:47:00.793812 | 2026-03-18 09:47:00.793934 | TASK [Generate Zuul manifest] 2026-03-18 09:47:00.815003 | localhost | ok 2026-03-18 09:47:00.831019 | 2026-03-18 09:47:00.831119 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-03-18 09:47:01.154638 | localhost | changed 2026-03-18 09:47:01.166268 | 2026-03-18 09:47:01.166350 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-03-18 09:47:01.197357 | localhost | ok 2026-03-18 09:47:01.207418 | 2026-03-18 09:47:01.207546 | TASK [Upload logs] 2026-03-18 09:47:01.229147 | localhost | ok 2026-03-18 09:47:01.291234 | 2026-03-18 09:47:01.291375 | TASK [Set zuul-log-path fact] 2026-03-18 09:47:01.312993 | localhost | ok 2026-03-18 09:47:01.325425 | 2026-03-18 09:47:01.325490 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-03-18 09:47:01.356681 | localhost | ok 2026-03-18 09:47:01.365678 | 2026-03-18 09:47:01.365749 | TASK [upload-logs : Create log directories] 2026-03-18 09:47:01.752439 | localhost | changed 2026-03-18 09:47:01.756924 | 2026-03-18 09:47:01.757029 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-03-18 09:47:02.132853 | localhost -> localhost | ok: Runtime: 0:00:00.003410 2026-03-18 09:47:02.140160 | 2026-03-18 09:47:02.140270 | TASK [upload-logs : Upload logs to log server] 2026-03-18 09:47:02.578593 | localhost | Output suppressed because no_log was given 2026-03-18 09:47:02.585228 | 2026-03-18 09:47:02.585321 | LOOP [upload-logs : Compress console log and json output] 2026-03-18 09:47:02.631133 | localhost | skipping: Conditional result was False 2026-03-18 09:47:02.638499 | localhost | skipping: Conditional result was False 2026-03-18 09:47:02.651127 | 2026-03-18 09:47:02.651273 | LOOP [upload-logs : Upload compressed console log and json output] 2026-03-18 09:47:02.703432 | localhost | skipping: Conditional result was False 2026-03-18 09:47:02.703804 | 2026-03-18 09:47:02.708276 | localhost | skipping: Conditional result was False 2026-03-18 09:47:02.724983 | 2026-03-18 09:47:02.725055 | LOOP [upload-logs : Upload console log and json output]