2026-04-27 12:32:40.072590 | Job console starting
2026-04-27 12:32:40.087476 | Updating git repos
2026-04-27 12:32:40.116822 | Cloning repos into workspace
2026-04-27 12:32:42.852052 | Restoring repo states
2026-04-27 12:32:42.862076 | Merging changes
2026-04-27 12:32:43.455909 | Checking out repos
2026-04-27 12:32:43.606198 | Preparing playbooks
2026-04-27 12:32:45.476168 | Running Ansible setup
2026-04-27 12:32:49.729191 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-04-27 12:32:50.370698 | 
2026-04-27 12:32:50.371070 | PLAY [localhost]
2026-04-27 12:32:50.380383 | 
2026-04-27 12:32:50.380477 | TASK [Gathering Facts]
2026-04-27 12:32:51.448794 | localhost | ok
2026-04-27 12:32:51.617336 | 
2026-04-27 12:32:51.617492 | TASK [Setup log path fact]
2026-04-27 12:32:51.637828 | localhost | ok
2026-04-27 12:32:51.652203 | 
2026-04-27 12:32:51.652299 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-04-27 12:32:51.682327 | localhost | ok
2026-04-27 12:32:51.690775 | 
2026-04-27 12:32:51.690871 | TASK [emit-job-header : Print job information]
2026-04-27 12:32:51.732649 | # Job Information
2026-04-27 12:32:51.732946 | Ansible Version: 2.16.16
2026-04-27 12:32:51.733092 | Job: ansible-collection-containers-molecule-nerdctl-rockylinux-9
2026-04-27 12:32:51.733149 | Pipeline: check
2026-04-27 12:32:51.733207 | Executor: 0a8996d2b663
2026-04-27 12:32:51.733280 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/123
2026-04-27 12:32:51.733342 | Event ID: b6724230-4234-11f1-96cd-837a86eff685
2026-04-27 12:32:51.737099 | 
2026-04-27 12:32:51.737171 | LOOP [emit-job-header : Print node information]
2026-04-27 12:32:51.831365 | localhost | ok:
2026-04-27 12:32:51.831652 | localhost | # Node Information
2026-04-27 12:32:51.831715 | localhost | Inventory Hostname: rockylinux-9
2026-04-27 12:32:51.831775 | localhost | Hostname: np0000170576
2026-04-27 12:32:51.831829 | localhost | Username: zuul
2026-04-27 12:32:51.831880 | localhost | Distro: Rocky 9.7
2026-04-27 12:32:51.831934 | localhost | Provider: yul1
2026-04-27 12:32:51.831977 | localhost | Region: ca-ymq-1
2026-04-27 12:32:51.832019 | localhost | Label: rockylinux-9
2026-04-27 12:32:51.832060 | localhost | Product Name: OpenStack Nova
2026-04-27 12:32:51.832101 | localhost | Interface IP: 199.19.213.226
2026-04-27 12:32:51.845230 | 
2026-04-27 12:32:51.845511 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-04-27 12:32:52.264242 | localhost -> localhost | changed
2026-04-27 12:32:52.273142 | 
2026-04-27 12:32:52.273296 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-04-27 12:32:53.180934 | localhost -> localhost | changed
2026-04-27 12:32:53.189963 | 
2026-04-27 12:32:53.190072 | PLAY [all]
2026-04-27 12:32:53.201638 | 
2026-04-27 12:32:53.201776 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-04-27 12:32:53.429333 | rockylinux-9 -> localhost | ok
2026-04-27 12:32:53.435370 | 
2026-04-27 12:32:53.435435 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-04-27 12:32:53.467803 | rockylinux-9 | ok
2026-04-27 12:32:53.485834 | rockylinux-9 | included: /var/lib/zuul/builds/5060bc2678044988869cd271d345f3d5/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-04-27 12:32:53.491169 | 
2026-04-27 12:32:53.491231 | TASK [add-build-sshkey : Create Temp SSH key]
2026-04-27 12:32:55.076217 | rockylinux-9 -> localhost | Generating public/private rsa key pair.
2026-04-27 12:32:55.076450 | rockylinux-9 -> localhost | Your identification has been saved in /var/lib/zuul/builds/5060bc2678044988869cd271d345f3d5/work/5060bc2678044988869cd271d345f3d5_id_rsa
2026-04-27 12:32:55.076496 | rockylinux-9 -> localhost | Your public key has been saved in /var/lib/zuul/builds/5060bc2678044988869cd271d345f3d5/work/5060bc2678044988869cd271d345f3d5_id_rsa.pub
2026-04-27 12:32:55.076531 | rockylinux-9 -> localhost | The key fingerprint is:
2026-04-27 12:32:55.076563 | rockylinux-9 -> localhost | SHA256:cSjOqsB5crX2x2a5QviqsIk+ec5MNE+pYkO4Zl/doNY zuul-build-sshkey
2026-04-27 12:32:55.076614 | rockylinux-9 -> localhost | The key's randomart image is:
2026-04-27 12:32:55.076645 | rockylinux-9 -> localhost | +---[RSA 3072]----+
2026-04-27 12:32:55.076684 | rockylinux-9 -> localhost | |                 |
2026-04-27 12:32:55.076716 | rockylinux-9 -> localhost | |         .       |
2026-04-27 12:32:55.076746 | rockylinux-9 -> localhost | |      . o .      |
2026-04-27 12:32:55.076776 | rockylinux-9 -> localhost | |.    o.. o       |
2026-04-27 12:32:55.076805 | rockylinux-9 -> localhost | |.. o ++.S        |
2026-04-27 12:32:55.076835 | rockylinux-9 -> localhost | |o.o *o=.o        |
2026-04-27 12:32:55.076864 | rockylinux-9 -> localhost | |o@.=.BoE...      |
2026-04-27 12:32:55.076893 | rockylinux-9 -> localhost | |=+#o+ .o *       |
2026-04-27 12:32:55.076925 | rockylinux-9 -> localhost | |o+=*....=..      |
2026-04-27 12:32:55.076987 | rockylinux-9 -> localhost | +----[SHA256]-----+
2026-04-27 12:32:55.077080 | rockylinux-9 -> localhost | ok: Runtime: 0:00:01.091780
2026-04-27 12:32:55.084444 | 
2026-04-27 12:32:55.084559 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-04-27 12:32:55.117684 | rockylinux-9 | ok
2026-04-27 12:32:55.128642 | rockylinux-9 | included: /var/lib/zuul/builds/5060bc2678044988869cd271d345f3d5/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-04-27 12:32:55.137627 | 
2026-04-27 12:32:55.137792 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-04-27 12:32:55.162540 | rockylinux-9 | skipping: Conditional result was False
2026-04-27 12:32:55.171871 | 
2026-04-27 12:32:55.172041 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-04-27 12:32:55.657274 | rockylinux-9 | changed
2026-04-27 12:32:55.662904 | 
2026-04-27 12:32:55.662999 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-04-27 12:32:55.947474 | rockylinux-9 | ok
2026-04-27 12:32:55.954216 | 
2026-04-27 12:32:55.954381 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-04-27 12:32:56.917356 | rockylinux-9 | changed
2026-04-27 12:32:56.925389 | 
2026-04-27 12:32:56.925480 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-04-27 12:32:57.890334 | rockylinux-9 | changed
2026-04-27 12:32:57.895138 | 
2026-04-27 12:32:57.895211 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-04-27 12:32:57.920226 | rockylinux-9 | skipping: Conditional result was False
2026-04-27 12:32:57.931725 | 
2026-04-27 12:32:57.931858 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-04-27 12:32:58.328278 | rockylinux-9 -> localhost | changed
2026-04-27 12:32:58.344470 | 
2026-04-27 12:32:58.344643 | TASK [add-build-sshkey : Add back temp key]
2026-04-27 12:32:58.669526 | rockylinux-9 -> localhost | Identity added: /var/lib/zuul/builds/5060bc2678044988869cd271d345f3d5/work/5060bc2678044988869cd271d345f3d5_id_rsa (zuul-build-sshkey)
2026-04-27 12:32:58.669825 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.019859
2026-04-27 12:32:58.679174 | 
2026-04-27 12:32:58.679267 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-04-27 12:32:59.081494 | rockylinux-9 | ok
2026-04-27 12:32:59.088387 | 
2026-04-27 12:32:59.088458 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-04-27 12:32:59.113761 | rockylinux-9 | skipping: Conditional result was False
2026-04-27 12:32:59.129398 | 
2026-04-27 12:32:59.129607 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-04-27 12:32:59.564075 | rockylinux-9 | ok
2026-04-27 12:32:59.572301 | 
2026-04-27 12:32:59.572406 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-04-27 12:33:00.527461 | rockylinux-9 | Output suppressed because no_log was given
2026-04-27 12:33:00.539097 | 
2026-04-27 12:33:00.539199 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-04-27 12:33:00.811558 | rockylinux-9 | ok: "logs"
2026-04-27 12:33:00.811916 | rockylinux-9 | ok: All items complete
2026-04-27 12:33:00.811958 | 
2026-04-27 12:33:01.052985 | rockylinux-9 | ok: "artifacts"
2026-04-27 12:33:01.311150 | rockylinux-9 | ok: "docs"
2026-04-27 12:33:01.329214 | 
2026-04-27 12:33:01.329332 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-04-27 12:33:01.609040 | rockylinux-9 | changed: "logs"
2026-04-27 12:33:01.854921 | rockylinux-9 | changed: "artifacts"
2026-04-27 12:33:02.109852 | rockylinux-9 | changed: "docs"
2026-04-27 12:33:02.129905 | 
2026-04-27 12:33:02.130036 | PLAY RECAP
2026-04-27 12:33:02.130086 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-04-27 12:33:02.130121 | rockylinux-9 | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-04-27 12:33:02.130146 | 
2026-04-27 12:33:02.275532 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-04-27 12:33:02.277685 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-04-27 12:33:02.877313 | 
2026-04-27 12:33:02.877446 | PLAY [all]
2026-04-27 12:33:02.888294 | 
2026-04-27 12:33:02.888404 | TASK [setup-uv : Extract archive]
2026-04-27 12:33:05.823167 | rockylinux-9 | changed
2026-04-27 12:33:05.831063 | 
2026-04-27 12:33:05.831178 | TASK [setup-uv : Print version]
2026-04-27 12:33:05.853822 | [rockylinux-9] Waiting on logger
2026-04-27 12:33:06.373150 | rockylinux-9 | ok: Runtime: 0:00:00.015579
2026-04-27 12:33:06.380251 | 
2026-04-27 12:33:06.380296 | PLAY RECAP
2026-04-27 12:33:06.380339 | rockylinux-9 | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-04-27 12:33:06.380363 | 
2026-04-27 12:33:06.504390 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-04-27 12:33:06.506740 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-04-27 12:33:07.118175 | 
2026-04-27 12:33:07.118371 | PLAY [all]
2026-04-27 12:33:07.130721 | 
2026-04-27 12:33:07.130894 | TASK [Copy inventory file for Zuul]
2026-04-27 12:33:08.548652 | rockylinux-9 | changed
2026-04-27 12:33:08.555665 | 
2026-04-27 12:33:08.555760 | TASK [Switch "ansible_host" to private IP]
2026-04-27 12:33:08.925459 | rockylinux-9 | changed: 1 replacements made
2026-04-27 12:33:08.934363 | 
2026-04-27 12:33:08.934507 | TASK [Run Molecule scenario]
2026-04-27 12:33:08.958093 | [rockylinux-9] Waiting on logger
2026-04-27 12:33:17.098143 | [rockylinux-9] Waiting on logger
2026-04-27 12:33:27.270136 | [rockylinux-9] Waiting on logger
2026-04-27 12:33:37.445926 | [rockylinux-9] Waiting on logger
2026-04-27 12:33:47.622258 | [rockylinux-9] Waiting on logger
2026-04-27 12:33:57.802239 | [rockylinux-9] Waiting on logger
2026-04-27 12:34:06.955793 | [rockylinux-9] Waiting on logger
2026-04-27 12:34:17.126152 | [rockylinux-9] Waiting on logger
2026-04-27 12:34:27.302021 | [rockylinux-9] Waiting on logger
2026-04-27 12:34:37.478120 | [rockylinux-9] Waiting on logger
2026-04-27 12:34:47.654067 | [rockylinux-9] Waiting on logger
2026-04-27 12:34:57.830183 | [rockylinux-9] Waiting on logger
2026-04-27 12:35:06.982088 | [rockylinux-9] Waiting on logger
2026-04-27 12:35:17.158137 | [rockylinux-9] Waiting on logger
2026-04-27 12:35:19.407191 | rockylinux-9 | ok: Runtime: 0:02:09.302713
2026-04-27 12:35:19.413807 | 
2026-04-27 12:35:19.413897 | PLAY RECAP
2026-04-27 12:35:19.413949 | rockylinux-9 | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-04-27 12:35:19.413971 | 
2026-04-27 12:35:19.550173 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-04-27 12:35:19.552013 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-04-27 12:35:20.129730 | 
2026-04-27 12:35:20.129869 | PLAY [all]
2026-04-27 12:35:20.140375 | 
2026-04-27 12:35:20.140452 | TASK [fetch-output : Set log path for multiple nodes]
2026-04-27 12:35:20.184873 | rockylinux-9 | skipping: Conditional result was False
2026-04-27 12:35:20.193655 | 
2026-04-27 12:35:20.193788 | TASK [fetch-output : Set log path for single node]
2026-04-27 12:35:20.237554 | rockylinux-9 | ok
2026-04-27 12:35:20.244191 | 
2026-04-27 12:35:20.244273 | LOOP [fetch-output : Ensure local output dirs]
2026-04-27 12:35:20.640901 | rockylinux-9 -> localhost | ok: "/var/lib/zuul/builds/5060bc2678044988869cd271d345f3d5/work/logs"
2026-04-27 12:35:20.836616 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/5060bc2678044988869cd271d345f3d5/work/artifacts"
2026-04-27 12:35:21.037549 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/5060bc2678044988869cd271d345f3d5/work/docs"
2026-04-27 12:35:21.053862 | 
2026-04-27 12:35:21.053989 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-04-27 12:35:21.804592 | rockylinux-9 | changed: .d..t...... ./
2026-04-27 12:35:21.804813 | rockylinux-9 | changed: All items complete
2026-04-27 12:35:21.804841 | 
2026-04-27 12:35:22.333112 | rockylinux-9 | changed: .d..t...... ./
2026-04-27 12:35:22.871190 | rockylinux-9 | changed: .d..t...... ./
2026-04-27 12:35:22.902807 | 
2026-04-27 12:35:22.903003 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-04-27 12:35:23.340044 | rockylinux-9 -> localhost | ok: Item: artifacts Runtime: 0:00:00.008044
2026-04-27 12:35:23.564729 | rockylinux-9 -> localhost | ok: Item: docs Runtime: 0:00:00.008301
2026-04-27 12:35:23.584541 | 
2026-04-27 12:35:23.584701 | PLAY [all]
2026-04-27 12:35:23.600216 | 
2026-04-27 12:35:23.600333 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-04-27 12:35:24.068356 | rockylinux-9 | changed
2026-04-27 12:35:24.075865 | 
2026-04-27 12:35:24.075948 | PLAY RECAP
2026-04-27 12:35:24.076013 | rockylinux-9 | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-04-27 12:35:24.076047 | 
2026-04-27 12:35:24.201292 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-04-27 12:35:24.203681 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-04-27 12:35:24.803010 | 
2026-04-27 12:35:24.803144 | PLAY [localhost]
2026-04-27 12:35:24.813216 | 
2026-04-27 12:35:24.813319 | TASK [Generate Zuul manifest]
2026-04-27 12:35:24.831797 | localhost | ok
2026-04-27 12:35:24.849359 | 
2026-04-27 12:35:24.849505 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-04-27 12:35:25.196560 | localhost | changed
2026-04-27 12:35:25.212545 | 
2026-04-27 12:35:25.374878 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-04-27 12:35:25.797423 | localhost | ok
2026-04-27 12:35:25.807039 | 
2026-04-27 12:35:25.807111 | TASK [Upload logs]
2026-04-27 12:35:25.844405 | localhost | ok
2026-04-27 12:35:25.930399 | 
2026-04-27 12:35:25.930562 | TASK [Set zuul-log-path fact]
2026-04-27 12:35:25.953453 | localhost | ok
2026-04-27 12:35:25.967973 | 
2026-04-27 12:35:25.968130 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-04-27 12:35:26.000036 | localhost | ok
2026-04-27 12:35:26.009312 | 
2026-04-27 12:35:26.009415 | TASK [upload-logs : Create log directories]
2026-04-27 12:35:26.490260 | localhost | changed
2026-04-27 12:35:26.499531 | 
2026-04-27 12:35:26.499791 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-04-27 12:35:26.919279 | localhost -> localhost | ok: Runtime: 0:00:00.005756
2026-04-27 12:35:26.928035 | 
2026-04-27 12:35:26.928128 | TASK [upload-logs : Upload logs to log server]
2026-04-27 12:35:27.383263 | localhost | Output suppressed because no_log was given
2026-04-27 12:35:27.398917 | 
2026-04-27 12:35:27.399176 | LOOP [upload-logs : Compress console log and json output]
2026-04-27 12:35:27.446992 | localhost | skipping: Conditional result was False
2026-04-27 12:35:27.453814 | localhost | skipping: Conditional result was False
2026-04-27 12:35:27.475384 | 
2026-04-27 12:35:27.475569 | LOOP [upload-logs : Upload compressed console log and json output]
2026-04-27 12:35:27.520690 | localhost | skipping: Conditional result was False
2026-04-27 12:35:27.521120 | 
2026-04-27 12:35:27.525193 | localhost | skipping: Conditional result was False
2026-04-27 12:35:27.539025 | 
2026-04-27 12:35:27.539229 | LOOP [upload-logs : Upload console log and json output]