2026-06-04 17:59:58.281951 | Job console starting 2026-06-04 17:59:58.299842 | Updating git repos 2026-06-04 17:59:58.321773 | Cloning repos into workspace 2026-06-04 17:59:58.629907 | Restoring repo states 2026-06-04 17:59:58.636485 | Merging changes 2026-06-04 17:59:58.957803 | Checking out repos 2026-06-04 17:59:59.004499 | Preparing playbooks 2026-06-04 18:00:00.097719 | Running Ansible setup 2026-06-04 18:00:04.329124 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-06-04 18:00:04.929531 | 2026-06-04 18:00:04.929921 | PLAY [localhost] 2026-06-04 18:00:04.937406 | 2026-06-04 18:00:04.937477 | TASK [Gathering Facts] 2026-06-04 18:00:05.827844 | localhost | ok 2026-06-04 18:00:05.839163 | 2026-06-04 18:00:05.839356 | TASK [Setup log path fact] 2026-06-04 18:00:05.859661 | localhost | ok 2026-06-04 18:00:05.872536 | 2026-06-04 18:00:05.872624 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-06-04 18:00:05.904398 | localhost | ok 2026-06-04 18:00:05.913192 | 2026-06-04 18:00:05.913296 | TASK [emit-job-header : Print job information] 2026-06-04 18:00:05.954126 | # Job Information 2026-06-04 18:00:05.954317 | Ansible Version: 2.16.18 2026-06-04 18:00:05.954361 | Job: ansible-collection-containers-molecule-docker-rockylinux-9 2026-06-04 18:00:05.954394 | Pipeline: check 2026-06-04 18:00:05.954423 | Executor: 2d72f0692154 2026-06-04 18:00:05.954452 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/134 2026-06-04 18:00:05.954485 | Event ID: f1cae1c0-603e-11f1-90c0-a491a17f1f0e 2026-06-04 18:00:05.958874 | 2026-06-04 18:00:05.958973 | LOOP [emit-job-header : Print node information] 2026-06-04 18:00:06.057350 | localhost | ok: 2026-06-04 18:00:06.057573 | localhost | # Node Information 2026-06-04 18:00:06.057609 | localhost | Inventory Hostname: rockylinux-9 2026-06-04 18:00:06.057633 | localhost | Hostname: np0000178557 2026-06-04 18:00:06.057657 | localhost | Username: zuul 2026-06-04 18:00:06.057684 | localhost | Distro: Rocky 9.7 2026-06-04 18:00:06.057708 | localhost | Provider: yul1 2026-06-04 18:00:06.057732 | localhost | Region: ca-ymq-1 2026-06-04 18:00:06.057754 | localhost | Label: rockylinux-9 2026-06-04 18:00:06.057776 | localhost | Product Name: OpenStack Nova 2026-06-04 18:00:06.057799 | localhost | Interface IP: 199.19.213.249 2026-06-04 18:00:06.068924 | 2026-06-04 18:00:06.069101 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-06-04 18:00:06.479740 | localhost -> localhost | changed 2026-06-04 18:00:06.488358 | 2026-06-04 18:00:06.488502 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-06-04 18:00:07.336370 | localhost -> localhost | changed 2026-06-04 18:00:07.345828 | 2026-06-04 18:00:07.345898 | PLAY [all] 2026-06-04 18:00:07.353765 | 2026-06-04 18:00:07.353828 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-06-04 18:00:07.590377 | rockylinux-9 -> localhost | ok 2026-06-04 18:00:07.598121 | 2026-06-04 18:00:07.598221 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-06-04 18:00:07.622018 | rockylinux-9 | ok 2026-06-04 18:00:07.636395 | rockylinux-9 | included: /var/lib/zuul/builds/16c166be71304f92a1051ffcb28e8221/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-06-04 18:00:07.643853 | 2026-06-04 18:00:07.643918 | TASK [add-build-sshkey : Create Temp SSH key] 2026-06-04 18:00:08.441435 | rockylinux-9 -> localhost | Generating public/private rsa key pair. 2026-06-04 18:00:08.441695 | rockylinux-9 -> localhost | Your identification has been saved in /var/lib/zuul/builds/16c166be71304f92a1051ffcb28e8221/work/16c166be71304f92a1051ffcb28e8221_id_rsa 2026-06-04 18:00:08.441760 | rockylinux-9 -> localhost | Your public key has been saved in /var/lib/zuul/builds/16c166be71304f92a1051ffcb28e8221/work/16c166be71304f92a1051ffcb28e8221_id_rsa.pub 2026-06-04 18:00:08.441808 | rockylinux-9 -> localhost | The key fingerprint is: 2026-06-04 18:00:08.441854 | rockylinux-9 -> localhost | SHA256:J9/gdbl+fvRv9gT06kZEGiORuyVLEmPq9CoqCok1/2w zuul-build-sshkey 2026-06-04 18:00:08.441918 | rockylinux-9 -> localhost | The key's randomart image is: 2026-06-04 18:00:08.441964 | rockylinux-9 -> localhost | +---[RSA 3072]----+ 2026-06-04 18:00:08.442013 | rockylinux-9 -> localhost | | .o | 2026-06-04 18:00:08.442069 | rockylinux-9 -> localhost | | + o o . | 2026-06-04 18:00:08.442138 | rockylinux-9 -> localhost | | o o o =. | 2026-06-04 18:00:08.442187 | rockylinux-9 -> localhost | | o . + o..o | 2026-06-04 18:00:08.442233 | rockylinux-9 -> localhost | | o o .Soo=..+ .| 2026-06-04 18:00:08.442278 | rockylinux-9 -> localhost | |.o o . .=o+ ..+.| 2026-06-04 18:00:08.442322 | rockylinux-9 -> localhost | |+ . . o ..o.o| 2026-06-04 18:00:08.442365 | rockylinux-9 -> localhost | |o .oE o..*| 2026-06-04 18:00:08.442423 | rockylinux-9 -> localhost | |o... oo .o=B| 2026-06-04 18:00:08.442471 | rockylinux-9 -> localhost | +----[SHA256]-----+ 2026-06-04 18:00:08.442568 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.328966 2026-06-04 18:00:08.448084 | 2026-06-04 18:00:08.448153 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-06-04 18:00:08.476755 | rockylinux-9 | ok 2026-06-04 18:00:08.484682 | rockylinux-9 | included: /var/lib/zuul/builds/16c166be71304f92a1051ffcb28e8221/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-06-04 18:00:08.492176 | 2026-06-04 18:00:08.492245 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-06-04 18:00:08.526914 | rockylinux-9 | skipping: Conditional result was False 2026-06-04 18:00:08.533260 | 2026-06-04 18:00:08.533333 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-06-04 18:00:09.043082 | rockylinux-9 | changed 2026-06-04 18:00:09.048332 | 2026-06-04 18:00:09.048410 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-06-04 18:00:09.305834 | rockylinux-9 | ok 2026-06-04 18:00:09.312846 | 2026-06-04 18:00:09.312943 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-06-04 18:00:10.302239 | rockylinux-9 | changed 2026-06-04 18:00:10.309224 | 2026-06-04 18:00:10.309386 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-06-04 18:00:11.254522 | rockylinux-9 | changed 2026-06-04 18:00:11.260806 | 2026-06-04 18:00:11.260897 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-06-04 18:00:11.285783 | rockylinux-9 | skipping: Conditional result was False 2026-06-04 18:00:11.294205 | 2026-06-04 18:00:11.294309 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-06-04 18:00:11.683676 | rockylinux-9 -> localhost | changed 2026-06-04 18:00:11.694265 | 2026-06-04 18:00:11.694330 | TASK [add-build-sshkey : Add back temp key] 2026-06-04 18:00:11.969835 | rockylinux-9 -> localhost | Identity added: /var/lib/zuul/builds/16c166be71304f92a1051ffcb28e8221/work/16c166be71304f92a1051ffcb28e8221_id_rsa (zuul-build-sshkey) 2026-06-04 18:00:11.970099 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.014087 2026-06-04 18:00:11.976387 | 2026-06-04 18:00:11.976474 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-06-04 18:00:12.366966 | rockylinux-9 | ok 2026-06-04 18:00:12.477360 | 2026-06-04 18:00:12.477544 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-06-04 18:00:12.503190 | rockylinux-9 | skipping: Conditional result was False 2026-06-04 18:00:12.517934 | 2026-06-04 18:00:12.518097 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-06-04 18:00:12.917943 | rockylinux-9 | ok 2026-06-04 18:00:12.924976 | 2026-06-04 18:00:12.925066 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-06-04 18:00:14.057079 | rockylinux-9 | Output suppressed because no_log was given 2026-06-04 18:00:14.077212 | 2026-06-04 18:00:14.077424 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-06-04 18:00:14.334069 | rockylinux-9 | ok: "logs" 2026-06-04 18:00:14.334349 | rockylinux-9 | ok: All items complete 2026-06-04 18:00:14.334384 | 2026-06-04 18:00:14.570770 | rockylinux-9 | ok: "artifacts" 2026-06-04 18:00:14.800875 | rockylinux-9 | ok: "docs" 2026-06-04 18:00:14.815427 | 2026-06-04 18:00:14.815663 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-06-04 18:00:15.092757 | rockylinux-9 | changed: "logs" 2026-06-04 18:00:15.367949 | rockylinux-9 | changed: "artifacts" 2026-06-04 18:00:15.556606 | rockylinux-9 | changed: "docs" 2026-06-04 18:00:15.579351 | 2026-06-04 18:00:15.579465 | PLAY RECAP 2026-06-04 18:00:15.579511 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-06-04 18:00:15.579543 | rockylinux-9 | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-06-04 18:00:15.579565 | 2026-06-04 18:00:16.028139 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-06-04 18:00:16.057927 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-06-04 18:00:16.664165 | 2026-06-04 18:00:16.664355 | PLAY [all] 2026-06-04 18:00:16.684365 | 2026-06-04 18:00:16.684799 | TASK [setup-uv : Extract archive] 2026-06-04 18:00:19.563671 | rockylinux-9 | changed 2026-06-04 18:00:19.569604 | 2026-06-04 18:00:19.569693 | TASK [setup-uv : Print version] 2026-06-04 18:00:19.610173 | [rockylinux-9] Waiting on logger 2026-06-04 18:00:20.122706 | rockylinux-9 | ok: Runtime: 0:00:00.016160 2026-06-04 18:00:20.198513 | 2026-06-04 18:00:20.198627 | TASK [Install Ansible collection siblings] 2026-06-04 18:00:20.686536 | rockylinux-9 | ok: 2026-06-04 18:00:20.686777 | rockylinux-9 | Skipping ansible.posix: no checked-out sibling 2026-06-04 18:00:20.686841 | rockylinux-9 | Skipping community.general: no checked-out sibling 2026-06-04 18:00:20.689955 | 2026-06-04 18:00:20.690000 | PLAY RECAP 2026-06-04 18:00:20.690046 | rockylinux-9 | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-06-04 18:00:20.690068 | 2026-06-04 18:00:20.810797 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-06-04 18:00:20.818692 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-06-04 18:00:21.427103 | 2026-06-04 18:00:21.427218 | PLAY [all] 2026-06-04 18:00:21.439962 | 2026-06-04 18:00:21.440064 | TASK [Copy inventory file for Zuul] 2026-06-04 18:00:22.775291 | rockylinux-9 | changed 2026-06-04 18:00:22.780798 | 2026-06-04 18:00:22.780892 | TASK [Switch "ansible_host" to private IP] 2026-06-04 18:00:23.174956 | rockylinux-9 | changed: 1 replacements made 2026-06-04 18:00:23.179896 | 2026-06-04 18:00:23.179960 | TASK [Run Molecule scenario] 2026-06-04 18:00:23.201946 | [rockylinux-9] Waiting on logger 2026-06-04 18:00:31.334201 | [rockylinux-9] Waiting on logger 2026-06-04 18:00:41.514107 | [rockylinux-9] Waiting on logger 2026-06-04 18:00:50.667901 | [rockylinux-9] Waiting on logger 2026-06-04 18:01:00.869958 | [rockylinux-9] Waiting on logger 2026-06-04 18:01:11.045947 | [rockylinux-9] Waiting on logger 2026-06-04 18:01:21.221671 | [rockylinux-9] Waiting on logger 2026-06-04 18:01:31.398348 | [rockylinux-9] Waiting on logger 2026-06-04 18:01:41.574099 | [rockylinux-9] Waiting on logger 2026-06-04 18:01:50.726030 | [rockylinux-9] Waiting on logger 2026-06-04 18:02:00.901909 | [rockylinux-9] Waiting on logger 2026-06-04 18:02:11.078070 | [rockylinux-9] Waiting on logger 2026-06-04 18:02:21.286213 | [rockylinux-9] Waiting on logger 2026-06-04 18:02:31.461933 | [rockylinux-9] Waiting on logger 2026-06-04 18:02:40.619058 | [rockylinux-9] Waiting on logger 2026-06-04 18:02:50.790036 | [rockylinux-9] Waiting on logger 2026-06-04 18:03:00.965954 | [rockylinux-9] Waiting on logger 2026-06-04 18:03:11.142119 | [rockylinux-9] Waiting on logger 2026-06-04 18:03:21.318003 | [rockylinux-9] Waiting on logger 2026-06-04 18:03:31.493692 | [rockylinux-9] Waiting on logger 2026-06-04 18:03:40.651429 | [rockylinux-9] Waiting on logger 2026-06-04 18:03:45.133576 | rockylinux-9 | ok: Runtime: 0:03:20.936013 2026-06-04 18:03:45.441361 | 2026-06-04 18:03:45.441461 | PLAY RECAP 2026-06-04 18:03:45.441506 | rockylinux-9 | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-06-04 18:03:45.441531 | 2026-06-04 18:03:45.558402 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-06-04 18:03:45.561354 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-06-04 18:03:46.151812 | 2026-06-04 18:03:46.152421 | PLAY [all] 2026-06-04 18:03:46.164627 | 2026-06-04 18:03:46.164706 | TASK [fetch-output : Set log path for multiple nodes] 2026-06-04 18:03:46.210616 | rockylinux-9 | skipping: Conditional result was False 2026-06-04 18:03:46.220832 | 2026-06-04 18:03:46.220925 | TASK [fetch-output : Set log path for single node] 2026-06-04 18:03:46.277231 | rockylinux-9 | ok 2026-06-04 18:03:46.283468 | 2026-06-04 18:03:46.283574 | LOOP [fetch-output : Ensure local output dirs] 2026-06-04 18:03:46.674063 | rockylinux-9 -> localhost | ok: "/var/lib/zuul/builds/16c166be71304f92a1051ffcb28e8221/work/logs" 2026-06-04 18:03:46.896424 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/16c166be71304f92a1051ffcb28e8221/work/artifacts" 2026-06-04 18:03:47.122079 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/16c166be71304f92a1051ffcb28e8221/work/docs" 2026-06-04 18:03:47.138066 | 2026-06-04 18:03:47.138814 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-06-04 18:03:47.856113 | rockylinux-9 | ok 2026-06-04 18:03:47.856340 | rockylinux-9 | changed: All items complete 2026-06-04 18:03:47.856368 | 2026-06-04 18:03:48.374348 | rockylinux-9 | changed: .d..t...... ./ 2026-06-04 18:03:48.891803 | rockylinux-9 | changed: .d..t...... ./ 2026-06-04 18:03:48.918737 | 2026-06-04 18:03:48.918894 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-06-04 18:03:49.371284 | rockylinux-9 -> localhost | ok: Item: artifacts Runtime: 0:00:00.009614 2026-06-04 18:03:49.624475 | rockylinux-9 -> localhost | ok: Item: docs Runtime: 0:00:00.009186 2026-06-04 18:03:49.637633 | 2026-06-04 18:03:49.637778 | PLAY [all] 2026-06-04 18:03:49.644068 | 2026-06-04 18:03:49.644134 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-06-04 18:03:50.103242 | rockylinux-9 | changed 2026-06-04 18:03:50.110076 | 2026-06-04 18:03:50.110126 | PLAY RECAP 2026-06-04 18:03:50.110171 | rockylinux-9 | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-06-04 18:03:50.110194 | 2026-06-04 18:03:50.227021 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-06-04 18:03:50.228242 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-06-04 18:03:50.778361 | 2026-06-04 18:03:50.778727 | PLAY [localhost] 2026-06-04 18:03:50.789009 | 2026-06-04 18:03:50.789082 | TASK [Generate Zuul manifest] 2026-06-04 18:03:50.807046 | localhost | ok 2026-06-04 18:03:50.821018 | 2026-06-04 18:03:50.821100 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-06-04 18:03:51.229417 | localhost | changed 2026-06-04 18:03:51.240028 | 2026-06-04 18:03:51.240105 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-06-04 18:03:51.274961 | localhost | ok 2026-06-04 18:03:51.282343 | 2026-06-04 18:03:51.282412 | TASK [Upload logs] 2026-06-04 18:03:51.303020 | localhost | ok 2026-06-04 18:03:51.367983 | 2026-06-04 18:03:51.368124 | TASK [Set zuul-log-path fact] 2026-06-04 18:03:51.390885 | localhost | ok 2026-06-04 18:03:51.404150 | 2026-06-04 18:03:51.404218 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-06-04 18:03:51.434926 | localhost | ok 2026-06-04 18:03:51.443830 | 2026-06-04 18:03:51.443916 | TASK [upload-logs : Create log directories] 2026-06-04 18:03:51.862445 | localhost | changed 2026-06-04 18:03:51.870435 | 2026-06-04 18:03:51.870596 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-06-04 18:03:52.291071 | localhost -> localhost | ok: Runtime: 0:00:00.006374 2026-06-04 18:03:52.298160 | 2026-06-04 18:03:52.298234 | TASK [upload-logs : Upload logs to log server] 2026-06-04 18:03:52.751096 | localhost | Output suppressed because no_log was given 2026-06-04 18:03:52.756787 | 2026-06-04 18:03:52.756871 | LOOP [upload-logs : Compress console log and json output] 2026-06-04 18:03:52.808919 | localhost | skipping: Conditional result was False 2026-06-04 18:03:52.817131 | localhost | skipping: Conditional result was False 2026-06-04 18:03:52.833815 | 2026-06-04 18:03:52.834020 | LOOP [upload-logs : Upload compressed console log and json output] 2026-06-04 18:03:52.881365 | localhost | skipping: Conditional result was False 2026-06-04 18:03:52.881755 | 2026-06-04 18:03:52.885554 | localhost | skipping: Conditional result was False 2026-06-04 18:03:52.898645 | 2026-06-04 18:03:52.898741 | LOOP [upload-logs : Upload console log and json output]