2026-04-14 02:27:05.244172 | Job console starting 2026-04-14 02:27:05.255461 | Updating git repos 2026-04-14 02:27:05.282452 | Cloning repos into workspace 2026-04-14 02:27:05.447981 | Restoring repo states 2026-04-14 02:27:05.458162 | Merging changes 2026-04-14 02:27:05.748910 | Checking out repos 2026-04-14 02:27:05.801790 | Preparing playbooks 2026-04-14 02:27:07.253967 | Running Ansible setup 2026-04-14 02:27:11.065704 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-04-14 02:27:11.719869 | 2026-04-14 02:27:11.720045 | PLAY [localhost] 2026-04-14 02:27:11.729928 | 2026-04-14 02:27:11.730040 | TASK [Gathering Facts] 2026-04-14 02:27:12.647117 | localhost | ok 2026-04-14 02:27:12.654035 | 2026-04-14 02:27:12.654114 | TASK [Setup log path fact] 2026-04-14 02:27:12.675179 | localhost | ok 2026-04-14 02:27:12.689703 | 2026-04-14 02:27:12.689774 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-14 02:27:12.719971 | localhost | ok 2026-04-14 02:27:12.728436 | 2026-04-14 02:27:12.728514 | TASK [emit-job-header : Print job information] 2026-04-14 02:27:12.770609 | # Job Information 2026-04-14 02:27:12.770824 | Ansible Version: 2.16.16 2026-04-14 02:27:12.770884 | Job: ansible-collection-containers-molecule-containerd-rockylinux-9 2026-04-14 02:27:12.770918 | Pipeline: check 2026-04-14 02:27:12.770948 | Executor: 0a8996d2b663 2026-04-14 02:27:12.770975 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/104 2026-04-14 02:27:12.771006 | Event ID: d1276790-37a8-11f1-89b6-dc8b072af067 2026-04-14 02:27:12.774848 | 2026-04-14 02:27:12.774939 | LOOP [emit-job-header : Print node information] 2026-04-14 02:27:13.101876 | localhost | ok: 2026-04-14 02:27:13.102066 | localhost | # Node Information 2026-04-14 02:27:13.102096 | localhost | Inventory Hostname: rockylinux-9 2026-04-14 02:27:13.102124 | localhost | Hostname: np0000165957 2026-04-14 02:27:13.102145 | localhost | Username: zuul 2026-04-14 02:27:13.102169 | localhost | Distro: Rocky 9.7 2026-04-14 02:27:13.102190 | localhost | Provider: yul1 2026-04-14 02:27:13.102210 | localhost | Region: ca-ymq-1 2026-04-14 02:27:13.102230 | localhost | Label: rockylinux-9 2026-04-14 02:27:13.102248 | localhost | Product Name: OpenStack Nova 2026-04-14 02:27:13.102266 | localhost | Interface IP: 199.19.213.130 2026-04-14 02:27:13.117563 | 2026-04-14 02:27:13.117813 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-04-14 02:27:13.566896 | localhost -> localhost | changed 2026-04-14 02:27:13.574912 | 2026-04-14 02:27:13.575023 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-04-14 02:27:14.510214 | localhost -> localhost | changed 2026-04-14 02:27:14.516220 | 2026-04-14 02:27:14.516291 | PLAY [all] 2026-04-14 02:27:14.526155 | 2026-04-14 02:27:14.526262 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-04-14 02:27:14.811334 | rockylinux-9 -> localhost | ok 2026-04-14 02:27:14.816617 | 2026-04-14 02:27:14.816696 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-04-14 02:27:14.849045 | rockylinux-9 | ok 2026-04-14 02:27:14.871634 | rockylinux-9 | included: /var/lib/zuul/builds/91c69050870342e6839375924db7186a/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-04-14 02:27:14.877633 | 2026-04-14 02:27:14.877716 | TASK [add-build-sshkey : Create Temp SSH key] 2026-04-14 02:27:16.313435 | rockylinux-9 -> localhost | Generating public/private rsa key pair. 2026-04-14 02:27:16.313616 | rockylinux-9 -> localhost | Your identification has been saved in /var/lib/zuul/builds/91c69050870342e6839375924db7186a/work/91c69050870342e6839375924db7186a_id_rsa 2026-04-14 02:27:16.313648 | rockylinux-9 -> localhost | Your public key has been saved in /var/lib/zuul/builds/91c69050870342e6839375924db7186a/work/91c69050870342e6839375924db7186a_id_rsa.pub 2026-04-14 02:27:16.313670 | rockylinux-9 -> localhost | The key fingerprint is: 2026-04-14 02:27:16.313692 | rockylinux-9 -> localhost | SHA256:WGMcl53oO9JpzVC0CgsejQMXMs0dgviv+6ObfOaVX6Q zuul-build-sshkey 2026-04-14 02:27:16.314175 | rockylinux-9 -> localhost | The key's randomart image is: 2026-04-14 02:27:16.314206 | rockylinux-9 -> localhost | +---[RSA 3072]----+ 2026-04-14 02:27:16.314234 | rockylinux-9 -> localhost | | .+++oo..=.. | 2026-04-14 02:27:16.314256 | rockylinux-9 -> localhost | | . .=o=.oo +. | 2026-04-14 02:27:16.314277 | rockylinux-9 -> localhost | | . = B. .. | 2026-04-14 02:27:16.314297 | rockylinux-9 -> localhost | | .. B +o. | 2026-04-14 02:27:16.314316 | rockylinux-9 -> localhost | | .o S..*. | 2026-04-14 02:27:16.314402 | rockylinux-9 -> localhost | | . ..*oo | 2026-04-14 02:27:16.314433 | rockylinux-9 -> localhost | | . ooE.. | 2026-04-14 02:27:16.314454 | rockylinux-9 -> localhost | | ...+. . . | 2026-04-14 02:27:16.314477 | rockylinux-9 -> localhost | | *Oo. . | 2026-04-14 02:27:16.314497 | rockylinux-9 -> localhost | +----[SHA256]-----+ 2026-04-14 02:27:16.314549 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.980018 2026-04-14 02:27:16.320915 | 2026-04-14 02:27:16.320998 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-04-14 02:27:16.351980 | rockylinux-9 | ok 2026-04-14 02:27:16.362643 | rockylinux-9 | included: /var/lib/zuul/builds/91c69050870342e6839375924db7186a/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-04-14 02:27:16.370929 | 2026-04-14 02:27:16.370993 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-04-14 02:27:16.405575 | rockylinux-9 | skipping: Conditional result was False 2026-04-14 02:27:16.416115 | 2026-04-14 02:27:16.416260 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-04-14 02:27:16.923108 | rockylinux-9 | changed 2026-04-14 02:27:16.929079 | 2026-04-14 02:27:16.929150 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-04-14 02:27:17.172308 | rockylinux-9 | ok 2026-04-14 02:27:17.177833 | 2026-04-14 02:27:17.177935 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-04-14 02:27:18.090807 | rockylinux-9 | changed 2026-04-14 02:27:18.101482 | 2026-04-14 02:27:18.101612 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-04-14 02:27:19.011470 | rockylinux-9 | changed 2026-04-14 02:27:19.018059 | 2026-04-14 02:27:19.018169 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-04-14 02:27:19.043608 | rockylinux-9 | skipping: Conditional result was False 2026-04-14 02:27:19.053705 | 2026-04-14 02:27:19.053855 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-04-14 02:27:19.483523 | rockylinux-9 -> localhost | changed 2026-04-14 02:27:19.493327 | 2026-04-14 02:27:19.493432 | TASK [add-build-sshkey : Add back temp key] 2026-04-14 02:27:19.791295 | rockylinux-9 -> localhost | Identity added: /var/lib/zuul/builds/91c69050870342e6839375924db7186a/work/91c69050870342e6839375924db7186a_id_rsa (zuul-build-sshkey) 2026-04-14 02:27:19.791556 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.013610 2026-04-14 02:27:19.801346 | 2026-04-14 02:27:19.801442 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-04-14 02:27:20.206607 | rockylinux-9 | ok 2026-04-14 02:27:20.211493 | 2026-04-14 02:27:20.211572 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-04-14 02:27:20.239368 | rockylinux-9 | skipping: Conditional result was False 2026-04-14 02:27:20.254424 | 2026-04-14 02:27:20.254579 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-04-14 02:27:20.658477 | rockylinux-9 | ok 2026-04-14 02:27:20.665682 | 2026-04-14 02:27:20.665778 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-04-14 02:27:21.631557 | rockylinux-9 | Output suppressed because no_log was given 2026-04-14 02:27:21.639692 | 2026-04-14 02:27:21.639761 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-04-14 02:27:21.901080 | rockylinux-9 | ok: "logs" 2026-04-14 02:27:21.901652 | rockylinux-9 | ok: All items complete 2026-04-14 02:27:21.901710 | 2026-04-14 02:27:22.123217 | rockylinux-9 | ok: "artifacts" 2026-04-14 02:27:22.346981 | rockylinux-9 | ok: "docs" 2026-04-14 02:27:22.363978 | 2026-04-14 02:27:22.364136 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-04-14 02:27:22.621644 | rockylinux-9 | changed: "logs" 2026-04-14 02:27:22.852227 | rockylinux-9 | changed: "artifacts" 2026-04-14 02:27:23.077876 | rockylinux-9 | changed: "docs" 2026-04-14 02:27:23.153549 | 2026-04-14 02:27:23.153704 | PLAY RECAP 2026-04-14 02:27:23.153754 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-14 02:27:23.153787 | rockylinux-9 | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-04-14 02:27:23.153811 | 2026-04-14 02:27:23.291818 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-04-14 02:27:23.294896 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-04-14 02:27:23.966902 | 2026-04-14 02:27:23.967034 | PLAY [all] 2026-04-14 02:27:23.983140 | 2026-04-14 02:27:23.983253 | TASK [setup-uv : Extract archive] 2026-04-14 02:27:26.815763 | rockylinux-9 | changed 2026-04-14 02:27:26.866215 | 2026-04-14 02:27:26.866369 | TASK [setup-uv : Print version] 2026-04-14 02:27:26.889661 | [rockylinux-9] Waiting on logger 2026-04-14 02:27:27.402942 | rockylinux-9 | ok: Runtime: 0:00:00.012815 2026-04-14 02:27:27.489118 | 2026-04-14 02:27:27.489263 | PLAY RECAP 2026-04-14 02:27:27.489361 | rockylinux-9 | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-14 02:27:27.489416 | 2026-04-14 02:27:27.610039 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-04-14 02:27:27.611622 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-04-14 02:27:28.198717 | 2026-04-14 02:27:28.198918 | PLAY [all] 2026-04-14 02:27:28.211076 | 2026-04-14 02:27:28.211179 | TASK [Copy inventory file for Zuul] 2026-04-14 02:27:29.555016 | rockylinux-9 | changed 2026-04-14 02:27:29.562564 | 2026-04-14 02:27:29.563382 | TASK [Switch "ansible_host" to private IP] 2026-04-14 02:27:29.912653 | rockylinux-9 | changed: 1 replacements made 2026-04-14 02:27:29.925666 | 2026-04-14 02:27:29.925864 | TASK [Run Molecule scenario] 2026-04-14 02:27:29.971984 | [rockylinux-9] Waiting on logger 2026-04-14 02:27:38.118150 | [rockylinux-9] Waiting on logger 2026-04-14 02:27:48.293926 | [rockylinux-9] Waiting on logger 2026-04-14 02:27:58.470050 | [rockylinux-9] Waiting on logger 2026-04-14 02:28:08.646175 | [rockylinux-9] Waiting on logger 2026-04-14 02:28:18.822127 | [rockylinux-9] Waiting on logger 2026-04-14 02:28:27.979631 | [rockylinux-9] Waiting on logger 2026-04-14 02:28:38.154126 | [rockylinux-9] Waiting on logger 2026-04-14 02:28:48.329890 | [rockylinux-9] Waiting on logger 2026-04-14 02:28:58.502585 | [rockylinux-9] Waiting on logger 2026-04-14 02:29:08.678295 | [rockylinux-9] Waiting on logger 2026-04-14 02:29:18.858142 | [rockylinux-9] Waiting on logger 2026-04-14 02:29:28.042054 | [rockylinux-9] Waiting on logger 2026-04-14 02:29:38.218040 | [rockylinux-9] Waiting on logger 2026-04-14 02:29:48.390060 | [rockylinux-9] Waiting on logger 2026-04-14 02:29:58.566029 | [rockylinux-9] Waiting on logger 2026-04-14 02:30:08.742331 | [rockylinux-9] Waiting on logger 2026-04-14 02:30:17.899520 | [rockylinux-9] Waiting on logger 2026-04-14 02:30:28.070198 | [rockylinux-9] Waiting on logger 2026-04-14 02:30:38.246139 | [rockylinux-9] Waiting on logger 2026-04-14 02:30:48.422223 | [rockylinux-9] Waiting on logger 2026-04-14 02:30:58.598445 | [rockylinux-9] Waiting on logger 2026-04-14 02:31:08.774371 | [rockylinux-9] Waiting on logger 2026-04-14 02:31:17.935978 | [rockylinux-9] Waiting on logger 2026-04-14 02:31:28.102245 | [rockylinux-9] Waiting on logger 2026-04-14 02:31:38.278267 | [rockylinux-9] Waiting on logger 2026-04-14 02:31:48.454330 | [rockylinux-9] Waiting on logger 2026-04-14 02:31:50.702735 | rockylinux-9 | ok: Runtime: 0:04:19.619166 2026-04-14 02:31:50.709722 | 2026-04-14 02:31:50.709777 | PLAY RECAP 2026-04-14 02:31:50.709825 | rockylinux-9 | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-14 02:31:50.709849 | 2026-04-14 02:31:50.825160 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-04-14 02:31:50.827278 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-04-14 02:31:51.438976 | 2026-04-14 02:31:51.439130 | PLAY [all] 2026-04-14 02:31:51.450497 | 2026-04-14 02:31:51.450623 | TASK [fetch-output : Set log path for multiple nodes] 2026-04-14 02:31:51.495964 | rockylinux-9 | skipping: Conditional result was False 2026-04-14 02:31:51.505974 | 2026-04-14 02:31:51.506078 | TASK [fetch-output : Set log path for single node] 2026-04-14 02:31:51.550304 | rockylinux-9 | ok 2026-04-14 02:31:51.556405 | 2026-04-14 02:31:51.556486 | LOOP [fetch-output : Ensure local output dirs] 2026-04-14 02:31:51.944906 | rockylinux-9 -> localhost | ok: "/var/lib/zuul/builds/91c69050870342e6839375924db7186a/work/logs" 2026-04-14 02:31:52.164988 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/91c69050870342e6839375924db7186a/work/artifacts" 2026-04-14 02:31:52.413853 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/91c69050870342e6839375924db7186a/work/docs" 2026-04-14 02:31:52.431617 | 2026-04-14 02:31:52.431746 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-04-14 02:31:53.138625 | rockylinux-9 | changed: .d..t...... ./ 2026-04-14 02:31:53.138919 | rockylinux-9 | changed: All items complete 2026-04-14 02:31:53.138980 | 2026-04-14 02:31:53.644146 | rockylinux-9 | changed: .d..t...... ./ 2026-04-14 02:31:54.160663 | rockylinux-9 | changed: .d..t...... ./ 2026-04-14 02:31:54.179766 | 2026-04-14 02:31:54.179967 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-04-14 02:31:54.618201 | rockylinux-9 -> localhost | ok: Item: artifacts Runtime: 0:00:00.008839 2026-04-14 02:31:54.852643 | rockylinux-9 -> localhost | ok: Item: docs Runtime: 0:00:00.008571 2026-04-14 02:31:54.869584 | 2026-04-14 02:31:54.869672 | PLAY [all] 2026-04-14 02:31:54.875798 | 2026-04-14 02:31:54.875868 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-04-14 02:31:55.335162 | rockylinux-9 | changed 2026-04-14 02:31:55.341227 | 2026-04-14 02:31:55.341354 | PLAY RECAP 2026-04-14 02:31:55.341409 | rockylinux-9 | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-04-14 02:31:55.341437 | 2026-04-14 02:31:55.474894 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-04-14 02:31:55.477476 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-04-14 02:31:56.043664 | 2026-04-14 02:31:56.043824 | PLAY [localhost] 2026-04-14 02:31:56.054372 | 2026-04-14 02:31:56.054491 | TASK [Generate Zuul manifest] 2026-04-14 02:31:56.075825 | localhost | ok 2026-04-14 02:31:56.100418 | 2026-04-14 02:31:56.100558 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-04-14 02:31:56.416770 | localhost | changed 2026-04-14 02:31:56.431082 | 2026-04-14 02:31:56.431179 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-04-14 02:31:56.463720 | localhost | ok 2026-04-14 02:31:56.474139 | 2026-04-14 02:31:56.474222 | TASK [Upload logs] 2026-04-14 02:31:56.495728 | localhost | ok 2026-04-14 02:31:56.552776 | 2026-04-14 02:31:56.552870 | TASK [Set zuul-log-path fact] 2026-04-14 02:31:56.570313 | localhost | ok 2026-04-14 02:31:56.580752 | 2026-04-14 02:31:56.580840 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-14 02:31:56.612296 | localhost | ok 2026-04-14 02:31:56.622787 | 2026-04-14 02:31:56.622903 | TASK [upload-logs : Create log directories] 2026-04-14 02:31:57.030137 | localhost | changed 2026-04-14 02:31:57.036828 | 2026-04-14 02:31:57.036919 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-04-14 02:31:57.444937 | localhost -> localhost | ok: Runtime: 0:00:00.005468 2026-04-14 02:31:57.452340 | 2026-04-14 02:31:57.452430 | TASK [upload-logs : Upload logs to log server] 2026-04-14 02:31:57.939087 | localhost | Output suppressed because no_log was given 2026-04-14 02:31:57.942895 | 2026-04-14 02:31:57.943009 | LOOP [upload-logs : Compress console log and json output] 2026-04-14 02:31:57.990922 | localhost | skipping: Conditional result was False 2026-04-14 02:31:57.999417 | localhost | skipping: Conditional result was False 2026-04-14 02:31:58.017527 | 2026-04-14 02:31:58.017741 | LOOP [upload-logs : Upload compressed console log and json output] 2026-04-14 02:31:58.064165 | localhost | skipping: Conditional result was False 2026-04-14 02:31:58.064684 | 2026-04-14 02:31:58.068113 | localhost | skipping: Conditional result was False 2026-04-14 02:31:58.083903 | 2026-04-14 02:31:58.084080 | LOOP [upload-logs : Upload console log and json output]