2026-03-15 13:19:01.119723 | Job console starting 2026-03-15 13:19:01.132794 | Updating git repos 2026-03-15 13:19:01.189173 | Cloning repos into workspace 2026-03-15 13:19:01.272997 | Restoring repo states 2026-03-15 13:19:01.280863 | Merging changes 2026-03-15 13:19:01.625250 | Checking out repos 2026-03-15 13:19:01.811610 | Preparing playbooks 2026-03-15 13:19:04.569519 | Running Ansible setup 2026-03-15 13:19:08.317562 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-03-15 13:19:08.950486 | 2026-03-15 13:19:08.950655 | PLAY [localhost] 2026-03-15 13:19:08.958726 | 2026-03-15 13:19:08.958800 | TASK [Gathering Facts] 2026-03-15 13:19:09.817369 | localhost | ok 2026-03-15 13:19:09.827043 | 2026-03-15 13:19:09.827153 | TASK [Setup log path fact] 2026-03-15 13:19:09.849875 | localhost | ok 2026-03-15 13:19:09.996438 | 2026-03-15 13:19:09.996579 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-03-15 13:19:10.026788 | localhost | ok 2026-03-15 13:19:10.038383 | 2026-03-15 13:19:10.038876 | TASK [emit-job-header : Print job information] 2026-03-15 13:19:10.081552 | # Job Information 2026-03-15 13:19:10.081782 | Ansible Version: 2.16.16 2026-03-15 13:19:10.081829 | Job: ansible-collection-containers-molecule-containerd-rockylinux-9 2026-03-15 13:19:10.081863 | Pipeline: check 2026-03-15 13:19:10.081893 | Executor: 0a8996d2b663 2026-03-15 13:19:10.081922 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/93 2026-03-15 13:19:10.081956 | Event ID: 2231fa50-2071-11f1-92aa-810779cc3e94 2026-03-15 13:19:10.086487 | 2026-03-15 13:19:10.086582 | LOOP [emit-job-header : Print node information] 2026-03-15 13:19:10.176835 | localhost | ok: 2026-03-15 13:19:10.177202 | localhost | # Node Information 2026-03-15 13:19:10.177265 | localhost | Inventory Hostname: rockylinux-9 2026-03-15 13:19:10.177321 | localhost | Hostname: np0000158861 2026-03-15 13:19:10.177367 | localhost | Username: zuul 2026-03-15 13:19:10.177419 | localhost | Distro: Rocky 9.7 2026-03-15 13:19:10.177466 | localhost | Provider: yul1 2026-03-15 13:19:10.177512 | localhost | Region: ca-ymq-1 2026-03-15 13:19:10.177557 | localhost | Label: rockylinux-9 2026-03-15 13:19:10.177601 | localhost | Product Name: OpenStack Nova 2026-03-15 13:19:10.177645 | localhost | Interface IP: 199.204.45.3 2026-03-15 13:19:10.193081 | 2026-03-15 13:19:10.193931 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-03-15 13:19:10.612464 | localhost -> localhost | changed 2026-03-15 13:19:10.621026 | 2026-03-15 13:19:10.621119 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-03-15 13:19:11.612249 | localhost -> localhost | changed 2026-03-15 13:19:11.623891 | 2026-03-15 13:19:11.623965 | PLAY [all] 2026-03-15 13:19:11.632458 | 2026-03-15 13:19:11.632526 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-03-15 13:19:11.919946 | rockylinux-9 -> localhost | ok 2026-03-15 13:19:11.927447 | 2026-03-15 13:19:11.927535 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-03-15 13:19:11.963029 | rockylinux-9 | ok 2026-03-15 13:19:11.981466 | rockylinux-9 | included: /var/lib/zuul/builds/5c8f66a36acd44a19a2d21e4cd1ada18/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-03-15 13:19:11.986558 | 2026-03-15 13:19:11.986621 | TASK [add-build-sshkey : Create Temp SSH key] 2026-03-15 13:19:12.844265 | rockylinux-9 -> localhost | Generating public/private rsa key pair. 2026-03-15 13:19:12.845020 | rockylinux-9 -> localhost | Your identification has been saved in /var/lib/zuul/builds/5c8f66a36acd44a19a2d21e4cd1ada18/work/5c8f66a36acd44a19a2d21e4cd1ada18_id_rsa 2026-03-15 13:19:12.845189 | rockylinux-9 -> localhost | Your public key has been saved in /var/lib/zuul/builds/5c8f66a36acd44a19a2d21e4cd1ada18/work/5c8f66a36acd44a19a2d21e4cd1ada18_id_rsa.pub 2026-03-15 13:19:12.845404 | rockylinux-9 -> localhost | The key fingerprint is: 2026-03-15 13:19:12.845560 | rockylinux-9 -> localhost | SHA256:kITfY2F0AykdJDItU/jMYePGEqOfbuJl6cZIKxQcWQA zuul-build-sshkey 2026-03-15 13:19:12.845651 | rockylinux-9 -> localhost | The key's randomart image is: 2026-03-15 13:19:12.845705 | rockylinux-9 -> localhost | +---[RSA 3072]----+ 2026-03-15 13:19:12.845771 | rockylinux-9 -> localhost | |E.+.o=++=+o | 2026-03-15 13:19:12.845823 | rockylinux-9 -> localhost | | o B+*o=. . | 2026-03-15 13:19:12.845871 | rockylinux-9 -> localhost | |. .. &o* . | 2026-03-15 13:19:12.845919 | rockylinux-9 -> localhost | | o. . O.+ | 2026-03-15 13:19:12.845968 | rockylinux-9 -> localhost | | .. + .S. | 2026-03-15 13:19:12.846015 | rockylinux-9 -> localhost | | .. o. | 2026-03-15 13:19:12.846063 | rockylinux-9 -> localhost | |.. =+ | 2026-03-15 13:19:12.846110 | rockylinux-9 -> localhost | |. ++= | 2026-03-15 13:19:12.846163 | rockylinux-9 -> localhost | | o.+. | 2026-03-15 13:19:12.846212 | rockylinux-9 -> localhost | +----[SHA256]-----+ 2026-03-15 13:19:12.846323 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.372821 2026-03-15 13:19:12.853415 | 2026-03-15 13:19:12.853557 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-03-15 13:19:12.889249 | rockylinux-9 | ok 2026-03-15 13:19:12.899651 | rockylinux-9 | included: /var/lib/zuul/builds/5c8f66a36acd44a19a2d21e4cd1ada18/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-03-15 13:19:12.907979 | 2026-03-15 13:19:12.908047 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-03-15 13:19:12.934131 | rockylinux-9 | skipping: Conditional result was False 2026-03-15 13:19:12.944165 | 2026-03-15 13:19:12.944279 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-03-15 13:19:13.434496 | rockylinux-9 | changed 2026-03-15 13:19:13.478369 | 2026-03-15 13:19:13.478462 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-03-15 13:19:13.730449 | rockylinux-9 | ok 2026-03-15 13:19:13.735237 | 2026-03-15 13:19:13.735300 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-03-15 13:19:14.672188 | rockylinux-9 | changed 2026-03-15 13:19:14.678486 | 2026-03-15 13:19:14.678551 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-03-15 13:19:15.618296 | rockylinux-9 | changed 2026-03-15 13:19:15.623178 | 2026-03-15 13:19:15.623246 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-03-15 13:19:15.648305 | rockylinux-9 | skipping: Conditional result was False 2026-03-15 13:19:15.657604 | 2026-03-15 13:19:15.657699 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-03-15 13:19:16.087214 | rockylinux-9 -> localhost | changed 2026-03-15 13:19:16.097558 | 2026-03-15 13:19:16.097628 | TASK [add-build-sshkey : Add back temp key] 2026-03-15 13:19:16.369308 | rockylinux-9 -> localhost | Identity added: /var/lib/zuul/builds/5c8f66a36acd44a19a2d21e4cd1ada18/work/5c8f66a36acd44a19a2d21e4cd1ada18_id_rsa (zuul-build-sshkey) 2026-03-15 13:19:16.369546 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.014481 2026-03-15 13:19:16.378078 | 2026-03-15 13:19:16.378166 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-03-15 13:19:16.748065 | rockylinux-9 | ok 2026-03-15 13:19:16.757218 | 2026-03-15 13:19:16.757283 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-03-15 13:19:16.782920 | rockylinux-9 | skipping: Conditional result was False 2026-03-15 13:19:16.797211 | 2026-03-15 13:19:16.797300 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-03-15 13:19:17.216941 | rockylinux-9 | ok 2026-03-15 13:19:17.224515 | 2026-03-15 13:19:17.224606 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-03-15 13:19:18.124690 | rockylinux-9 | Output suppressed because no_log was given 2026-03-15 13:19:18.135707 | 2026-03-15 13:19:18.135797 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-03-15 13:19:18.387696 | rockylinux-9 | ok: "logs" 2026-03-15 13:19:18.388043 | rockylinux-9 | ok: All items complete 2026-03-15 13:19:18.388073 | 2026-03-15 13:19:18.611789 | rockylinux-9 | ok: "artifacts" 2026-03-15 13:19:18.838573 | rockylinux-9 | ok: "docs" 2026-03-15 13:19:18.856026 | 2026-03-15 13:19:18.856237 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-03-15 13:19:19.126367 | rockylinux-9 | changed: "logs" 2026-03-15 13:19:19.356238 | rockylinux-9 | changed: "artifacts" 2026-03-15 13:19:19.579973 | rockylinux-9 | changed: "docs" 2026-03-15 13:19:19.599988 | 2026-03-15 13:19:19.600131 | PLAY RECAP 2026-03-15 13:19:19.600180 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-03-15 13:19:19.600209 | rockylinux-9 | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-03-15 13:19:19.600233 | 2026-03-15 13:19:19.776280 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-03-15 13:19:19.777614 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-03-15 13:19:20.416582 | 2026-03-15 13:19:20.416691 | PLAY [all] 2026-03-15 13:19:20.427259 | 2026-03-15 13:19:20.427332 | TASK [setup-uv : Extract archive] 2026-03-15 13:19:23.136367 | rockylinux-9 | changed 2026-03-15 13:19:23.143514 | 2026-03-15 13:19:23.143615 | TASK [setup-uv : Print version] 2026-03-15 13:19:23.167735 | [rockylinux-9] Waiting on logger 2026-03-15 13:19:23.685454 | rockylinux-9 | ok: Runtime: 0:00:00.014757 2026-03-15 13:19:23.693278 | 2026-03-15 13:19:23.693353 | PLAY RECAP 2026-03-15 13:19:23.693418 | rockylinux-9 | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-03-15 13:19:23.693453 | 2026-03-15 13:19:23.798383 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-03-15 13:19:23.799721 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-03-15 13:19:24.414874 | 2026-03-15 13:19:24.415026 | PLAY [all] 2026-03-15 13:19:24.425766 | 2026-03-15 13:19:24.425838 | TASK [Copy inventory file for Zuul] 2026-03-15 13:19:25.811947 | rockylinux-9 | changed 2026-03-15 13:19:25.817522 | 2026-03-15 13:19:25.817596 | TASK [Switch "ansible_host" to private IP] 2026-03-15 13:19:26.151610 | rockylinux-9 | changed: 1 replacements made 2026-03-15 13:19:26.159618 | 2026-03-15 13:19:26.159705 | TASK [Run Molecule scenario] 2026-03-15 13:19:26.180199 | [rockylinux-9] Waiting on logger 2026-03-15 13:19:34.310107 | [rockylinux-9] Waiting on logger 2026-03-15 13:19:44.490159 | [rockylinux-9] Waiting on logger 2026-03-15 13:19:54.661836 | [rockylinux-9] Waiting on logger 2026-03-15 13:20:04.842275 | [rockylinux-9] Waiting on logger 2026-03-15 13:20:15.018572 | [rockylinux-9] Waiting on logger 2026-03-15 13:20:24.171279 | [rockylinux-9] Waiting on logger 2026-03-15 13:20:34.346120 | [rockylinux-9] Waiting on logger 2026-03-15 13:20:44.517941 | [rockylinux-9] Waiting on logger 2026-03-15 13:20:54.694160 | [rockylinux-9] Waiting on logger 2026-03-15 13:21:04.873984 | [rockylinux-9] Waiting on logger 2026-03-15 13:21:15.046031 | [rockylinux-9] Waiting on logger 2026-03-15 13:21:24.203928 | [rockylinux-9] Waiting on logger 2026-03-15 13:21:34.373936 | [rockylinux-9] Waiting on logger 2026-03-15 13:21:44.550119 | [rockylinux-9] Waiting on logger 2026-03-15 13:21:54.726119 | [rockylinux-9] Waiting on logger 2026-03-15 13:22:04.902157 | [rockylinux-9] Waiting on logger 2026-03-15 13:22:15.077945 | [rockylinux-9] Waiting on logger 2026-03-15 13:22:24.238956 | [rockylinux-9] Waiting on logger 2026-03-15 13:22:34.406143 | [rockylinux-9] Waiting on logger 2026-03-15 13:22:44.583071 | [rockylinux-9] Waiting on logger 2026-03-15 13:22:54.758457 | [rockylinux-9] Waiting on logger 2026-03-15 13:23:04.938069 | [rockylinux-9] Waiting on logger 2026-03-15 13:23:15.110522 | [rockylinux-9] Waiting on logger 2026-03-15 13:23:24.266880 | [rockylinux-9] Waiting on logger 2026-03-15 13:23:34.438018 | [rockylinux-9] Waiting on logger 2026-03-15 13:23:44.614173 | [rockylinux-9] Waiting on logger 2026-03-15 13:23:54.793943 | [rockylinux-9] Waiting on logger 2026-03-15 13:23:57.038838 | rockylinux-9 | ok: Runtime: 0:04:29.623619 2026-03-15 13:23:57.046884 | 2026-03-15 13:23:57.046939 | PLAY RECAP 2026-03-15 13:23:57.046990 | rockylinux-9 | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-03-15 13:23:57.047012 | 2026-03-15 13:23:57.203463 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-03-15 13:23:57.204751 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-03-15 13:23:57.829024 | 2026-03-15 13:23:57.829441 | PLAY [all] 2026-03-15 13:23:57.840564 | 2026-03-15 13:23:57.840645 | TASK [fetch-output : Set log path for multiple nodes] 2026-03-15 13:23:57.886246 | rockylinux-9 | skipping: Conditional result was False 2026-03-15 13:23:57.895125 | 2026-03-15 13:23:57.895205 | TASK [fetch-output : Set log path for single node] 2026-03-15 13:23:57.937347 | rockylinux-9 | ok 2026-03-15 13:23:57.943828 | 2026-03-15 13:23:57.943894 | LOOP [fetch-output : Ensure local output dirs] 2026-03-15 13:23:58.334408 | rockylinux-9 -> localhost | ok: "/var/lib/zuul/builds/5c8f66a36acd44a19a2d21e4cd1ada18/work/logs" 2026-03-15 13:23:58.535526 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/5c8f66a36acd44a19a2d21e4cd1ada18/work/artifacts" 2026-03-15 13:23:58.750854 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/5c8f66a36acd44a19a2d21e4cd1ada18/work/docs" 2026-03-15 13:23:58.775555 | 2026-03-15 13:23:58.775709 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-03-15 13:23:59.513029 | rockylinux-9 | changed: .d..t...... ./ 2026-03-15 13:23:59.513310 | rockylinux-9 | changed: All items complete 2026-03-15 13:23:59.513350 | 2026-03-15 13:24:00.034727 | rockylinux-9 | changed: .d..t...... ./ 2026-03-15 13:24:00.539893 | rockylinux-9 | changed: .d..t...... ./ 2026-03-15 13:24:00.557359 | 2026-03-15 13:24:00.557575 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-03-15 13:24:00.951347 | rockylinux-9 -> localhost | ok: Item: artifacts Runtime: 0:00:00.008248 2026-03-15 13:24:01.160944 | rockylinux-9 -> localhost | ok: Item: docs Runtime: 0:00:00.006780 2026-03-15 13:24:01.174570 | 2026-03-15 13:24:01.174679 | PLAY [all] 2026-03-15 13:24:01.180111 | 2026-03-15 13:24:01.180173 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-03-15 13:24:01.624179 | rockylinux-9 | changed 2026-03-15 13:24:01.629332 | 2026-03-15 13:24:01.629379 | PLAY RECAP 2026-03-15 13:24:01.629421 | rockylinux-9 | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-03-15 13:24:01.629443 | 2026-03-15 13:24:01.750347 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-03-15 13:24:01.752542 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-03-15 13:24:02.289378 | 2026-03-15 13:24:02.289511 | PLAY [localhost] 2026-03-15 13:24:02.299247 | 2026-03-15 13:24:02.299319 | TASK [Generate Zuul manifest] 2026-03-15 13:24:02.319840 | localhost | ok 2026-03-15 13:24:02.335774 | 2026-03-15 13:24:02.335851 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-03-15 13:24:02.670209 | localhost | changed 2026-03-15 13:24:02.680913 | 2026-03-15 13:24:02.681006 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-03-15 13:24:02.708814 | localhost | ok 2026-03-15 13:24:02.714994 | 2026-03-15 13:24:02.715057 | TASK [Upload logs] 2026-03-15 13:24:02.731892 | localhost | ok 2026-03-15 13:24:02.793907 | 2026-03-15 13:24:02.794030 | TASK [Set zuul-log-path fact] 2026-03-15 13:24:02.812755 | localhost | ok 2026-03-15 13:24:02.825443 | 2026-03-15 13:24:02.825509 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-03-15 13:24:02.856819 | localhost | ok 2026-03-15 13:24:02.865844 | 2026-03-15 13:24:02.865909 | TASK [upload-logs : Create log directories] 2026-03-15 13:24:03.273564 | localhost | changed 2026-03-15 13:24:03.279541 | 2026-03-15 13:24:03.279632 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-03-15 13:24:03.666734 | localhost -> localhost | ok: Runtime: 0:00:00.004145 2026-03-15 13:24:03.672384 | 2026-03-15 13:24:03.672446 | TASK [upload-logs : Upload logs to log server] 2026-03-15 13:24:04.136306 | localhost | Output suppressed because no_log was given 2026-03-15 13:24:04.141028 | 2026-03-15 13:24:04.141118 | LOOP [upload-logs : Compress console log and json output] 2026-03-15 13:24:04.183331 | localhost | skipping: Conditional result was False 2026-03-15 13:24:04.190282 | localhost | skipping: Conditional result was False 2026-03-15 13:24:04.203250 | 2026-03-15 13:24:04.203372 | LOOP [upload-logs : Upload compressed console log and json output] 2026-03-15 13:24:04.242513 | localhost | skipping: Conditional result was False 2026-03-15 13:24:04.242918 | 2026-03-15 13:24:04.246600 | localhost | skipping: Conditional result was False 2026-03-15 13:24:04.256152 | 2026-03-15 13:24:04.256314 | LOOP [upload-logs : Upload console log and json output]