2026-06-04 19:17:03.477206 | Job console starting
2026-06-04 19:17:03.513111 | Updating git repos
2026-06-04 19:17:03.580520 | Cloning repos into workspace
2026-06-04 19:17:03.656494 | Restoring repo states
2026-06-04 19:17:03.664412 | Merging changes
2026-06-04 19:17:04.353893 | Checking out repos
2026-06-04 19:17:04.388919 | Preparing playbooks
2026-06-04 19:17:08.434389 | Running Ansible setup
2026-06-04 19:17:37.356644 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-06-04 19:17:38.041960 | 
2026-06-04 19:17:38.042137 | PLAY [localhost]
2026-06-04 19:17:38.050688 | 
2026-06-04 19:17:38.050820 | TASK [Gathering Facts]
2026-06-04 19:17:39.170282 | localhost | ok
2026-06-04 19:17:39.180211 | 
2026-06-04 19:17:39.180361 | TASK [Setup log path fact]
2026-06-04 19:17:39.202226 | localhost | ok
2026-06-04 19:17:39.519249 | 
2026-06-04 19:17:39.519361 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-06-04 19:17:39.546850 | localhost | ok
2026-06-04 19:17:39.554886 | 
2026-06-04 19:17:39.554958 | TASK [emit-job-header : Print job information]
2026-06-04 19:17:39.595415 | # Job Information
2026-06-04 19:17:39.595635 | Ansible Version: 2.16.18
2026-06-04 19:17:39.595705 | Job: ansible-collection-containers-molecule-nerdctl-rockylinux-9
2026-06-04 19:17:39.595754 | Pipeline: check
2026-06-04 19:17:39.595799 | Executor: 2d72f0692154
2026-06-04 19:17:39.595843 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/134
2026-06-04 19:17:39.595891 | Event ID: d978afa2-6049-11f1-9023-56732c62b08e
2026-06-04 19:17:39.600445 | 
2026-06-04 19:17:39.600517 | LOOP [emit-job-header : Print node information]
2026-06-04 19:17:39.713128 | localhost | ok:
2026-06-04 19:17:39.713438 | localhost | # Node Information
2026-06-04 19:17:39.713492 | localhost | Inventory Hostname: rockylinux-9
2026-06-04 19:17:39.713524 | localhost | Hostname: np0000178589
2026-06-04 19:17:39.713551 | localhost | Username: zuul
2026-06-04 19:17:39.713579 | localhost | Distro: Rocky 9.7
2026-06-04 19:17:39.713605 | localhost | Provider: yul1
2026-06-04 19:17:39.713633 | localhost | Region: ca-ymq-1
2026-06-04 19:17:39.713658 | localhost | Label: rockylinux-9
2026-06-04 19:17:39.713680 | localhost | Product Name: OpenStack Nova
2026-06-04 19:17:39.713703 | localhost | Interface IP: 199.19.213.88
2026-06-04 19:17:39.730019 | 
2026-06-04 19:17:39.730172 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-06-04 19:17:40.865876 | localhost -> localhost | changed
2026-06-04 19:17:40.874151 | 
2026-06-04 19:17:40.874302 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-06-04 19:17:42.144252 | localhost -> localhost | changed
2026-06-04 19:17:42.156363 | 
2026-06-04 19:17:42.156476 | PLAY [all]
2026-06-04 19:17:42.164519 | 
2026-06-04 19:17:42.164635 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-06-04 19:17:42.402468 | rockylinux-9 -> localhost | ok
2026-06-04 19:17:42.410353 | 
2026-06-04 19:17:42.410456 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-06-04 19:17:42.442020 | rockylinux-9 | ok
2026-06-04 19:17:42.459859 | rockylinux-9 | included: /var/lib/zuul/builds/3c9d8c5f859e4862839a02ff1ed67cfa/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-06-04 19:17:42.466124 | 
2026-06-04 19:17:42.466208 | TASK [add-build-sshkey : Create Temp SSH key]
2026-06-04 19:17:44.043113 | rockylinux-9 -> localhost | Generating public/private rsa key pair.
2026-06-04 19:17:44.043346 | rockylinux-9 -> localhost | Your identification has been saved in /var/lib/zuul/builds/3c9d8c5f859e4862839a02ff1ed67cfa/work/3c9d8c5f859e4862839a02ff1ed67cfa_id_rsa
2026-06-04 19:17:44.043391 | rockylinux-9 -> localhost | Your public key has been saved in /var/lib/zuul/builds/3c9d8c5f859e4862839a02ff1ed67cfa/work/3c9d8c5f859e4862839a02ff1ed67cfa_id_rsa.pub
2026-06-04 19:17:44.043458 | rockylinux-9 -> localhost | The key fingerprint is:
2026-06-04 19:17:44.043492 | rockylinux-9 -> localhost | SHA256:+p7HabxxI69xK3mdVgbgEWGl9T2+p0wifaDEII7TqXw zuul-build-sshkey
2026-06-04 19:17:44.043543 | rockylinux-9 -> localhost | The key's randomart image is:
2026-06-04 19:17:44.043574 | rockylinux-9 -> localhost | +---[RSA 3072]----+
2026-06-04 19:17:44.043611 | rockylinux-9 -> localhost | |            ++o  |
2026-06-04 19:17:44.043642 | rockylinux-9 -> localhost | |           .oo ..|
2026-06-04 19:17:44.043672 | rockylinux-9 -> localhost | |      . .  ..o .o|
2026-06-04 19:17:44.043702 | rockylinux-9 -> localhost | |     + o o  . o .|
2026-06-04 19:17:44.043732 | rockylinux-9 -> localhost | |    o + S o .  o |
2026-06-04 19:17:44.043765 | rockylinux-9 -> localhost | |   . o . . o .  +|
2026-06-04 19:17:44.043803 | rockylinux-9 -> localhost | |    o E  o*o*.o=.|
2026-06-04 19:17:44.043837 | rockylinux-9 -> localhost | |     . . .OO.B+..|
2026-06-04 19:17:44.043871 | rockylinux-9 -> localhost | |       .+oo=o.o  |
2026-06-04 19:17:44.043902 | rockylinux-9 -> localhost | +----[SHA256]-----+
2026-06-04 19:17:44.043970 | rockylinux-9 -> localhost | ok: Runtime: 0:00:01.140852
2026-06-04 19:17:44.050028 | 
2026-06-04 19:17:44.050096 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-06-04 19:17:44.079952 | rockylinux-9 | ok
2026-06-04 19:17:44.089233 | rockylinux-9 | included: /var/lib/zuul/builds/3c9d8c5f859e4862839a02ff1ed67cfa/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-06-04 19:17:44.096286 | 
2026-06-04 19:17:44.096352 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-06-04 19:17:44.120894 | rockylinux-9 | skipping: Conditional result was False
2026-06-04 19:17:44.130694 | 
2026-06-04 19:17:44.130775 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-06-04 19:17:44.615009 | rockylinux-9 | changed
2026-06-04 19:17:44.621567 | 
2026-06-04 19:17:44.621638 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-06-04 19:17:44.875305 | rockylinux-9 | ok
2026-06-04 19:17:44.940120 | 
2026-06-04 19:17:44.940276 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-06-04 19:17:45.866683 | rockylinux-9 | changed
2026-06-04 19:17:45.873209 | 
2026-06-04 19:17:45.873274 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-06-04 19:17:46.766673 | rockylinux-9 | changed
2026-06-04 19:17:46.771284 | 
2026-06-04 19:17:46.771357 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-06-04 19:17:46.796781 | rockylinux-9 | skipping: Conditional result was False
2026-06-04 19:17:46.804794 | 
2026-06-04 19:17:46.804905 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-06-04 19:17:47.197921 | rockylinux-9 -> localhost | changed
2026-06-04 19:17:47.207927 | 
2026-06-04 19:17:47.208026 | TASK [add-build-sshkey : Add back temp key]
2026-06-04 19:17:47.611445 | rockylinux-9 -> localhost | Identity added: /var/lib/zuul/builds/3c9d8c5f859e4862839a02ff1ed67cfa/work/3c9d8c5f859e4862839a02ff1ed67cfa_id_rsa (zuul-build-sshkey)
2026-06-04 19:17:47.611663 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.013929
2026-06-04 19:17:47.616812 | 
2026-06-04 19:17:47.616906 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-06-04 19:17:48.005150 | rockylinux-9 | ok
2026-06-04 19:17:48.010631 | 
2026-06-04 19:17:48.010698 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-06-04 19:17:48.035978 | rockylinux-9 | skipping: Conditional result was False
2026-06-04 19:17:48.049920 | 
2026-06-04 19:17:48.049994 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-06-04 19:17:48.485756 | rockylinux-9 | ok
2026-06-04 19:17:48.651488 | 
2026-06-04 19:17:48.651595 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-06-04 19:17:49.649305 | rockylinux-9 | Output suppressed because no_log was given
2026-06-04 19:17:49.682659 | 
2026-06-04 19:17:49.682838 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-06-04 19:17:49.930918 | rockylinux-9 | ok: "logs"
2026-06-04 19:17:49.931195 | rockylinux-9 | ok: All items complete
2026-06-04 19:17:49.931226 | 
2026-06-04 19:17:50.153464 | rockylinux-9 | ok: "artifacts"
2026-06-04 19:17:50.380654 | rockylinux-9 | ok: "docs"
2026-06-04 19:17:50.426553 | 
2026-06-04 19:17:50.426788 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-06-04 19:17:50.698059 | rockylinux-9 | changed: "logs"
2026-06-04 19:17:50.988147 | rockylinux-9 | changed: "artifacts"
2026-06-04 19:17:51.173946 | rockylinux-9 | changed: "docs"
2026-06-04 19:17:51.194692 | 
2026-06-04 19:17:51.194838 | PLAY RECAP
2026-06-04 19:17:51.194887 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-06-04 19:17:51.194919 | rockylinux-9 | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-06-04 19:17:51.194939 | 
2026-06-04 19:17:51.375282 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-06-04 19:17:51.377921 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-06-04 19:17:52.060587 | 
2026-06-04 19:17:52.060855 | PLAY [all]
2026-06-04 19:17:52.074940 | 
2026-06-04 19:17:52.075098 | TASK [setup-uv : Extract archive]
2026-06-04 19:17:54.930393 | rockylinux-9 | changed
2026-06-04 19:17:54.936140 | 
2026-06-04 19:17:54.936227 | TASK [setup-uv : Print version]
2026-06-04 19:17:54.962589 | [rockylinux-9] Waiting on logger
2026-06-04 19:17:55.511583 | rockylinux-9 | ok: Runtime: 0:00:00.016173
2026-06-04 19:17:55.604784 | 
2026-06-04 19:17:55.604926 | TASK [Install Ansible collection siblings]
2026-06-04 19:17:56.064238 | rockylinux-9 | ok:
2026-06-04 19:17:56.275628 | rockylinux-9 | Skipping ansible.posix: no checked-out sibling
2026-06-04 19:17:56.275840 | rockylinux-9 | Skipping community.general: no checked-out sibling
2026-06-04 19:17:56.284698 | 
2026-06-04 19:17:56.284830 | PLAY RECAP
2026-06-04 19:17:56.285031 | rockylinux-9 | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-06-04 19:17:56.285105 | 
2026-06-04 19:17:56.495299 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-06-04 19:17:56.658586 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-06-04 19:17:57.302254 | 
2026-06-04 19:17:57.302414 | PLAY [all]
2026-06-04 19:17:57.314921 | 
2026-06-04 19:17:57.315039 | TASK [Copy inventory file for Zuul]
2026-06-04 19:17:58.659766 | rockylinux-9 | changed
2026-06-04 19:17:58.666793 | 
2026-06-04 19:17:58.666983 | TASK [Switch "ansible_host" to private IP]
2026-06-04 19:17:59.067323 | rockylinux-9 | changed: 1 replacements made
2026-06-04 19:17:59.197933 | 
2026-06-04 19:17:59.198051 | TASK [Run Molecule scenario]
2026-06-04 19:17:59.226332 | [rockylinux-9] Waiting on logger
2026-06-04 19:18:06.601897 | [rockylinux-9] Waiting on logger
2026-06-04 19:18:16.778060 | [rockylinux-9] Waiting on logger
2026-06-04 19:18:26.150098 | [rockylinux-9] Waiting on logger
2026-06-04 19:18:36.326073 | [rockylinux-9] Waiting on logger
2026-06-04 19:18:46.662230 | [rockylinux-9] Waiting on logger
2026-06-04 19:18:56.838024 | [rockylinux-9] Waiting on logger
2026-06-04 19:19:06.566227 | [rockylinux-9] Waiting on logger
2026-06-04 19:19:16.742149 | [rockylinux-9] Waiting on logger
2026-06-04 19:19:26.922629 | [rockylinux-9] Waiting on logger
2026-06-04 19:19:36.075742 | [rockylinux-9] Waiting on logger
2026-06-04 19:19:46.246263 | [rockylinux-9] Waiting on logger
2026-06-04 19:19:56.422225 | [rockylinux-9] Waiting on logger
2026-06-04 19:20:00.907951 | rockylinux-9 | ok: Runtime: 0:02:00.987133
2026-06-04 19:20:00.924973 | 
2026-06-04 19:20:00.925045 | PLAY RECAP
2026-06-04 19:20:00.925105 | rockylinux-9 | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-06-04 19:20:00.925137 | 
2026-06-04 19:20:01.045123 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-06-04 19:20:01.047950 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-06-04 19:20:01.677570 | 
2026-06-04 19:20:01.678015 | PLAY [all]
2026-06-04 19:20:01.689571 | 
2026-06-04 19:20:01.689698 | TASK [fetch-output : Set log path for multiple nodes]
2026-06-04 19:20:01.735875 | rockylinux-9 | skipping: Conditional result was False
2026-06-04 19:20:01.743013 | 
2026-06-04 19:20:01.743573 | TASK [fetch-output : Set log path for single node]
2026-06-04 19:20:01.788638 | rockylinux-9 | ok
2026-06-04 19:20:01.795227 | 
2026-06-04 19:20:01.795366 | LOOP [fetch-output : Ensure local output dirs]
2026-06-04 19:20:02.230865 | rockylinux-9 -> localhost | ok: "/var/lib/zuul/builds/3c9d8c5f859e4862839a02ff1ed67cfa/work/logs"
2026-06-04 19:20:02.447176 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/3c9d8c5f859e4862839a02ff1ed67cfa/work/artifacts"
2026-06-04 19:20:02.655024 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/3c9d8c5f859e4862839a02ff1ed67cfa/work/docs"
2026-06-04 19:20:02.680460 | 
2026-06-04 19:20:02.680575 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-06-04 19:20:03.366046 | rockylinux-9 | changed: .d..t...... ./
2026-06-04 19:20:03.366323 | rockylinux-9 | changed: All items complete
2026-06-04 19:20:03.366371 | 
2026-06-04 19:20:03.886164 | rockylinux-9 | changed: .d..t...... ./
2026-06-04 19:20:04.408081 | rockylinux-9 | changed: .d..t...... ./
2026-06-04 19:20:04.431829 | 
2026-06-04 19:20:04.431977 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-06-04 19:20:04.939920 | rockylinux-9 -> localhost | ok: Item: artifacts Runtime: 0:00:00.007883
2026-06-04 19:20:05.179586 | rockylinux-9 -> localhost | ok: Item: docs Runtime: 0:00:00.008539
2026-06-04 19:20:05.198971 | 
2026-06-04 19:20:05.199108 | PLAY [all]
2026-06-04 19:20:05.204767 | 
2026-06-04 19:20:05.204833 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-06-04 19:20:05.673938 | rockylinux-9 | changed
2026-06-04 19:20:05.680980 | 
2026-06-04 19:20:05.681029 | PLAY RECAP
2026-06-04 19:20:05.681073 | rockylinux-9 | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-06-04 19:20:05.681094 | 
2026-06-04 19:20:05.822688 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-06-04 19:20:05.825041 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-06-04 19:20:06.424751 | 
2026-06-04 19:20:06.424879 | PLAY [localhost]
2026-06-04 19:20:06.435680 | 
2026-06-04 19:20:06.435783 | TASK [Generate Zuul manifest]
2026-06-04 19:20:06.469314 | localhost | ok
2026-06-04 19:20:06.487435 | 
2026-06-04 19:20:06.487535 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-06-04 19:20:07.021211 | localhost | changed
2026-06-04 19:20:07.034722 | 
2026-06-04 19:20:07.034824 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-06-04 19:20:07.070456 | localhost | ok
2026-06-04 19:20:07.076978 | 
2026-06-04 19:20:07.077045 | TASK [Upload logs]
2026-06-04 19:20:07.098498 | localhost | ok
2026-06-04 19:20:07.157906 | 
2026-06-04 19:20:07.158056 | TASK [Set zuul-log-path fact]
2026-06-04 19:20:07.179382 | localhost | ok
2026-06-04 19:20:07.193403 | 
2026-06-04 19:20:07.193498 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-06-04 19:20:07.226707 | localhost | ok
2026-06-04 19:20:07.236776 | 
2026-06-04 19:20:07.236979 | TASK [upload-logs : Create log directories]
2026-06-04 19:20:07.680660 | localhost | changed
2026-06-04 19:20:07.690043 | 
2026-06-04 19:20:07.690119 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-06-04 19:20:08.169411 | localhost -> localhost | ok: Runtime: 0:00:00.005687
2026-06-04 19:20:08.179076 | 
2026-06-04 19:20:08.179352 | TASK [upload-logs : Upload logs to log server]
2026-06-04 19:20:08.651265 | localhost | Output suppressed because no_log was given
2026-06-04 19:20:08.658120 | 
2026-06-04 19:20:08.658194 | LOOP [upload-logs : Compress console log and json output]
2026-06-04 19:20:08.704627 | localhost | skipping: Conditional result was False
2026-06-04 19:20:08.711672 | localhost | skipping: Conditional result was False
2026-06-04 19:20:08.721155 | 
2026-06-04 19:20:08.721354 | LOOP [upload-logs : Upload compressed console log and json output]
2026-06-04 19:20:08.766278 | localhost | skipping: Conditional result was False
2026-06-04 19:20:08.766664 | 
2026-06-04 19:20:08.770439 | localhost | skipping: Conditional result was False
2026-06-04 19:20:08.787268 | 
2026-06-04 19:20:08.787448 | LOOP [upload-logs : Upload console log and json output]