2026-05-21 22:44:21.219373 | Job console starting 2026-05-21 22:44:21.231224 | Updating git repos 2026-05-21 22:44:21.269307 | Cloning repos into workspace 2026-05-21 22:44:21.518658 | Restoring repo states 2026-05-21 22:44:21.529958 | Merging changes 2026-05-21 22:44:22.015503 | Checking out repos 2026-05-21 22:44:22.051487 | Preparing playbooks 2026-05-21 22:44:24.350333 | Running Ansible setup 2026-05-21 22:44:28.614136 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-05-21 22:44:29.220292 | 2026-05-21 22:44:29.353009 | PLAY [localhost] 2026-05-21 22:44:29.382925 | 2026-05-21 22:44:29.383052 | TASK [Gathering Facts] 2026-05-21 22:44:30.346971 | localhost | ok 2026-05-21 22:44:30.354367 | 2026-05-21 22:44:30.354445 | TASK [Setup log path fact] 2026-05-21 22:44:30.374310 | localhost | ok 2026-05-21 22:44:30.385083 | 2026-05-21 22:44:30.385162 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-21 22:44:30.413047 | localhost | ok 2026-05-21 22:44:30.419653 | 2026-05-21 22:44:30.419735 | TASK [emit-job-header : Print job information] 2026-05-21 22:44:30.460548 | # Job Information 2026-05-21 22:44:30.460768 | Ansible Version: 2.16.18 2026-05-21 22:44:30.460942 | Job: ansible-collection-containers-molecule-nerdctl-rockylinux-9 2026-05-21 22:44:30.461021 | Pipeline: check 2026-05-21 22:44:30.461055 | Executor: 2d72f0692154 2026-05-21 22:44:30.461086 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/130 2026-05-21 22:44:30.461120 | Event ID: 7e688440-5566-11f1-87a2-04a2a7b79ebe 2026-05-21 22:44:30.466476 | 2026-05-21 22:44:30.466573 | LOOP [emit-job-header : Print node information] 2026-05-21 22:44:30.560928 | localhost | ok: 2026-05-21 22:44:30.561146 | localhost | # Node Information 2026-05-21 22:44:30.561191 | localhost | Inventory Hostname: rockylinux-9 2026-05-21 22:44:30.561224 | localhost | Hostname: np0000176027 2026-05-21 22:44:30.561254 | localhost | Username: zuul 2026-05-21 22:44:30.561287 | localhost | Distro: Rocky 9.7 2026-05-21 22:44:30.561317 | localhost | Provider: yul1 2026-05-21 22:44:30.561345 | localhost | Region: ca-ymq-1 2026-05-21 22:44:30.561373 | localhost | Label: rockylinux-9 2026-05-21 22:44:30.561401 | localhost | Product Name: OpenStack Nova 2026-05-21 22:44:30.561429 | localhost | Interface IP: 199.204.45.38 2026-05-21 22:44:30.571743 | 2026-05-21 22:44:30.571985 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-05-21 22:44:31.253138 | localhost -> localhost | changed 2026-05-21 22:44:31.264661 | 2026-05-21 22:44:31.264790 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-05-21 22:44:32.393269 | localhost -> localhost | changed 2026-05-21 22:44:32.400023 | 2026-05-21 22:44:32.400125 | PLAY [all] 2026-05-21 22:44:32.409284 | 2026-05-21 22:44:32.409350 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-05-21 22:44:32.649021 | rockylinux-9 -> localhost | ok 2026-05-21 22:44:32.656076 | 2026-05-21 22:44:32.656141 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-05-21 22:44:32.690038 | rockylinux-9 | ok 2026-05-21 22:44:32.703888 | rockylinux-9 | included: /var/lib/zuul/builds/951ff84231114ef08a3ab98d4277d1c2/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-05-21 22:44:32.710048 | 2026-05-21 22:44:32.710109 | TASK [add-build-sshkey : Create Temp SSH key] 2026-05-21 22:44:33.653111 | rockylinux-9 -> localhost | Generating public/private rsa key pair. 2026-05-21 22:44:33.653314 | rockylinux-9 -> localhost | Your identification has been saved in /var/lib/zuul/builds/951ff84231114ef08a3ab98d4277d1c2/work/951ff84231114ef08a3ab98d4277d1c2_id_rsa 2026-05-21 22:44:33.653345 | rockylinux-9 -> localhost | Your public key has been saved in /var/lib/zuul/builds/951ff84231114ef08a3ab98d4277d1c2/work/951ff84231114ef08a3ab98d4277d1c2_id_rsa.pub 2026-05-21 22:44:33.653369 | rockylinux-9 -> localhost | The key fingerprint is: 2026-05-21 22:44:33.653390 | rockylinux-9 -> localhost | SHA256:vwq1bXg4puQ6X623KFjnn12zMqUswTRSj5PpnlthEmY zuul-build-sshkey 2026-05-21 22:44:33.653421 | rockylinux-9 -> localhost | The key's randomart image is: 2026-05-21 22:44:33.653442 | rockylinux-9 -> localhost | +---[RSA 3072]----+ 2026-05-21 22:44:33.653467 | rockylinux-9 -> localhost | | | 2026-05-21 22:44:33.653489 | rockylinux-9 -> localhost | | . | 2026-05-21 22:44:33.653509 | rockylinux-9 -> localhost | | .E= | 2026-05-21 22:44:33.653529 | rockylinux-9 -> localhost | | .oB.. | 2026-05-21 22:44:33.653549 | rockylinux-9 -> localhost | | S=.oo | 2026-05-21 22:44:33.653569 | rockylinux-9 -> localhost | | ...B+o .. | 2026-05-21 22:44:33.653588 | rockylinux-9 -> localhost | | oooB.*+.oo | 2026-05-21 22:44:33.653608 | rockylinux-9 -> localhost | | oo.=.*===. o | 2026-05-21 22:44:33.653631 | rockylinux-9 -> localhost | | .++.++*+.o. | 2026-05-21 22:44:33.653651 | rockylinux-9 -> localhost | +----[SHA256]-----+ 2026-05-21 22:44:33.653743 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.462267 2026-05-21 22:44:33.658441 | 2026-05-21 22:44:33.658506 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-05-21 22:44:33.687370 | rockylinux-9 | ok 2026-05-21 22:44:33.695582 | rockylinux-9 | included: /var/lib/zuul/builds/951ff84231114ef08a3ab98d4277d1c2/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-05-21 22:44:33.702990 | 2026-05-21 22:44:33.703051 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-05-21 22:44:33.727552 | rockylinux-9 | skipping: Conditional result was False 2026-05-21 22:44:33.733857 | 2026-05-21 22:44:33.733926 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-05-21 22:44:34.231319 | rockylinux-9 | changed 2026-05-21 22:44:34.236085 | 2026-05-21 22:44:34.236151 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-05-21 22:44:34.500093 | rockylinux-9 | ok 2026-05-21 22:44:34.506519 | 2026-05-21 22:44:34.506607 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-05-21 22:44:35.432284 | rockylinux-9 | changed 2026-05-21 22:44:35.440814 | 2026-05-21 22:44:35.440911 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-05-21 22:44:36.395510 | rockylinux-9 | changed 2026-05-21 22:44:36.565142 | 2026-05-21 22:44:36.565255 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-05-21 22:44:36.592590 | rockylinux-9 | skipping: Conditional result was False 2026-05-21 22:44:36.598361 | 2026-05-21 22:44:36.598430 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-05-21 22:44:37.010426 | rockylinux-9 -> localhost | changed 2026-05-21 22:44:37.032145 | 2026-05-21 22:44:37.032242 | TASK [add-build-sshkey : Add back temp key] 2026-05-21 22:44:37.379184 | rockylinux-9 -> localhost | Identity added: /var/lib/zuul/builds/951ff84231114ef08a3ab98d4277d1c2/work/951ff84231114ef08a3ab98d4277d1c2_id_rsa (zuul-build-sshkey) 2026-05-21 22:44:37.379391 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.015295 2026-05-21 22:44:37.384688 | 2026-05-21 22:44:37.384821 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-05-21 22:44:37.815636 | rockylinux-9 | ok 2026-05-21 22:44:37.825111 | 2026-05-21 22:44:37.825311 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-05-21 22:44:37.851324 | rockylinux-9 | skipping: Conditional result was False 2026-05-21 22:44:37.865664 | 2026-05-21 22:44:37.865783 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-05-21 22:44:38.295811 | rockylinux-9 | ok 2026-05-21 22:44:38.305393 | 2026-05-21 22:44:38.305489 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-05-21 22:44:39.303591 | rockylinux-9 | Output suppressed because no_log was given 2026-05-21 22:44:39.488200 | 2026-05-21 22:44:39.488338 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-05-21 22:44:39.761594 | rockylinux-9 | ok: "logs" 2026-05-21 22:44:39.761919 | rockylinux-9 | ok: All items complete 2026-05-21 22:44:39.761962 | 2026-05-21 22:44:39.985737 | rockylinux-9 | ok: "artifacts" 2026-05-21 22:44:40.208447 | rockylinux-9 | ok: "docs" 2026-05-21 22:44:40.223193 | 2026-05-21 22:44:40.223344 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-05-21 22:44:40.487945 | rockylinux-9 | changed: "logs" 2026-05-21 22:44:40.774457 | rockylinux-9 | changed: "artifacts" 2026-05-21 22:44:40.974350 | rockylinux-9 | changed: "docs" 2026-05-21 22:44:40.997133 | 2026-05-21 22:44:40.997271 | PLAY RECAP 2026-05-21 22:44:40.997336 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-05-21 22:44:40.997380 | rockylinux-9 | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-05-21 22:44:40.997409 | 2026-05-21 22:44:41.149761 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-05-21 22:44:41.264334 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-05-21 22:44:41.909947 | 2026-05-21 22:44:41.910073 | PLAY [all] 2026-05-21 22:44:41.920578 | 2026-05-21 22:44:41.920656 | TASK [setup-uv : Extract archive] 2026-05-21 22:44:44.854136 | rockylinux-9 | changed 2026-05-21 22:44:44.871719 | 2026-05-21 22:44:44.871915 | TASK [setup-uv : Print version] 2026-05-21 22:44:44.903129 | [rockylinux-9] Waiting on logger 2026-05-21 22:44:45.418721 | rockylinux-9 | ok: Runtime: 0:00:00.012966 2026-05-21 22:44:45.436884 | 2026-05-21 22:44:45.436999 | TASK [Install Ansible collection siblings] 2026-05-21 22:44:45.954507 | rockylinux-9 | ok: 2026-05-21 22:44:45.954870 | rockylinux-9 | Skipping ansible.posix: no checked-out sibling 2026-05-21 22:44:45.954941 | rockylinux-9 | Skipping community.general: no checked-out sibling 2026-05-21 22:44:45.957551 | 2026-05-21 22:44:45.957603 | PLAY RECAP 2026-05-21 22:44:45.957650 | rockylinux-9 | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-05-21 22:44:45.957672 | 2026-05-21 22:44:46.149301 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-05-21 22:44:46.598139 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-05-21 22:44:47.215700 | 2026-05-21 22:44:47.215902 | PLAY [all] 2026-05-21 22:44:47.229330 | 2026-05-21 22:44:47.229475 | TASK [Copy inventory file for Zuul] 2026-05-21 22:44:48.607416 | rockylinux-9 | changed 2026-05-21 22:44:48.612303 | 2026-05-21 22:44:48.612383 | TASK [Switch "ansible_host" to private IP] 2026-05-21 22:44:49.010731 | rockylinux-9 | changed: 1 replacements made 2026-05-21 22:44:49.017880 | 2026-05-21 22:44:49.017980 | TASK [Run Molecule scenario] 2026-05-21 22:44:49.039149 | [rockylinux-9] Waiting on logger 2026-05-21 22:44:56.166195 | [rockylinux-9] Waiting on logger 2026-05-21 22:45:06.342072 | [rockylinux-9] Waiting on logger 2026-05-21 22:45:16.522195 | [rockylinux-9] Waiting on logger 2026-05-21 22:45:26.698179 | [rockylinux-9] Waiting on logger 2026-05-21 22:45:36.870161 | [rockylinux-9] Waiting on logger 2026-05-21 22:45:46.022191 | [rockylinux-9] Waiting on logger 2026-05-21 22:45:56.198485 | [rockylinux-9] Waiting on logger 2026-05-21 22:46:06.373891 | [rockylinux-9] Waiting on logger 2026-05-21 22:46:16.549975 | [rockylinux-9] Waiting on logger 2026-05-21 22:46:26.757979 | [rockylinux-9] Waiting on logger 2026-05-21 22:46:36.011684 | [rockylinux-9] Waiting on logger 2026-05-21 22:46:46.182236 | [rockylinux-9] Waiting on logger 2026-05-21 22:46:52.910599 | rockylinux-9 | ok: Runtime: 0:02:03.124048 2026-05-21 22:46:52.916146 | 2026-05-21 22:46:52.916199 | PLAY RECAP 2026-05-21 22:46:52.916244 | rockylinux-9 | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-05-21 22:46:52.916268 | 2026-05-21 22:46:53.056390 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-05-21 22:46:53.057974 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-05-21 22:46:53.644239 | 2026-05-21 22:46:53.644372 | PLAY [all] 2026-05-21 22:46:53.654932 | 2026-05-21 22:46:53.655013 | TASK [fetch-output : Set log path for multiple nodes] 2026-05-21 22:46:53.699604 | rockylinux-9 | skipping: Conditional result was False 2026-05-21 22:46:53.704705 | 2026-05-21 22:46:53.704784 | TASK [fetch-output : Set log path for single node] 2026-05-21 22:46:53.748584 | rockylinux-9 | ok 2026-05-21 22:46:53.754412 | 2026-05-21 22:46:53.754508 | LOOP [fetch-output : Ensure local output dirs] 2026-05-21 22:46:54.157400 | rockylinux-9 -> localhost | ok: "/var/lib/zuul/builds/951ff84231114ef08a3ab98d4277d1c2/work/logs" 2026-05-21 22:46:54.363434 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/951ff84231114ef08a3ab98d4277d1c2/work/artifacts" 2026-05-21 22:46:54.572565 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/951ff84231114ef08a3ab98d4277d1c2/work/docs" 2026-05-21 22:46:54.592039 | 2026-05-21 22:46:54.592197 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-05-21 22:46:55.319180 | rockylinux-9 | changed: .d..t...... ./ 2026-05-21 22:46:55.319388 | rockylinux-9 | changed: All items complete 2026-05-21 22:46:55.319415 | 2026-05-21 22:46:55.854556 | rockylinux-9 | changed: .d..t...... ./ 2026-05-21 22:46:56.341081 | rockylinux-9 | changed: .d..t...... ./ 2026-05-21 22:46:56.361170 | 2026-05-21 22:46:56.362011 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-05-21 22:46:56.821201 | rockylinux-9 -> localhost | ok: Item: artifacts Runtime: 0:00:00.011682 2026-05-21 22:46:57.048672 | rockylinux-9 -> localhost | ok: Item: docs Runtime: 0:00:00.007435 2026-05-21 22:46:57.065372 | 2026-05-21 22:46:57.065460 | PLAY [all] 2026-05-21 22:46:57.070950 | 2026-05-21 22:46:57.071011 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-05-21 22:46:57.563703 | rockylinux-9 | changed 2026-05-21 22:46:57.575552 | 2026-05-21 22:46:57.575604 | PLAY RECAP 2026-05-21 22:46:57.575650 | rockylinux-9 | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-05-21 22:46:57.575672 | 2026-05-21 22:46:57.707100 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-05-21 22:46:57.709422 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-05-21 22:46:58.279909 | 2026-05-21 22:46:58.280036 | PLAY [localhost] 2026-05-21 22:46:58.290765 | 2026-05-21 22:46:58.290873 | TASK [Generate Zuul manifest] 2026-05-21 22:46:58.310176 | localhost | ok 2026-05-21 22:46:58.329860 | 2026-05-21 22:46:58.329937 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-05-21 22:46:58.705607 | localhost | changed 2026-05-21 22:46:58.719680 | 2026-05-21 22:46:58.719760 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-05-21 22:46:58.753681 | localhost | ok 2026-05-21 22:46:58.762627 | 2026-05-21 22:46:58.762713 | TASK [Upload logs] 2026-05-21 22:46:58.784301 | localhost | ok 2026-05-21 22:46:58.845634 | 2026-05-21 22:46:58.845779 | TASK [Set zuul-log-path fact] 2026-05-21 22:46:58.864694 | localhost | ok 2026-05-21 22:46:58.875364 | 2026-05-21 22:46:58.875429 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-21 22:46:58.903069 | localhost | ok 2026-05-21 22:46:58.910100 | 2026-05-21 22:46:58.910167 | TASK [upload-logs : Create log directories] 2026-05-21 22:46:59.326056 | localhost | changed 2026-05-21 22:46:59.333919 | 2026-05-21 22:46:59.334010 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-05-21 22:46:59.742267 | localhost -> localhost | ok: Runtime: 0:00:00.004920 2026-05-21 22:46:59.750555 | 2026-05-21 22:46:59.750672 | TASK [upload-logs : Upload logs to log server] 2026-05-21 22:47:00.205400 | localhost | Output suppressed because no_log was given 2026-05-21 22:47:00.209274 | 2026-05-21 22:47:00.209350 | LOOP [upload-logs : Compress console log and json output] 2026-05-21 22:47:00.250715 | localhost | skipping: Conditional result was False 2026-05-21 22:47:00.257673 | localhost | skipping: Conditional result was False 2026-05-21 22:47:00.273689 | 2026-05-21 22:47:00.273902 | LOOP [upload-logs : Upload compressed console log and json output] 2026-05-21 22:47:00.325074 | localhost | skipping: Conditional result was False 2026-05-21 22:47:00.325449 | 2026-05-21 22:47:00.329357 | localhost | skipping: Conditional result was False 2026-05-21 22:47:00.340663 | 2026-05-21 22:47:00.340874 | LOOP [upload-logs : Upload console log and json output]