2026-06-03 01:26:40.940963 | Job console starting 2026-06-03 01:26:40.972101 | Updating git repos 2026-06-03 01:26:41.013821 | Cloning repos into workspace 2026-06-03 01:26:41.147232 | Restoring repo states 2026-06-03 01:26:41.154946 | Merging changes 2026-06-03 01:26:41.758577 | Checking out repos 2026-06-03 01:26:41.791662 | Preparing playbooks 2026-06-03 01:26:44.667374 | Running Ansible setup 2026-06-03 01:26:49.128664 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-06-03 01:26:49.934546 | 2026-06-03 01:26:50.153061 | PLAY [localhost] 2026-06-03 01:26:50.168182 | 2026-06-03 01:26:50.168329 | TASK [Gathering Facts] 2026-06-03 01:26:51.247373 | localhost | ok 2026-06-03 01:26:51.259106 | 2026-06-03 01:26:51.259269 | TASK [Setup log path fact] 2026-06-03 01:26:51.279108 | localhost | ok 2026-06-03 01:26:51.292120 | 2026-06-03 01:26:51.292225 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-06-03 01:26:51.321340 | localhost | ok 2026-06-03 01:26:51.327082 | 2026-06-03 01:26:51.327155 | TASK [emit-job-header : Print job information] 2026-06-03 01:26:51.388028 | # Job Information 2026-06-03 01:26:51.388227 | Ansible Version: 2.16.18 2026-06-03 01:26:51.388274 | Job: ansible-collection-containers-molecule-docker-rockylinux-9 2026-06-03 01:26:51.388308 | Pipeline: check 2026-06-03 01:26:51.388338 | Executor: 2d72f0692154 2026-06-03 01:26:51.388368 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/121 2026-06-03 01:26:51.388401 | Event ID: 244f0540-5eeb-11f1-8698-27c6e1487be6 2026-06-03 01:26:51.392883 | 2026-06-03 01:26:51.393001 | LOOP [emit-job-header : Print node information] 2026-06-03 01:26:51.482375 | localhost | ok: 2026-06-03 01:26:51.482609 | localhost | # Node Information 2026-06-03 01:26:51.482706 | localhost | Inventory Hostname: rockylinux-9 2026-06-03 01:26:51.482803 | localhost | Hostname: np0000178354 2026-06-03 01:26:51.482844 | localhost | Username: zuul 2026-06-03 01:26:51.482871 | localhost | Distro: Rocky 9.7 2026-06-03 01:26:51.482892 | localhost | Provider: yul1 2026-06-03 01:26:51.482912 | localhost | Region: ca-ymq-1 2026-06-03 01:26:51.482931 | localhost | Label: rockylinux-9 2026-06-03 01:26:51.482950 | localhost | Product Name: OpenStack Nova 2026-06-03 01:26:51.482968 | localhost | Interface IP: 199.204.45.216 2026-06-03 01:26:51.498829 | 2026-06-03 01:26:51.499124 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-06-03 01:26:52.014648 | localhost -> localhost | changed 2026-06-03 01:26:52.020181 | 2026-06-03 01:26:52.020253 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-06-03 01:26:53.477110 | localhost -> localhost | changed 2026-06-03 01:26:53.483569 | 2026-06-03 01:26:53.483656 | PLAY [all] 2026-06-03 01:26:53.498710 | 2026-06-03 01:26:53.498887 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-06-03 01:26:53.789249 | rockylinux-9 -> localhost | ok 2026-06-03 01:26:53.794701 | 2026-06-03 01:26:53.794774 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-06-03 01:26:53.823882 | rockylinux-9 | ok 2026-06-03 01:26:53.848986 | rockylinux-9 | included: /var/lib/zuul/builds/397235c5b2474d39b418d4a3023daee9/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-06-03 01:26:53.857937 | 2026-06-03 01:26:53.858003 | TASK [add-build-sshkey : Create Temp SSH key] 2026-06-03 01:26:55.603061 | rockylinux-9 -> localhost | Generating public/private rsa key pair. 2026-06-03 01:26:55.603245 | rockylinux-9 -> localhost | Your identification has been saved in /var/lib/zuul/builds/397235c5b2474d39b418d4a3023daee9/work/397235c5b2474d39b418d4a3023daee9_id_rsa 2026-06-03 01:26:55.603276 | rockylinux-9 -> localhost | Your public key has been saved in /var/lib/zuul/builds/397235c5b2474d39b418d4a3023daee9/work/397235c5b2474d39b418d4a3023daee9_id_rsa.pub 2026-06-03 01:26:55.603299 | rockylinux-9 -> localhost | The key fingerprint is: 2026-06-03 01:26:55.603320 | rockylinux-9 -> localhost | SHA256:vRHXGzRKH+BSfE7/JVIXug96dmUSnxJEtV+VgLYwYFY zuul-build-sshkey 2026-06-03 01:26:55.603358 | rockylinux-9 -> localhost | The key's randomart image is: 2026-06-03 01:26:55.603379 | rockylinux-9 -> localhost | +---[RSA 3072]----+ 2026-06-03 01:26:55.603406 | rockylinux-9 -> localhost | | +oE .*=*o+| 2026-06-03 01:26:55.603428 | rockylinux-9 -> localhost | | o o *o==++| 2026-06-03 01:26:55.603448 | rockylinux-9 -> localhost | | * =B=+.| 2026-06-03 01:26:55.603468 | rockylinux-9 -> localhost | | . =. ==*| 2026-06-03 01:26:55.603488 | rockylinux-9 -> localhost | | S o =ooB| 2026-06-03 01:26:55.603508 | rockylinux-9 -> localhost | | o. +oo| 2026-06-03 01:26:55.603527 | rockylinux-9 -> localhost | | .. o o | 2026-06-03 01:26:55.603547 | rockylinux-9 -> localhost | | o . | 2026-06-03 01:26:55.603569 | rockylinux-9 -> localhost | | | 2026-06-03 01:26:55.603590 | rockylinux-9 -> localhost | +----[SHA256]-----+ 2026-06-03 01:26:55.603637 | rockylinux-9 -> localhost | ok: Runtime: 0:00:01.264343 2026-06-03 01:26:55.608477 | 2026-06-03 01:26:55.608544 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-06-03 01:26:55.637957 | rockylinux-9 | ok 2026-06-03 01:26:55.646043 | rockylinux-9 | included: /var/lib/zuul/builds/397235c5b2474d39b418d4a3023daee9/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-06-03 01:26:55.652976 | 2026-06-03 01:26:55.653040 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-06-03 01:26:55.678849 | rockylinux-9 | skipping: Conditional result was False 2026-06-03 01:26:55.690592 | 2026-06-03 01:26:55.690705 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-06-03 01:26:56.174861 | rockylinux-9 | changed 2026-06-03 01:26:56.179567 | 2026-06-03 01:26:56.179656 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-06-03 01:26:56.447735 | rockylinux-9 | ok 2026-06-03 01:26:56.455597 | 2026-06-03 01:26:56.455673 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-06-03 01:26:57.374935 | rockylinux-9 | changed 2026-06-03 01:26:57.379724 | 2026-06-03 01:26:57.379791 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-06-03 01:26:58.301050 | rockylinux-9 | changed 2026-06-03 01:26:58.351898 | 2026-06-03 01:26:58.352066 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-06-03 01:26:58.386026 | rockylinux-9 | skipping: Conditional result was False 2026-06-03 01:26:58.400993 | 2026-06-03 01:26:58.401278 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-06-03 01:26:58.814395 | rockylinux-9 -> localhost | changed 2026-06-03 01:26:58.823739 | 2026-06-03 01:26:58.823811 | TASK [add-build-sshkey : Add back temp key] 2026-06-03 01:26:59.136712 | rockylinux-9 -> localhost | Identity added: /var/lib/zuul/builds/397235c5b2474d39b418d4a3023daee9/work/397235c5b2474d39b418d4a3023daee9_id_rsa (zuul-build-sshkey) 2026-06-03 01:26:59.136900 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.014735 2026-06-03 01:26:59.141865 | 2026-06-03 01:26:59.141928 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-06-03 01:26:59.534083 | rockylinux-9 | ok 2026-06-03 01:26:59.538304 | 2026-06-03 01:26:59.538372 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-06-03 01:26:59.575124 | rockylinux-9 | skipping: Conditional result was False 2026-06-03 01:26:59.591189 | 2026-06-03 01:26:59.591582 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-06-03 01:27:00.047652 | rockylinux-9 | ok 2026-06-03 01:27:00.053214 | 2026-06-03 01:27:00.053280 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-06-03 01:27:01.067978 | rockylinux-9 | Output suppressed because no_log was given 2026-06-03 01:27:01.075637 | 2026-06-03 01:27:01.075701 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-06-03 01:27:01.341383 | rockylinux-9 | ok: "logs" 2026-06-03 01:27:01.399439 | rockylinux-9 | ok: All items complete 2026-06-03 01:27:01.399533 | 2026-06-03 01:27:01.557166 | rockylinux-9 | ok: "artifacts" 2026-06-03 01:27:01.779881 | rockylinux-9 | ok: "docs" 2026-06-03 01:27:01.787299 | 2026-06-03 01:27:01.787457 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-06-03 01:27:02.065555 | rockylinux-9 | changed: "logs" 2026-06-03 01:27:02.342598 | rockylinux-9 | changed: "artifacts" 2026-06-03 01:27:02.761645 | rockylinux-9 | changed: "docs" 2026-06-03 01:27:02.783577 | 2026-06-03 01:27:02.783672 | PLAY RECAP 2026-06-03 01:27:02.783716 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-06-03 01:27:02.783747 | rockylinux-9 | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-06-03 01:27:02.783769 | 2026-06-03 01:27:02.959319 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-06-03 01:27:02.962017 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-06-03 01:27:03.553321 | 2026-06-03 01:27:03.686846 | PLAY [all] 2026-06-03 01:27:03.704789 | 2026-06-03 01:27:03.704895 | TASK [setup-uv : Extract archive] 2026-06-03 01:27:06.525094 | rockylinux-9 | changed 2026-06-03 01:27:06.530454 | 2026-06-03 01:27:06.530548 | TASK [setup-uv : Print version] 2026-06-03 01:27:06.551888 | [rockylinux-9] Waiting on logger 2026-06-03 01:27:07.063705 | rockylinux-9 | ok: Runtime: 0:00:00.020723 2026-06-03 01:27:07.072323 | 2026-06-03 01:27:07.072395 | TASK [Install Ansible collection siblings] 2026-06-03 01:27:07.524387 | rockylinux-9 | ok: 2026-06-03 01:27:08.596541 | rockylinux-9 | Skipping ansible.posix: no checked-out sibling 2026-06-03 01:27:08.596727 | rockylinux-9 | Skipping community.general: no checked-out sibling 2026-06-03 01:27:08.602100 | 2026-06-03 01:27:08.602238 | PLAY RECAP 2026-06-03 01:27:08.602336 | rockylinux-9 | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-06-03 01:27:08.602451 | 2026-06-03 01:27:08.743761 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-06-03 01:27:08.747773 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-06-03 01:27:09.577985 | 2026-06-03 01:27:09.625072 | PLAY [all] 2026-06-03 01:27:09.639128 | 2026-06-03 01:27:09.639257 | TASK [Copy inventory file for Zuul] 2026-06-03 01:27:11.058789 | rockylinux-9 | changed 2026-06-03 01:27:11.158384 | 2026-06-03 01:27:11.158563 | TASK [Switch "ansible_host" to private IP] 2026-06-03 01:27:11.555439 | rockylinux-9 | changed: 1 replacements made 2026-06-03 01:27:11.560732 | 2026-06-03 01:27:11.560826 | TASK [Run Molecule scenario] 2026-06-03 01:27:11.601502 | [rockylinux-9] Waiting on logger 2026-06-03 01:27:17.702102 | [rockylinux-9] Waiting on logger 2026-06-03 01:27:28.105957 | [rockylinux-9] Waiting on logger 2026-06-03 01:27:38.278199 | [rockylinux-9] Waiting on logger 2026-06-03 01:27:48.458077 | [rockylinux-9] Waiting on logger 2026-06-03 01:27:57.643485 | [rockylinux-9] Waiting on logger 2026-06-03 01:28:07.814322 | [rockylinux-9] Waiting on logger 2026-06-03 01:28:18.469963 | [rockylinux-9] Waiting on logger 2026-06-03 01:28:27.627563 | [rockylinux-9] Waiting on logger 2026-06-03 01:28:37.797873 | [rockylinux-9] Waiting on logger 2026-06-03 01:28:48.006056 | [rockylinux-9] Waiting on logger 2026-06-03 01:28:58.185813 | [rockylinux-9] Waiting on logger 2026-06-03 01:29:08.889262 | [rockylinux-9] Waiting on logger 2026-06-03 01:29:18.317396 | rockylinux-9 | ok: Runtime: 0:02:05.498412 2026-06-03 01:29:18.324646 | 2026-06-03 01:29:18.324731 | PLAY RECAP 2026-06-03 01:29:18.324795 | rockylinux-9 | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-06-03 01:29:18.324827 | 2026-06-03 01:29:18.470445 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-06-03 01:29:18.473224 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-06-03 01:29:19.079982 | 2026-06-03 01:29:19.080149 | PLAY [all] 2026-06-03 01:29:19.091216 | 2026-06-03 01:29:19.091286 | TASK [fetch-output : Set log path for multiple nodes] 2026-06-03 01:29:19.137056 | rockylinux-9 | skipping: Conditional result was False 2026-06-03 01:29:19.145544 | 2026-06-03 01:29:19.145654 | TASK [fetch-output : Set log path for single node] 2026-06-03 01:29:19.189621 | rockylinux-9 | ok 2026-06-03 01:29:19.193677 | 2026-06-03 01:29:19.193744 | LOOP [fetch-output : Ensure local output dirs] 2026-06-03 01:29:19.595006 | rockylinux-9 -> localhost | ok: "/var/lib/zuul/builds/397235c5b2474d39b418d4a3023daee9/work/logs" 2026-06-03 01:29:19.814085 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/397235c5b2474d39b418d4a3023daee9/work/artifacts" 2026-06-03 01:29:20.027800 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/397235c5b2474d39b418d4a3023daee9/work/docs" 2026-06-03 01:29:20.045882 | 2026-06-03 01:29:20.045968 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-06-03 01:29:20.719361 | rockylinux-9 | changed: .d..t...... ./ 2026-06-03 01:29:20.719596 | rockylinux-9 | changed: All items complete 2026-06-03 01:29:20.719623 | 2026-06-03 01:29:21.255742 | rockylinux-9 | changed: .d..t...... ./ 2026-06-03 01:29:21.764256 | rockylinux-9 | changed: .d..t...... ./ 2026-06-03 01:29:21.781321 | 2026-06-03 01:29:21.781464 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-06-03 01:29:22.205936 | rockylinux-9 -> localhost | ok: Item: artifacts Runtime: 0:00:00.007666 2026-06-03 01:29:22.421699 | rockylinux-9 -> localhost | ok: Item: docs Runtime: 0:00:00.006773 2026-06-03 01:29:22.439101 | 2026-06-03 01:29:22.439313 | PLAY [all] 2026-06-03 01:29:22.445532 | 2026-06-03 01:29:22.445600 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-06-03 01:29:22.927978 | rockylinux-9 | changed 2026-06-03 01:29:22.961076 | 2026-06-03 01:29:22.961135 | PLAY RECAP 2026-06-03 01:29:22.961184 | rockylinux-9 | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-06-03 01:29:22.961206 | 2026-06-03 01:29:23.173964 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-06-03 01:29:23.177091 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-06-03 01:29:23.753360 | 2026-06-03 01:29:23.753496 | PLAY [localhost] 2026-06-03 01:29:23.764314 | 2026-06-03 01:29:23.764425 | TASK [Generate Zuul manifest] 2026-06-03 01:29:23.786575 | localhost | ok 2026-06-03 01:29:23.802555 | 2026-06-03 01:29:23.802646 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-06-03 01:29:24.198389 | localhost | changed 2026-06-03 01:29:24.210201 | 2026-06-03 01:29:24.210279 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-06-03 01:29:24.243381 | localhost | ok 2026-06-03 01:29:24.251261 | 2026-06-03 01:29:24.251334 | TASK [Upload logs] 2026-06-03 01:29:24.275202 | localhost | ok 2026-06-03 01:29:24.336580 | 2026-06-03 01:29:24.336718 | TASK [Set zuul-log-path fact] 2026-06-03 01:29:24.354945 | localhost | ok 2026-06-03 01:29:24.368063 | 2026-06-03 01:29:24.368133 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-06-03 01:29:24.410297 | localhost | ok 2026-06-03 01:29:24.419401 | 2026-06-03 01:29:24.419479 | TASK [upload-logs : Create log directories] 2026-06-03 01:29:24.856661 | localhost | changed 2026-06-03 01:29:24.864563 | 2026-06-03 01:29:24.864657 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-06-03 01:29:25.277759 | localhost -> localhost | ok: Runtime: 0:00:00.004810 2026-06-03 01:29:25.284382 | 2026-06-03 01:29:25.284448 | TASK [upload-logs : Upload logs to log server] 2026-06-03 01:29:25.771708 | localhost | Output suppressed because no_log was given 2026-06-03 01:29:25.776214 | 2026-06-03 01:29:25.776306 | LOOP [upload-logs : Compress console log and json output] 2026-06-03 01:29:25.825201 | localhost | skipping: Conditional result was False 2026-06-03 01:29:25.832331 | localhost | skipping: Conditional result was False 2026-06-03 01:29:25.845457 | 2026-06-03 01:29:25.845632 | LOOP [upload-logs : Upload compressed console log and json output] 2026-06-03 01:29:25.888633 | localhost | skipping: Conditional result was False 2026-06-03 01:29:25.889213 | 2026-06-03 01:29:25.892578 | localhost | skipping: Conditional result was False 2026-06-03 01:29:25.911160 | 2026-06-03 01:29:25.911313 | LOOP [upload-logs : Upload console log and json output]