2026-05-21 10:08:30.718525 | Job console starting 2026-05-21 10:08:30.744401 | Updating git repos 2026-05-21 10:08:30.773320 | Cloning repos into workspace 2026-05-21 10:08:30.857893 | Restoring repo states 2026-05-21 10:08:30.865124 | Merging changes 2026-05-21 10:08:31.287393 | Checking out repos 2026-05-21 10:08:31.329746 | Preparing playbooks 2026-05-21 10:08:33.061117 | Running Ansible setup 2026-05-21 10:08:37.330698 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-05-21 10:08:38.081069 | 2026-05-21 10:08:38.081216 | PLAY [localhost] 2026-05-21 10:08:38.088909 | 2026-05-21 10:08:38.089162 | TASK [Gathering Facts] 2026-05-21 10:08:39.191836 | localhost | ok 2026-05-21 10:08:39.202113 | 2026-05-21 10:08:39.202305 | TASK [Setup log path fact] 2026-05-21 10:08:39.224057 | localhost | ok 2026-05-21 10:08:39.258156 | 2026-05-21 10:08:39.258343 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-21 10:08:39.309128 | localhost | ok 2026-05-21 10:08:39.316602 | 2026-05-21 10:08:39.316811 | TASK [emit-job-header : Print job information] 2026-05-21 10:08:39.366309 | # Job Information 2026-05-21 10:08:39.366489 | Ansible Version: 2.16.18 2026-05-21 10:08:39.366524 | Job: ansible-collection-containers-molecule-docker-rockylinux-9 2026-05-21 10:08:39.366556 | Pipeline: check 2026-05-21 10:08:39.366581 | Executor: 2d72f0692154 2026-05-21 10:08:39.366602 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/124 2026-05-21 10:08:39.366627 | Event ID: da2537c0-54fc-11f1-97ac-d0d861a4fea1 2026-05-21 10:08:39.370119 | 2026-05-21 10:08:39.370194 | LOOP [emit-job-header : Print node information] 2026-05-21 10:08:39.503080 | localhost | ok: 2026-05-21 10:08:39.503619 | localhost | # Node Information 2026-05-21 10:08:39.503655 | localhost | Inventory Hostname: rockylinux-9 2026-05-21 10:08:39.503678 | localhost | Hostname: np0000175878 2026-05-21 10:08:39.503698 | localhost | Username: zuul 2026-05-21 10:08:39.503724 | localhost | Distro: Rocky 9.7 2026-05-21 10:08:39.503745 | localhost | Provider: yul1 2026-05-21 10:08:39.503765 | localhost | Region: ca-ymq-1 2026-05-21 10:08:39.503784 | localhost | Label: rockylinux-9 2026-05-21 10:08:39.503802 | localhost | Product Name: OpenStack Nova 2026-05-21 10:08:39.503821 | localhost | Interface IP: 199.204.45.113 2026-05-21 10:08:39.520155 | 2026-05-21 10:08:39.704244 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-05-21 10:08:40.413354 | localhost -> localhost | changed 2026-05-21 10:08:40.420148 | 2026-05-21 10:08:40.420224 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-05-21 10:08:41.806780 | localhost -> localhost | changed 2026-05-21 10:08:41.813537 | 2026-05-21 10:08:41.813648 | PLAY [all] 2026-05-21 10:08:41.826613 | 2026-05-21 10:08:41.826756 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-05-21 10:08:42.304891 | rockylinux-9 -> localhost | ok 2026-05-21 10:08:42.316044 | 2026-05-21 10:08:42.316172 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-05-21 10:08:42.356899 | rockylinux-9 | ok 2026-05-21 10:08:42.382994 | rockylinux-9 | included: /var/lib/zuul/builds/cef712c1a0ce44b78569aeb1e7a3fcdf/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-05-21 10:08:42.390045 | 2026-05-21 10:08:42.390122 | TASK [add-build-sshkey : Create Temp SSH key] 2026-05-21 10:08:44.055701 | rockylinux-9 -> localhost | Generating public/private rsa key pair. 2026-05-21 10:08:44.055883 | rockylinux-9 -> localhost | Your identification has been saved in /var/lib/zuul/builds/cef712c1a0ce44b78569aeb1e7a3fcdf/work/cef712c1a0ce44b78569aeb1e7a3fcdf_id_rsa 2026-05-21 10:08:44.055913 | rockylinux-9 -> localhost | Your public key has been saved in /var/lib/zuul/builds/cef712c1a0ce44b78569aeb1e7a3fcdf/work/cef712c1a0ce44b78569aeb1e7a3fcdf_id_rsa.pub 2026-05-21 10:08:44.055936 | rockylinux-9 -> localhost | The key fingerprint is: 2026-05-21 10:08:44.055957 | rockylinux-9 -> localhost | SHA256:hBrHYsnMvSBX86WKr7HQTva8QrrjzA77wNDfrpWeiFg zuul-build-sshkey 2026-05-21 10:08:44.055991 | rockylinux-9 -> localhost | The key's randomart image is: 2026-05-21 10:08:44.056011 | rockylinux-9 -> localhost | +---[RSA 3072]----+ 2026-05-21 10:08:44.056039 | rockylinux-9 -> localhost | | o . | 2026-05-21 10:08:44.056062 | rockylinux-9 -> localhost | | + = + o | 2026-05-21 10:08:44.056082 | rockylinux-9 -> localhost | | . @ = + | 2026-05-21 10:08:44.056103 | rockylinux-9 -> localhost | | . + B + | 2026-05-21 10:08:44.056123 | rockylinux-9 -> localhost | |. . o o S | 2026-05-21 10:08:44.056142 | rockylinux-9 -> localhost | |o o.o . | 2026-05-21 10:08:44.056161 | rockylinux-9 -> localhost | |o.Eo* = | 2026-05-21 10:08:44.056180 | rockylinux-9 -> localhost | | Oo*.@ . | 2026-05-21 10:08:44.056203 | rockylinux-9 -> localhost | |o+Oo*oB. | 2026-05-21 10:08:44.056223 | rockylinux-9 -> localhost | +----[SHA256]-----+ 2026-05-21 10:08:44.056272 | rockylinux-9 -> localhost | ok: Runtime: 0:00:01.102257 2026-05-21 10:08:44.061100 | 2026-05-21 10:08:44.061170 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-05-21 10:08:44.091007 | rockylinux-9 | ok 2026-05-21 10:08:44.099441 | rockylinux-9 | included: /var/lib/zuul/builds/cef712c1a0ce44b78569aeb1e7a3fcdf/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-05-21 10:08:44.119909 | 2026-05-21 10:08:44.120086 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-05-21 10:08:44.160564 | rockylinux-9 | skipping: Conditional result was False 2026-05-21 10:08:44.166901 | 2026-05-21 10:08:44.166971 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-05-21 10:08:44.649077 | rockylinux-9 | changed 2026-05-21 10:08:44.657354 | 2026-05-21 10:08:44.657469 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-05-21 10:08:44.921246 | rockylinux-9 | ok 2026-05-21 10:08:44.926905 | 2026-05-21 10:08:44.926974 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-05-21 10:08:45.891189 | rockylinux-9 | changed 2026-05-21 10:08:45.896106 | 2026-05-21 10:08:45.896180 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-05-21 10:08:46.830644 | rockylinux-9 | changed 2026-05-21 10:08:46.838792 | 2026-05-21 10:08:46.838872 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-05-21 10:08:46.872766 | rockylinux-9 | skipping: Conditional result was False 2026-05-21 10:08:46.878611 | 2026-05-21 10:08:46.878709 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-05-21 10:08:47.475406 | rockylinux-9 -> localhost | changed 2026-05-21 10:08:47.485082 | 2026-05-21 10:08:47.485229 | TASK [add-build-sshkey : Add back temp key] 2026-05-21 10:08:47.921926 | rockylinux-9 -> localhost | Identity added: /var/lib/zuul/builds/cef712c1a0ce44b78569aeb1e7a3fcdf/work/cef712c1a0ce44b78569aeb1e7a3fcdf_id_rsa (zuul-build-sshkey) 2026-05-21 10:08:47.922137 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.013441 2026-05-21 10:08:47.926580 | 2026-05-21 10:08:47.926645 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-05-21 10:08:48.340384 | rockylinux-9 | ok 2026-05-21 10:08:48.345036 | 2026-05-21 10:08:48.345180 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-05-21 10:08:48.371392 | rockylinux-9 | skipping: Conditional result was False 2026-05-21 10:08:48.382198 | 2026-05-21 10:08:48.382353 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-05-21 10:08:48.796673 | rockylinux-9 | ok 2026-05-21 10:08:48.802210 | 2026-05-21 10:08:48.802309 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-05-21 10:08:50.084494 | rockylinux-9 | Output suppressed because no_log was given 2026-05-21 10:08:50.096289 | 2026-05-21 10:08:50.096428 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-05-21 10:08:50.360191 | rockylinux-9 | ok: "logs" 2026-05-21 10:08:50.360466 | rockylinux-9 | ok: All items complete 2026-05-21 10:08:50.360495 | 2026-05-21 10:08:50.574483 | rockylinux-9 | ok: "artifacts" 2026-05-21 10:08:50.808234 | rockylinux-9 | ok: "docs" 2026-05-21 10:08:50.823706 | 2026-05-21 10:08:50.823870 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-05-21 10:08:51.086153 | rockylinux-9 | changed: "logs" 2026-05-21 10:08:51.370081 | rockylinux-9 | changed: "artifacts" 2026-05-21 10:08:51.543759 | rockylinux-9 | changed: "docs" 2026-05-21 10:08:51.563113 | 2026-05-21 10:08:51.563255 | PLAY RECAP 2026-05-21 10:08:51.563313 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-05-21 10:08:51.563353 | rockylinux-9 | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-05-21 10:08:51.563383 | 2026-05-21 10:08:51.719597 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-05-21 10:08:51.901792 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-05-21 10:08:52.535928 | 2026-05-21 10:08:52.536123 | PLAY [all] 2026-05-21 10:08:52.559329 | 2026-05-21 10:08:52.559558 | TASK [setup-uv : Extract archive] 2026-05-21 10:08:55.365230 | rockylinux-9 | changed 2026-05-21 10:08:55.375995 | 2026-05-21 10:08:55.376157 | TASK [setup-uv : Print version] 2026-05-21 10:08:55.421798 | [rockylinux-9] Waiting on logger 2026-05-21 10:08:55.940985 | rockylinux-9 | ok: Runtime: 0:00:00.013813 2026-05-21 10:08:55.951177 | 2026-05-21 10:08:55.951256 | TASK [Install Ansible collection siblings] 2026-05-21 10:08:56.426563 | rockylinux-9 | ok: 2026-05-21 10:08:56.426720 | rockylinux-9 | Skipping ansible.posix: no checked-out sibling 2026-05-21 10:08:56.426749 | rockylinux-9 | Skipping community.general: no checked-out sibling 2026-05-21 10:08:56.428800 | 2026-05-21 10:08:56.428854 | PLAY RECAP 2026-05-21 10:08:56.428907 | rockylinux-9 | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-05-21 10:08:56.428935 | 2026-05-21 10:08:56.609156 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-05-21 10:08:56.610484 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-05-21 10:08:57.291785 | 2026-05-21 10:08:57.291980 | PLAY [all] 2026-05-21 10:08:57.305083 | 2026-05-21 10:08:57.305252 | TASK [Copy inventory file for Zuul] 2026-05-21 10:08:58.620720 | rockylinux-9 | changed 2026-05-21 10:08:58.628569 | 2026-05-21 10:08:58.628739 | TASK [Switch "ansible_host" to private IP] 2026-05-21 10:08:59.045556 | rockylinux-9 | changed: 1 replacements made 2026-05-21 10:08:59.054618 | 2026-05-21 10:08:59.054742 | TASK [Run Molecule scenario] 2026-05-21 10:08:59.083272 | [rockylinux-9] Waiting on logger 2026-05-21 10:09:07.206166 | [rockylinux-9] Waiting on logger 2026-05-21 10:09:17.382137 | [rockylinux-9] Waiting on logger 2026-05-21 10:09:26.539721 | [rockylinux-9] Waiting on logger 2026-05-21 10:09:36.710025 | [rockylinux-9] Waiting on logger 2026-05-21 10:09:46.886334 | [rockylinux-9] Waiting on logger 2026-05-21 10:09:57.062105 | [rockylinux-9] Waiting on logger 2026-05-21 10:10:07.238053 | [rockylinux-9] Waiting on logger 2026-05-21 10:10:17.414111 | [rockylinux-9] Waiting on logger 2026-05-21 10:10:26.566087 | [rockylinux-9] Waiting on logger 2026-05-21 10:10:36.746011 | [rockylinux-9] Waiting on logger 2026-05-21 10:10:46.918239 | [rockylinux-9] Waiting on logger 2026-05-21 10:10:57.094404 | [rockylinux-9] Waiting on logger 2026-05-21 10:11:07.270438 | [rockylinux-9] Waiting on logger 2026-05-21 10:11:16.427443 | [rockylinux-9] Waiting on logger 2026-05-21 10:11:18.673993 | rockylinux-9 | ok: Runtime: 0:02:18.007433 2026-05-21 10:11:18.681780 | 2026-05-21 10:11:18.681875 | PLAY RECAP 2026-05-21 10:11:18.681919 | rockylinux-9 | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-05-21 10:11:18.681941 | 2026-05-21 10:11:18.831745 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-05-21 10:11:18.834965 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-05-21 10:11:19.438949 | 2026-05-21 10:11:19.439093 | PLAY [all] 2026-05-21 10:11:19.450410 | 2026-05-21 10:11:19.450510 | TASK [fetch-output : Set log path for multiple nodes] 2026-05-21 10:11:19.496133 | rockylinux-9 | skipping: Conditional result was False 2026-05-21 10:11:19.505991 | 2026-05-21 10:11:19.506104 | TASK [fetch-output : Set log path for single node] 2026-05-21 10:11:19.551039 | rockylinux-9 | ok 2026-05-21 10:11:19.556719 | 2026-05-21 10:11:19.556791 | LOOP [fetch-output : Ensure local output dirs] 2026-05-21 10:11:19.984857 | rockylinux-9 -> localhost | ok: "/var/lib/zuul/builds/cef712c1a0ce44b78569aeb1e7a3fcdf/work/logs" 2026-05-21 10:11:20.198454 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/cef712c1a0ce44b78569aeb1e7a3fcdf/work/artifacts" 2026-05-21 10:11:20.414374 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/cef712c1a0ce44b78569aeb1e7a3fcdf/work/docs" 2026-05-21 10:11:20.436549 | 2026-05-21 10:11:20.436714 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-05-21 10:11:21.181018 | rockylinux-9 | changed: .d..t...... ./ 2026-05-21 10:11:21.181293 | rockylinux-9 | changed: All items complete 2026-05-21 10:11:21.181341 | 2026-05-21 10:11:21.724036 | rockylinux-9 | changed: .d..t...... ./ 2026-05-21 10:11:22.254424 | rockylinux-9 | changed: .d..t...... ./ 2026-05-21 10:11:22.277532 | 2026-05-21 10:11:22.277674 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-05-21 10:11:22.705273 | rockylinux-9 -> localhost | ok: Item: artifacts Runtime: 0:00:00.008197 2026-05-21 10:11:22.925346 | rockylinux-9 -> localhost | ok: Item: docs Runtime: 0:00:00.007058 2026-05-21 10:11:22.948417 | 2026-05-21 10:11:22.948484 | PLAY [all] 2026-05-21 10:11:22.953773 | 2026-05-21 10:11:22.953836 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-05-21 10:11:23.412120 | rockylinux-9 | changed 2026-05-21 10:11:23.419044 | 2026-05-21 10:11:23.419192 | PLAY RECAP 2026-05-21 10:11:23.419260 | rockylinux-9 | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-05-21 10:11:23.419292 | 2026-05-21 10:11:23.541594 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-05-21 10:11:23.543836 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-05-21 10:11:24.137254 | 2026-05-21 10:11:24.137380 | PLAY [localhost] 2026-05-21 10:11:24.147936 | 2026-05-21 10:11:24.148015 | TASK [Generate Zuul manifest] 2026-05-21 10:11:24.169969 | localhost | ok 2026-05-21 10:11:24.187781 | 2026-05-21 10:11:24.187860 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-05-21 10:11:24.553378 | localhost | changed 2026-05-21 10:11:24.566121 | 2026-05-21 10:11:24.566205 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-05-21 10:11:24.599523 | localhost | ok 2026-05-21 10:11:24.608553 | 2026-05-21 10:11:24.608640 | TASK [Upload logs] 2026-05-21 10:11:24.630711 | localhost | ok 2026-05-21 10:11:24.691688 | 2026-05-21 10:11:24.691828 | TASK [Set zuul-log-path fact] 2026-05-21 10:11:24.712847 | localhost | ok 2026-05-21 10:11:24.726699 | 2026-05-21 10:11:24.726767 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-21 10:11:24.759609 | localhost | ok 2026-05-21 10:11:24.768845 | 2026-05-21 10:11:24.768937 | TASK [upload-logs : Create log directories] 2026-05-21 10:11:25.186880 | localhost | changed 2026-05-21 10:11:25.194357 | 2026-05-21 10:11:25.194427 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-05-21 10:11:25.622921 | localhost -> localhost | ok: Runtime: 0:00:00.004592 2026-05-21 10:11:25.629655 | 2026-05-21 10:11:25.629718 | TASK [upload-logs : Upload logs to log server] 2026-05-21 10:11:26.088641 | localhost | Output suppressed because no_log was given 2026-05-21 10:11:26.094524 | 2026-05-21 10:11:26.094642 | LOOP [upload-logs : Compress console log and json output] 2026-05-21 10:11:26.144726 | localhost | skipping: Conditional result was False 2026-05-21 10:11:26.151478 | localhost | skipping: Conditional result was False 2026-05-21 10:11:26.170471 | 2026-05-21 10:11:26.170723 | LOOP [upload-logs : Upload compressed console log and json output] 2026-05-21 10:11:26.214836 | localhost | skipping: Conditional result was False 2026-05-21 10:11:26.215310 | 2026-05-21 10:11:26.219526 | localhost | skipping: Conditional result was False 2026-05-21 10:11:26.235666 | 2026-05-21 10:11:26.235854 | LOOP [upload-logs : Upload console log and json output]