2026-05-21 01:15:06.207311 | Job console starting 2026-05-21 01:15:06.214434 | Updating git repos 2026-05-21 01:15:06.237315 | Cloning repos into workspace 2026-05-21 01:15:06.390773 | Restoring repo states 2026-05-21 01:15:06.398624 | Merging changes 2026-05-21 01:15:06.851683 | Checking out repos 2026-05-21 01:15:06.924258 | Preparing playbooks 2026-05-21 01:15:08.728577 | Running Ansible setup 2026-05-21 01:15:12.542850 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-05-21 01:15:13.216148 | 2026-05-21 01:15:13.216302 | PLAY [localhost] 2026-05-21 01:15:13.224048 | 2026-05-21 01:15:13.224115 | TASK [Gathering Facts] 2026-05-21 01:15:14.111732 | localhost | ok 2026-05-21 01:15:14.120301 | 2026-05-21 01:15:14.120376 | TASK [Setup log path fact] 2026-05-21 01:15:14.138171 | localhost | ok 2026-05-21 01:15:14.151465 | 2026-05-21 01:15:14.151584 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-21 01:15:14.182012 | localhost | ok 2026-05-21 01:15:14.191824 | 2026-05-21 01:15:14.191909 | TASK [emit-job-header : Print job information] 2026-05-21 01:15:14.233015 | # Job Information 2026-05-21 01:15:14.233278 | Ansible Version: 2.16.18 2026-05-21 01:15:14.233346 | Job: ansible-collection-containers-molecule-nerdctl-rockylinux-9 2026-05-21 01:15:14.233396 | Pipeline: check 2026-05-21 01:15:14.233441 | Executor: 2d72f0692154 2026-05-21 01:15:14.233485 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/127 2026-05-21 01:15:14.233533 | Event ID: 5521daf0-54b2-11f1-96f5-a64bb83e1cf4 2026-05-21 01:15:14.237446 | 2026-05-21 01:15:14.237534 | LOOP [emit-job-header : Print node information] 2026-05-21 01:15:14.319821 | localhost | ok: 2026-05-21 01:15:14.319975 | localhost | # Node Information 2026-05-21 01:15:14.320004 | localhost | Inventory Hostname: rockylinux-9 2026-05-21 01:15:14.320027 | localhost | Hostname: np0000175625 2026-05-21 01:15:14.320046 | localhost | Username: zuul 2026-05-21 01:15:14.320069 | localhost | Distro: Rocky 9.7 2026-05-21 01:15:14.320089 | localhost | Provider: yul1 2026-05-21 01:15:14.320121 | localhost | Region: ca-ymq-1 2026-05-21 01:15:14.320143 | localhost | Label: rockylinux-9 2026-05-21 01:15:14.320162 | localhost | Product Name: OpenStack Nova 2026-05-21 01:15:14.320182 | localhost | Interface IP: 199.204.45.240 2026-05-21 01:15:14.336574 | 2026-05-21 01:15:14.336772 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-05-21 01:15:14.727514 | localhost -> localhost | changed 2026-05-21 01:15:14.735699 | 2026-05-21 01:15:14.735796 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-05-21 01:15:15.586821 | localhost -> localhost | changed 2026-05-21 01:15:15.597709 | 2026-05-21 01:15:15.597822 | PLAY [all] 2026-05-21 01:15:15.607245 | 2026-05-21 01:15:15.607359 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-05-21 01:15:15.835218 | rockylinux-9 -> localhost | ok 2026-05-21 01:15:15.842620 | 2026-05-21 01:15:15.842695 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-05-21 01:15:15.872800 | rockylinux-9 | ok 2026-05-21 01:15:15.886608 | rockylinux-9 | included: /var/lib/zuul/builds/f816ded2be734a1d8b0bf9641134588d/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-05-21 01:15:15.892677 | 2026-05-21 01:15:15.892734 | TASK [add-build-sshkey : Create Temp SSH key] 2026-05-21 01:15:16.713450 | rockylinux-9 -> localhost | Generating public/private rsa key pair. 2026-05-21 01:15:16.713659 | rockylinux-9 -> localhost | Your identification has been saved in /var/lib/zuul/builds/f816ded2be734a1d8b0bf9641134588d/work/f816ded2be734a1d8b0bf9641134588d_id_rsa 2026-05-21 01:15:16.713701 | rockylinux-9 -> localhost | Your public key has been saved in /var/lib/zuul/builds/f816ded2be734a1d8b0bf9641134588d/work/f816ded2be734a1d8b0bf9641134588d_id_rsa.pub 2026-05-21 01:15:16.713732 | rockylinux-9 -> localhost | The key fingerprint is: 2026-05-21 01:15:16.713762 | rockylinux-9 -> localhost | SHA256:gjhmGm+eU79Ye3gBlhgqrSnYWqeNuK7LZY9iDf+4p1E zuul-build-sshkey 2026-05-21 01:15:16.713801 | rockylinux-9 -> localhost | The key's randomart image is: 2026-05-21 01:15:16.713831 | rockylinux-9 -> localhost | +---[RSA 3072]----+ 2026-05-21 01:15:16.713874 | rockylinux-9 -> localhost | | | 2026-05-21 01:15:16.713904 | rockylinux-9 -> localhost | | . | 2026-05-21 01:15:16.713932 | rockylinux-9 -> localhost | | . . o . | 2026-05-21 01:15:16.713960 | rockylinux-9 -> localhost | |. o...+ | 2026-05-21 01:15:16.713989 | rockylinux-9 -> localhost | |o== .E..S | 2026-05-21 01:15:16.714016 | rockylinux-9 -> localhost | |=Bo.+ .. | 2026-05-21 01:15:16.714042 | rockylinux-9 -> localhost | |o+*X ... . | 2026-05-21 01:15:16.714069 | rockylinux-9 -> localhost | |+=*+*+o.o | 2026-05-21 01:15:16.714100 | rockylinux-9 -> localhost | |B=+**oo+ | 2026-05-21 01:15:16.714128 | rockylinux-9 -> localhost | +----[SHA256]-----+ 2026-05-21 01:15:16.714197 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.434264 2026-05-21 01:15:16.721703 | 2026-05-21 01:15:16.721795 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-05-21 01:15:16.755890 | rockylinux-9 | ok 2026-05-21 01:15:16.764403 | rockylinux-9 | included: /var/lib/zuul/builds/f816ded2be734a1d8b0bf9641134588d/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-05-21 01:15:16.771385 | 2026-05-21 01:15:16.771454 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-05-21 01:15:16.795939 | rockylinux-9 | skipping: Conditional result was False 2026-05-21 01:15:16.805863 | 2026-05-21 01:15:16.805970 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-05-21 01:15:17.316428 | rockylinux-9 | changed 2026-05-21 01:15:17.324732 | 2026-05-21 01:15:17.324841 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-05-21 01:15:17.585091 | rockylinux-9 | ok 2026-05-21 01:15:17.589568 | 2026-05-21 01:15:17.589631 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-05-21 01:15:18.551936 | rockylinux-9 | changed 2026-05-21 01:15:18.556304 | 2026-05-21 01:15:18.556378 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-05-21 01:15:19.447739 | rockylinux-9 | changed 2026-05-21 01:15:19.453634 | 2026-05-21 01:15:19.453719 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-05-21 01:15:19.477604 | rockylinux-9 | skipping: Conditional result was False 2026-05-21 01:15:19.487374 | 2026-05-21 01:15:19.487506 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-05-21 01:15:19.931520 | rockylinux-9 -> localhost | changed 2026-05-21 01:15:19.945949 | 2026-05-21 01:15:19.946044 | TASK [add-build-sshkey : Add back temp key] 2026-05-21 01:15:20.218271 | rockylinux-9 -> localhost | Identity added: /var/lib/zuul/builds/f816ded2be734a1d8b0bf9641134588d/work/f816ded2be734a1d8b0bf9641134588d_id_rsa (zuul-build-sshkey) 2026-05-21 01:15:20.218474 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.013170 2026-05-21 01:15:20.225062 | 2026-05-21 01:15:20.225127 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-05-21 01:15:20.603670 | rockylinux-9 | ok 2026-05-21 01:15:20.611312 | 2026-05-21 01:15:20.611377 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-05-21 01:15:20.645569 | rockylinux-9 | skipping: Conditional result was False 2026-05-21 01:15:20.660588 | 2026-05-21 01:15:20.660656 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-05-21 01:15:21.051627 | rockylinux-9 | ok 2026-05-21 01:15:21.059538 | 2026-05-21 01:15:21.059958 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-05-21 01:15:22.007434 | rockylinux-9 | Output suppressed because no_log was given 2026-05-21 01:15:22.017355 | 2026-05-21 01:15:22.017416 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-05-21 01:15:22.295221 | rockylinux-9 | ok: "logs" 2026-05-21 01:15:22.295510 | rockylinux-9 | ok: All items complete 2026-05-21 01:15:22.295550 | 2026-05-21 01:15:22.532011 | rockylinux-9 | ok: "artifacts" 2026-05-21 01:15:22.763427 | rockylinux-9 | ok: "docs" 2026-05-21 01:15:22.775900 | 2026-05-21 01:15:22.776054 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-05-21 01:15:23.059405 | rockylinux-9 | changed: "logs" 2026-05-21 01:15:23.331209 | rockylinux-9 | changed: "artifacts" 2026-05-21 01:15:23.535471 | rockylinux-9 | changed: "docs" 2026-05-21 01:15:23.556775 | 2026-05-21 01:15:23.556909 | PLAY RECAP 2026-05-21 01:15:23.557001 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-05-21 01:15:23.557048 | rockylinux-9 | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-05-21 01:15:23.557077 | 2026-05-21 01:15:23.673603 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-05-21 01:15:23.676142 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-05-21 01:15:24.277424 | 2026-05-21 01:15:24.277563 | PLAY [all] 2026-05-21 01:15:24.288684 | 2026-05-21 01:15:24.288764 | TASK [setup-uv : Extract archive] 2026-05-21 01:15:26.994129 | rockylinux-9 | changed 2026-05-21 01:15:27.000803 | 2026-05-21 01:15:27.000883 | TASK [setup-uv : Print version] 2026-05-21 01:15:27.022868 | [rockylinux-9] Waiting on logger 2026-05-21 01:15:27.537220 | rockylinux-9 | ok: Runtime: 0:00:00.012377 2026-05-21 01:15:27.554017 | 2026-05-21 01:15:27.554261 | TASK [Install Ansible collection siblings] 2026-05-21 01:15:28.038342 | rockylinux-9 | ok: 2026-05-21 01:15:28.038502 | rockylinux-9 | Skipping ansible.posix: no checked-out sibling 2026-05-21 01:15:28.038531 | rockylinux-9 | Skipping community.general: no checked-out sibling 2026-05-21 01:15:28.040256 | 2026-05-21 01:15:28.040305 | PLAY RECAP 2026-05-21 01:15:28.040353 | rockylinux-9 | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-05-21 01:15:28.040376 | 2026-05-21 01:15:28.167441 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-05-21 01:15:28.169778 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-05-21 01:15:28.759135 | 2026-05-21 01:15:28.859851 | PLAY [all] 2026-05-21 01:15:28.878600 | 2026-05-21 01:15:28.878674 | TASK [Copy inventory file for Zuul] 2026-05-21 01:15:30.243804 | rockylinux-9 | changed 2026-05-21 01:15:30.376226 | 2026-05-21 01:15:30.376419 | TASK [Switch "ansible_host" to private IP] 2026-05-21 01:15:30.781540 | rockylinux-9 | changed: 1 replacements made 2026-05-21 01:15:30.786719 | 2026-05-21 01:15:30.786784 | TASK [Run Molecule scenario] 2026-05-21 01:15:30.807317 | [rockylinux-9] Waiting on logger 2026-05-21 01:15:38.954184 | [rockylinux-9] Waiting on logger 2026-05-21 01:15:48.106990 | [rockylinux-9] Waiting on logger 2026-05-21 01:15:58.277935 | [rockylinux-9] Waiting on logger 2026-05-21 01:16:08.453886 | [rockylinux-9] Waiting on logger 2026-05-21 01:16:18.630196 | [rockylinux-9] Waiting on logger 2026-05-21 01:16:28.805942 | [rockylinux-9] Waiting on logger 2026-05-21 01:16:38.982321 | [rockylinux-9] Waiting on logger 2026-05-21 01:16:48.139682 | [rockylinux-9] Waiting on logger 2026-05-21 01:16:58.313878 | [rockylinux-9] Waiting on logger 2026-05-21 01:17:08.485969 | [rockylinux-9] Waiting on logger 2026-05-21 01:17:18.662133 | [rockylinux-9] Waiting on logger 2026-05-21 01:17:28.837808 | [rockylinux-9] Waiting on logger 2026-05-21 01:17:33.421750 | rockylinux-9 | ok: Runtime: 0:02:01.121107 2026-05-21 01:17:33.427604 | 2026-05-21 01:17:33.427667 | PLAY RECAP 2026-05-21 01:17:33.427724 | rockylinux-9 | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-05-21 01:17:33.427754 | 2026-05-21 01:17:33.528867 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-05-21 01:17:33.531407 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-05-21 01:17:34.098900 | 2026-05-21 01:17:34.099055 | PLAY [all] 2026-05-21 01:17:34.110679 | 2026-05-21 01:17:34.110752 | TASK [fetch-output : Set log path for multiple nodes] 2026-05-21 01:17:34.155393 | rockylinux-9 | skipping: Conditional result was False 2026-05-21 01:17:34.164378 | 2026-05-21 01:17:34.164463 | TASK [fetch-output : Set log path for single node] 2026-05-21 01:17:34.208405 | rockylinux-9 | ok 2026-05-21 01:17:34.229709 | 2026-05-21 01:17:34.229828 | LOOP [fetch-output : Ensure local output dirs] 2026-05-21 01:17:34.621641 | rockylinux-9 -> localhost | ok: "/var/lib/zuul/builds/f816ded2be734a1d8b0bf9641134588d/work/logs" 2026-05-21 01:17:34.838059 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/f816ded2be734a1d8b0bf9641134588d/work/artifacts" 2026-05-21 01:17:35.043599 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/f816ded2be734a1d8b0bf9641134588d/work/docs" 2026-05-21 01:17:35.064561 | 2026-05-21 01:17:35.064707 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-05-21 01:17:35.742443 | rockylinux-9 | changed: .d..t...... ./ 2026-05-21 01:17:35.742661 | rockylinux-9 | changed: All items complete 2026-05-21 01:17:35.742700 | 2026-05-21 01:17:36.245639 | rockylinux-9 | changed: .d..t...... ./ 2026-05-21 01:17:36.777642 | rockylinux-9 | changed: .d..t...... ./ 2026-05-21 01:17:36.793857 | 2026-05-21 01:17:36.793947 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-05-21 01:17:37.215435 | rockylinux-9 -> localhost | ok: Item: artifacts Runtime: 0:00:00.006635 2026-05-21 01:17:37.438665 | rockylinux-9 -> localhost | ok: Item: docs Runtime: 0:00:00.006523 2026-05-21 01:17:37.461116 | 2026-05-21 01:17:37.461224 | PLAY [all] 2026-05-21 01:17:37.466845 | 2026-05-21 01:17:37.466906 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-05-21 01:17:37.910985 | rockylinux-9 | changed 2026-05-21 01:17:37.916865 | 2026-05-21 01:17:37.916911 | PLAY RECAP 2026-05-21 01:17:37.916976 | rockylinux-9 | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-05-21 01:17:37.917001 | 2026-05-21 01:17:38.040835 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-05-21 01:17:38.043320 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-05-21 01:17:38.585910 | 2026-05-21 01:17:38.586014 | PLAY [localhost] 2026-05-21 01:17:38.595913 | 2026-05-21 01:17:38.595985 | TASK [Generate Zuul manifest] 2026-05-21 01:17:38.627767 | localhost | ok 2026-05-21 01:17:38.657632 | 2026-05-21 01:17:38.657835 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-05-21 01:17:39.046135 | localhost | changed 2026-05-21 01:17:39.058999 | 2026-05-21 01:17:39.059166 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-05-21 01:17:39.144852 | localhost | ok 2026-05-21 01:17:39.154525 | 2026-05-21 01:17:39.154618 | TASK [Upload logs] 2026-05-21 01:17:39.188254 | localhost | ok 2026-05-21 01:17:39.246471 | 2026-05-21 01:17:39.246602 | TASK [Set zuul-log-path fact] 2026-05-21 01:17:39.271253 | localhost | ok 2026-05-21 01:17:39.285659 | 2026-05-21 01:17:39.285730 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-21 01:17:39.327605 | localhost | ok 2026-05-21 01:17:39.336647 | 2026-05-21 01:17:39.336734 | TASK [upload-logs : Create log directories] 2026-05-21 01:17:39.778040 | localhost | changed 2026-05-21 01:17:39.786705 | 2026-05-21 01:17:39.786809 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-05-21 01:17:40.205951 | localhost -> localhost | ok: Runtime: 0:00:00.005222 2026-05-21 01:17:40.211143 | 2026-05-21 01:17:40.211211 | TASK [upload-logs : Upload logs to log server] 2026-05-21 01:17:40.676539 | localhost | Output suppressed because no_log was given 2026-05-21 01:17:40.681876 | 2026-05-21 01:17:40.681969 | LOOP [upload-logs : Compress console log and json output] 2026-05-21 01:17:40.729522 | localhost | skipping: Conditional result was False 2026-05-21 01:17:40.736726 | localhost | skipping: Conditional result was False 2026-05-21 01:17:40.751177 | 2026-05-21 01:17:40.751413 | LOOP [upload-logs : Upload compressed console log and json output] 2026-05-21 01:17:40.801261 | localhost | skipping: Conditional result was False 2026-05-21 01:17:40.801629 | 2026-05-21 01:17:40.807122 | localhost | skipping: Conditional result was False 2026-05-21 01:17:40.820797 | 2026-05-21 01:17:40.821197 | LOOP [upload-logs : Upload console log and json output]