2026-05-28 21:10:07.043978 | Job console starting 2026-05-28 21:10:07.060314 | Updating git repos 2026-05-28 21:10:07.085132 | Cloning repos into workspace 2026-05-28 21:10:07.140231 | Restoring repo states 2026-05-28 21:10:07.149615 | Merging changes 2026-05-28 21:10:07.523888 | Checking out repos 2026-05-28 21:10:07.562966 | Preparing playbooks 2026-05-28 21:10:08.608275 | Running Ansible setup 2026-05-28 21:10:13.504400 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-05-28 21:10:14.110009 | 2026-05-28 21:10:14.110146 | PLAY [localhost] 2026-05-28 21:10:14.117990 | 2026-05-28 21:10:14.118064 | TASK [Gathering Facts] 2026-05-28 21:10:15.059774 | localhost | ok 2026-05-28 21:10:15.071418 | 2026-05-28 21:10:15.071498 | TASK [Setup log path fact] 2026-05-28 21:10:15.092559 | localhost | ok 2026-05-28 21:10:15.106306 | 2026-05-28 21:10:15.106402 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-28 21:10:15.136353 | localhost | ok 2026-05-28 21:10:15.145298 | 2026-05-28 21:10:15.145372 | TASK [emit-job-header : Print job information] 2026-05-28 21:10:15.188903 | # Job Information 2026-05-28 21:10:15.189101 | Ansible Version: 2.16.18 2026-05-28 21:10:15.189147 | Job: ansible-collection-containers-molecule-nerdctl-rockylinux-9 2026-05-28 21:10:15.189179 | Pipeline: check 2026-05-28 21:10:15.189207 | Executor: 2d72f0692154 2026-05-28 21:10:15.189235 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/131 2026-05-28 21:10:15.189268 | Event ID: 716f5200-5ad9-11f1-91a5-93c65174d954 2026-05-28 21:10:15.194544 | 2026-05-28 21:10:15.194638 | LOOP [emit-job-header : Print node information] 2026-05-28 21:10:15.310089 | localhost | ok: 2026-05-28 21:10:15.310551 | localhost | # Node Information 2026-05-28 21:10:15.310595 | localhost | Inventory Hostname: rockylinux-9 2026-05-28 21:10:15.310625 | localhost | Hostname: np0000177250 2026-05-28 21:10:15.310652 | localhost | Username: zuul 2026-05-28 21:10:15.310683 | localhost | Distro: Rocky 9.7 2026-05-28 21:10:15.310710 | localhost | Provider: yul1 2026-05-28 21:10:15.310737 | localhost | Region: ca-ymq-1 2026-05-28 21:10:15.310762 | localhost | Label: rockylinux-9 2026-05-28 21:10:15.310788 | localhost | Product Name: OpenStack Nova 2026-05-28 21:10:15.310814 | localhost | Interface IP: 199.204.45.229 2026-05-28 21:10:15.322371 | 2026-05-28 21:10:15.322570 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-05-28 21:10:15.738698 | localhost -> localhost | changed 2026-05-28 21:10:15.746285 | 2026-05-28 21:10:15.746399 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-05-28 21:10:16.795761 | localhost -> localhost | changed 2026-05-28 21:10:16.803919 | 2026-05-28 21:10:16.803995 | PLAY [all] 2026-05-28 21:10:16.812495 | 2026-05-28 21:10:16.812567 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-05-28 21:10:17.073712 | rockylinux-9 -> localhost | ok 2026-05-28 21:10:17.082773 | 2026-05-28 21:10:17.082863 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-05-28 21:10:17.116918 | rockylinux-9 | ok 2026-05-28 21:10:17.132224 | rockylinux-9 | included: /var/lib/zuul/builds/8dbec62dd0a74f789c9d553a3d77d9e1/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-05-28 21:10:17.138509 | 2026-05-28 21:10:17.138573 | TASK [add-build-sshkey : Create Temp SSH key] 2026-05-28 21:10:18.990471 | rockylinux-9 -> localhost | Generating public/private rsa key pair. 2026-05-28 21:10:18.990749 | rockylinux-9 -> localhost | Your identification has been saved in /var/lib/zuul/builds/8dbec62dd0a74f789c9d553a3d77d9e1/work/8dbec62dd0a74f789c9d553a3d77d9e1_id_rsa 2026-05-28 21:10:18.990880 | rockylinux-9 -> localhost | Your public key has been saved in /var/lib/zuul/builds/8dbec62dd0a74f789c9d553a3d77d9e1/work/8dbec62dd0a74f789c9d553a3d77d9e1_id_rsa.pub 2026-05-28 21:10:18.990934 | rockylinux-9 -> localhost | The key fingerprint is: 2026-05-28 21:10:18.990983 | rockylinux-9 -> localhost | SHA256:G5ywErW1k0NCYqi9PhQxe3eOyWlUFVGcCT4xbvBUmVg zuul-build-sshkey 2026-05-28 21:10:18.991023 | rockylinux-9 -> localhost | The key's randomart image is: 2026-05-28 21:10:18.991045 | rockylinux-9 -> localhost | +---[RSA 3072]----+ 2026-05-28 21:10:18.991072 | rockylinux-9 -> localhost | | .o.+ +.OXE= | 2026-05-28 21:10:18.991095 | rockylinux-9 -> localhost | | +. o =.O.o* | 2026-05-28 21:10:18.991117 | rockylinux-9 -> localhost | | o +. o.= * | 2026-05-28 21:10:18.991137 | rockylinux-9 -> localhost | |. + ..o+.= . | 2026-05-28 21:10:18.991158 | rockylinux-9 -> localhost | | +.+.*S | 2026-05-28 21:10:18.991178 | rockylinux-9 -> localhost | | o .* .o | 2026-05-28 21:10:18.991199 | rockylinux-9 -> localhost | | o . . | 2026-05-28 21:10:18.991219 | rockylinux-9 -> localhost | | o | 2026-05-28 21:10:18.991242 | rockylinux-9 -> localhost | | . | 2026-05-28 21:10:18.991263 | rockylinux-9 -> localhost | +----[SHA256]-----+ 2026-05-28 21:10:18.991322 | rockylinux-9 -> localhost | ok: Runtime: 0:00:01.387157 2026-05-28 21:10:18.997130 | 2026-05-28 21:10:18.997218 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-05-28 21:10:19.031570 | rockylinux-9 | ok 2026-05-28 21:10:19.043148 | rockylinux-9 | included: /var/lib/zuul/builds/8dbec62dd0a74f789c9d553a3d77d9e1/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-05-28 21:10:19.051172 | 2026-05-28 21:10:19.051247 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-05-28 21:10:19.077647 | rockylinux-9 | skipping: Conditional result was False 2026-05-28 21:10:19.090360 | 2026-05-28 21:10:19.090542 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-05-28 21:10:19.651930 | rockylinux-9 | changed 2026-05-28 21:10:19.656522 | 2026-05-28 21:10:19.656586 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-05-28 21:10:19.923513 | rockylinux-9 | ok 2026-05-28 21:10:19.928439 | 2026-05-28 21:10:19.928683 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-05-28 21:10:20.868562 | rockylinux-9 | changed 2026-05-28 21:10:20.874209 | 2026-05-28 21:10:20.874312 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-05-28 21:10:21.826417 | rockylinux-9 | changed 2026-05-28 21:10:21.830826 | 2026-05-28 21:10:21.830890 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-05-28 21:10:21.853569 | rockylinux-9 | skipping: Conditional result was False 2026-05-28 21:10:21.858587 | 2026-05-28 21:10:21.858650 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-05-28 21:10:22.208983 | rockylinux-9 -> localhost | changed 2026-05-28 21:10:22.223751 | 2026-05-28 21:10:22.223855 | TASK [add-build-sshkey : Add back temp key] 2026-05-28 21:10:22.512178 | rockylinux-9 -> localhost | Identity added: /var/lib/zuul/builds/8dbec62dd0a74f789c9d553a3d77d9e1/work/8dbec62dd0a74f789c9d553a3d77d9e1_id_rsa (zuul-build-sshkey) 2026-05-28 21:10:22.512679 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.014142 2026-05-28 21:10:22.518006 | 2026-05-28 21:10:22.518104 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-05-28 21:10:22.902733 | rockylinux-9 | ok 2026-05-28 21:10:22.906928 | 2026-05-28 21:10:22.906992 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-05-28 21:10:22.931793 | rockylinux-9 | skipping: Conditional result was False 2026-05-28 21:10:22.940583 | 2026-05-28 21:10:22.940650 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-05-28 21:10:23.353626 | rockylinux-9 | ok 2026-05-28 21:10:23.358917 | 2026-05-28 21:10:23.358982 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-05-28 21:10:24.314647 | rockylinux-9 | Output suppressed because no_log was given 2026-05-28 21:10:24.322182 | 2026-05-28 21:10:24.322280 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-05-28 21:10:24.590673 | rockylinux-9 | ok: "logs" 2026-05-28 21:10:24.590973 | rockylinux-9 | ok: All items complete 2026-05-28 21:10:24.591003 | 2026-05-28 21:10:24.824855 | rockylinux-9 | ok: "artifacts" 2026-05-28 21:10:25.044910 | rockylinux-9 | ok: "docs" 2026-05-28 21:10:25.065666 | 2026-05-28 21:10:25.065847 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-05-28 21:10:25.330628 | rockylinux-9 | changed: "logs" 2026-05-28 21:10:25.614725 | rockylinux-9 | changed: "artifacts" 2026-05-28 21:10:25.785845 | rockylinux-9 | changed: "docs" 2026-05-28 21:10:25.805639 | 2026-05-28 21:10:25.805769 | PLAY RECAP 2026-05-28 21:10:25.805818 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-05-28 21:10:25.805849 | rockylinux-9 | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-05-28 21:10:25.805870 | 2026-05-28 21:10:25.946603 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-05-28 21:10:25.947879 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-05-28 21:10:26.586094 | 2026-05-28 21:10:26.586223 | PLAY [all] 2026-05-28 21:10:26.596742 | 2026-05-28 21:10:26.596816 | TASK [setup-uv : Extract archive] 2026-05-28 21:10:29.371939 | rockylinux-9 | changed 2026-05-28 21:10:29.381019 | 2026-05-28 21:10:29.381160 | TASK [setup-uv : Print version] 2026-05-28 21:10:29.405067 | [rockylinux-9] Waiting on logger 2026-05-28 21:10:29.919072 | rockylinux-9 | ok: Runtime: 0:00:00.017635 2026-05-28 21:10:29.929062 | 2026-05-28 21:10:29.929133 | TASK [Install Ansible collection siblings] 2026-05-28 21:10:30.393157 | rockylinux-9 | ok: 2026-05-28 21:10:30.393358 | rockylinux-9 | Skipping ansible.posix: no checked-out sibling 2026-05-28 21:10:30.393406 | rockylinux-9 | Skipping community.general: no checked-out sibling 2026-05-28 21:10:30.395613 | 2026-05-28 21:10:30.395677 | PLAY RECAP 2026-05-28 21:10:30.395737 | rockylinux-9 | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-05-28 21:10:30.395767 | 2026-05-28 21:10:30.518191 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-05-28 21:10:30.519336 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-05-28 21:10:31.090121 | 2026-05-28 21:10:31.090262 | PLAY [all] 2026-05-28 21:10:31.101180 | 2026-05-28 21:10:31.101257 | TASK [Copy inventory file for Zuul] 2026-05-28 21:10:32.466803 | rockylinux-9 | changed 2026-05-28 21:10:32.473497 | 2026-05-28 21:10:32.473576 | TASK [Switch "ansible_host" to private IP] 2026-05-28 21:10:32.871244 | rockylinux-9 | changed: 1 replacements made 2026-05-28 21:10:32.876524 | 2026-05-28 21:10:32.876599 | TASK [Run Molecule scenario] 2026-05-28 21:10:32.899179 | [rockylinux-9] Waiting on logger 2026-05-28 21:10:41.034163 | [rockylinux-9] Waiting on logger 2026-05-28 21:10:51.210118 | [rockylinux-9] Waiting on logger 2026-05-28 21:11:01.382085 | [rockylinux-9] Waiting on logger 2026-05-28 21:11:10.533867 | [rockylinux-9] Waiting on logger 2026-05-28 21:11:20.714305 | [rockylinux-9] Waiting on logger 2026-05-28 21:11:30.886310 | [rockylinux-9] Waiting on logger 2026-05-28 21:11:41.062072 | [rockylinux-9] Waiting on logger 2026-05-28 21:11:51.238275 | [rockylinux-9] Waiting on logger 2026-05-28 21:12:01.414084 | [rockylinux-9] Waiting on logger 2026-05-28 21:12:10.566100 | [rockylinux-9] Waiting on logger 2026-05-28 21:12:20.742247 | [rockylinux-9] Waiting on logger 2026-05-28 21:12:30.918247 | [rockylinux-9] Waiting on logger 2026-05-28 21:12:35.407102 | rockylinux-9 | ok: Runtime: 0:02:01.537168 2026-05-28 21:12:35.413967 | 2026-05-28 21:12:35.414024 | PLAY RECAP 2026-05-28 21:12:35.414066 | rockylinux-9 | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-05-28 21:12:35.414088 | 2026-05-28 21:12:35.540368 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-05-28 21:12:35.543002 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-05-28 21:12:36.130768 | 2026-05-28 21:12:36.130900 | PLAY [all] 2026-05-28 21:12:36.141568 | 2026-05-28 21:12:36.141650 | TASK [fetch-output : Set log path for multiple nodes] 2026-05-28 21:12:36.187219 | rockylinux-9 | skipping: Conditional result was False 2026-05-28 21:12:36.196570 | 2026-05-28 21:12:36.196729 | TASK [fetch-output : Set log path for single node] 2026-05-28 21:12:36.240405 | rockylinux-9 | ok 2026-05-28 21:12:36.247900 | 2026-05-28 21:12:36.247968 | LOOP [fetch-output : Ensure local output dirs] 2026-05-28 21:12:36.625093 | rockylinux-9 -> localhost | ok: "/var/lib/zuul/builds/8dbec62dd0a74f789c9d553a3d77d9e1/work/logs" 2026-05-28 21:12:36.832787 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/8dbec62dd0a74f789c9d553a3d77d9e1/work/artifacts" 2026-05-28 21:12:37.030824 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/8dbec62dd0a74f789c9d553a3d77d9e1/work/docs" 2026-05-28 21:12:37.045690 | 2026-05-28 21:12:37.045835 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-05-28 21:12:37.756802 | rockylinux-9 | changed: .d..t...... ./ 2026-05-28 21:12:37.757103 | rockylinux-9 | changed: All items complete 2026-05-28 21:12:37.757143 | 2026-05-28 21:12:38.277852 | rockylinux-9 | changed: .d..t...... ./ 2026-05-28 21:12:38.795834 | rockylinux-9 | changed: .d..t...... ./ 2026-05-28 21:12:38.816545 | 2026-05-28 21:12:38.816707 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-05-28 21:12:39.228480 | rockylinux-9 -> localhost | ok: Item: artifacts Runtime: 0:00:00.006874 2026-05-28 21:12:39.461075 | rockylinux-9 -> localhost | ok: Item: docs Runtime: 0:00:00.007013 2026-05-28 21:12:39.491716 | 2026-05-28 21:12:39.492019 | PLAY [all] 2026-05-28 21:12:39.504013 | 2026-05-28 21:12:40.017517 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-05-28 21:12:40.500819 | rockylinux-9 | changed 2026-05-28 21:12:40.506120 | 2026-05-28 21:12:40.506172 | PLAY RECAP 2026-05-28 21:12:40.506220 | rockylinux-9 | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-05-28 21:12:40.506241 | 2026-05-28 21:12:40.631336 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-05-28 21:12:40.633923 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-05-28 21:12:41.195299 | 2026-05-28 21:12:41.195924 | PLAY [localhost] 2026-05-28 21:12:41.206379 | 2026-05-28 21:12:41.206449 | TASK [Generate Zuul manifest] 2026-05-28 21:12:41.229595 | localhost | ok 2026-05-28 21:12:41.248073 | 2026-05-28 21:12:41.248151 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-05-28 21:12:41.644377 | localhost | changed 2026-05-28 21:12:41.658578 | 2026-05-28 21:12:41.658709 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-05-28 21:12:42.304115 | localhost | ok 2026-05-28 21:12:42.313616 | 2026-05-28 21:12:42.313715 | TASK [Upload logs] 2026-05-28 21:12:42.334881 | localhost | ok 2026-05-28 21:12:42.394455 | 2026-05-28 21:12:42.394591 | TASK [Set zuul-log-path fact] 2026-05-28 21:12:42.417216 | localhost | ok 2026-05-28 21:12:42.430141 | 2026-05-28 21:12:42.430209 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-28 21:12:42.461292 | localhost | ok 2026-05-28 21:12:42.470963 | 2026-05-28 21:12:42.471063 | TASK [upload-logs : Create log directories] 2026-05-28 21:12:42.857594 | localhost | changed 2026-05-28 21:12:42.866342 | 2026-05-28 21:12:42.866443 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-05-28 21:12:43.273569 | localhost -> localhost | ok: Runtime: 0:00:00.006029 2026-05-28 21:12:43.280251 | 2026-05-28 21:12:43.280342 | TASK [upload-logs : Upload logs to log server] 2026-05-28 21:12:43.736878 | localhost | Output suppressed because no_log was given 2026-05-28 21:12:43.741933 | 2026-05-28 21:12:43.742020 | LOOP [upload-logs : Compress console log and json output] 2026-05-28 21:12:43.787915 | localhost | skipping: Conditional result was False 2026-05-28 21:12:43.795089 | localhost | skipping: Conditional result was False 2026-05-28 21:12:43.806097 | 2026-05-28 21:12:43.806347 | LOOP [upload-logs : Upload compressed console log and json output] 2026-05-28 21:12:43.843799 | localhost | skipping: Conditional result was False 2026-05-28 21:12:43.844190 | 2026-05-28 21:12:43.848116 | localhost | skipping: Conditional result was False 2026-05-28 21:12:43.864565 | 2026-05-28 21:12:43.864765 | LOOP [upload-logs : Upload console log and json output]