2026-04-17 10:43:54.372984 | Job console starting 2026-04-17 10:43:54.387638 | Updating git repos 2026-04-17 10:43:54.424350 | Cloning repos into workspace 2026-04-17 10:43:58.209496 | Restoring repo states 2026-04-17 10:43:58.218065 | Merging changes 2026-04-17 10:43:59.125188 | Checking out repos 2026-04-17 10:43:59.863976 | Preparing playbooks 2026-04-17 10:44:07.319822 | Running Ansible setup 2026-04-17 10:44:12.763071 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-04-17 10:44:13.482937 | 2026-04-17 10:44:13.483130 | PLAY [localhost] 2026-04-17 10:44:13.503861 | 2026-04-17 10:44:13.504019 | TASK [Gathering Facts] 2026-04-17 10:44:14.471101 | localhost | ok 2026-04-17 10:44:14.480436 | 2026-04-17 10:44:14.480544 | TASK [Setup log path fact] 2026-04-17 10:44:14.500234 | localhost | ok 2026-04-17 10:44:14.512903 | 2026-04-17 10:44:14.513155 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-17 10:44:14.542888 | localhost | ok 2026-04-17 10:44:14.549127 | 2026-04-17 10:44:14.549203 | TASK [emit-job-header : Print job information] 2026-04-17 10:44:14.589813 | # Job Information 2026-04-17 10:44:14.590063 | Ansible Version: 2.16.16 2026-04-17 10:44:14.590136 | Job: ansible-collection-containers-molecule-containerd-rockylinux-9 2026-04-17 10:44:14.590187 | Pipeline: check 2026-04-17 10:44:14.590233 | Executor: 0a8996d2b663 2026-04-17 10:44:14.590277 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/114 2026-04-17 10:44:14.590327 | Event ID: 34b67640-3a4a-11f1-9787-0a435e8cfe42 2026-04-17 10:44:14.595475 | 2026-04-17 10:44:14.595547 | LOOP [emit-job-header : Print node information] 2026-04-17 10:44:14.711680 | localhost | ok: 2026-04-17 10:44:14.711961 | localhost | # Node Information 2026-04-17 10:44:14.711993 | localhost | Inventory Hostname: rockylinux-9 2026-04-17 10:44:14.712019 | localhost | Hostname: np0000167078 2026-04-17 10:44:14.712040 | localhost | Username: zuul 2026-04-17 10:44:14.712062 | localhost | Distro: Rocky 9.7 2026-04-17 10:44:14.712082 | localhost | Provider: yul1 2026-04-17 10:44:14.712102 | localhost | Region: ca-ymq-1 2026-04-17 10:44:14.712121 | localhost | Label: rockylinux-9 2026-04-17 10:44:14.712139 | localhost | Product Name: OpenStack Nova 2026-04-17 10:44:14.712159 | localhost | Interface IP: 199.19.213.85 2026-04-17 10:44:14.732705 | 2026-04-17 10:44:14.732894 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-04-17 10:44:15.123117 | localhost -> localhost | changed 2026-04-17 10:44:15.128300 | 2026-04-17 10:44:15.128378 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-04-17 10:44:16.017153 | localhost -> localhost | changed 2026-04-17 10:44:16.026984 | 2026-04-17 10:44:16.027106 | PLAY [all] 2026-04-17 10:44:16.037198 | 2026-04-17 10:44:16.037300 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-04-17 10:44:16.273905 | rockylinux-9 -> localhost | ok 2026-04-17 10:44:16.294575 | 2026-04-17 10:44:16.294801 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-04-17 10:44:16.327970 | rockylinux-9 | ok 2026-04-17 10:44:16.347322 | rockylinux-9 | included: /var/lib/zuul/builds/e527be52902b4378ac3833e937f5b8d3/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-04-17 10:44:16.352707 | 2026-04-17 10:44:16.352770 | TASK [add-build-sshkey : Create Temp SSH key] 2026-04-17 10:44:17.211366 | rockylinux-9 -> localhost | Generating public/private rsa key pair. 2026-04-17 10:44:17.212039 | rockylinux-9 -> localhost | Your identification has been saved in /var/lib/zuul/builds/e527be52902b4378ac3833e937f5b8d3/work/e527be52902b4378ac3833e937f5b8d3_id_rsa 2026-04-17 10:44:17.212092 | rockylinux-9 -> localhost | Your public key has been saved in /var/lib/zuul/builds/e527be52902b4378ac3833e937f5b8d3/work/e527be52902b4378ac3833e937f5b8d3_id_rsa.pub 2026-04-17 10:44:17.212119 | rockylinux-9 -> localhost | The key fingerprint is: 2026-04-17 10:44:17.212153 | rockylinux-9 -> localhost | SHA256:ZErLbSplZohbAIbsFhHT38ceXKkguSapzoTbCxDviU0 zuul-build-sshkey 2026-04-17 10:44:17.212188 | rockylinux-9 -> localhost | The key's randomart image is: 2026-04-17 10:44:17.212212 | rockylinux-9 -> localhost | +---[RSA 3072]----+ 2026-04-17 10:44:17.212243 | rockylinux-9 -> localhost | |+=+ . . | 2026-04-17 10:44:17.212267 | rockylinux-9 -> localhost | |o+.. o . o | 2026-04-17 10:44:17.212303 | rockylinux-9 -> localhost | |o o o = * o | 2026-04-17 10:44:17.212330 | rockylinux-9 -> localhost | | = = B B * | 2026-04-17 10:44:17.212356 | rockylinux-9 -> localhost | |+ E = O S . | 2026-04-17 10:44:17.212378 | rockylinux-9 -> localhost | |oB + = o . | 2026-04-17 10:44:17.212400 | rockylinux-9 -> localhost | |*o= . . | 2026-04-17 10:44:17.212421 | rockylinux-9 -> localhost | |.+. . | 2026-04-17 10:44:17.212446 | rockylinux-9 -> localhost | | .. | 2026-04-17 10:44:17.212469 | rockylinux-9 -> localhost | +----[SHA256]-----+ 2026-04-17 10:44:17.212535 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.479950 2026-04-17 10:44:17.218929 | 2026-04-17 10:44:17.219019 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-04-17 10:44:17.247546 | rockylinux-9 | ok 2026-04-17 10:44:17.255517 | rockylinux-9 | included: /var/lib/zuul/builds/e527be52902b4378ac3833e937f5b8d3/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-04-17 10:44:17.269716 | 2026-04-17 10:44:17.269785 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-04-17 10:44:17.293429 | rockylinux-9 | skipping: Conditional result was False 2026-04-17 10:44:17.299569 | 2026-04-17 10:44:17.299634 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-04-17 10:44:17.793103 | rockylinux-9 | changed 2026-04-17 10:44:17.797674 | 2026-04-17 10:44:17.797741 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-04-17 10:44:18.065078 | rockylinux-9 | ok 2026-04-17 10:44:18.070378 | 2026-04-17 10:44:18.078869 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-04-17 10:44:19.027092 | rockylinux-9 | changed 2026-04-17 10:44:19.035330 | 2026-04-17 10:44:19.035395 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-04-17 10:44:19.981428 | rockylinux-9 | changed 2026-04-17 10:44:19.986310 | 2026-04-17 10:44:19.986378 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-04-17 10:44:20.011601 | rockylinux-9 | skipping: Conditional result was False 2026-04-17 10:44:20.031201 | 2026-04-17 10:44:20.031298 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-04-17 10:44:20.442902 | rockylinux-9 -> localhost | changed 2026-04-17 10:44:20.453400 | 2026-04-17 10:44:20.453482 | TASK [add-build-sshkey : Add back temp key] 2026-04-17 10:44:20.786229 | rockylinux-9 -> localhost | Identity added: /var/lib/zuul/builds/e527be52902b4378ac3833e937f5b8d3/work/e527be52902b4378ac3833e937f5b8d3_id_rsa (zuul-build-sshkey) 2026-04-17 10:44:20.786498 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.015128 2026-04-17 10:44:20.801648 | 2026-04-17 10:44:20.801772 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-04-17 10:44:21.195426 | rockylinux-9 | ok 2026-04-17 10:44:21.204432 | 2026-04-17 10:44:21.204570 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-04-17 10:44:21.230527 | rockylinux-9 | skipping: Conditional result was False 2026-04-17 10:44:21.244525 | 2026-04-17 10:44:21.244615 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-04-17 10:44:21.645694 | rockylinux-9 | ok 2026-04-17 10:44:21.651641 | 2026-04-17 10:44:21.651711 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-04-17 10:44:22.578607 | rockylinux-9 | Output suppressed because no_log was given 2026-04-17 10:44:22.586597 | 2026-04-17 10:44:22.586664 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-04-17 10:44:22.850103 | rockylinux-9 | ok: "logs" 2026-04-17 10:44:22.850485 | rockylinux-9 | ok: All items complete 2026-04-17 10:44:22.850528 | 2026-04-17 10:44:23.089185 | rockylinux-9 | ok: "artifacts" 2026-04-17 10:44:23.340339 | rockylinux-9 | ok: "docs" 2026-04-17 10:44:23.356532 | 2026-04-17 10:44:23.356776 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-04-17 10:44:23.615631 | rockylinux-9 | changed: "logs" 2026-04-17 10:44:23.834095 | rockylinux-9 | changed: "artifacts" 2026-04-17 10:44:24.069899 | rockylinux-9 | changed: "docs" 2026-04-17 10:44:24.090489 | 2026-04-17 10:44:24.090633 | PLAY RECAP 2026-04-17 10:44:24.090710 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-17 10:44:24.090793 | rockylinux-9 | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-04-17 10:44:24.090837 | 2026-04-17 10:44:24.293684 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-04-17 10:44:24.295245 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-04-17 10:44:24.938108 | 2026-04-17 10:44:24.938217 | PLAY [all] 2026-04-17 10:44:24.950170 | 2026-04-17 10:44:24.950269 | TASK [setup-uv : Extract archive] 2026-04-17 10:44:27.798652 | rockylinux-9 | changed 2026-04-17 10:44:27.805109 | 2026-04-17 10:44:27.805189 | TASK [setup-uv : Print version] 2026-04-17 10:44:27.826836 | [rockylinux-9] Waiting on logger 2026-04-17 10:44:28.340546 | rockylinux-9 | ok: Runtime: 0:00:00.016185 2026-04-17 10:44:28.347754 | 2026-04-17 10:44:28.347813 | PLAY RECAP 2026-04-17 10:44:28.347858 | rockylinux-9 | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-17 10:44:28.347883 | 2026-04-17 10:44:28.444871 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-04-17 10:44:28.447028 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-04-17 10:44:29.015938 | 2026-04-17 10:44:29.016080 | PLAY [all] 2026-04-17 10:44:29.027102 | 2026-04-17 10:44:29.027186 | TASK [Copy inventory file for Zuul] 2026-04-17 10:44:30.405869 | rockylinux-9 | changed 2026-04-17 10:44:30.412532 | 2026-04-17 10:44:30.412641 | TASK [Switch "ansible_host" to private IP] 2026-04-17 10:44:30.769917 | rockylinux-9 | changed: 1 replacements made 2026-04-17 10:44:30.777823 | 2026-04-17 10:44:30.777896 | TASK [Run Molecule scenario] 2026-04-17 10:44:30.800546 | [rockylinux-9] Waiting on logger 2026-04-17 10:44:38.950026 | [rockylinux-9] Waiting on logger 2026-04-17 10:44:49.285856 | [rockylinux-9] Waiting on logger 2026-04-17 10:44:59.462329 | [rockylinux-9] Waiting on logger 2026-04-17 10:45:09.638012 | [rockylinux-9] Waiting on logger 2026-04-17 10:45:19.813850 | [rockylinux-9] Waiting on logger 2026-04-17 10:45:29.537537 | [rockylinux-9] Waiting on logger 2026-04-17 10:45:38.859008 | [rockylinux-9] Waiting on logger 2026-04-17 10:45:49.030258 | [rockylinux-9] Waiting on logger 2026-04-17 10:45:59.786115 | [rockylinux-9] Waiting on logger 2026-04-17 10:46:08.939017 | [rockylinux-9] Waiting on logger 2026-04-17 10:46:19.525980 | [rockylinux-9] Waiting on logger 2026-04-17 10:46:29.702218 | [rockylinux-9] Waiting on logger 2026-04-17 10:46:38.859637 | [rockylinux-9] Waiting on logger 2026-04-17 10:46:49.030704 | [rockylinux-9] Waiting on logger 2026-04-17 10:46:59.206232 | [rockylinux-9] Waiting on logger 2026-04-17 10:47:09.382140 | [rockylinux-9] Waiting on logger 2026-04-17 10:47:19.557859 | [rockylinux-9] Waiting on logger 2026-04-17 10:47:29.765968 | [rockylinux-9] Waiting on logger 2026-04-17 10:47:38.954140 | [rockylinux-9] Waiting on logger 2026-04-17 10:47:49.126049 | [rockylinux-9] Waiting on logger 2026-04-17 10:47:59.302155 | [rockylinux-9] Waiting on logger 2026-04-17 10:48:09.478330 | [rockylinux-9] Waiting on logger 2026-04-17 10:48:19.654150 | [rockylinux-9] Waiting on logger 2026-04-17 10:48:29.829875 | [rockylinux-9] Waiting on logger 2026-04-17 10:48:38.982038 | [rockylinux-9] Waiting on logger 2026-04-17 10:48:49.158303 | [rockylinux-9] Waiting on logger 2026-04-17 10:48:59.334049 | [rockylinux-9] Waiting on logger 2026-04-17 10:49:03.822090 | rockylinux-9 | ok: Runtime: 0:04:31.909312 2026-04-17 10:49:03.831222 | 2026-04-17 10:49:03.831270 | PLAY RECAP 2026-04-17 10:49:03.831311 | rockylinux-9 | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-17 10:49:03.831333 | 2026-04-17 10:49:03.924925 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-04-17 10:49:03.927102 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-04-17 10:49:04.464900 | 2026-04-17 10:49:04.465103 | PLAY [all] 2026-04-17 10:49:04.475533 | 2026-04-17 10:49:04.475609 | TASK [fetch-output : Set log path for multiple nodes] 2026-04-17 10:49:04.520255 | rockylinux-9 | skipping: Conditional result was False 2026-04-17 10:49:04.529514 | 2026-04-17 10:49:04.529619 | TASK [fetch-output : Set log path for single node] 2026-04-17 10:49:04.573747 | rockylinux-9 | ok 2026-04-17 10:49:04.582824 | 2026-04-17 10:49:04.582894 | LOOP [fetch-output : Ensure local output dirs] 2026-04-17 10:49:04.984291 | rockylinux-9 -> localhost | ok: "/var/lib/zuul/builds/e527be52902b4378ac3833e937f5b8d3/work/logs" 2026-04-17 10:49:05.188798 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/e527be52902b4378ac3833e937f5b8d3/work/artifacts" 2026-04-17 10:49:05.388999 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/e527be52902b4378ac3833e937f5b8d3/work/docs" 2026-04-17 10:49:05.403002 | 2026-04-17 10:49:05.403117 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-04-17 10:49:06.079023 | rockylinux-9 | changed: .d..t...... ./ 2026-04-17 10:49:06.079319 | rockylinux-9 | changed: All items complete 2026-04-17 10:49:06.079378 | 2026-04-17 10:49:06.604941 | rockylinux-9 | changed: .d..t...... ./ 2026-04-17 10:49:07.115819 | rockylinux-9 | changed: .d..t...... ./ 2026-04-17 10:49:07.142378 | 2026-04-17 10:49:07.142516 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-04-17 10:49:07.528454 | rockylinux-9 -> localhost | ok: Item: artifacts Runtime: 0:00:00.007226 2026-04-17 10:49:07.743061 | rockylinux-9 -> localhost | ok: Item: docs Runtime: 0:00:00.007434 2026-04-17 10:49:07.763877 | 2026-04-17 10:49:07.763992 | PLAY [all] 2026-04-17 10:49:07.769313 | 2026-04-17 10:49:07.769385 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-04-17 10:49:08.214924 | rockylinux-9 | changed 2026-04-17 10:49:08.223150 | 2026-04-17 10:49:08.223256 | PLAY RECAP 2026-04-17 10:49:08.223314 | rockylinux-9 | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-04-17 10:49:08.223338 | 2026-04-17 10:49:08.329939 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-04-17 10:49:08.332037 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-04-17 10:49:08.860874 | 2026-04-17 10:49:08.861000 | PLAY [localhost] 2026-04-17 10:49:08.870282 | 2026-04-17 10:49:08.870353 | TASK [Generate Zuul manifest] 2026-04-17 10:49:08.889465 | localhost | ok 2026-04-17 10:49:08.908152 | 2026-04-17 10:49:08.908227 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-04-17 10:49:09.212512 | localhost | changed 2026-04-17 10:49:09.224480 | 2026-04-17 10:49:09.224550 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-04-17 10:49:09.255328 | localhost | ok 2026-04-17 10:49:09.263884 | 2026-04-17 10:49:09.263968 | TASK [Upload logs] 2026-04-17 10:49:09.284759 | localhost | ok 2026-04-17 10:49:09.340566 | 2026-04-17 10:49:09.340672 | TASK [Set zuul-log-path fact] 2026-04-17 10:49:09.362407 | localhost | ok 2026-04-17 10:49:09.373702 | 2026-04-17 10:49:09.373819 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-17 10:49:09.404709 | localhost | ok 2026-04-17 10:49:09.414648 | 2026-04-17 10:49:09.414736 | TASK [upload-logs : Create log directories] 2026-04-17 10:49:09.791610 | localhost | changed 2026-04-17 10:49:09.798176 | 2026-04-17 10:49:09.798241 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-04-17 10:49:10.163766 | localhost -> localhost | ok: Runtime: 0:00:00.004804 2026-04-17 10:49:10.170674 | 2026-04-17 10:49:10.170737 | TASK [upload-logs : Upload logs to log server] 2026-04-17 10:49:10.607453 | localhost | Output suppressed because no_log was given 2026-04-17 10:49:10.613036 | 2026-04-17 10:49:10.613104 | LOOP [upload-logs : Compress console log and json output] 2026-04-17 10:49:10.657889 | localhost | skipping: Conditional result was False 2026-04-17 10:49:10.664683 | localhost | skipping: Conditional result was False 2026-04-17 10:49:10.677519 | 2026-04-17 10:49:10.677695 | LOOP [upload-logs : Upload compressed console log and json output] 2026-04-17 10:49:10.718119 | localhost | skipping: Conditional result was False 2026-04-17 10:49:10.718481 | 2026-04-17 10:49:10.722262 | localhost | skipping: Conditional result was False 2026-04-17 10:49:10.731932 | 2026-04-17 10:49:10.732098 | LOOP [upload-logs : Upload console log and json output]