2026-04-14 02:38:29.729474 | Job console starting 2026-04-14 02:38:29.738797 | Updating git repos 2026-04-14 02:38:29.765001 | Cloning repos into workspace 2026-04-14 02:38:34.438368 | Restoring repo states 2026-04-14 02:38:34.449712 | Merging changes 2026-04-14 02:38:35.287691 | Checking out repos 2026-04-14 02:38:35.888064 | Preparing playbooks 2026-04-14 02:38:54.968017 | Running Ansible setup 2026-04-14 02:39:00.206484 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-04-14 02:39:00.907682 | 2026-04-14 02:39:00.907810 | PLAY [localhost] 2026-04-14 02:39:00.916114 | 2026-04-14 02:39:00.916186 | TASK [Gathering Facts] 2026-04-14 02:39:02.078292 | localhost | ok 2026-04-14 02:39:02.088087 | 2026-04-14 02:39:02.088200 | TASK [Setup log path fact] 2026-04-14 02:39:02.110309 | localhost | ok 2026-04-14 02:39:02.124387 | 2026-04-14 02:39:02.124539 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-14 02:39:02.154492 | localhost | ok 2026-04-14 02:39:02.164079 | 2026-04-14 02:39:02.164213 | TASK [emit-job-header : Print job information] 2026-04-14 02:39:02.206837 | # Job Information 2026-04-14 02:39:02.254338 | Ansible Version: 2.16.16 2026-04-14 02:39:02.254570 | Job: ansible-collection-containers-molecule-docker-rockylinux-9 2026-04-14 02:39:02.254633 | Pipeline: check 2026-04-14 02:39:02.254681 | Executor: 0a8996d2b663 2026-04-14 02:39:02.254725 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/111 2026-04-14 02:39:02.254775 | Event ID: ed477fd0-37aa-11f1-9375-ba961ffa0837 2026-04-14 02:39:02.266965 | 2026-04-14 02:39:02.267208 | LOOP [emit-job-header : Print node information] 2026-04-14 02:39:02.381316 | localhost | ok: 2026-04-14 02:39:02.381804 | localhost | # Node Information 2026-04-14 02:39:02.381836 | localhost | Inventory Hostname: rockylinux-9 2026-04-14 02:39:02.381859 | localhost | Hostname: np0000165984 2026-04-14 02:39:02.381879 | localhost | Username: zuul 2026-04-14 02:39:02.381902 | localhost | Distro: Rocky 9.7 2026-04-14 02:39:02.381922 | localhost | Provider: yul1 2026-04-14 02:39:02.381942 | localhost | Region: ca-ymq-1 2026-04-14 02:39:02.381961 | localhost | Label: rockylinux-9 2026-04-14 02:39:02.381979 | localhost | Product Name: OpenStack Nova 2026-04-14 02:39:02.382139 | localhost | Interface IP: 199.19.213.68 2026-04-14 02:39:02.407128 | 2026-04-14 02:39:02.407324 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-04-14 02:39:02.911397 | localhost -> localhost | changed 2026-04-14 02:39:02.916211 | 2026-04-14 02:39:02.916282 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-04-14 02:39:03.921007 | localhost -> localhost | changed 2026-04-14 02:39:03.929325 | 2026-04-14 02:39:03.929425 | PLAY [all] 2026-04-14 02:39:03.938369 | 2026-04-14 02:39:03.938466 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-04-14 02:39:04.538083 | rockylinux-9 -> localhost | ok 2026-04-14 02:39:04.545900 | 2026-04-14 02:39:04.546068 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-04-14 02:39:04.580006 | rockylinux-9 | ok 2026-04-14 02:39:04.595097 | rockylinux-9 | included: /var/lib/zuul/builds/3bf927c90c9147ab9d96cb959c5f98a0/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-04-14 02:39:04.600216 | 2026-04-14 02:39:04.600276 | TASK [add-build-sshkey : Create Temp SSH key] 2026-04-14 02:39:05.941807 | rockylinux-9 -> localhost | Generating public/private rsa key pair. 2026-04-14 02:39:05.941997 | rockylinux-9 -> localhost | Your identification has been saved in /var/lib/zuul/builds/3bf927c90c9147ab9d96cb959c5f98a0/work/3bf927c90c9147ab9d96cb959c5f98a0_id_rsa 2026-04-14 02:39:05.942030 | rockylinux-9 -> localhost | Your public key has been saved in /var/lib/zuul/builds/3bf927c90c9147ab9d96cb959c5f98a0/work/3bf927c90c9147ab9d96cb959c5f98a0_id_rsa.pub 2026-04-14 02:39:05.942054 | rockylinux-9 -> localhost | The key fingerprint is: 2026-04-14 02:39:05.942076 | rockylinux-9 -> localhost | SHA256:zmQePV+OlzpvWBppwQQ18WZRVHFFXKh7pRMSYW3EVUI zuul-build-sshkey 2026-04-14 02:39:05.942111 | rockylinux-9 -> localhost | The key's randomart image is: 2026-04-14 02:39:05.942134 | rockylinux-9 -> localhost | +---[RSA 3072]----+ 2026-04-14 02:39:05.942159 | rockylinux-9 -> localhost | | .o*BEO^| 2026-04-14 02:39:05.942182 | rockylinux-9 -> localhost | | .oo=oo| 2026-04-14 02:39:05.942203 | rockylinux-9 -> localhost | | o ++ | 2026-04-14 02:39:05.942224 | rockylinux-9 -> localhost | | . =o. .| 2026-04-14 02:39:05.942244 | rockylinux-9 -> localhost | | S o =.+ | 2026-04-14 02:39:05.942265 | rockylinux-9 -> localhost | | * . o=+=. | 2026-04-14 02:39:05.942285 | rockylinux-9 -> localhost | | + .o*+. | 2026-04-14 02:39:05.942306 | rockylinux-9 -> localhost | | +o. | 2026-04-14 02:39:05.942329 | rockylinux-9 -> localhost | | .+. | 2026-04-14 02:39:05.942350 | rockylinux-9 -> localhost | +----[SHA256]-----+ 2026-04-14 02:39:05.942400 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.885074 2026-04-14 02:39:05.947156 | 2026-04-14 02:39:06.100647 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-04-14 02:39:06.130982 | rockylinux-9 | ok 2026-04-14 02:39:06.139081 | rockylinux-9 | included: /var/lib/zuul/builds/3bf927c90c9147ab9d96cb959c5f98a0/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-04-14 02:39:06.160281 | 2026-04-14 02:39:06.160473 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-04-14 02:39:06.185789 | rockylinux-9 | skipping: Conditional result was False 2026-04-14 02:39:06.195894 | 2026-04-14 02:39:06.195997 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-04-14 02:39:06.703919 | rockylinux-9 | changed 2026-04-14 02:39:06.709971 | 2026-04-14 02:39:06.710042 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-04-14 02:39:06.980741 | rockylinux-9 | ok 2026-04-14 02:39:06.985894 | 2026-04-14 02:39:06.985960 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-04-14 02:39:07.914136 | rockylinux-9 | changed 2026-04-14 02:39:07.981272 | 2026-04-14 02:39:07.981352 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-04-14 02:39:08.959324 | rockylinux-9 | changed 2026-04-14 02:39:09.285242 | 2026-04-14 02:39:09.285349 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-04-14 02:39:09.323939 | rockylinux-9 | skipping: Conditional result was False 2026-04-14 02:39:09.333898 | 2026-04-14 02:39:09.333979 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-04-14 02:39:09.751954 | rockylinux-9 -> localhost | changed 2026-04-14 02:39:09.761929 | 2026-04-14 02:39:09.762048 | TASK [add-build-sshkey : Add back temp key] 2026-04-14 02:39:10.083788 | rockylinux-9 -> localhost | Identity added: /var/lib/zuul/builds/3bf927c90c9147ab9d96cb959c5f98a0/work/3bf927c90c9147ab9d96cb959c5f98a0_id_rsa (zuul-build-sshkey) 2026-04-14 02:39:10.084114 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.015727 2026-04-14 02:39:10.091550 | 2026-04-14 02:39:10.091614 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-04-14 02:39:10.512781 | rockylinux-9 | ok 2026-04-14 02:39:10.518815 | 2026-04-14 02:39:10.518897 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-04-14 02:39:10.543500 | rockylinux-9 | skipping: Conditional result was False 2026-04-14 02:39:10.556581 | 2026-04-14 02:39:10.556838 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-04-14 02:39:10.974772 | rockylinux-9 | ok 2026-04-14 02:39:11.188804 | 2026-04-14 02:39:11.188900 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-04-14 02:39:12.202808 | rockylinux-9 | Output suppressed because no_log was given 2026-04-14 02:39:12.211478 | 2026-04-14 02:39:12.211575 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-04-14 02:39:12.495350 | rockylinux-9 | ok: "logs" 2026-04-14 02:39:12.495714 | rockylinux-9 | ok: All items complete 2026-04-14 02:39:12.495766 | 2026-04-14 02:39:12.714923 | rockylinux-9 | ok: "artifacts" 2026-04-14 02:39:12.939884 | rockylinux-9 | ok: "docs" 2026-04-14 02:39:12.952130 | 2026-04-14 02:39:12.952614 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-04-14 02:39:13.208091 | rockylinux-9 | changed: "logs" 2026-04-14 02:39:13.452073 | rockylinux-9 | changed: "artifacts" 2026-04-14 02:39:13.670038 | rockylinux-9 | changed: "docs" 2026-04-14 02:39:13.686493 | 2026-04-14 02:39:13.686655 | PLAY RECAP 2026-04-14 02:39:13.686720 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-14 02:39:13.686762 | rockylinux-9 | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-04-14 02:39:13.686793 | 2026-04-14 02:39:13.841979 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-04-14 02:39:13.899711 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-04-14 02:39:14.560412 | 2026-04-14 02:39:14.560558 | PLAY [all] 2026-04-14 02:39:14.572611 | 2026-04-14 02:39:14.572711 | TASK [setup-uv : Extract archive] 2026-04-14 02:39:17.421237 | rockylinux-9 | changed 2026-04-14 02:39:17.427962 | 2026-04-14 02:39:17.428123 | TASK [setup-uv : Print version] 2026-04-14 02:39:17.458475 | [rockylinux-9] Waiting on logger 2026-04-14 02:39:17.974257 | rockylinux-9 | ok: Runtime: 0:00:00.017680 2026-04-14 02:39:17.989569 | 2026-04-14 02:39:17.989811 | PLAY RECAP 2026-04-14 02:39:17.989965 | rockylinux-9 | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-14 02:39:17.990071 | 2026-04-14 02:39:18.135315 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-04-14 02:39:18.136574 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-04-14 02:39:18.757916 | 2026-04-14 02:39:18.758090 | PLAY [all] 2026-04-14 02:39:18.769832 | 2026-04-14 02:39:18.769949 | TASK [Copy inventory file for Zuul] 2026-04-14 02:39:20.180296 | rockylinux-9 | changed 2026-04-14 02:39:20.186590 | 2026-04-14 02:39:20.186694 | TASK [Switch "ansible_host" to private IP] 2026-04-14 02:39:20.551696 | rockylinux-9 | changed: 1 replacements made 2026-04-14 02:39:20.557106 | 2026-04-14 02:39:20.557183 | TASK [Run Molecule scenario] 2026-04-14 02:39:20.577213 | [rockylinux-9] Waiting on logger 2026-04-14 02:39:28.709929 | [rockylinux-9] Waiting on logger 2026-04-14 02:39:38.886014 | [rockylinux-9] Waiting on logger 2026-04-14 02:39:49.066116 | [rockylinux-9] Waiting on logger 2026-04-14 02:39:59.238137 | [rockylinux-9] Waiting on logger 2026-04-14 02:40:09.414068 | [rockylinux-9] Waiting on logger 2026-04-14 02:40:18.571517 | [rockylinux-9] Waiting on logger 2026-04-14 02:40:28.742134 | [rockylinux-9] Waiting on logger 2026-04-14 02:40:38.918113 | [rockylinux-9] Waiting on logger 2026-04-14 02:40:49.094192 | [rockylinux-9] Waiting on logger 2026-04-14 02:40:59.270089 | [rockylinux-9] Waiting on logger 2026-04-14 02:41:09.446345 | [rockylinux-9] Waiting on logger 2026-04-14 02:41:18.602193 | [rockylinux-9] Waiting on logger 2026-04-14 02:41:19.725287 | rockylinux-9 | ok: Runtime: 0:01:58.349185 2026-04-14 02:41:19.732988 | 2026-04-14 02:41:19.733035 | PLAY RECAP 2026-04-14 02:41:19.733077 | rockylinux-9 | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-14 02:41:19.733099 | 2026-04-14 02:41:19.844021 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-04-14 02:41:19.845283 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-04-14 02:41:20.490180 | 2026-04-14 02:41:20.490460 | PLAY [all] 2026-04-14 02:41:20.502392 | 2026-04-14 02:41:20.502499 | TASK [fetch-output : Set log path for multiple nodes] 2026-04-14 02:41:20.548550 | rockylinux-9 | skipping: Conditional result was False 2026-04-14 02:41:20.553690 | 2026-04-14 02:41:20.553775 | TASK [fetch-output : Set log path for single node] 2026-04-14 02:41:20.599968 | rockylinux-9 | ok 2026-04-14 02:41:20.604373 | 2026-04-14 02:41:20.604446 | LOOP [fetch-output : Ensure local output dirs] 2026-04-14 02:41:20.989902 | rockylinux-9 -> localhost | ok: "/var/lib/zuul/builds/3bf927c90c9147ab9d96cb959c5f98a0/work/logs" 2026-04-14 02:41:21.212029 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/3bf927c90c9147ab9d96cb959c5f98a0/work/artifacts" 2026-04-14 02:41:21.426349 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/3bf927c90c9147ab9d96cb959c5f98a0/work/docs" 2026-04-14 02:41:21.444701 | 2026-04-14 02:41:21.444862 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-04-14 02:41:22.176449 | rockylinux-9 | changed: .d..t...... ./ 2026-04-14 02:41:22.176727 | rockylinux-9 | changed: All items complete 2026-04-14 02:41:22.176763 | 2026-04-14 02:41:22.700918 | rockylinux-9 | changed: .d..t...... ./ 2026-04-14 02:41:23.218948 | rockylinux-9 | changed: .d..t...... ./ 2026-04-14 02:41:23.244689 | 2026-04-14 02:41:23.244867 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-04-14 02:41:23.668983 | rockylinux-9 -> localhost | ok: Item: artifacts Runtime: 0:00:00.007917 2026-04-14 02:41:23.895174 | rockylinux-9 -> localhost | ok: Item: docs Runtime: 0:00:00.008444 2026-04-14 02:41:23.918202 | 2026-04-14 02:41:23.918351 | PLAY [all] 2026-04-14 02:41:23.924262 | 2026-04-14 02:41:23.924333 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-04-14 02:41:24.384062 | rockylinux-9 | changed 2026-04-14 02:41:24.391525 | 2026-04-14 02:41:24.391571 | PLAY RECAP 2026-04-14 02:41:24.391616 | rockylinux-9 | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-04-14 02:41:24.391639 | 2026-04-14 02:41:24.522876 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-04-14 02:41:24.525652 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-04-14 02:41:25.116417 | 2026-04-14 02:41:25.116563 | PLAY [localhost] 2026-04-14 02:41:25.126913 | 2026-04-14 02:41:25.127003 | TASK [Generate Zuul manifest] 2026-04-14 02:41:25.159615 | localhost | ok 2026-04-14 02:41:25.176203 | 2026-04-14 02:41:25.176283 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-04-14 02:41:25.502142 | localhost | changed 2026-04-14 02:41:25.515569 | 2026-04-14 02:41:25.515650 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-04-14 02:41:25.547225 | localhost | ok 2026-04-14 02:41:25.555722 | 2026-04-14 02:41:25.555790 | TASK [Upload logs] 2026-04-14 02:41:25.578264 | localhost | ok 2026-04-14 02:41:25.638679 | 2026-04-14 02:41:25.638825 | TASK [Set zuul-log-path fact] 2026-04-14 02:41:25.662037 | localhost | ok 2026-04-14 02:41:25.674956 | 2026-04-14 02:41:25.675028 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-14 02:41:25.706916 | localhost | ok 2026-04-14 02:41:25.716006 | 2026-04-14 02:41:25.716068 | TASK [upload-logs : Create log directories] 2026-04-14 02:41:26.133073 | localhost | changed 2026-04-14 02:41:26.139331 | 2026-04-14 02:41:26.139403 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-04-14 02:41:26.564143 | localhost -> localhost | ok: Runtime: 0:00:00.005886 2026-04-14 02:41:26.569518 | 2026-04-14 02:41:26.569582 | TASK [upload-logs : Upload logs to log server] 2026-04-14 02:41:27.041198 | localhost | Output suppressed because no_log was given 2026-04-14 02:41:27.046765 | 2026-04-14 02:41:27.046835 | LOOP [upload-logs : Compress console log and json output] 2026-04-14 02:41:27.093524 | localhost | skipping: Conditional result was False 2026-04-14 02:41:27.100374 | localhost | skipping: Conditional result was False 2026-04-14 02:41:27.113603 | 2026-04-14 02:41:27.113876 | LOOP [upload-logs : Upload compressed console log and json output] 2026-04-14 02:41:27.158982 | localhost | skipping: Conditional result was False 2026-04-14 02:41:27.159342 | 2026-04-14 02:41:27.163363 | localhost | skipping: Conditional result was False 2026-04-14 02:41:27.177940 | 2026-04-14 02:41:27.178149 | LOOP [upload-logs : Upload console log and json output]