2026-02-04 13:52:02.837351 | Job console starting 2026-02-04 13:52:02.858050 | Updating git repos 2026-02-04 13:52:02.892312 | Cloning repos into workspace 2026-02-04 13:52:02.928485 | Restoring repo states 2026-02-04 13:52:02.983364 | Merging changes 2026-02-04 13:52:03.396672 | Checking out repos 2026-02-04 13:52:03.427955 | Preparing playbooks 2026-02-04 13:52:04.834911 | Running Ansible setup 2026-02-04 13:52:08.666672 | PRE-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/pre.yaml@main] 2026-02-04 13:52:09.288286 | 2026-02-04 13:52:09.288949 | PLAY [localhost] 2026-02-04 13:52:09.296635 | 2026-02-04 13:52:09.296713 | TASK [Gathering Facts] 2026-02-04 13:52:10.230147 | localhost | ok 2026-02-04 13:52:10.238912 | 2026-02-04 13:52:10.239077 | TASK [Setup log path fact] 2026-02-04 13:52:10.270044 | localhost | ok 2026-02-04 13:52:10.283656 | 2026-02-04 13:52:10.283741 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-02-04 13:52:10.313684 | localhost | ok 2026-02-04 13:52:10.321557 | 2026-02-04 13:52:10.321630 | TASK [emit-job-header : Print job information] 2026-02-04 13:52:10.363434 | # Job Information 2026-02-04 13:52:10.363734 | Ansible Version: 2.16.15 2026-02-04 13:52:10.363809 | Job: ansible-collection-containers-molecule-containerd-rockylinux-9 2026-02-04 13:52:10.363873 | Pipeline: check 2026-02-04 13:52:10.363922 | Executor: 3a2793d2bd32 2026-02-04 13:52:10.363968 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/93 2026-02-04 13:52:10.364017 | Event ID: 8df6747e-01d0-11f1-93f2-82fc7d812c5c 2026-02-04 13:52:10.368298 | 2026-02-04 13:52:10.368398 | LOOP [emit-job-header : Print node information] 2026-02-04 13:52:10.474378 | localhost | ok: 2026-02-04 13:52:10.475172 | localhost | # Node Information 2026-02-04 13:52:10.475208 | localhost | Inventory Hostname: rockylinux-9 2026-02-04 13:52:10.475230 | localhost | Hostname: np0000154846 2026-02-04 13:52:10.475250 | localhost | Username: zuul 2026-02-04 13:52:10.475274 | localhost | Distro: Rocky 9.7 2026-02-04 13:52:10.475322 | localhost | Provider: yul1 2026-02-04 13:52:10.475387 | localhost | Region: ca-ymq-1 2026-02-04 13:52:10.475418 | localhost | Label: rockylinux-9 2026-02-04 13:52:10.475443 | localhost | Product Name: OpenStack Nova 2026-02-04 13:52:10.475467 | localhost | Interface IP: 199.19.213.76 2026-02-04 13:52:10.497752 | 2026-02-04 13:52:10.497903 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-02-04 13:52:10.988830 | localhost -> localhost | changed 2026-02-04 13:52:10.998323 | 2026-02-04 13:52:10.998395 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-02-04 13:52:12.014624 | localhost -> localhost | changed 2026-02-04 13:52:12.021387 | 2026-02-04 13:52:12.021448 | PLAY [all] 2026-02-04 13:52:12.039889 | 2026-02-04 13:52:12.040038 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-02-04 13:52:12.281446 | rockylinux-9 -> localhost | ok 2026-02-04 13:52:12.287631 | 2026-02-04 13:52:12.287714 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-02-04 13:52:12.307567 | rockylinux-9 | ok 2026-02-04 13:52:12.325531 | rockylinux-9 | included: /var/lib/zuul/builds/ba2a0f6390dc4640a29ddabdaf0f7552/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-02-04 13:52:12.334982 | 2026-02-04 13:52:12.335064 | TASK [add-build-sshkey : Create Temp SSH key] 2026-02-04 13:52:13.556428 | rockylinux-9 -> localhost | Generating public/private rsa key pair. 2026-02-04 13:52:13.556619 | rockylinux-9 -> localhost | Your identification has been saved in /var/lib/zuul/builds/ba2a0f6390dc4640a29ddabdaf0f7552/work/ba2a0f6390dc4640a29ddabdaf0f7552_id_rsa 2026-02-04 13:52:13.556662 | rockylinux-9 -> localhost | Your public key has been saved in /var/lib/zuul/builds/ba2a0f6390dc4640a29ddabdaf0f7552/work/ba2a0f6390dc4640a29ddabdaf0f7552_id_rsa.pub 2026-02-04 13:52:13.556694 | rockylinux-9 -> localhost | The key fingerprint is: 2026-02-04 13:52:13.556724 | rockylinux-9 -> localhost | SHA256:2buQPDUxjpTB/ZN6vhzC78Sk/R+91Jfl1qYK4qbVslU zuul-build-sshkey 2026-02-04 13:52:13.556763 | rockylinux-9 -> localhost | The key's randomart image is: 2026-02-04 13:52:13.556793 | rockylinux-9 -> localhost | +---[RSA 3072]----+ 2026-02-04 13:52:13.556825 | rockylinux-9 -> localhost | | ... | 2026-02-04 13:52:13.556855 | rockylinux-9 -> localhost | | .o. | 2026-02-04 13:52:13.556883 | rockylinux-9 -> localhost | | o o. . | 2026-02-04 13:52:13.556912 | rockylinux-9 -> localhost | | . = o+ | 2026-02-04 13:52:13.556939 | rockylinux-9 -> localhost | | S =.E. .| 2026-02-04 13:52:13.556988 | rockylinux-9 -> localhost | | . =.O. .*| 2026-02-04 13:52:13.557018 | rockylinux-9 -> localhost | | O Oo= +O| 2026-02-04 13:52:13.557045 | rockylinux-9 -> localhost | | o.B B.o.++| 2026-02-04 13:52:13.557076 | rockylinux-9 -> localhost | | .oo ..*ooo.| 2026-02-04 13:52:13.557103 | rockylinux-9 -> localhost | +----[SHA256]-----+ 2026-02-04 13:52:13.557166 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.529883 2026-02-04 13:52:13.566262 | 2026-02-04 13:52:13.566353 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-02-04 13:52:13.603040 | rockylinux-9 | ok 2026-02-04 13:52:13.611313 | rockylinux-9 | included: /var/lib/zuul/builds/ba2a0f6390dc4640a29ddabdaf0f7552/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-02-04 13:52:13.618361 | 2026-02-04 13:52:13.618424 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-02-04 13:52:13.649163 | rockylinux-9 | skipping: Conditional result was False 2026-02-04 13:52:13.654174 | 2026-02-04 13:52:13.654238 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-02-04 13:52:14.124372 | rockylinux-9 | changed 2026-02-04 13:52:14.129892 | 2026-02-04 13:52:14.129961 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-02-04 13:52:14.384439 | rockylinux-9 | ok 2026-02-04 13:52:14.390792 | 2026-02-04 13:52:14.390884 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-02-04 13:52:15.261010 | rockylinux-9 | changed 2026-02-04 13:52:15.271579 | 2026-02-04 13:52:15.271662 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-02-04 13:52:16.126553 | rockylinux-9 | changed 2026-02-04 13:52:16.133928 | 2026-02-04 13:52:16.133990 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-02-04 13:52:16.158953 | rockylinux-9 | skipping: Conditional result was False 2026-02-04 13:52:16.223996 | 2026-02-04 13:52:16.236096 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-02-04 13:52:16.652232 | rockylinux-9 -> localhost | changed 2026-02-04 13:52:16.680510 | 2026-02-04 13:52:16.680607 | TASK [add-build-sshkey : Add back temp key] 2026-02-04 13:52:17.142919 | rockylinux-9 -> localhost | Identity added: /var/lib/zuul/builds/ba2a0f6390dc4640a29ddabdaf0f7552/work/ba2a0f6390dc4640a29ddabdaf0f7552_id_rsa (zuul-build-sshkey) 2026-02-04 13:52:17.143125 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.012684 2026-02-04 13:52:17.147645 | 2026-02-04 13:52:17.147712 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-02-04 13:52:17.595338 | rockylinux-9 | ok 2026-02-04 13:52:17.605061 | 2026-02-04 13:52:17.605213 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-02-04 13:52:17.630316 | rockylinux-9 | skipping: Conditional result was False 2026-02-04 13:52:17.646530 | 2026-02-04 13:52:17.646607 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-02-04 13:52:18.047641 | rockylinux-9 | ok 2026-02-04 13:52:18.054421 | 2026-02-04 13:52:18.054553 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-02-04 13:52:19.001483 | rockylinux-9 | Output suppressed because no_log was given 2026-02-04 13:52:19.022774 | 2026-02-04 13:52:19.022935 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-02-04 13:52:19.258139 | rockylinux-9 | ok: "logs" 2026-02-04 13:52:19.258452 | rockylinux-9 | ok: All items complete 2026-02-04 13:52:19.258483 | 2026-02-04 13:52:19.457831 | rockylinux-9 | ok: "artifacts" 2026-02-04 13:52:19.677324 | rockylinux-9 | ok: "docs" 2026-02-04 13:52:19.694430 | 2026-02-04 13:52:19.694576 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-02-04 13:52:19.947590 | rockylinux-9 | changed: "logs" 2026-02-04 13:52:20.155835 | rockylinux-9 | changed: "artifacts" 2026-02-04 13:52:20.379664 | rockylinux-9 | changed: "docs" 2026-02-04 13:52:20.397886 | 2026-02-04 13:52:20.398158 | PLAY RECAP 2026-02-04 13:52:20.398229 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-02-04 13:52:20.398263 | rockylinux-9 | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-02-04 13:52:20.398287 | 2026-02-04 13:52:20.545772 | PRE-RUN END RESULT_NORMAL: [trusted : vexxhost.dev/zuul-config/playbooks/base/pre.yaml@main] 2026-02-04 13:52:20.547749 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-02-04 13:52:21.120501 | 2026-02-04 13:52:21.159503 | PLAY [all] 2026-02-04 13:52:21.177984 | 2026-02-04 13:52:21.178166 | TASK [setup-uv : Extract archive] 2026-02-04 13:52:23.963398 | rockylinux-9 | changed 2026-02-04 13:52:23.969399 | 2026-02-04 13:52:23.969502 | TASK [setup-uv : Print version] 2026-02-04 13:52:23.990873 | [rockylinux-9] Waiting on logger 2026-02-04 13:52:24.398969 | rockylinux-9 | ok: Runtime: 0:00:00.010877 2026-02-04 13:52:24.472324 | 2026-02-04 13:52:24.472458 | PLAY RECAP 2026-02-04 13:52:24.472523 | rockylinux-9 | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-02-04 13:52:24.472557 | 2026-02-04 13:52:24.627880 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-02-04 13:52:24.629089 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-02-04 13:52:25.206554 | 2026-02-04 13:52:25.207174 | PLAY [all] 2026-02-04 13:52:25.218464 | 2026-02-04 13:52:25.218542 | TASK [Copy inventory file for Zuul] 2026-02-04 13:52:26.458540 | rockylinux-9 | changed 2026-02-04 13:52:26.466109 | 2026-02-04 13:52:26.466225 | TASK [Switch "ansible_host" to private IP] 2026-02-04 13:52:26.807276 | rockylinux-9 | changed: 1 replacements made 2026-02-04 13:52:26.817810 | 2026-02-04 13:52:26.817940 | TASK [Run Molecule scenario] 2026-02-04 13:52:26.842427 | [rockylinux-9] Waiting on logger 2026-02-04 13:52:34.982221 | [rockylinux-9] Waiting on logger 2026-02-04 13:52:44.134049 | [rockylinux-9] Waiting on logger 2026-02-04 13:52:54.310097 | [rockylinux-9] Waiting on logger 2026-02-04 13:53:04.485928 | [rockylinux-9] Waiting on logger 2026-02-04 13:53:14.662074 | [rockylinux-9] Waiting on logger 2026-02-04 13:53:24.837790 | [rockylinux-9] Waiting on logger 2026-02-04 13:53:34.000266 | [rockylinux-9] Waiting on logger 2026-02-04 13:53:44.166111 | [rockylinux-9] Waiting on logger 2026-02-04 13:53:54.342053 | [rockylinux-9] Waiting on logger 2026-02-04 13:54:04.517729 | [rockylinux-9] Waiting on logger 2026-02-04 13:54:14.693947 | [rockylinux-9] Waiting on logger 2026-02-04 13:54:24.870044 | [rockylinux-9] Waiting on logger 2026-02-04 13:54:34.027214 | [rockylinux-9] Waiting on logger 2026-02-04 13:54:44.198116 | [rockylinux-9] Waiting on logger 2026-02-04 13:54:54.374157 | [rockylinux-9] Waiting on logger 2026-02-04 13:55:04.549945 | [rockylinux-9] Waiting on logger 2026-02-04 13:55:14.726247 | [rockylinux-9] Waiting on logger 2026-02-04 13:55:24.901962 | [rockylinux-9] Waiting on logger 2026-02-04 13:55:34.059244 | [rockylinux-9] Waiting on logger 2026-02-04 13:55:44.229959 | [rockylinux-9] Waiting on logger 2026-02-04 13:55:54.410121 | [rockylinux-9] Waiting on logger 2026-02-04 13:56:04.582281 | [rockylinux-9] Waiting on logger 2026-02-04 13:56:14.758243 | [rockylinux-9] Waiting on logger 2026-02-04 13:56:24.937929 | [rockylinux-9] Waiting on logger 2026-02-04 13:56:30.637705 | rockylinux-9 | ok: Runtime: 0:04:03.238846 2026-02-04 13:56:30.644760 | 2026-02-04 13:56:30.644816 | PLAY RECAP 2026-02-04 13:56:30.644866 | rockylinux-9 | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-02-04 13:56:30.644888 | 2026-02-04 13:56:30.760064 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-02-04 13:56:30.761294 | POST-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/post.yaml@main] 2026-02-04 13:56:31.317483 | 2026-02-04 13:56:31.317615 | PLAY [all] 2026-02-04 13:56:31.333739 | 2026-02-04 13:56:31.334166 | TASK [fetch-output : Set log path for multiple nodes] 2026-02-04 13:56:31.373174 | rockylinux-9 | skipping: Conditional result was False 2026-02-04 13:56:31.381215 | 2026-02-04 13:56:31.381364 | TASK [fetch-output : Set log path for single node] 2026-02-04 13:56:31.426570 | rockylinux-9 | ok 2026-02-04 13:56:31.434018 | 2026-02-04 13:56:31.434132 | LOOP [fetch-output : Ensure local output dirs] 2026-02-04 13:56:31.844899 | rockylinux-9 -> localhost | ok: "/var/lib/zuul/builds/ba2a0f6390dc4640a29ddabdaf0f7552/work/logs" 2026-02-04 13:56:32.064746 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/ba2a0f6390dc4640a29ddabdaf0f7552/work/artifacts" 2026-02-04 13:56:32.275490 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/ba2a0f6390dc4640a29ddabdaf0f7552/work/docs" 2026-02-04 13:56:32.294307 | 2026-02-04 13:56:32.294499 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-02-04 13:56:33.007588 | rockylinux-9 | changed: .d..t...... ./ 2026-02-04 13:56:33.007904 | rockylinux-9 | changed: All items complete 2026-02-04 13:56:33.007933 | 2026-02-04 13:56:33.519150 | rockylinux-9 | changed: .d..t...... ./ 2026-02-04 13:56:34.012733 | rockylinux-9 | changed: .d..t...... ./ 2026-02-04 13:56:34.030059 | 2026-02-04 13:56:34.030230 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-02-04 13:56:34.445688 | rockylinux-9 -> localhost | ok: Item: artifacts Runtime: 0:00:00.007959 2026-02-04 13:56:34.718008 | rockylinux-9 -> localhost | ok: Item: docs Runtime: 0:00:00.007207 2026-02-04 13:56:34.732454 | 2026-02-04 13:56:34.732659 | PLAY [all] 2026-02-04 13:56:34.740804 | 2026-02-04 13:56:34.740891 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-02-04 13:56:35.189160 | rockylinux-9 | changed 2026-02-04 13:56:35.196055 | 2026-02-04 13:56:35.196126 | PLAY RECAP 2026-02-04 13:56:35.196190 | rockylinux-9 | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-02-04 13:56:35.196219 | 2026-02-04 13:56:35.365040 | POST-RUN END RESULT_NORMAL: [trusted : vexxhost.dev/zuul-config/playbooks/base/post.yaml@main] 2026-02-04 13:56:35.367207 | POST-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/post-logs.yaml@main] 2026-02-04 13:56:35.932204 | 2026-02-04 13:56:35.932360 | PLAY [localhost] 2026-02-04 13:56:35.943363 | 2026-02-04 13:56:35.943443 | TASK [Generate Zuul manifest] 2026-02-04 13:56:35.962546 | localhost | ok 2026-02-04 13:56:35.979125 | 2026-02-04 13:56:35.979202 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-02-04 13:56:36.303836 | localhost | changed 2026-02-04 13:56:36.318083 | 2026-02-04 13:56:36.318248 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-02-04 13:56:36.352069 | localhost | ok 2026-02-04 13:56:36.360765 | 2026-02-04 13:56:36.360886 | TASK [Upload logs] 2026-02-04 13:56:36.378135 | localhost | ok 2026-02-04 13:56:36.437686 | 2026-02-04 13:56:36.437825 | TASK [Set zuul-log-path fact] 2026-02-04 13:56:36.460208 | localhost | ok 2026-02-04 13:56:36.473891 | 2026-02-04 13:56:36.473957 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-02-04 13:56:36.516018 | localhost | ok 2026-02-04 13:56:36.525752 | 2026-02-04 13:56:36.525813 | TASK [upload-logs : Create log directories] 2026-02-04 13:56:36.943563 | localhost | changed 2026-02-04 13:56:36.950061 | 2026-02-04 13:56:36.950136 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-02-04 13:56:37.378879 | localhost -> localhost | ok: Runtime: 0:00:00.006155 2026-02-04 13:56:37.383697 | 2026-02-04 13:56:37.383759 | TASK [upload-logs : Upload logs to log server] 2026-02-04 13:56:37.839679 | localhost | Output suppressed because no_log was given 2026-02-04 13:56:37.844526 | 2026-02-04 13:56:37.844609 | LOOP [upload-logs : Compress console log and json output] 2026-02-04 13:56:37.887121 | localhost | skipping: Conditional result was False 2026-02-04 13:56:37.893228 | localhost | skipping: Conditional result was False 2026-02-04 13:56:37.908173 | 2026-02-04 13:56:37.908339 | LOOP [upload-logs : Upload compressed console log and json output] 2026-02-04 13:56:37.946757 | localhost | skipping: Conditional result was False 2026-02-04 13:56:37.947192 | 2026-02-04 13:56:37.949733 | localhost | skipping: Conditional result was False 2026-02-04 13:56:37.963128 | 2026-02-04 13:56:37.963211 | LOOP [upload-logs : Upload console log and json output]