2026-04-04 10:11:24.719248 | Job console starting
2026-04-04 10:11:24.778741 | Updating git repos
2026-04-04 10:11:24.808769 | Cloning repos into workspace
2026-04-04 10:11:24.911003 | Restoring repo states
2026-04-04 10:11:24.923917 | Merging changes
2026-04-04 10:11:25.192932 | Checking out repos
2026-04-04 10:11:26.465805 | Preparing playbooks
2026-04-04 10:11:29.304294 | Running Ansible setup
2026-04-04 10:11:41.630158 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-04-04 10:11:42.332466 | 
2026-04-04 10:11:42.639603 | PLAY [localhost]
2026-04-04 10:11:42.649050 | 
2026-04-04 10:11:42.649165 | TASK [Gathering Facts]
2026-04-04 10:11:48.856887 | localhost | ok
2026-04-04 10:11:48.865043 | 
2026-04-04 10:11:48.865160 | TASK [Setup log path fact]
2026-04-04 10:11:48.885376 | localhost | ok
2026-04-04 10:11:48.897127 | 
2026-04-04 10:11:48.897251 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-04-04 10:11:48.926060 | localhost | ok
2026-04-04 10:11:48.933118 | 
2026-04-04 10:11:48.933188 | TASK [emit-job-header : Print job information]
2026-04-04 10:11:48.971924 | # Job Information
2026-04-04 10:11:48.972141 | Ansible Version: 2.16.16
2026-04-04 10:11:48.972212 | Job: ansible-collection-containers-molecule-nerdctl-rockylinux-9
2026-04-04 10:11:48.972263 | Pipeline: check
2026-04-04 10:11:48.972309 | Executor: 0a8996d2b663
2026-04-04 10:11:48.972354 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/106
2026-04-04 10:11:48.972404 | Event ID: f6253a90-2fbf-11f1-9abc-054f17db168c
2026-04-04 10:11:48.976696 | 
2026-04-04 10:11:48.976789 | LOOP [emit-job-header : Print node information]
2026-04-04 10:11:49.096981 | localhost | ok:
2026-04-04 10:11:50.356621 | localhost | # Node Information
2026-04-04 10:11:50.356790 | localhost | Inventory Hostname: rockylinux-9
2026-04-04 10:11:50.356847 | localhost | Hostname: np0000163499
2026-04-04 10:11:50.356893 | localhost | Username: zuul
2026-04-04 10:11:50.356943 | localhost | Distro: Rocky 9.7
2026-04-04 10:11:50.357014 | localhost | Provider: yul1
2026-04-04 10:11:50.357057 | localhost | Region: ca-ymq-1
2026-04-04 10:11:50.357099 | localhost | Label: rockylinux-9
2026-04-04 10:11:50.357139 | localhost | Product Name: OpenStack Nova
2026-04-04 10:11:50.357198 | localhost | Interface IP: 199.19.213.99
2026-04-04 10:11:50.373622 | 
2026-04-04 10:11:50.373749 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-04-04 10:11:50.814091 | localhost -> localhost | changed
2026-04-04 10:11:50.820711 | 
2026-04-04 10:11:50.820781 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-04-04 10:11:52.356052 | localhost -> localhost | changed
2026-04-04 10:11:52.363060 | 
2026-04-04 10:11:52.363338 | PLAY [all]
2026-04-04 10:11:52.370865 | 
2026-04-04 10:11:52.370930 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-04-04 10:11:52.739208 | rockylinux-9 -> localhost | ok
2026-04-04 10:11:52.744779 | 
2026-04-04 10:11:52.744846 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-04-04 10:11:52.784580 | rockylinux-9 | ok
2026-04-04 10:11:52.799430 | rockylinux-9 | included: /var/lib/zuul/builds/76a3c6759fc4400ba1305d8a795f8277/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-04-04 10:11:52.805792 | 
2026-04-04 10:11:52.805861 | TASK [add-build-sshkey : Create Temp SSH key]
2026-04-04 10:11:54.234560 | rockylinux-9 -> localhost | Generating public/private rsa key pair.
2026-04-04 10:11:54.234755 | rockylinux-9 -> localhost | Your identification has been saved in /var/lib/zuul/builds/76a3c6759fc4400ba1305d8a795f8277/work/76a3c6759fc4400ba1305d8a795f8277_id_rsa
2026-04-04 10:11:54.234788 | rockylinux-9 -> localhost | Your public key has been saved in /var/lib/zuul/builds/76a3c6759fc4400ba1305d8a795f8277/work/76a3c6759fc4400ba1305d8a795f8277_id_rsa.pub
2026-04-04 10:11:54.234812 | rockylinux-9 -> localhost | The key fingerprint is:
2026-04-04 10:11:54.234834 | rockylinux-9 -> localhost | SHA256:/n6tcYqxf2wZwK5KtyTp2YIj1WET36BwMO3m4gwtAlQ zuul-build-sshkey
2026-04-04 10:11:54.234869 | rockylinux-9 -> localhost | The key's randomart image is:
2026-04-04 10:11:54.234891 | rockylinux-9 -> localhost | +---[RSA 3072]----+
2026-04-04 10:11:54.234917 | rockylinux-9 -> localhost | |  .E  oo         |
2026-04-04 10:11:54.234939 | rockylinux-9 -> localhost | | .    ..+ .      |
2026-04-04 10:11:54.234960 | rockylinux-9 -> localhost | |.      + + +     |
2026-04-04 10:11:54.234980 | rockylinux-9 -> localhost | |.       B . +    |
2026-04-04 10:11:54.235001 | rockylinux-9 -> localhost | | .   . =So . .   |
2026-04-04 10:11:54.235021 | rockylinux-9 -> localhost | |  . o +.o.  . .  |
2026-04-04 10:11:54.235040 | rockylinux-9 -> localhost | |   . * o= +..o.o |
2026-04-04 10:11:54.235059 | rockylinux-9 -> localhost | |    . =o.B.=.+*  |
2026-04-04 10:11:54.235083 | rockylinux-9 -> localhost | |     . .++Bo++   |
2026-04-04 10:11:54.235104 | rockylinux-9 -> localhost | +----[SHA256]-----+
2026-04-04 10:11:54.235175 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.914667
2026-04-04 10:11:54.246488 | 
2026-04-04 10:11:54.246697 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-04-04 10:11:54.281969 | rockylinux-9 | ok
2026-04-04 10:11:54.290704 | rockylinux-9 | included: /var/lib/zuul/builds/76a3c6759fc4400ba1305d8a795f8277/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-04-04 10:11:54.300207 | 
2026-04-04 10:11:54.300291 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-04-04 10:11:54.336360 | rockylinux-9 | skipping: Conditional result was False
2026-04-04 10:11:54.346893 | 
2026-04-04 10:11:54.347016 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-04-04 10:11:54.824348 | rockylinux-9 | changed
2026-04-04 10:11:54.829672 | 
2026-04-04 10:11:54.829737 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-04-04 10:11:55.085822 | rockylinux-9 | ok
2026-04-04 10:11:55.091639 | 
2026-04-04 10:11:55.091702 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-04-04 10:11:56.036748 | rockylinux-9 | changed
2026-04-04 10:11:56.448336 | 
2026-04-04 10:11:56.453309 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-04-04 10:11:57.394006 | rockylinux-9 | changed
2026-04-04 10:11:57.434449 | 
2026-04-04 10:11:57.434630 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-04-04 10:11:57.460047 | rockylinux-9 | skipping: Conditional result was False
2026-04-04 10:11:57.467520 | 
2026-04-04 10:11:57.467651 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-04-04 10:11:57.909933 | rockylinux-9 -> localhost | changed
2026-04-04 10:11:57.925357 | 
2026-04-04 10:11:57.925532 | TASK [add-build-sshkey : Add back temp key]
2026-04-04 10:11:58.256979 | rockylinux-9 -> localhost | Identity added: /var/lib/zuul/builds/76a3c6759fc4400ba1305d8a795f8277/work/76a3c6759fc4400ba1305d8a795f8277_id_rsa (zuul-build-sshkey)
2026-04-04 10:11:58.257360 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.013019
2026-04-04 10:11:58.267337 | 
2026-04-04 10:11:58.267418 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-04-04 10:11:58.741871 | rockylinux-9 | ok
2026-04-04 10:11:58.746818 | 
2026-04-04 10:11:58.746890 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-04-04 10:11:58.771452 | rockylinux-9 | skipping: Conditional result was False
2026-04-04 10:11:58.789907 | 
2026-04-04 10:11:58.861272 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-04-04 10:11:59.310850 | rockylinux-9 | ok
2026-04-04 10:11:59.316146 | 
2026-04-04 10:11:59.316209 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-04-04 10:12:00.263335 | rockylinux-9 | Output suppressed because no_log was given
2026-04-04 10:12:00.276316 | 
2026-04-04 10:12:00.276436 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-04-04 10:12:00.515255 | rockylinux-9 | ok: "logs"
2026-04-04 10:12:00.515528 | rockylinux-9 | ok: All items complete
2026-04-04 10:12:00.515559 | 
2026-04-04 10:12:00.723687 | rockylinux-9 | ok: "artifacts"
2026-04-04 10:12:00.932269 | rockylinux-9 | ok: "docs"
2026-04-04 10:12:00.951894 | 
2026-04-04 10:12:00.952054 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-04-04 10:12:01.217633 | rockylinux-9 | changed: "logs"
2026-04-04 10:12:01.484368 | rockylinux-9 | changed: "artifacts"
2026-04-04 10:12:01.711727 | rockylinux-9 | changed: "docs"
2026-04-04 10:12:01.724865 | 
2026-04-04 10:12:01.724989 | PLAY RECAP
2026-04-04 10:12:01.725040 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-04-04 10:12:01.725071 | rockylinux-9 | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-04-04 10:12:01.725091 | 
2026-04-04 10:12:01.847246 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-04-04 10:12:01.849367 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-04-04 10:12:02.454633 | 
2026-04-04 10:12:02.454761 | PLAY [all]
2026-04-04 10:12:02.465408 | 
2026-04-04 10:12:02.465489 | TASK [setup-uv : Extract archive]
2026-04-04 10:12:05.105241 | rockylinux-9 | changed
2026-04-04 10:12:05.112474 | 
2026-04-04 10:12:05.112563 | TASK [setup-uv : Print version]
2026-04-04 10:12:05.136212 | [rockylinux-9] Waiting on logger
2026-04-04 10:12:05.654274 | rockylinux-9 | ok: Runtime: 0:00:00.013522
2026-04-04 10:12:05.660345 | 
2026-04-04 10:12:05.660399 | PLAY RECAP
2026-04-04 10:12:05.660447 | rockylinux-9 | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-04-04 10:12:05.660471 | 
2026-04-04 10:12:06.184476 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-04-04 10:12:06.185576 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-04-04 10:12:06.834756 | 
2026-04-04 10:12:06.835378 | PLAY [all]
2026-04-04 10:12:06.846269 | 
2026-04-04 10:12:06.846351 | TASK [Copy inventory file for Zuul]
2026-04-04 10:12:08.488491 | rockylinux-9 | changed
2026-04-04 10:12:08.493545 | 
2026-04-04 10:12:08.493688 | TASK [Switch "ansible_host" to private IP]
2026-04-04 10:12:08.833919 | rockylinux-9 | changed: 1 replacements made
2026-04-04 10:12:08.841953 | 
2026-04-04 10:12:08.842021 | TASK [Run Molecule scenario]
2026-04-04 10:12:08.862512 | [rockylinux-9] Waiting on logger
2026-04-04 10:12:16.997996 | [rockylinux-9] Waiting on logger
2026-04-04 10:12:26.184405 | [rockylinux-9] Waiting on logger
2026-04-04 10:12:36.358092 | [rockylinux-9] Waiting on logger
2026-04-04 10:12:41.424076 | rockylinux-9 | ERROR
2026-04-04 10:12:41.821789 | rockylinux-9 | {
2026-04-04 10:12:41.821963 | rockylinux-9 |   "delta": "0:00:31.775020",
2026-04-04 10:12:41.822117 | rockylinux-9 |   "end": "2026-04-04 10:12:41.303590",
2026-04-04 10:12:41.822206 | rockylinux-9 |   "msg": "non-zero return code",
2026-04-04 10:12:41.822271 | rockylinux-9 |   "rc": 2,
2026-04-04 10:12:41.822325 | rockylinux-9 |   "start": "2026-04-04 10:12:09.528570"
2026-04-04 10:12:41.822384 | rockylinux-9 | }
failure
2026-04-04 10:12:41.826173 | 
2026-04-04 10:12:41.826251 | PLAY RECAP
2026-04-04 10:12:41.826315 | rockylinux-9 | ok: 2 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-04-04 10:12:41.826351 | 
2026-04-04 10:12:41.988272 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-04-04 10:12:41.992692 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-04-04 10:12:42.575817 | 
2026-04-04 10:12:42.575941 | PLAY [all]
2026-04-04 10:12:42.586273 | 
2026-04-04 10:12:42.586350 | TASK [fetch-output : Set log path for multiple nodes]
2026-04-04 10:12:42.631496 | rockylinux-9 | skipping: Conditional result was False
2026-04-04 10:12:42.640367 | 
2026-04-04 10:12:42.640508 | TASK [fetch-output : Set log path for single node]
2026-04-04 10:12:42.681545 | rockylinux-9 | ok
2026-04-04 10:12:42.689040 | 
2026-04-04 10:12:42.689143 | LOOP [fetch-output : Ensure local output dirs]
2026-04-04 10:12:43.071194 | rockylinux-9 -> localhost | ok: "/var/lib/zuul/builds/76a3c6759fc4400ba1305d8a795f8277/work/logs"
2026-04-04 10:12:43.282853 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/76a3c6759fc4400ba1305d8a795f8277/work/artifacts"
2026-04-04 10:12:43.494334 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/76a3c6759fc4400ba1305d8a795f8277/work/docs"
2026-04-04 10:12:43.508683 | 
2026-04-04 10:12:43.508821 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-04-04 10:12:44.263597 | rockylinux-9 | ok
2026-04-04 10:12:44.264013 | rockylinux-9 | changed: All items complete
2026-04-04 10:12:44.264062 | 
2026-04-04 10:12:44.862495 | rockylinux-9 | changed: .d..t...... ./
2026-04-04 10:12:45.392402 | rockylinux-9 | changed: .d..t...... ./
2026-04-04 10:12:45.414363 | 
2026-04-04 10:12:45.414793 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-04-04 10:12:49.536264 | rockylinux-9 -> localhost | ok: Item: artifacts Runtime: 0:00:03.493095
2026-04-04 10:12:49.758559 | rockylinux-9 -> localhost | ok: Item: docs Runtime: 0:00:00.007423
2026-04-04 10:12:49.783712 | 
2026-04-04 10:12:49.783814 | PLAY [all]
2026-04-04 10:12:49.789188 | 
2026-04-04 10:12:49.789257 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-04-04 10:12:50.266648 | rockylinux-9 | changed
2026-04-04 10:12:50.272942 | 
2026-04-04 10:12:50.273016 | PLAY RECAP
2026-04-04 10:12:50.273063 | rockylinux-9 | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-04-04 10:12:50.273099 | 
2026-04-04 10:12:50.429826 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-04-04 10:12:50.895431 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-04-04 10:12:51.468719 | 
2026-04-04 10:12:51.468851 | PLAY [localhost]
2026-04-04 10:12:51.485974 | 
2026-04-04 10:12:51.486308 | TASK [Generate Zuul manifest]
2026-04-04 10:12:51.510163 | localhost | ok
2026-04-04 10:12:51.529432 | 
2026-04-04 10:12:51.529513 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-04-04 10:12:51.891853 | localhost | changed
2026-04-04 10:12:51.905664 | 
2026-04-04 10:12:51.905776 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-04-04 10:12:51.939001 | localhost | ok
2026-04-04 10:12:51.945556 | 
2026-04-04 10:12:51.945624 | TASK [Upload logs]
2026-04-04 10:12:51.967047 | localhost | ok
2026-04-04 10:12:52.026507 | 
2026-04-04 10:12:52.026640 | TASK [Set zuul-log-path fact]
2026-04-04 10:12:52.058417 | localhost | ok
2026-04-04 10:12:52.071825 | 
2026-04-04 10:12:52.071897 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-04-04 10:12:52.103497 | localhost | ok
2026-04-04 10:12:52.113367 | 
2026-04-04 10:12:52.113454 | TASK [upload-logs : Create log directories]
2026-04-04 10:12:54.475800 | localhost | changed
2026-04-04 10:12:54.481933 | 
2026-04-04 10:12:54.482013 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-04-04 10:12:54.939636 | localhost -> localhost | ok: Runtime: 0:00:00.005848
2026-04-04 10:12:54.946097 | 
2026-04-04 10:12:54.946184 | TASK [upload-logs : Upload logs to log server]
2026-04-04 10:12:55.507182 | localhost | Output suppressed because no_log was given
2026-04-04 10:12:55.512284 | 
2026-04-04 10:12:55.512375 | LOOP [upload-logs : Compress console log and json output]
2026-04-04 10:12:55.561395 | localhost | skipping: Conditional result was False
2026-04-04 10:12:55.571870 | localhost | skipping: Conditional result was False
2026-04-04 10:12:55.588104 | 
2026-04-04 10:12:55.588332 | LOOP [upload-logs : Upload compressed console log and json output]
2026-04-04 10:12:55.633959 | localhost | skipping: Conditional result was False
2026-04-04 10:12:55.634272 | 
2026-04-04 10:12:55.638998 | localhost | skipping: Conditional result was False
2026-04-04 10:12:55.660939 | 
2026-04-04 10:12:55.661269 | LOOP [upload-logs : Upload console log and json output]