2026-04-10 18:02:08.078008 | Job console starting 2026-04-10 18:02:08.088381 | Updating git repos 2026-04-10 18:02:08.116826 | Cloning repos into workspace 2026-04-10 18:02:08.169449 | Restoring repo states 2026-04-10 18:02:08.174486 | Merging changes 2026-04-10 18:02:08.439844 | Checking out repos 2026-04-10 18:02:08.477510 | Preparing playbooks 2026-04-10 18:02:09.580762 | Running Ansible setup 2026-04-10 18:02:13.066404 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-04-10 18:02:13.635228 | 2026-04-10 18:02:13.635401 | PLAY [localhost] 2026-04-10 18:02:13.643114 | 2026-04-10 18:02:13.643192 | TASK [Gathering Facts] 2026-04-10 18:02:14.521305 | localhost | ok 2026-04-10 18:02:14.529629 | 2026-04-10 18:02:14.529702 | TASK [Setup log path fact] 2026-04-10 18:02:14.552979 | localhost | ok 2026-04-10 18:02:14.565974 | 2026-04-10 18:02:14.566074 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-10 18:02:14.608737 | localhost | ok 2026-04-10 18:02:14.618454 | 2026-04-10 18:02:14.618610 | TASK [emit-job-header : Print job information] 2026-04-10 18:02:14.659077 | # Job Information 2026-04-10 18:02:14.659387 | Ansible Version: 2.16.16 2026-04-10 18:02:14.659438 | Job: ansible-collection-containers-molecule-containerd-rockylinux-9 2026-04-10 18:02:14.659470 | Pipeline: check 2026-04-10 18:02:14.659499 | Executor: 0a8996d2b663 2026-04-10 18:02:14.659527 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/107 2026-04-10 18:02:14.659560 | Event ID: 4a3f6d70-3507-11f1-95f5-3f4f9ca288e7 2026-04-10 18:02:14.663496 | 2026-04-10 18:02:14.663581 | LOOP [emit-job-header : Print node information] 2026-04-10 18:02:14.752220 | localhost | ok: 2026-04-10 18:02:14.752413 | localhost | # Node Information 2026-04-10 18:02:14.752456 | localhost | Inventory Hostname: rockylinux-9 2026-04-10 18:02:14.752494 | localhost | Hostname: np0000164890 2026-04-10 18:02:14.752525 | localhost | Username: zuul 2026-04-10 18:02:14.752559 | localhost | Distro: Rocky 9.7 2026-04-10 18:02:14.752589 | localhost | Provider: yul1 2026-04-10 18:02:14.752617 | localhost | Region: ca-ymq-1 2026-04-10 18:02:14.752645 | localhost | Label: rockylinux-9 2026-04-10 18:02:14.752672 | localhost | Product Name: OpenStack Nova 2026-04-10 18:02:14.752700 | localhost | Interface IP: 162.253.55.62 2026-04-10 18:02:14.761531 | 2026-04-10 18:02:14.761679 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-04-10 18:02:15.150412 | localhost -> localhost | changed 2026-04-10 18:02:15.155917 | 2026-04-10 18:02:15.156041 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-04-10 18:02:16.126379 | localhost -> localhost | changed 2026-04-10 18:02:16.134796 | 2026-04-10 18:02:16.134869 | PLAY [all] 2026-04-10 18:02:16.144550 | 2026-04-10 18:02:16.144626 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-04-10 18:02:16.533597 | rockylinux-9 -> localhost | ok 2026-04-10 18:02:16.541849 | 2026-04-10 18:02:16.541950 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-04-10 18:02:16.571615 | rockylinux-9 | ok 2026-04-10 18:02:16.590270 | rockylinux-9 | included: /var/lib/zuul/builds/a5ccee2d0ca44a9495e7740d93ae0b4a/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-04-10 18:02:16.595875 | 2026-04-10 18:02:16.595948 | TASK [add-build-sshkey : Create Temp SSH key] 2026-04-10 18:02:17.505519 | rockylinux-9 -> localhost | Generating public/private rsa key pair. 2026-04-10 18:02:17.505794 | rockylinux-9 -> localhost | Your identification has been saved in /var/lib/zuul/builds/a5ccee2d0ca44a9495e7740d93ae0b4a/work/a5ccee2d0ca44a9495e7740d93ae0b4a_id_rsa 2026-04-10 18:02:17.505866 | rockylinux-9 -> localhost | Your public key has been saved in /var/lib/zuul/builds/a5ccee2d0ca44a9495e7740d93ae0b4a/work/a5ccee2d0ca44a9495e7740d93ae0b4a_id_rsa.pub 2026-04-10 18:02:17.505921 | rockylinux-9 -> localhost | The key fingerprint is: 2026-04-10 18:02:17.505972 | rockylinux-9 -> localhost | SHA256:jWkYFSM8lsEBaiOERFXrVXB/AtlxqCFb2JsGJ3ZZdwo zuul-build-sshkey 2026-04-10 18:02:17.506059 | rockylinux-9 -> localhost | The key's randomart image is: 2026-04-10 18:02:17.506109 | rockylinux-9 -> localhost | +---[RSA 3072]----+ 2026-04-10 18:02:17.506164 | rockylinux-9 -> localhost | |++..o=+=B==Eoo . | 2026-04-10 18:02:17.506213 | rockylinux-9 -> localhost | |o . BOoXoo+ o | 2026-04-10 18:02:17.506260 | rockylinux-9 -> localhost | |. + oooO =o o | 2026-04-10 18:02:17.506305 | rockylinux-9 -> localhost | | o .. .+ O o | 2026-04-10 18:02:17.506351 | rockylinux-9 -> localhost | | .. S . | 2026-04-10 18:02:17.506395 | rockylinux-9 -> localhost | | . | 2026-04-10 18:02:17.506438 | rockylinux-9 -> localhost | | | 2026-04-10 18:02:17.506481 | rockylinux-9 -> localhost | | | 2026-04-10 18:02:17.506570 | rockylinux-9 -> localhost | | | 2026-04-10 18:02:17.506620 | rockylinux-9 -> localhost | +----[SHA256]-----+ 2026-04-10 18:02:17.506727 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.461035 2026-04-10 18:02:17.515961 | 2026-04-10 18:02:17.516127 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-04-10 18:02:17.549195 | rockylinux-9 | ok 2026-04-10 18:02:17.562270 | rockylinux-9 | included: /var/lib/zuul/builds/a5ccee2d0ca44a9495e7740d93ae0b4a/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-04-10 18:02:17.573528 | 2026-04-10 18:02:17.573638 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-04-10 18:02:17.587252 | rockylinux-9 | skipping: Conditional result was False 2026-04-10 18:02:17.600001 | 2026-04-10 18:02:17.600125 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-04-10 18:02:18.119074 | rockylinux-9 | changed 2026-04-10 18:02:18.125000 | 2026-04-10 18:02:18.125126 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-04-10 18:02:18.368286 | rockylinux-9 | ok 2026-04-10 18:02:18.375444 | 2026-04-10 18:02:18.375564 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-04-10 18:02:19.304814 | rockylinux-9 | changed 2026-04-10 18:02:19.326457 | 2026-04-10 18:02:19.326613 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-04-10 18:02:20.250124 | rockylinux-9 | changed 2026-04-10 18:02:20.254674 | 2026-04-10 18:02:20.254736 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-04-10 18:02:20.277980 | rockylinux-9 | skipping: Conditional result was False 2026-04-10 18:02:20.285722 | 2026-04-10 18:02:20.285807 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-04-10 18:02:21.097511 | rockylinux-9 -> localhost | changed 2026-04-10 18:02:21.119687 | 2026-04-10 18:02:21.119955 | TASK [add-build-sshkey : Add back temp key] 2026-04-10 18:02:21.384219 | rockylinux-9 -> localhost | Identity added: /var/lib/zuul/builds/a5ccee2d0ca44a9495e7740d93ae0b4a/work/a5ccee2d0ca44a9495e7740d93ae0b4a_id_rsa (zuul-build-sshkey) 2026-04-10 18:02:21.384453 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.011836 2026-04-10 18:02:21.392074 | 2026-04-10 18:02:21.392197 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-04-10 18:02:21.777709 | rockylinux-9 | ok 2026-04-10 18:02:21.796030 | 2026-04-10 18:02:21.796146 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-04-10 18:02:21.824405 | rockylinux-9 | skipping: Conditional result was False 2026-04-10 18:02:21.836817 | 2026-04-10 18:02:21.836934 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-04-10 18:02:22.226756 | rockylinux-9 | ok 2026-04-10 18:02:22.232474 | 2026-04-10 18:02:22.232536 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-04-10 18:02:23.082548 | rockylinux-9 | Output suppressed because no_log was given 2026-04-10 18:02:23.094707 | 2026-04-10 18:02:23.094854 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-04-10 18:02:23.333358 | rockylinux-9 | ok: "logs" 2026-04-10 18:02:23.333675 | rockylinux-9 | ok: All items complete 2026-04-10 18:02:23.333711 | 2026-04-10 18:02:23.572750 | rockylinux-9 | ok: "artifacts" 2026-04-10 18:02:23.801622 | rockylinux-9 | ok: "docs" 2026-04-10 18:02:23.815830 | 2026-04-10 18:02:23.816022 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-04-10 18:02:24.076560 | rockylinux-9 | changed: "logs" 2026-04-10 18:02:24.309537 | rockylinux-9 | changed: "artifacts" 2026-04-10 18:02:24.529227 | rockylinux-9 | changed: "docs" 2026-04-10 18:02:24.542803 | 2026-04-10 18:02:24.542914 | PLAY RECAP 2026-04-10 18:02:24.542957 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-10 18:02:24.542988 | rockylinux-9 | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-04-10 18:02:24.543011 | 2026-04-10 18:02:24.644299 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-04-10 18:02:24.648711 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-04-10 18:02:25.275380 | 2026-04-10 18:02:25.275509 | PLAY [all] 2026-04-10 18:02:25.291794 | 2026-04-10 18:02:25.291972 | TASK [setup-uv : Extract archive] 2026-04-10 18:02:28.334717 | rockylinux-9 | changed 2026-04-10 18:02:28.340234 | 2026-04-10 18:02:28.340310 | TASK [setup-uv : Print version] 2026-04-10 18:02:28.364564 | [rockylinux-9] Waiting on logger 2026-04-10 18:02:28.883663 | rockylinux-9 | ok: Runtime: 0:00:00.014035 2026-04-10 18:02:28.894909 | 2026-04-10 18:02:28.894987 | PLAY RECAP 2026-04-10 18:02:28.895045 | rockylinux-9 | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-10 18:02:28.895078 | 2026-04-10 18:02:29.015477 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-04-10 18:02:29.080079 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-04-10 18:02:29.715983 | 2026-04-10 18:02:29.716110 | PLAY [all] 2026-04-10 18:02:29.727933 | 2026-04-10 18:02:29.731921 | TASK [Copy inventory file for Zuul] 2026-04-10 18:02:31.103404 | rockylinux-9 | changed 2026-04-10 18:02:31.110172 | 2026-04-10 18:02:31.110272 | TASK [Switch "ansible_host" to private IP] 2026-04-10 18:02:31.462060 | rockylinux-9 | changed: 1 replacements made 2026-04-10 18:02:31.499476 | 2026-04-10 18:02:31.499566 | TASK [Run Molecule scenario] 2026-04-10 18:02:31.520115 | [rockylinux-9] Waiting on logger 2026-04-10 18:02:39.658390 | [rockylinux-9] Waiting on logger 2026-04-10 18:02:49.830040 | [rockylinux-9] Waiting on logger 2026-04-10 18:03:00.006172 | [rockylinux-9] Waiting on logger 2026-04-10 18:03:10.182120 | [rockylinux-9] Waiting on logger 2026-04-10 18:03:20.358133 | [rockylinux-9] Waiting on logger 2026-04-10 18:03:29.510172 | [rockylinux-9] Waiting on logger 2026-04-10 18:03:39.686138 | [rockylinux-9] Waiting on logger 2026-04-10 18:03:49.862298 | [rockylinux-9] Waiting on logger 2026-04-10 18:04:00.038292 | [rockylinux-9] Waiting on logger 2026-04-10 18:04:10.213938 | [rockylinux-9] Waiting on logger 2026-04-10 18:04:19.371241 | [rockylinux-9] Waiting on logger 2026-04-10 18:04:29.541942 | [rockylinux-9] Waiting on logger 2026-04-10 18:04:39.722071 | [rockylinux-9] Waiting on logger 2026-04-10 18:04:49.894197 | [rockylinux-9] Waiting on logger 2026-04-10 18:05:00.070045 | [rockylinux-9] Waiting on logger 2026-04-10 18:05:10.246142 | [rockylinux-9] Waiting on logger 2026-04-10 18:05:19.403750 | [rockylinux-9] Waiting on logger 2026-04-10 18:05:29.574188 | [rockylinux-9] Waiting on logger 2026-04-10 18:05:39.749909 | [rockylinux-9] Waiting on logger 2026-04-10 18:05:49.926267 | [rockylinux-9] Waiting on logger 2026-04-10 18:06:00.102180 | [rockylinux-9] Waiting on logger 2026-04-10 18:06:10.278203 | [rockylinux-9] Waiting on logger 2026-04-10 18:06:19.439547 | [rockylinux-9] Waiting on logger 2026-04-10 18:06:29.606049 | [rockylinux-9] Waiting on logger 2026-04-10 18:06:39.782112 | [rockylinux-9] Waiting on logger 2026-04-10 18:06:49.958196 | [rockylinux-9] Waiting on logger 2026-04-10 18:07:00.138240 | [rockylinux-9] Waiting on logger 2026-04-10 18:07:03.597752 | rockylinux-9 | ok: Runtime: 0:04:31.565207 2026-04-10 18:07:03.604929 | 2026-04-10 18:07:03.604994 | PLAY RECAP 2026-04-10 18:07:03.605039 | rockylinux-9 | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-10 18:07:03.605061 | 2026-04-10 18:07:03.707484 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-04-10 18:07:03.710196 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-04-10 18:07:04.287179 | 2026-04-10 18:07:04.287307 | PLAY [all] 2026-04-10 18:07:04.297860 | 2026-04-10 18:07:04.297966 | TASK [fetch-output : Set log path for multiple nodes] 2026-04-10 18:07:04.342467 | rockylinux-9 | skipping: Conditional result was False 2026-04-10 18:07:04.350203 | 2026-04-10 18:07:04.350694 | TASK [fetch-output : Set log path for single node] 2026-04-10 18:07:04.395056 | rockylinux-9 | ok 2026-04-10 18:07:04.400734 | 2026-04-10 18:07:04.400802 | LOOP [fetch-output : Ensure local output dirs] 2026-04-10 18:07:04.789209 | rockylinux-9 -> localhost | ok: "/var/lib/zuul/builds/a5ccee2d0ca44a9495e7740d93ae0b4a/work/logs" 2026-04-10 18:07:04.994712 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/a5ccee2d0ca44a9495e7740d93ae0b4a/work/artifacts" 2026-04-10 18:07:05.215040 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/a5ccee2d0ca44a9495e7740d93ae0b4a/work/docs" 2026-04-10 18:07:05.230050 | 2026-04-10 18:07:05.230204 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-04-10 18:07:05.917757 | rockylinux-9 | changed: .d..t...... ./ 2026-04-10 18:07:05.918045 | rockylinux-9 | changed: All items complete 2026-04-10 18:07:05.918084 | 2026-04-10 18:07:06.422041 | rockylinux-9 | changed: .d..t...... ./ 2026-04-10 18:07:06.920333 | rockylinux-9 | changed: .d..t...... ./ 2026-04-10 18:07:06.938295 | 2026-04-10 18:07:06.938463 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-04-10 18:07:07.365556 | rockylinux-9 -> localhost | ok: Item: artifacts Runtime: 0:00:00.007484 2026-04-10 18:07:07.574810 | rockylinux-9 -> localhost | ok: Item: docs Runtime: 0:00:00.007064 2026-04-10 18:07:07.587063 | 2026-04-10 18:07:07.587193 | PLAY [all] 2026-04-10 18:07:07.593211 | 2026-04-10 18:07:07.593272 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-04-10 18:07:08.040351 | rockylinux-9 | changed 2026-04-10 18:07:08.046951 | 2026-04-10 18:07:08.046999 | PLAY RECAP 2026-04-10 18:07:08.047042 | rockylinux-9 | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-04-10 18:07:08.047066 | 2026-04-10 18:07:08.141176 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-04-10 18:07:08.143385 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-04-10 18:07:08.712000 | 2026-04-10 18:07:08.712130 | PLAY [localhost] 2026-04-10 18:07:08.723220 | 2026-04-10 18:07:08.723309 | TASK [Generate Zuul manifest] 2026-04-10 18:07:08.744900 | localhost | ok 2026-04-10 18:07:08.762661 | 2026-04-10 18:07:08.762819 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-04-10 18:07:09.069063 | localhost | changed 2026-04-10 18:07:09.080126 | 2026-04-10 18:07:09.080230 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-04-10 18:07:09.111621 | localhost | ok 2026-04-10 18:07:09.119919 | 2026-04-10 18:07:09.119988 | TASK [Upload logs] 2026-04-10 18:07:09.141932 | localhost | ok 2026-04-10 18:07:09.213168 | 2026-04-10 18:07:09.213330 | TASK [Set zuul-log-path fact] 2026-04-10 18:07:09.235053 | localhost | ok 2026-04-10 18:07:09.248501 | 2026-04-10 18:07:09.248576 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-10 18:07:09.280176 | localhost | ok 2026-04-10 18:07:09.289710 | 2026-04-10 18:07:09.289779 | TASK [upload-logs : Create log directories] 2026-04-10 18:07:09.684667 | localhost | changed 2026-04-10 18:07:09.692581 | 2026-04-10 18:07:09.692667 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-04-10 18:07:10.077749 | localhost -> localhost | ok: Runtime: 0:00:00.005565 2026-04-10 18:07:10.083530 | 2026-04-10 18:07:10.083590 | TASK [upload-logs : Upload logs to log server] 2026-04-10 18:07:10.548296 | localhost | Output suppressed because no_log was given 2026-04-10 18:07:10.553708 | 2026-04-10 18:07:10.553808 | LOOP [upload-logs : Compress console log and json output] 2026-04-10 18:07:10.597614 | localhost | skipping: Conditional result was False 2026-04-10 18:07:10.604904 | localhost | skipping: Conditional result was False 2026-04-10 18:07:10.617123 | 2026-04-10 18:07:10.617268 | LOOP [upload-logs : Upload compressed console log and json output] 2026-04-10 18:07:10.659988 | localhost | skipping: Conditional result was False 2026-04-10 18:07:10.660372 | 2026-04-10 18:07:10.664246 | localhost | skipping: Conditional result was False 2026-04-10 18:07:10.678254 | 2026-04-10 18:07:10.678350 | LOOP [upload-logs : Upload console log and json output]