2026-04-13 18:41:57.957838 | Job console starting 2026-04-13 18:41:57.979146 | Updating git repos 2026-04-13 18:41:58.020336 | Cloning repos into workspace 2026-04-13 18:41:58.072269 | Restoring repo states 2026-04-13 18:41:58.083415 | Merging changes 2026-04-13 18:41:58.360071 | Checking out repos 2026-04-13 18:41:58.398462 | Preparing playbooks 2026-04-13 18:41:59.485703 | Running Ansible setup 2026-04-13 18:42:03.227321 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-04-13 18:42:03.923464 | 2026-04-13 18:42:03.923977 | PLAY [localhost] 2026-04-13 18:42:03.932180 | 2026-04-13 18:42:03.932251 | TASK [Gathering Facts] 2026-04-13 18:42:04.937276 | localhost | ok 2026-04-13 18:42:04.948120 | 2026-04-13 18:42:04.948249 | TASK [Setup log path fact] 2026-04-13 18:42:04.969366 | localhost | ok 2026-04-13 18:42:04.982849 | 2026-04-13 18:42:04.982952 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-13 18:42:05.012468 | localhost | ok 2026-04-13 18:42:05.019854 | 2026-04-13 18:42:05.019958 | TASK [emit-job-header : Print job information] 2026-04-13 18:42:05.050354 | # Job Information 2026-04-13 18:42:05.050543 | Ansible Version: 2.16.16 2026-04-13 18:42:05.050590 | Job: ansible-collection-containers-molecule-containerd-rockylinux-9 2026-04-13 18:42:05.050623 | Pipeline: check 2026-04-13 18:42:05.050652 | Executor: 0a8996d2b663 2026-04-13 18:42:05.050680 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/108 2026-04-13 18:42:05.050712 | Event ID: 4a670cf0-3768-11f1-97e0-7c8334f2b834 2026-04-13 18:42:05.055095 | 2026-04-13 18:42:05.055188 | LOOP [emit-job-header : Print node information] 2026-04-13 18:42:05.148881 | localhost | ok: 2026-04-13 18:42:05.149305 | localhost | # Node Information 2026-04-13 18:42:05.149338 | localhost | Inventory Hostname: rockylinux-9 2026-04-13 18:42:05.149364 | localhost | Hostname: np0000165631 2026-04-13 18:42:05.149385 | localhost | Username: zuul 2026-04-13 18:42:05.149407 | localhost | Distro: Rocky 9.7 2026-04-13 18:42:05.149426 | localhost | Provider: yul1 2026-04-13 18:42:05.149445 | localhost | Region: ca-ymq-1 2026-04-13 18:42:05.149463 | localhost | Label: rockylinux-9 2026-04-13 18:42:05.149482 | localhost | Product Name: OpenStack Nova 2026-04-13 18:42:05.149500 | localhost | Interface IP: 199.204.45.229 2026-04-13 18:42:05.164635 | 2026-04-13 18:42:05.164779 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-04-13 18:42:05.800213 | localhost -> localhost | changed 2026-04-13 18:42:05.807358 | 2026-04-13 18:42:05.807486 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-04-13 18:42:06.721751 | localhost -> localhost | changed 2026-04-13 18:42:06.729862 | 2026-04-13 18:42:06.729951 | PLAY [all] 2026-04-13 18:42:06.738203 | 2026-04-13 18:42:06.738329 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-04-13 18:42:06.956399 | rockylinux-9 -> localhost | ok 2026-04-13 18:42:06.965663 | 2026-04-13 18:42:06.965759 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-04-13 18:42:07.006576 | rockylinux-9 | ok 2026-04-13 18:42:07.021016 | rockylinux-9 | included: /var/lib/zuul/builds/8f63bccbbdef41949dd95b36f5476bb0/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-04-13 18:42:07.027214 | 2026-04-13 18:42:07.027282 | TASK [add-build-sshkey : Create Temp SSH key] 2026-04-13 18:42:08.075541 | rockylinux-9 -> localhost | Generating public/private rsa key pair. 2026-04-13 18:42:08.075835 | rockylinux-9 -> localhost | Your identification has been saved in /var/lib/zuul/builds/8f63bccbbdef41949dd95b36f5476bb0/work/8f63bccbbdef41949dd95b36f5476bb0_id_rsa 2026-04-13 18:42:08.075935 | rockylinux-9 -> localhost | Your public key has been saved in /var/lib/zuul/builds/8f63bccbbdef41949dd95b36f5476bb0/work/8f63bccbbdef41949dd95b36f5476bb0_id_rsa.pub 2026-04-13 18:42:08.075962 | rockylinux-9 -> localhost | The key fingerprint is: 2026-04-13 18:42:08.075984 | rockylinux-9 -> localhost | SHA256:kOyRyVvnN5LdS7j69gZ827/tdWJ8rFYtHF5vR1Rwakk zuul-build-sshkey 2026-04-13 18:42:08.076013 | rockylinux-9 -> localhost | The key's randomart image is: 2026-04-13 18:42:08.076034 | rockylinux-9 -> localhost | +---[RSA 3072]----+ 2026-04-13 18:42:08.076061 | rockylinux-9 -> localhost | | E.+| 2026-04-13 18:42:08.076083 | rockylinux-9 -> localhost | | o + . +.| 2026-04-13 18:42:08.076104 | rockylinux-9 -> localhost | | O . . +. | 2026-04-13 18:42:08.076124 | rockylinux-9 -> localhost | | . = o o +. o| 2026-04-13 18:42:08.076144 | rockylinux-9 -> localhost | | o S +.=oo+o| 2026-04-13 18:42:08.076163 | rockylinux-9 -> localhost | | oo==o*| 2026-04-13 18:42:08.076182 | rockylinux-9 -> localhost | | .o=**| 2026-04-13 18:42:08.076201 | rockylinux-9 -> localhost | | ...++=| 2026-04-13 18:42:08.076223 | rockylinux-9 -> localhost | | .o.+oo*| 2026-04-13 18:42:08.076243 | rockylinux-9 -> localhost | +----[SHA256]-----+ 2026-04-13 18:42:08.076291 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.602070 2026-04-13 18:42:08.081086 | 2026-04-13 18:42:08.081155 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-04-13 18:42:08.113646 | rockylinux-9 | ok 2026-04-13 18:42:08.124516 | rockylinux-9 | included: /var/lib/zuul/builds/8f63bccbbdef41949dd95b36f5476bb0/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-04-13 18:42:08.132473 | 2026-04-13 18:42:08.132538 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-04-13 18:42:08.157802 | rockylinux-9 | skipping: Conditional result was False 2026-04-13 18:42:08.166736 | 2026-04-13 18:42:08.166878 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-04-13 18:42:08.677243 | rockylinux-9 | changed 2026-04-13 18:42:08.683006 | 2026-04-13 18:42:08.683087 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-04-13 18:42:08.944621 | rockylinux-9 | ok 2026-04-13 18:42:08.949319 | 2026-04-13 18:42:08.949392 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-04-13 18:42:09.925851 | rockylinux-9 | changed 2026-04-13 18:42:09.933953 | 2026-04-13 18:42:09.934024 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-04-13 18:42:10.941602 | rockylinux-9 | changed 2026-04-13 18:42:10.948879 | 2026-04-13 18:42:10.949058 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-04-13 18:42:10.974869 | rockylinux-9 | skipping: Conditional result was False 2026-04-13 18:42:10.984292 | 2026-04-13 18:42:10.984462 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-04-13 18:42:11.384787 | rockylinux-9 -> localhost | changed 2026-04-13 18:42:11.394282 | 2026-04-13 18:42:11.394374 | TASK [add-build-sshkey : Add back temp key] 2026-04-13 18:42:11.657873 | rockylinux-9 -> localhost | Identity added: /var/lib/zuul/builds/8f63bccbbdef41949dd95b36f5476bb0/work/8f63bccbbdef41949dd95b36f5476bb0_id_rsa (zuul-build-sshkey) 2026-04-13 18:42:11.658086 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.013123 2026-04-13 18:42:11.664239 | 2026-04-13 18:42:11.664313 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-04-13 18:42:12.098780 | rockylinux-9 | ok 2026-04-13 18:42:12.103464 | 2026-04-13 18:42:12.103531 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-04-13 18:42:12.138839 | rockylinux-9 | skipping: Conditional result was False 2026-04-13 18:42:12.152022 | 2026-04-13 18:42:12.152116 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-04-13 18:42:12.594751 | rockylinux-9 | ok 2026-04-13 18:42:12.608328 | 2026-04-13 18:42:12.608405 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-04-13 18:42:13.565704 | rockylinux-9 | Output suppressed because no_log was given 2026-04-13 18:42:13.575417 | 2026-04-13 18:42:13.575486 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-04-13 18:42:13.840092 | rockylinux-9 | ok: "logs" 2026-04-13 18:42:13.840384 | rockylinux-9 | ok: All items complete 2026-04-13 18:42:13.840415 | 2026-04-13 18:42:14.078683 | rockylinux-9 | ok: "artifacts" 2026-04-13 18:42:14.310660 | rockylinux-9 | ok: "docs" 2026-04-13 18:42:14.325867 | 2026-04-13 18:42:14.326073 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-04-13 18:42:14.591811 | rockylinux-9 | changed: "logs" 2026-04-13 18:42:14.880794 | rockylinux-9 | changed: "artifacts" 2026-04-13 18:42:15.127481 | rockylinux-9 | changed: "docs" 2026-04-13 18:42:15.142810 | 2026-04-13 18:42:15.142965 | PLAY RECAP 2026-04-13 18:42:15.143032 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-13 18:42:15.143073 | rockylinux-9 | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-04-13 18:42:15.143103 | 2026-04-13 18:42:15.282282 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-04-13 18:42:15.284639 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-04-13 18:42:15.888392 | 2026-04-13 18:42:15.888543 | PLAY [all] 2026-04-13 18:42:15.900722 | 2026-04-13 18:42:15.900846 | TASK [setup-uv : Extract archive] 2026-04-13 18:42:18.773728 | rockylinux-9 | changed 2026-04-13 18:42:18.779778 | 2026-04-13 18:42:18.779865 | TASK [setup-uv : Print version] 2026-04-13 18:42:18.804201 | [rockylinux-9] Waiting on logger 2026-04-13 18:42:19.318181 | rockylinux-9 | ok: Runtime: 0:00:00.016068 2026-04-13 18:42:19.326110 | 2026-04-13 18:42:19.326162 | PLAY RECAP 2026-04-13 18:42:19.326210 | rockylinux-9 | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-13 18:42:19.326235 | 2026-04-13 18:42:19.514873 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-04-13 18:42:19.515987 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-04-13 18:42:20.082807 | 2026-04-13 18:42:20.082968 | PLAY [all] 2026-04-13 18:42:20.094148 | 2026-04-13 18:42:20.094227 | TASK [Copy inventory file for Zuul] 2026-04-13 18:42:21.497138 | rockylinux-9 | changed 2026-04-13 18:42:21.503066 | 2026-04-13 18:42:21.503146 | TASK [Switch "ansible_host" to private IP] 2026-04-13 18:42:21.861245 | rockylinux-9 | changed: 1 replacements made 2026-04-13 18:42:21.873799 | 2026-04-13 18:42:21.874051 | TASK [Run Molecule scenario] 2026-04-13 18:42:21.897765 | [rockylinux-9] Waiting on logger 2026-04-13 18:42:30.022132 | [rockylinux-9] Waiting on logger 2026-04-13 18:42:40.198175 | [rockylinux-9] Waiting on logger 2026-04-13 18:42:50.374067 | [rockylinux-9] Waiting on logger 2026-04-13 18:43:00.550315 | [rockylinux-9] Waiting on logger 2026-04-13 18:43:10.726130 | [rockylinux-9] Waiting on logger 2026-04-13 18:43:19.915650 | [rockylinux-9] Waiting on logger 2026-04-13 18:43:30.086016 | [rockylinux-9] Waiting on logger 2026-04-13 18:43:40.262134 | [rockylinux-9] Waiting on logger 2026-04-13 18:43:50.438162 | [rockylinux-9] Waiting on logger 2026-04-13 18:44:01.061431 | [rockylinux-9] Waiting on logger 2026-04-13 18:44:10.758078 | [rockylinux-9] Waiting on logger 2026-04-13 18:44:19.919451 | [rockylinux-9] Waiting on logger 2026-04-13 18:44:30.086221 | [rockylinux-9] Waiting on logger 2026-04-13 18:44:40.262296 | [rockylinux-9] Waiting on logger 2026-04-13 18:44:50.438465 | [rockylinux-9] Waiting on logger 2026-04-13 18:45:00.614460 | [rockylinux-9] Waiting on logger 2026-04-13 18:45:10.790427 | [rockylinux-9] Waiting on logger 2026-04-13 18:45:19.942210 | [rockylinux-9] Waiting on logger 2026-04-13 18:45:30.118639 | [rockylinux-9] Waiting on logger 2026-04-13 18:45:40.298101 | [rockylinux-9] Waiting on logger 2026-04-13 18:45:50.470210 | [rockylinux-9] Waiting on logger 2026-04-13 18:46:00.646253 | [rockylinux-9] Waiting on logger 2026-04-13 18:46:09.807735 | [rockylinux-9] Waiting on logger 2026-04-13 18:46:19.974193 | [rockylinux-9] Waiting on logger 2026-04-13 18:46:30.150151 | [rockylinux-9] Waiting on logger 2026-04-13 18:46:40.329988 | [rockylinux-9] Waiting on logger 2026-04-13 18:46:50.502225 | [rockylinux-9] Waiting on logger 2026-04-13 18:47:00.678259 | [rockylinux-9] Waiting on logger 2026-04-13 18:47:07.498979 | rockylinux-9 | ok: Runtime: 0:04:44.770042 2026-04-13 18:47:07.504715 | 2026-04-13 18:47:07.504868 | PLAY RECAP 2026-04-13 18:47:07.504981 | rockylinux-9 | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-13 18:47:07.505022 | 2026-04-13 18:47:07.629452 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-04-13 18:47:07.631405 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-04-13 18:47:08.239610 | 2026-04-13 18:47:08.239781 | PLAY [all] 2026-04-13 18:47:08.250695 | 2026-04-13 18:47:08.250804 | TASK [fetch-output : Set log path for multiple nodes] 2026-04-13 18:47:08.296293 | rockylinux-9 | skipping: Conditional result was False 2026-04-13 18:47:08.301773 | 2026-04-13 18:47:08.301901 | TASK [fetch-output : Set log path for single node] 2026-04-13 18:47:08.341985 | rockylinux-9 | ok 2026-04-13 18:47:08.348280 | 2026-04-13 18:47:08.348393 | LOOP [fetch-output : Ensure local output dirs] 2026-04-13 18:47:08.727602 | rockylinux-9 -> localhost | ok: "/var/lib/zuul/builds/8f63bccbbdef41949dd95b36f5476bb0/work/logs" 2026-04-13 18:47:08.948338 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/8f63bccbbdef41949dd95b36f5476bb0/work/artifacts" 2026-04-13 18:47:09.158749 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/8f63bccbbdef41949dd95b36f5476bb0/work/docs" 2026-04-13 18:47:09.177366 | 2026-04-13 18:47:09.177574 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-04-13 18:47:09.914564 | rockylinux-9 | changed: .d..t...... ./ 2026-04-13 18:47:09.914922 | rockylinux-9 | changed: All items complete 2026-04-13 18:47:09.914998 | 2026-04-13 18:47:10.433351 | rockylinux-9 | changed: .d..t...... ./ 2026-04-13 18:47:10.961406 | rockylinux-9 | changed: .d..t...... ./ 2026-04-13 18:47:10.982037 | 2026-04-13 18:47:10.982244 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-04-13 18:47:11.401920 | rockylinux-9 -> localhost | ok: Item: artifacts Runtime: 0:00:00.006625 2026-04-13 18:47:11.628075 | rockylinux-9 -> localhost | ok: Item: docs Runtime: 0:00:00.008165 2026-04-13 18:47:11.645716 | 2026-04-13 18:47:11.645850 | PLAY [all] 2026-04-13 18:47:11.653828 | 2026-04-13 18:47:11.653953 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-04-13 18:47:12.121486 | rockylinux-9 | changed 2026-04-13 18:47:12.129985 | 2026-04-13 18:47:12.130072 | PLAY RECAP 2026-04-13 18:47:12.130134 | rockylinux-9 | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-04-13 18:47:12.130168 | 2026-04-13 18:47:12.288128 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-04-13 18:47:12.290440 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-04-13 18:47:12.871138 | 2026-04-13 18:47:12.871290 | PLAY [localhost] 2026-04-13 18:47:12.880895 | 2026-04-13 18:47:12.880986 | TASK [Generate Zuul manifest] 2026-04-13 18:47:12.903254 | localhost | ok 2026-04-13 18:47:12.920369 | 2026-04-13 18:47:12.920449 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-04-13 18:47:13.255391 | localhost | changed 2026-04-13 18:47:13.267955 | 2026-04-13 18:47:13.268024 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-04-13 18:47:13.298657 | localhost | ok 2026-04-13 18:47:13.307227 | 2026-04-13 18:47:13.307311 | TASK [Upload logs] 2026-04-13 18:47:13.327902 | localhost | ok 2026-04-13 18:47:13.384131 | 2026-04-13 18:47:13.384229 | TASK [Set zuul-log-path fact] 2026-04-13 18:47:13.411331 | localhost | ok 2026-04-13 18:47:13.421778 | 2026-04-13 18:47:13.421847 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-13 18:47:13.449072 | localhost | ok 2026-04-13 18:47:13.455957 | 2026-04-13 18:47:13.456018 | TASK [upload-logs : Create log directories] 2026-04-13 18:47:13.893978 | localhost | changed 2026-04-13 18:47:13.900285 | 2026-04-13 18:47:13.900360 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-04-13 18:47:14.306076 | localhost -> localhost | ok: Runtime: 0:00:00.005846 2026-04-13 18:47:14.311826 | 2026-04-13 18:47:14.311889 | TASK [upload-logs : Upload logs to log server] 2026-04-13 18:47:14.788873 | localhost | Output suppressed because no_log was given 2026-04-13 18:47:14.794532 | 2026-04-13 18:47:14.794639 | LOOP [upload-logs : Compress console log and json output] 2026-04-13 18:47:14.853499 | localhost | skipping: Conditional result was False 2026-04-13 18:47:14.861371 | localhost | skipping: Conditional result was False 2026-04-13 18:47:14.879517 | 2026-04-13 18:47:14.879687 | LOOP [upload-logs : Upload compressed console log and json output] 2026-04-13 18:47:14.928728 | localhost | skipping: Conditional result was False 2026-04-13 18:47:14.929130 | 2026-04-13 18:47:14.933382 | localhost | skipping: Conditional result was False 2026-04-13 18:47:14.943486 | 2026-04-13 18:47:14.943657 | LOOP [upload-logs : Upload console log and json output]