2026-04-23 04:38:10.896885 | Job console starting 2026-04-23 04:38:10.912307 | Updating git repos 2026-04-23 04:38:10.930800 | Cloning repos into workspace 2026-04-23 04:38:11.466559 | Restoring repo states 2026-04-23 04:38:11.477740 | Merging changes 2026-04-23 04:38:12.084256 | Checking out repos 2026-04-23 04:38:12.274522 | Preparing playbooks 2026-04-23 04:38:17.022279 | Running Ansible setup 2026-04-23 04:38:21.803846 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-04-23 04:38:22.513431 | 2026-04-23 04:38:22.513554 | PLAY [localhost] 2026-04-23 04:38:22.521088 | 2026-04-23 04:38:22.521170 | TASK [Gathering Facts] 2026-04-23 04:38:23.850354 | localhost | ok 2026-04-23 04:38:23.857950 | 2026-04-23 04:38:23.858057 | TASK [Setup log path fact] 2026-04-23 04:38:23.895118 | localhost | ok 2026-04-23 04:38:23.906395 | 2026-04-23 04:38:23.906511 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-23 04:38:23.965824 | localhost | ok 2026-04-23 04:38:23.978970 | 2026-04-23 04:38:23.979120 | TASK [emit-job-header : Print job information] 2026-04-23 04:38:24.037987 | # Job Information 2026-04-23 04:38:24.038193 | Ansible Version: 2.16.16 2026-04-23 04:38:24.038225 | Job: ansible-collection-containers-molecule-nerdctl-rockylinux-9 2026-04-23 04:38:24.038247 | Pipeline: check 2026-04-23 04:38:24.038268 | Executor: 0a8996d2b663 2026-04-23 04:38:24.038288 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/118 2026-04-23 04:38:24.038310 | Event ID: 138f9cc0-3ece-11f1-96c6-0144615fd1ac 2026-04-23 04:38:24.042014 | 2026-04-23 04:38:24.042091 | LOOP [emit-job-header : Print node information] 2026-04-23 04:38:24.173236 | localhost | ok: 2026-04-23 04:38:24.174326 | localhost | # Node Information 2026-04-23 04:38:24.174393 | localhost | Inventory Hostname: rockylinux-9 2026-04-23 04:38:24.174430 | localhost | Hostname: np0000169688 2026-04-23 04:38:24.174462 | localhost | Username: zuul 2026-04-23 04:38:24.174497 | localhost | Distro: Rocky 9.7 2026-04-23 04:38:24.174528 | localhost | Provider: yul1 2026-04-23 04:38:24.174559 | localhost | Region: ca-ymq-1 2026-04-23 04:38:24.174588 | localhost | Label: rockylinux-9 2026-04-23 04:38:24.174618 | localhost | Product Name: OpenStack Nova 2026-04-23 04:38:24.174648 | localhost | Interface IP: 199.204.45.250 2026-04-23 04:38:24.210027 | 2026-04-23 04:38:24.210292 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-04-23 04:38:24.962041 | localhost -> localhost | changed 2026-04-23 04:38:24.971258 | 2026-04-23 04:38:24.971740 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-04-23 04:38:26.063528 | localhost -> localhost | changed 2026-04-23 04:38:26.069332 | 2026-04-23 04:38:26.069440 | PLAY [all] 2026-04-23 04:38:26.081201 | 2026-04-23 04:38:26.081374 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-04-23 04:38:26.405280 | rockylinux-9 -> localhost | ok 2026-04-23 04:38:26.410633 | 2026-04-23 04:38:26.410714 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-04-23 04:38:26.429374 | rockylinux-9 | ok 2026-04-23 04:38:26.461172 | rockylinux-9 | included: /var/lib/zuul/builds/ea8d5c20136d401990f6853d75256d13/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-04-23 04:38:26.470617 | 2026-04-23 04:38:26.470728 | TASK [add-build-sshkey : Create Temp SSH key] 2026-04-23 04:38:27.865017 | rockylinux-9 -> localhost | Generating public/private rsa key pair. 2026-04-23 04:38:27.865174 | rockylinux-9 -> localhost | Your identification has been saved in /var/lib/zuul/builds/ea8d5c20136d401990f6853d75256d13/work/ea8d5c20136d401990f6853d75256d13_id_rsa 2026-04-23 04:38:27.865205 | rockylinux-9 -> localhost | Your public key has been saved in /var/lib/zuul/builds/ea8d5c20136d401990f6853d75256d13/work/ea8d5c20136d401990f6853d75256d13_id_rsa.pub 2026-04-23 04:38:27.865229 | rockylinux-9 -> localhost | The key fingerprint is: 2026-04-23 04:38:27.865251 | rockylinux-9 -> localhost | SHA256:83WuQQMTp14SYAIoqZYv8WEHDWAzynz5GCiGemEYcCk zuul-build-sshkey 2026-04-23 04:38:27.865284 | rockylinux-9 -> localhost | The key's randomart image is: 2026-04-23 04:38:27.865305 | rockylinux-9 -> localhost | +---[RSA 3072]----+ 2026-04-23 04:38:27.865331 | rockylinux-9 -> localhost | |+*o++.. o.o . | 2026-04-23 04:38:27.865353 | rockylinux-9 -> localhost | |E+*... o = | 2026-04-23 04:38:27.865373 | rockylinux-9 -> localhost | |*B++. = . | 2026-04-23 04:38:27.865393 | rockylinux-9 -> localhost | |==.++. . = | 2026-04-23 04:38:27.865412 | rockylinux-9 -> localhost | |o *.o. S . + . | 2026-04-23 04:38:27.865432 | rockylinux-9 -> localhost | | o o o o + | 2026-04-23 04:38:27.865451 | rockylinux-9 -> localhost | | . . . . | 2026-04-23 04:38:27.865470 | rockylinux-9 -> localhost | | o | 2026-04-23 04:38:27.865492 | rockylinux-9 -> localhost | | . | 2026-04-23 04:38:27.865512 | rockylinux-9 -> localhost | +----[SHA256]-----+ 2026-04-23 04:38:27.865562 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.753130 2026-04-23 04:38:27.870256 | 2026-04-23 04:38:27.870330 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-04-23 04:38:27.904194 | rockylinux-9 | ok 2026-04-23 04:38:27.917744 | rockylinux-9 | included: /var/lib/zuul/builds/ea8d5c20136d401990f6853d75256d13/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-04-23 04:38:27.929445 | 2026-04-23 04:38:27.929576 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-04-23 04:38:27.955315 | rockylinux-9 | skipping: Conditional result was False 2026-04-23 04:38:27.962756 | 2026-04-23 04:38:27.962843 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-04-23 04:38:28.450381 | rockylinux-9 | changed 2026-04-23 04:38:28.455411 | 2026-04-23 04:38:28.455534 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-04-23 04:38:28.720869 | rockylinux-9 | ok 2026-04-23 04:38:28.730348 | 2026-04-23 04:38:28.730524 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-04-23 04:38:29.717165 | rockylinux-9 | changed 2026-04-23 04:38:29.722856 | 2026-04-23 04:38:29.722930 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-04-23 04:38:30.701733 | rockylinux-9 | changed 2026-04-23 04:38:30.714442 | 2026-04-23 04:38:30.714733 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-04-23 04:38:30.731737 | rockylinux-9 | skipping: Conditional result was False 2026-04-23 04:38:30.741103 | 2026-04-23 04:38:30.741325 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-04-23 04:38:31.681910 | rockylinux-9 -> localhost | changed 2026-04-23 04:38:31.704354 | 2026-04-23 04:38:31.704477 | TASK [add-build-sshkey : Add back temp key] 2026-04-23 04:38:32.048869 | rockylinux-9 -> localhost | Identity added: /var/lib/zuul/builds/ea8d5c20136d401990f6853d75256d13/work/ea8d5c20136d401990f6853d75256d13_id_rsa (zuul-build-sshkey) 2026-04-23 04:38:32.049157 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.019285 2026-04-23 04:38:32.062823 | 2026-04-23 04:38:32.062912 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-04-23 04:38:32.467085 | rockylinux-9 | ok 2026-04-23 04:38:32.498315 | 2026-04-23 04:38:32.498501 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-04-23 04:38:32.524610 | rockylinux-9 | skipping: Conditional result was False 2026-04-23 04:38:32.538723 | 2026-04-23 04:38:32.538863 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-04-23 04:38:32.978151 | rockylinux-9 | ok 2026-04-23 04:38:32.984487 | 2026-04-23 04:38:32.984588 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-04-23 04:38:33.947222 | rockylinux-9 | Output suppressed because no_log was given 2026-04-23 04:38:33.955333 | 2026-04-23 04:38:33.955414 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-04-23 04:38:34.220385 | rockylinux-9 | ok: "logs" 2026-04-23 04:38:34.220777 | rockylinux-9 | ok: All items complete 2026-04-23 04:38:34.220815 | 2026-04-23 04:38:34.446303 | rockylinux-9 | ok: "artifacts" 2026-04-23 04:38:34.771042 | rockylinux-9 | ok: "docs" 2026-04-23 04:38:34.787246 | 2026-04-23 04:38:34.787714 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-04-23 04:38:35.065793 | rockylinux-9 | changed: "logs" 2026-04-23 04:38:35.283149 | rockylinux-9 | changed: "artifacts" 2026-04-23 04:38:35.530386 | rockylinux-9 | changed: "docs" 2026-04-23 04:38:35.545489 | 2026-04-23 04:38:35.545578 | PLAY RECAP 2026-04-23 04:38:35.545622 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-23 04:38:35.545728 | rockylinux-9 | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-04-23 04:38:35.545754 | 2026-04-23 04:38:35.713021 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-04-23 04:38:35.715173 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-04-23 04:38:36.367622 | 2026-04-23 04:38:36.367748 | PLAY [all] 2026-04-23 04:38:36.398229 | 2026-04-23 04:38:36.398394 | TASK [setup-uv : Extract archive] 2026-04-23 04:38:39.174085 | rockylinux-9 | changed 2026-04-23 04:38:39.183227 | 2026-04-23 04:38:39.183364 | TASK [setup-uv : Print version] 2026-04-23 04:38:39.217956 | [rockylinux-9] Waiting on logger 2026-04-23 04:38:40.846381 | rockylinux-9 | ok: Runtime: 0:00:00.015346 2026-04-23 04:38:40.854927 | 2026-04-23 04:38:40.854981 | PLAY RECAP 2026-04-23 04:38:40.855028 | rockylinux-9 | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-23 04:38:40.855053 | 2026-04-23 04:38:40.987911 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-04-23 04:38:40.988903 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-04-23 04:38:41.630326 | 2026-04-23 04:38:41.630452 | PLAY [all] 2026-04-23 04:38:41.645117 | 2026-04-23 04:38:41.645287 | TASK [Copy inventory file for Zuul] 2026-04-23 04:38:42.948654 | rockylinux-9 | changed 2026-04-23 04:38:42.953340 | 2026-04-23 04:38:42.953415 | TASK [Switch "ansible_host" to private IP] 2026-04-23 04:38:43.281593 | rockylinux-9 | changed: 1 replacements made 2026-04-23 04:38:43.288512 | 2026-04-23 04:38:43.288611 | TASK [Run Molecule scenario] 2026-04-23 04:38:43.312748 | [rockylinux-9] Waiting on logger 2026-04-23 04:38:51.465702 | [rockylinux-9] Waiting on logger 2026-04-23 04:39:01.638331 | [rockylinux-9] Waiting on logger 2026-04-23 04:39:11.814009 | [rockylinux-9] Waiting on logger 2026-04-23 04:39:22.022277 | [rockylinux-9] Waiting on logger 2026-04-23 04:39:32.198082 | [rockylinux-9] Waiting on logger 2026-04-23 04:40:38.112312 | rockylinux-9 | ok: Runtime: 0:01:54.377071 2026-04-23 04:40:38.117308 | 2026-04-23 04:40:38.117376 | PLAY RECAP 2026-04-23 04:40:38.117423 | rockylinux-9 | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-23 04:40:38.117446 | 2026-04-23 04:40:38.220611 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-04-23 04:40:38.221886 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-04-23 04:40:38.794732 | 2026-04-23 04:40:38.794914 | PLAY [all] 2026-04-23 04:40:38.806221 | 2026-04-23 04:40:38.806308 | TASK [fetch-output : Set log path for multiple nodes] 2026-04-23 04:40:38.840808 | rockylinux-9 | skipping: Conditional result was False 2026-04-23 04:40:38.845782 | 2026-04-23 04:40:38.845872 | TASK [fetch-output : Set log path for single node] 2026-04-23 04:40:38.885450 | rockylinux-9 | ok 2026-04-23 04:40:38.891483 | 2026-04-23 04:40:38.891572 | LOOP [fetch-output : Ensure local output dirs] 2026-04-23 04:40:39.281154 | rockylinux-9 -> localhost | ok: "/var/lib/zuul/builds/ea8d5c20136d401990f6853d75256d13/work/logs" 2026-04-23 04:40:39.510556 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/ea8d5c20136d401990f6853d75256d13/work/artifacts" 2026-04-23 04:40:39.715926 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/ea8d5c20136d401990f6853d75256d13/work/docs" 2026-04-23 04:40:39.731227 | 2026-04-23 04:40:39.731379 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-04-23 04:40:40.422509 | rockylinux-9 | ok 2026-04-23 04:40:40.422801 | rockylinux-9 | changed: All items complete 2026-04-23 04:40:40.422860 | 2026-04-23 04:40:40.911634 | rockylinux-9 | changed: .d..t...... ./ 2026-04-23 04:40:41.423817 | rockylinux-9 | changed: .d..t...... ./ 2026-04-23 04:40:41.444413 | 2026-04-23 04:40:41.444532 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-04-23 04:40:41.840650 | rockylinux-9 -> localhost | ok: Item: artifacts Runtime: 0:00:00.005749 2026-04-23 04:40:42.064750 | rockylinux-9 -> localhost | ok: Item: docs Runtime: 0:00:00.005893 2026-04-23 04:40:42.083235 | 2026-04-23 04:40:42.083363 | PLAY [all] 2026-04-23 04:40:42.089236 | 2026-04-23 04:40:42.089315 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-04-23 04:40:42.553164 | rockylinux-9 | changed 2026-04-23 04:40:42.558682 | 2026-04-23 04:40:42.558749 | PLAY RECAP 2026-04-23 04:40:42.558793 | rockylinux-9 | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-04-23 04:40:42.558817 | 2026-04-23 04:40:42.671569 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-04-23 04:40:42.673066 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-04-23 04:40:43.214462 | 2026-04-23 04:40:43.214607 | PLAY [localhost] 2026-04-23 04:40:43.223867 | 2026-04-23 04:40:43.223935 | TASK [Generate Zuul manifest] 2026-04-23 04:40:43.242861 | localhost | ok 2026-04-23 04:40:43.258837 | 2026-04-23 04:40:43.258909 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-04-23 04:40:43.573184 | localhost | changed 2026-04-23 04:40:43.584620 | 2026-04-23 04:40:43.584694 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-04-23 04:40:43.615280 | localhost | ok 2026-04-23 04:40:43.622083 | 2026-04-23 04:40:43.622149 | TASK [Upload logs] 2026-04-23 04:40:43.642849 | localhost | ok 2026-04-23 04:40:43.703821 | 2026-04-23 04:40:43.703974 | TASK [Set zuul-log-path fact] 2026-04-23 04:40:43.724592 | localhost | ok 2026-04-23 04:40:43.736842 | 2026-04-23 04:40:43.736905 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-23 04:40:43.768285 | localhost | ok 2026-04-23 04:40:43.775179 | 2026-04-23 04:40:43.775242 | TASK [upload-logs : Create log directories] 2026-04-23 04:40:44.180785 | localhost | changed 2026-04-23 04:40:44.186448 | 2026-04-23 04:40:44.186514 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-04-23 04:40:44.552146 | localhost -> localhost | ok: Runtime: 0:00:00.004836 2026-04-23 04:40:44.559181 | 2026-04-23 04:40:44.559253 | TASK [upload-logs : Upload logs to log server] 2026-04-23 04:40:44.992764 | localhost | Output suppressed because no_log was given 2026-04-23 04:40:44.996280 | 2026-04-23 04:40:44.996347 | LOOP [upload-logs : Compress console log and json output] 2026-04-23 04:40:45.037857 | localhost | skipping: Conditional result was False 2026-04-23 04:40:45.045444 | localhost | skipping: Conditional result was False 2026-04-23 04:40:45.058959 | 2026-04-23 04:40:45.059115 | LOOP [upload-logs : Upload compressed console log and json output] 2026-04-23 04:40:45.100432 | localhost | skipping: Conditional result was False 2026-04-23 04:40:45.100802 | 2026-04-23 04:40:45.105179 | localhost | skipping: Conditional result was False 2026-04-23 04:40:45.121732 | 2026-04-23 04:40:45.121868 | LOOP [upload-logs : Upload console log and json output]