2026-04-14 02:57:07.137072 | Job console starting 2026-04-14 02:57:07.146816 | Updating git repos 2026-04-14 02:57:07.171783 | Cloning repos into workspace 2026-04-14 02:57:07.224189 | Restoring repo states 2026-04-14 02:57:07.231609 | Merging changes 2026-04-14 02:57:07.530930 | Checking out repos 2026-04-14 02:57:07.571664 | Preparing playbooks 2026-04-14 02:57:08.694077 | Running Ansible setup 2026-04-14 02:57:12.475047 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-04-14 02:57:13.088839 | 2026-04-14 02:57:13.088993 | PLAY [localhost] 2026-04-14 02:57:13.097069 | 2026-04-14 02:57:13.097145 | TASK [Gathering Facts] 2026-04-14 02:57:14.048912 | localhost | ok 2026-04-14 02:57:14.056392 | 2026-04-14 02:57:14.056497 | TASK [Setup log path fact] 2026-04-14 02:57:14.078388 | localhost | ok 2026-04-14 02:57:14.098890 | 2026-04-14 02:57:14.099009 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-14 02:57:14.130235 | localhost | ok 2026-04-14 02:57:14.142992 | 2026-04-14 02:57:14.143109 | TASK [emit-job-header : Print job information] 2026-04-14 02:57:14.185190 | # Job Information 2026-04-14 02:57:14.185978 | Ansible Version: 2.16.16 2026-04-14 02:57:14.186104 | Job: ansible-collection-containers-molecule-containerd-rockylinux-9 2026-04-14 02:57:14.186161 | Pipeline: check 2026-04-14 02:57:14.186209 | Executor: 0a8996d2b663 2026-04-14 02:57:14.186254 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/113 2026-04-14 02:57:14.186306 | Event ID: 81b63d30-37ad-11f1-8198-64bbaeb12eb2 2026-04-14 02:57:14.189902 | 2026-04-14 02:57:14.189973 | LOOP [emit-job-header : Print node information] 2026-04-14 02:57:14.280503 | localhost | ok: 2026-04-14 02:57:14.280698 | localhost | # Node Information 2026-04-14 02:57:14.280729 | localhost | Inventory Hostname: rockylinux-9 2026-04-14 02:57:14.280754 | localhost | Hostname: np0000166023 2026-04-14 02:57:14.280776 | localhost | Username: zuul 2026-04-14 02:57:14.280800 | localhost | Distro: Rocky 9.7 2026-04-14 02:57:14.280821 | localhost | Provider: yul1 2026-04-14 02:57:14.280840 | localhost | Region: ca-ymq-1 2026-04-14 02:57:14.280859 | localhost | Label: rockylinux-9 2026-04-14 02:57:14.280902 | localhost | Product Name: OpenStack Nova 2026-04-14 02:57:14.280923 | localhost | Interface IP: 199.19.213.35 2026-04-14 02:57:14.295052 | 2026-04-14 02:57:14.295220 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-04-14 02:57:14.716001 | localhost -> localhost | changed 2026-04-14 02:57:14.724291 | 2026-04-14 02:57:14.724436 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-04-14 02:57:15.734977 | localhost -> localhost | changed 2026-04-14 02:57:15.741579 | 2026-04-14 02:57:15.741637 | PLAY [all] 2026-04-14 02:57:15.749322 | 2026-04-14 02:57:15.749386 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-04-14 02:57:16.027596 | rockylinux-9 -> localhost | ok 2026-04-14 02:57:16.033722 | 2026-04-14 02:57:16.033847 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-04-14 02:57:16.066853 | rockylinux-9 | ok 2026-04-14 02:57:16.086114 | rockylinux-9 | included: /var/lib/zuul/builds/3f89299dfc1642ca8529751eb5f978e3/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-04-14 02:57:16.091722 | 2026-04-14 02:57:16.091787 | TASK [add-build-sshkey : Create Temp SSH key] 2026-04-14 02:57:17.883792 | rockylinux-9 -> localhost | Generating public/private rsa key pair. 2026-04-14 02:57:17.884072 | rockylinux-9 -> localhost | Your identification has been saved in /var/lib/zuul/builds/3f89299dfc1642ca8529751eb5f978e3/work/3f89299dfc1642ca8529751eb5f978e3_id_rsa 2026-04-14 02:57:17.884141 | rockylinux-9 -> localhost | Your public key has been saved in /var/lib/zuul/builds/3f89299dfc1642ca8529751eb5f978e3/work/3f89299dfc1642ca8529751eb5f978e3_id_rsa.pub 2026-04-14 02:57:17.884192 | rockylinux-9 -> localhost | The key fingerprint is: 2026-04-14 02:57:17.884241 | rockylinux-9 -> localhost | SHA256:qsMwhgkIfbnZxpXqiHLtLEcgFnUPkGjnUQ1yG20LJyg zuul-build-sshkey 2026-04-14 02:57:17.884309 | rockylinux-9 -> localhost | The key's randomart image is: 2026-04-14 02:57:17.884357 | rockylinux-9 -> localhost | +---[RSA 3072]----+ 2026-04-14 02:57:17.884416 | rockylinux-9 -> localhost | | .o+=O+ . | 2026-04-14 02:57:17.884468 | rockylinux-9 -> localhost | |.E.=*o==o | 2026-04-14 02:57:17.884514 | rockylinux-9 -> localhost | |+ =..*=+. | 2026-04-14 02:57:17.884560 | rockylinux-9 -> localhost | |+...o =. | 2026-04-14 02:57:17.884605 | rockylinux-9 -> localhost | |o+ + + S | 2026-04-14 02:57:17.884649 | rockylinux-9 -> localhost | |+ * + .. | 2026-04-14 02:57:17.884692 | rockylinux-9 -> localhost | | + B . | 2026-04-14 02:57:17.884736 | rockylinux-9 -> localhost | | . *. | 2026-04-14 02:57:17.884786 | rockylinux-9 -> localhost | | o.. | 2026-04-14 02:57:17.884832 | rockylinux-9 -> localhost | +----[SHA256]-----+ 2026-04-14 02:57:17.884925 | rockylinux-9 -> localhost | ok: Runtime: 0:00:01.334661 2026-04-14 02:57:17.890451 | 2026-04-14 02:57:17.890509 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-04-14 02:57:17.924487 | rockylinux-9 | ok 2026-04-14 02:57:17.938049 | rockylinux-9 | included: /var/lib/zuul/builds/3f89299dfc1642ca8529751eb5f978e3/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-04-14 02:57:17.947260 | 2026-04-14 02:57:17.947348 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-04-14 02:57:17.993865 | rockylinux-9 | skipping: Conditional result was False 2026-04-14 02:57:18.002116 | 2026-04-14 02:57:18.002294 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-04-14 02:57:18.524157 | rockylinux-9 | changed 2026-04-14 02:57:18.528832 | 2026-04-14 02:57:18.528913 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-04-14 02:57:18.795396 | rockylinux-9 | ok 2026-04-14 02:57:18.802737 | 2026-04-14 02:57:18.802841 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-04-14 02:57:19.772009 | rockylinux-9 | changed 2026-04-14 02:57:19.824052 | 2026-04-14 02:57:19.824205 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-04-14 02:57:20.725122 | rockylinux-9 | changed 2026-04-14 02:57:20.731554 | 2026-04-14 02:57:20.731665 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-04-14 02:57:20.756148 | rockylinux-9 | skipping: Conditional result was False 2026-04-14 02:57:20.761902 | 2026-04-14 02:57:20.762008 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-04-14 02:57:21.327825 | rockylinux-9 -> localhost | changed 2026-04-14 02:57:21.348599 | 2026-04-14 02:57:21.348684 | TASK [add-build-sshkey : Add back temp key] 2026-04-14 02:57:21.655161 | rockylinux-9 -> localhost | Identity added: /var/lib/zuul/builds/3f89299dfc1642ca8529751eb5f978e3/work/3f89299dfc1642ca8529751eb5f978e3_id_rsa (zuul-build-sshkey) 2026-04-14 02:57:21.655591 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.011103 2026-04-14 02:57:21.669304 | 2026-04-14 02:57:21.669419 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-04-14 02:57:22.053953 | rockylinux-9 | ok 2026-04-14 02:57:22.058311 | 2026-04-14 02:57:22.058373 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-04-14 02:57:22.084909 | rockylinux-9 | skipping: Conditional result was False 2026-04-14 02:57:22.098260 | 2026-04-14 02:57:22.099088 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-04-14 02:57:22.568372 | rockylinux-9 | ok 2026-04-14 02:57:22.575442 | 2026-04-14 02:57:22.575526 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-04-14 02:57:23.589354 | rockylinux-9 | Output suppressed because no_log was given 2026-04-14 02:57:23.669547 | 2026-04-14 02:57:23.669660 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-04-14 02:57:23.938831 | rockylinux-9 | ok: "logs" 2026-04-14 02:57:23.939307 | rockylinux-9 | ok: All items complete 2026-04-14 02:57:23.939828 | 2026-04-14 02:57:24.167032 | rockylinux-9 | ok: "artifacts" 2026-04-14 02:57:24.388803 | rockylinux-9 | ok: "docs" 2026-04-14 02:57:24.402935 | 2026-04-14 02:57:24.403050 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-04-14 02:57:24.681424 | rockylinux-9 | changed: "logs" 2026-04-14 02:57:24.917891 | rockylinux-9 | changed: "artifacts" 2026-04-14 02:57:25.171842 | rockylinux-9 | changed: "docs" 2026-04-14 02:57:25.189521 | 2026-04-14 02:57:25.189652 | PLAY RECAP 2026-04-14 02:57:25.189701 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-14 02:57:25.189731 | rockylinux-9 | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-04-14 02:57:25.189754 | 2026-04-14 02:57:25.372611 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-04-14 02:57:25.373714 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-04-14 02:57:26.045243 | 2026-04-14 02:57:26.045409 | PLAY [all] 2026-04-14 02:57:26.057835 | 2026-04-14 02:57:26.057944 | TASK [setup-uv : Extract archive] 2026-04-14 02:57:28.852578 | rockylinux-9 | changed 2026-04-14 02:57:28.857850 | 2026-04-14 02:57:28.857929 | TASK [setup-uv : Print version] 2026-04-14 02:57:28.880699 | [rockylinux-9] Waiting on logger 2026-04-14 02:57:29.397391 | rockylinux-9 | ok: Runtime: 0:00:00.014213 2026-04-14 02:57:29.539269 | 2026-04-14 02:57:29.539358 | PLAY RECAP 2026-04-14 02:57:29.539406 | rockylinux-9 | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-14 02:57:29.539434 | 2026-04-14 02:57:29.661602 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-04-14 02:57:29.663735 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-04-14 02:57:30.373711 | 2026-04-14 02:57:30.373872 | PLAY [all] 2026-04-14 02:57:30.385090 | 2026-04-14 02:57:30.385183 | TASK [Copy inventory file for Zuul] 2026-04-14 02:57:31.700688 | rockylinux-9 | changed 2026-04-14 02:57:31.705709 | 2026-04-14 02:57:31.705839 | TASK [Switch "ansible_host" to private IP] 2026-04-14 02:57:32.072286 | rockylinux-9 | changed: 1 replacements made 2026-04-14 02:57:32.078778 | 2026-04-14 02:57:32.078849 | TASK [Run Molecule scenario] 2026-04-14 02:57:32.105133 | [rockylinux-9] Waiting on logger 2026-04-14 02:57:40.262252 | [rockylinux-9] Waiting on logger 2026-04-14 02:57:50.442284 | [rockylinux-9] Waiting on logger 2026-04-14 02:58:00.614035 | [rockylinux-9] Waiting on logger 2026-04-14 02:58:10.790021 | [rockylinux-9] Waiting on logger 2026-04-14 02:58:19.948819 | [rockylinux-9] Waiting on logger 2026-04-14 02:58:30.150236 | [rockylinux-9] Waiting on logger 2026-04-14 02:58:40.326120 | [rockylinux-9] Waiting on logger 2026-04-14 02:58:50.502331 | [rockylinux-9] Waiting on logger 2026-04-14 02:59:00.678357 | [rockylinux-9] Waiting on logger 2026-04-14 02:59:10.854068 | [rockylinux-9] Waiting on logger 2026-04-14 02:59:20.010365 | [rockylinux-9] Waiting on logger 2026-04-14 02:59:30.182055 | [rockylinux-9] Waiting on logger 2026-04-14 02:59:40.363066 | [rockylinux-9] Waiting on logger 2026-04-14 02:59:50.534312 | [rockylinux-9] Waiting on logger 2026-04-14 03:00:00.710454 | [rockylinux-9] Waiting on logger 2026-04-14 03:00:10.886211 | [rockylinux-9] Waiting on logger 2026-04-14 03:00:20.038396 | [rockylinux-9] Waiting on logger 2026-04-14 03:00:30.214400 | [rockylinux-9] Waiting on logger 2026-04-14 03:00:40.390020 | [rockylinux-9] Waiting on logger 2026-04-14 03:00:50.566269 | [rockylinux-9] Waiting on logger 2026-04-14 03:01:00.742037 | [rockylinux-9] Waiting on logger 2026-04-14 03:01:09.903189 | [rockylinux-9] Waiting on logger 2026-04-14 03:01:20.070230 | [rockylinux-9] Waiting on logger 2026-04-14 03:01:30.246103 | [rockylinux-9] Waiting on logger 2026-04-14 03:01:40.422372 | [rockylinux-9] Waiting on logger 2026-04-14 03:01:50.598301 | [rockylinux-9] Waiting on logger 2026-04-14 03:02:00.778248 | [rockylinux-9] Waiting on logger 2026-04-14 03:02:09.869305 | rockylinux-9 | ok: Runtime: 0:04:36.409911 2026-04-14 03:02:09.876719 | 2026-04-14 03:02:09.876780 | PLAY RECAP 2026-04-14 03:02:09.876824 | rockylinux-9 | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-14 03:02:09.876845 | 2026-04-14 03:02:10.021323 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-04-14 03:02:10.023437 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-04-14 03:02:10.579588 | 2026-04-14 03:02:10.579723 | PLAY [all] 2026-04-14 03:02:10.590389 | 2026-04-14 03:02:10.590472 | TASK [fetch-output : Set log path for multiple nodes] 2026-04-14 03:02:10.635114 | rockylinux-9 | skipping: Conditional result was False 2026-04-14 03:02:10.640306 | 2026-04-14 03:02:10.640386 | TASK [fetch-output : Set log path for single node] 2026-04-14 03:02:10.679771 | rockylinux-9 | ok 2026-04-14 03:02:10.685222 | 2026-04-14 03:02:10.685290 | LOOP [fetch-output : Ensure local output dirs] 2026-04-14 03:02:11.058497 | rockylinux-9 -> localhost | ok: "/var/lib/zuul/builds/3f89299dfc1642ca8529751eb5f978e3/work/logs" 2026-04-14 03:02:11.272944 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/3f89299dfc1642ca8529751eb5f978e3/work/artifacts" 2026-04-14 03:02:11.492464 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/3f89299dfc1642ca8529751eb5f978e3/work/docs" 2026-04-14 03:02:11.510454 | 2026-04-14 03:02:11.510579 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-04-14 03:02:12.203992 | rockylinux-9 | changed: .d..t...... ./ 2026-04-14 03:02:12.204287 | rockylinux-9 | changed: All items complete 2026-04-14 03:02:12.204333 | 2026-04-14 03:02:12.720803 | rockylinux-9 | changed: .d..t...... ./ 2026-04-14 03:02:13.249713 | rockylinux-9 | changed: .d..t...... ./ 2026-04-14 03:02:13.261665 | 2026-04-14 03:02:13.261734 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-04-14 03:02:13.667329 | rockylinux-9 -> localhost | ok: Item: artifacts Runtime: 0:00:00.008509 2026-04-14 03:02:13.882379 | rockylinux-9 -> localhost | ok: Item: docs Runtime: 0:00:00.007620 2026-04-14 03:02:13.902609 | 2026-04-14 03:02:13.903008 | PLAY [all] 2026-04-14 03:02:13.908733 | 2026-04-14 03:02:13.908800 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-04-14 03:02:14.428196 | rockylinux-9 | changed 2026-04-14 03:02:14.435611 | 2026-04-14 03:02:14.435673 | PLAY RECAP 2026-04-14 03:02:14.435721 | rockylinux-9 | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-04-14 03:02:14.435746 | 2026-04-14 03:02:14.603206 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-04-14 03:02:14.605209 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-04-14 03:02:15.146561 | 2026-04-14 03:02:15.146874 | PLAY [localhost] 2026-04-14 03:02:15.156520 | 2026-04-14 03:02:15.156592 | TASK [Generate Zuul manifest] 2026-04-14 03:02:15.183823 | localhost | ok 2026-04-14 03:02:15.197638 | 2026-04-14 03:02:15.197725 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-04-14 03:02:15.884458 | localhost | changed 2026-04-14 03:02:15.896519 | 2026-04-14 03:02:15.896644 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-04-14 03:02:15.928067 | localhost | ok 2026-04-14 03:02:15.936805 | 2026-04-14 03:02:15.936876 | TASK [Upload logs] 2026-04-14 03:02:15.954630 | localhost | ok 2026-04-14 03:02:16.013124 | 2026-04-14 03:02:16.013256 | TASK [Set zuul-log-path fact] 2026-04-14 03:02:16.035389 | localhost | ok 2026-04-14 03:02:16.049163 | 2026-04-14 03:02:16.049258 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-14 03:02:16.081755 | localhost | ok 2026-04-14 03:02:16.089782 | 2026-04-14 03:02:16.089846 | TASK [upload-logs : Create log directories] 2026-04-14 03:02:16.682699 | localhost | changed 2026-04-14 03:02:16.689408 | 2026-04-14 03:02:16.689592 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-04-14 03:02:17.076370 | localhost -> localhost | ok: Runtime: 0:00:00.005837 2026-04-14 03:02:17.081926 | 2026-04-14 03:02:17.081996 | TASK [upload-logs : Upload logs to log server] 2026-04-14 03:02:17.905767 | localhost | Output suppressed because no_log was given 2026-04-14 03:02:17.910811 | 2026-04-14 03:02:17.910899 | LOOP [upload-logs : Compress console log and json output] 2026-04-14 03:02:17.959401 | localhost | skipping: Conditional result was False 2026-04-14 03:02:17.967005 | localhost | skipping: Conditional result was False 2026-04-14 03:02:17.984750 | 2026-04-14 03:02:17.984888 | LOOP [upload-logs : Upload compressed console log and json output] 2026-04-14 03:02:18.028263 | localhost | skipping: Conditional result was False 2026-04-14 03:02:18.028684 | 2026-04-14 03:02:18.032535 | localhost | skipping: Conditional result was False 2026-04-14 03:02:18.047187 | 2026-04-14 03:02:18.047333 | LOOP [upload-logs : Upload console log and json output]