2026-05-09 12:57:54.620809 | Job console starting 2026-05-09 12:57:54.631401 | Updating git repos 2026-05-09 12:57:54.656437 | Cloning repos into workspace 2026-05-09 12:57:57.015077 | Restoring repo states 2026-05-09 12:57:57.023732 | Merging changes 2026-05-09 12:57:57.647754 | Checking out repos 2026-05-09 12:57:57.984887 | Preparing playbooks 2026-05-09 12:58:01.341227 | Running Ansible setup 2026-05-09 12:58:05.038874 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-05-09 12:58:05.686742 | 2026-05-09 12:58:05.686887 | PLAY [localhost] 2026-05-09 12:58:05.694609 | 2026-05-09 12:58:05.694693 | TASK [Gathering Facts] 2026-05-09 12:58:06.583353 | localhost | ok 2026-05-09 12:58:06.594692 | 2026-05-09 12:58:06.594769 | TASK [Setup log path fact] 2026-05-09 12:58:06.640677 | localhost | ok 2026-05-09 12:58:06.660394 | 2026-05-09 12:58:06.660532 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-09 12:58:06.691548 | localhost | ok 2026-05-09 12:58:06.699262 | 2026-05-09 12:58:06.699332 | TASK [emit-job-header : Print job information] 2026-05-09 12:58:06.740467 | # Job Information 2026-05-09 12:58:06.740703 | Ansible Version: 2.16.18 2026-05-09 12:58:06.740772 | Job: ansible-collection-containers-molecule-containerd-rockylinux-9 2026-05-09 12:58:06.740825 | Pipeline: check 2026-05-09 12:58:06.740868 | Executor: 2d72f0692154 2026-05-09 12:58:06.740910 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/124 2026-05-09 12:58:06.740977 | Event ID: 13987950-4ba6-11f1-9dc4-27c1f9d27b57 2026-05-09 12:58:06.746950 | 2026-05-09 12:58:06.747049 | LOOP [emit-job-header : Print node information] 2026-05-09 12:58:06.835975 | localhost | ok: 2026-05-09 12:58:06.836150 | localhost | # Node Information 2026-05-09 12:58:06.836193 | localhost | Inventory Hostname: rockylinux-9 2026-05-09 12:58:06.836225 | localhost | Hostname: np0000173114 2026-05-09 12:58:06.836256 | localhost | Username: zuul 2026-05-09 12:58:06.836291 | localhost | Distro: Rocky 9.7 2026-05-09 12:58:06.836323 | localhost | Provider: yul1 2026-05-09 12:58:06.836353 | localhost | Region: ca-ymq-1 2026-05-09 12:58:06.836381 | localhost | Label: rockylinux-9 2026-05-09 12:58:06.836410 | localhost | Product Name: OpenStack Nova 2026-05-09 12:58:06.836439 | localhost | Interface IP: 199.204.45.35 2026-05-09 12:58:06.850767 | 2026-05-09 12:58:06.850921 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-05-09 12:58:07.256388 | localhost -> localhost | changed 2026-05-09 12:58:07.267965 | 2026-05-09 12:58:07.268116 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-05-09 12:58:08.213726 | localhost -> localhost | changed 2026-05-09 12:58:08.228448 | 2026-05-09 12:58:08.228825 | PLAY [all] 2026-05-09 12:58:08.235969 | 2026-05-09 12:58:08.236035 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-05-09 12:58:08.462002 | rockylinux-9 -> localhost | ok 2026-05-09 12:58:08.467483 | 2026-05-09 12:58:08.467551 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-05-09 12:58:08.501594 | rockylinux-9 | ok 2026-05-09 12:58:08.522275 | rockylinux-9 | included: /var/lib/zuul/builds/57a3ac557ec84405a6f518a50b78d610/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-05-09 12:58:08.529801 | 2026-05-09 12:58:08.529919 | TASK [add-build-sshkey : Create Temp SSH key] 2026-05-09 12:58:09.799008 | rockylinux-9 -> localhost | Generating public/private rsa key pair. 2026-05-09 12:58:09.799193 | rockylinux-9 -> localhost | Your identification has been saved in /var/lib/zuul/builds/57a3ac557ec84405a6f518a50b78d610/work/57a3ac557ec84405a6f518a50b78d610_id_rsa 2026-05-09 12:58:09.799235 | rockylinux-9 -> localhost | Your public key has been saved in /var/lib/zuul/builds/57a3ac557ec84405a6f518a50b78d610/work/57a3ac557ec84405a6f518a50b78d610_id_rsa.pub 2026-05-09 12:58:09.799268 | rockylinux-9 -> localhost | The key fingerprint is: 2026-05-09 12:58:09.799298 | rockylinux-9 -> localhost | SHA256:8YmUReGeLpssdQu3oRE+5481H+fVz0u4d8AEPBQ/948 zuul-build-sshkey 2026-05-09 12:58:09.799343 | rockylinux-9 -> localhost | The key's randomart image is: 2026-05-09 12:58:09.799373 | rockylinux-9 -> localhost | +---[RSA 3072]----+ 2026-05-09 12:58:09.799406 | rockylinux-9 -> localhost | | .++o. | 2026-05-09 12:58:09.799436 | rockylinux-9 -> localhost | | + +. | 2026-05-09 12:58:09.799465 | rockylinux-9 -> localhost | | + . oo .| 2026-05-09 12:58:09.799493 | rockylinux-9 -> localhost | | ..= o .o.| 2026-05-09 12:58:09.799521 | rockylinux-9 -> localhost | | .S.= o .| 2026-05-09 12:58:09.799549 | rockylinux-9 -> localhost | | *.= +.o| 2026-05-09 12:58:09.799576 | rockylinux-9 -> localhost | | ..X.+o.Eo=| 2026-05-09 12:58:09.799604 | rockylinux-9 -> localhost | | ...++o oo*+| 2026-05-09 12:58:09.799636 | rockylinux-9 -> localhost | | .+ ....o.*| 2026-05-09 12:58:09.799665 | rockylinux-9 -> localhost | +----[SHA256]-----+ 2026-05-09 12:58:09.799731 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.831100 2026-05-09 12:58:09.805052 | 2026-05-09 12:58:09.805117 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-05-09 12:58:09.836912 | rockylinux-9 | ok 2026-05-09 12:58:09.847060 | rockylinux-9 | included: /var/lib/zuul/builds/57a3ac557ec84405a6f518a50b78d610/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-05-09 12:58:09.854826 | 2026-05-09 12:58:09.855776 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-05-09 12:58:09.880184 | rockylinux-9 | skipping: Conditional result was False 2026-05-09 12:58:09.889926 | 2026-05-09 12:58:09.890016 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-05-09 12:58:10.402766 | rockylinux-9 | changed 2026-05-09 12:58:10.409174 | 2026-05-09 12:58:10.409264 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-05-09 12:58:10.666820 | rockylinux-9 | ok 2026-05-09 12:58:11.314707 | 2026-05-09 12:58:11.314926 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-05-09 12:58:12.259081 | rockylinux-9 | changed 2026-05-09 12:58:12.272529 | 2026-05-09 12:58:12.272711 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-05-09 12:58:13.254248 | rockylinux-9 | changed 2026-05-09 12:58:13.264711 | 2026-05-09 12:58:13.264822 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-05-09 12:58:13.284248 | rockylinux-9 | skipping: Conditional result was False 2026-05-09 12:58:13.290054 | 2026-05-09 12:58:13.290177 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-05-09 12:58:13.809941 | rockylinux-9 -> localhost | changed 2026-05-09 12:58:13.829078 | 2026-05-09 12:58:13.829653 | TASK [add-build-sshkey : Add back temp key] 2026-05-09 12:58:14.151857 | rockylinux-9 -> localhost | Identity added: /var/lib/zuul/builds/57a3ac557ec84405a6f518a50b78d610/work/57a3ac557ec84405a6f518a50b78d610_id_rsa (zuul-build-sshkey) 2026-05-09 12:58:14.152113 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.014006 2026-05-09 12:58:14.158571 | 2026-05-09 12:58:14.158673 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-05-09 12:58:14.576135 | rockylinux-9 | ok 2026-05-09 12:58:14.583609 | 2026-05-09 12:58:14.583697 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-05-09 12:58:14.617843 | rockylinux-9 | skipping: Conditional result was False 2026-05-09 12:58:14.632167 | 2026-05-09 12:58:14.632256 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-05-09 12:58:15.068677 | rockylinux-9 | ok 2026-05-09 12:58:15.076313 | 2026-05-09 12:58:15.146146 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-05-09 12:58:16.173488 | rockylinux-9 | Output suppressed because no_log was given 2026-05-09 12:58:16.187316 | 2026-05-09 12:58:16.187506 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-05-09 12:58:16.454974 | rockylinux-9 | ok: "logs" 2026-05-09 12:58:16.884419 | rockylinux-9 | ok: All items complete 2026-05-09 12:58:16.884694 | 2026-05-09 12:58:16.893803 | rockylinux-9 | ok: "artifacts" 2026-05-09 12:58:16.931839 | rockylinux-9 | ok: "docs" 2026-05-09 12:58:16.938612 | 2026-05-09 12:58:16.938735 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-05-09 12:58:17.192937 | rockylinux-9 | changed: "logs" 2026-05-09 12:58:17.450722 | rockylinux-9 | changed: "artifacts" 2026-05-09 12:58:17.715542 | rockylinux-9 | changed: "docs" 2026-05-09 12:58:17.731605 | 2026-05-09 12:58:17.731668 | PLAY RECAP 2026-05-09 12:58:17.731711 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-05-09 12:58:17.731742 | rockylinux-9 | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-05-09 12:58:17.731762 | 2026-05-09 12:58:17.877179 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-05-09 12:58:17.879519 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-05-09 12:58:18.579679 | 2026-05-09 12:58:18.579925 | PLAY [all] 2026-05-09 12:58:18.593835 | 2026-05-09 12:58:18.593987 | TASK [setup-uv : Extract archive] 2026-05-09 12:58:21.395344 | rockylinux-9 | changed 2026-05-09 12:58:21.411219 | 2026-05-09 12:58:21.411436 | TASK [setup-uv : Print version] 2026-05-09 12:58:21.442139 | [rockylinux-9] Waiting on logger 2026-05-09 12:58:21.953139 | rockylinux-9 | ok: Runtime: 0:00:00.014122 2026-05-09 12:58:21.959012 | 2026-05-09 12:58:21.959191 | PLAY RECAP 2026-05-09 12:58:21.959249 | rockylinux-9 | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-05-09 12:58:21.959274 | 2026-05-09 12:58:22.118999 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-05-09 12:58:22.121716 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-05-09 12:58:22.781650 | 2026-05-09 12:58:22.781804 | PLAY [all] 2026-05-09 12:58:22.793178 | 2026-05-09 12:58:22.793255 | TASK [Copy inventory file for Zuul] 2026-05-09 12:58:24.193349 | rockylinux-9 | changed 2026-05-09 12:58:24.201094 | 2026-05-09 12:58:24.201180 | TASK [Switch "ansible_host" to private IP] 2026-05-09 12:58:24.609927 | rockylinux-9 | changed: 1 replacements made 2026-05-09 12:58:24.618029 | 2026-05-09 12:58:24.618133 | TASK [Run Molecule scenario] 2026-05-09 12:58:24.637763 | [rockylinux-9] Waiting on logger 2026-05-09 12:58:32.773928 | [rockylinux-9] Waiting on logger 2026-05-09 12:58:42.758009 | [rockylinux-9] Waiting on logger 2026-05-09 12:58:52.933834 | [rockylinux-9] Waiting on logger 2026-05-09 12:59:03.109887 | [rockylinux-9] Waiting on logger 2026-05-09 12:59:13.290043 | [rockylinux-9] Waiting on logger 2026-05-09 12:59:22.443807 | [rockylinux-9] Waiting on logger 2026-05-09 12:59:32.613886 | [rockylinux-9] Waiting on logger 2026-05-09 12:59:42.790036 | [rockylinux-9] Waiting on logger 2026-05-09 12:59:52.966401 | [rockylinux-9] Waiting on logger 2026-05-09 13:00:03.142025 | [rockylinux-9] Waiting on logger 2026-05-09 13:00:13.317929 | [rockylinux-9] Waiting on logger 2026-05-09 13:00:22.479114 | [rockylinux-9] Waiting on logger 2026-05-09 13:00:32.646001 | [rockylinux-9] Waiting on logger 2026-05-09 13:00:42.822495 | [rockylinux-9] Waiting on logger 2026-05-09 13:00:52.459562 | [rockylinux-9] Waiting on logger 2026-05-09 13:01:02.566544 | [rockylinux-9] Waiting on logger 2026-05-09 13:01:12.742037 | [rockylinux-9] Waiting on logger 2026-05-09 13:01:22.917754 | [rockylinux-9] Waiting on logger 2026-05-09 13:01:33.094207 | [rockylinux-9] Waiting on logger 2026-05-09 13:01:43.269921 | [rockylinux-9] Waiting on logger 2026-05-09 13:01:52.458980 | [rockylinux-9] Waiting on logger 2026-05-09 13:02:02.630012 | [rockylinux-9] Waiting on logger 2026-05-09 13:02:12.806145 | [rockylinux-9] Waiting on logger 2026-05-09 13:02:22.986078 | [rockylinux-9] Waiting on logger 2026-05-09 13:02:33.159284 | [rockylinux-9] Waiting on logger 2026-05-09 13:02:43.334664 | [rockylinux-9] Waiting on logger 2026-05-09 13:02:52.491260 | [rockylinux-9] Waiting on logger 2026-05-09 13:03:02.662290 | [rockylinux-9] Waiting on logger 2026-05-09 13:03:12.838047 | [rockylinux-9] Waiting on logger 2026-05-09 13:03:16.205863 | rockylinux-9 | ok: Runtime: 0:04:50.262698 2026-05-09 13:03:16.214046 | 2026-05-09 13:03:16.214103 | PLAY RECAP 2026-05-09 13:03:16.214152 | rockylinux-9 | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-05-09 13:03:16.214174 | 2026-05-09 13:03:16.342243 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-05-09 13:03:16.344679 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-05-09 13:03:17.000562 | 2026-05-09 13:03:17.000705 | PLAY [all] 2026-05-09 13:03:17.012420 | 2026-05-09 13:03:17.012530 | TASK [fetch-output : Set log path for multiple nodes] 2026-05-09 13:03:17.058238 | rockylinux-9 | skipping: Conditional result was False 2026-05-09 13:03:17.067346 | 2026-05-09 13:03:17.067482 | TASK [fetch-output : Set log path for single node] 2026-05-09 13:03:17.110622 | rockylinux-9 | ok 2026-05-09 13:03:17.117207 | 2026-05-09 13:03:17.117307 | LOOP [fetch-output : Ensure local output dirs] 2026-05-09 13:03:17.517981 | rockylinux-9 -> localhost | ok: "/var/lib/zuul/builds/57a3ac557ec84405a6f518a50b78d610/work/logs" 2026-05-09 13:03:17.746225 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/57a3ac557ec84405a6f518a50b78d610/work/artifacts" 2026-05-09 13:03:18.002792 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/57a3ac557ec84405a6f518a50b78d610/work/docs" 2026-05-09 13:03:18.022951 | 2026-05-09 13:03:18.023123 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-05-09 13:03:18.735388 | rockylinux-9 | ok 2026-05-09 13:03:18.735633 | rockylinux-9 | changed: All items complete 2026-05-09 13:03:18.735697 | 2026-05-09 13:03:19.273373 | rockylinux-9 | changed: .d..t...... ./ 2026-05-09 13:03:19.801740 | rockylinux-9 | changed: .d..t...... ./ 2026-05-09 13:03:19.820689 | 2026-05-09 13:03:19.820831 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-05-09 13:03:20.353817 | rockylinux-9 -> localhost | ok: Item: artifacts Runtime: 0:00:00.008963 2026-05-09 13:03:20.593277 | rockylinux-9 -> localhost | ok: Item: docs Runtime: 0:00:00.007836 2026-05-09 13:03:20.610597 | 2026-05-09 13:03:20.611220 | PLAY [all] 2026-05-09 13:03:20.619093 | 2026-05-09 13:03:20.619188 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-05-09 13:03:21.071075 | rockylinux-9 | changed 2026-05-09 13:03:21.078386 | 2026-05-09 13:03:21.078447 | PLAY RECAP 2026-05-09 13:03:21.078494 | rockylinux-9 | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-05-09 13:03:21.078517 | 2026-05-09 13:03:21.237717 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-05-09 13:03:21.239081 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-05-09 13:03:21.820117 | 2026-05-09 13:03:21.820229 | PLAY [localhost] 2026-05-09 13:03:21.830451 | 2026-05-09 13:03:21.830532 | TASK [Generate Zuul manifest] 2026-05-09 13:03:21.849444 | localhost | ok 2026-05-09 13:03:21.866116 | 2026-05-09 13:03:21.866215 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-05-09 13:03:22.256772 | localhost | changed 2026-05-09 13:03:22.271646 | 2026-05-09 13:03:22.271741 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-05-09 13:03:22.304359 | localhost | ok 2026-05-09 13:03:22.315470 | 2026-05-09 13:03:22.315550 | TASK [Upload logs] 2026-05-09 13:03:22.340196 | localhost | ok 2026-05-09 13:03:22.403097 | 2026-05-09 13:03:22.403268 | TASK [Set zuul-log-path fact] 2026-05-09 13:03:22.428239 | localhost | ok 2026-05-09 13:03:22.441591 | 2026-05-09 13:03:22.441673 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-09 13:03:22.474914 | localhost | ok 2026-05-09 13:03:22.482568 | 2026-05-09 13:03:22.482644 | TASK [upload-logs : Create log directories] 2026-05-09 13:03:22.902080 | localhost | changed 2026-05-09 13:03:22.909028 | 2026-05-09 13:03:22.909121 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-05-09 13:03:23.400181 | localhost -> localhost | ok: Runtime: 0:00:00.006186 2026-05-09 13:03:23.409764 | 2026-05-09 13:03:23.409839 | TASK [upload-logs : Upload logs to log server] 2026-05-09 13:03:24.711435 | localhost | Output suppressed because no_log was given 2026-05-09 13:03:24.716398 | 2026-05-09 13:03:24.716482 | LOOP [upload-logs : Compress console log and json output] 2026-05-09 13:03:24.762887 | localhost | skipping: Conditional result was False 2026-05-09 13:03:24.770469 | localhost | skipping: Conditional result was False 2026-05-09 13:03:24.779166 | 2026-05-09 13:03:24.779315 | LOOP [upload-logs : Upload compressed console log and json output] 2026-05-09 13:03:24.819969 | localhost | skipping: Conditional result was False 2026-05-09 13:03:24.820343 | 2026-05-09 13:03:24.823964 | localhost | skipping: Conditional result was False 2026-05-09 13:03:24.833016 | 2026-05-09 13:03:24.833182 | LOOP [upload-logs : Upload console log and json output]