2026-04-28 06:12:32.335769 | Job console starting 2026-04-28 06:12:32.343753 | Updating git repos 2026-04-28 06:12:32.365321 | Cloning repos into workspace 2026-04-28 06:12:32.416713 | Restoring repo states 2026-04-28 06:12:32.423484 | Merging changes 2026-04-28 06:12:32.740988 | Checking out repos 2026-04-28 06:12:32.773500 | Preparing playbooks 2026-04-28 06:12:33.535581 | Running Ansible setup 2026-04-28 06:12:38.013326 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-04-28 06:12:38.575718 | 2026-04-28 06:12:38.575829 | PLAY [localhost] 2026-04-28 06:12:38.582928 | 2026-04-28 06:12:38.582997 | TASK [Gathering Facts] 2026-04-28 06:12:40.467723 | localhost | ok 2026-04-28 06:12:40.478402 | 2026-04-28 06:12:40.478837 | TASK [Setup log path fact] 2026-04-28 06:12:40.501063 | localhost | ok 2026-04-28 06:12:40.517373 | 2026-04-28 06:12:40.517457 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-28 06:12:40.547974 | localhost | ok 2026-04-28 06:12:40.558570 | 2026-04-28 06:12:40.558664 | TASK [emit-job-header : Print job information] 2026-04-28 06:12:40.601604 | # Job Information 2026-04-28 06:12:40.601826 | Ansible Version: 2.16.16 2026-04-28 06:12:40.601870 | Job: ansible-collection-containers-molecule-containerd-rockylinux-9 2026-04-28 06:12:40.601902 | Pipeline: check 2026-04-28 06:12:40.601930 | Executor: 0a8996d2b663 2026-04-28 06:12:40.601958 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/123 2026-04-28 06:12:40.601987 | Event ID: c09b9470-42c8-11f1-9317-8010d15d04fa 2026-04-28 06:12:40.605982 | 2026-04-28 06:12:40.606077 | LOOP [emit-job-header : Print node information] 2026-04-28 06:12:40.711239 | localhost | ok: 2026-04-28 06:12:40.711522 | localhost | # Node Information 2026-04-28 06:12:40.711573 | localhost | Inventory Hostname: rockylinux-9 2026-04-28 06:12:40.711612 | localhost | Hostname: np0000170918 2026-04-28 06:12:40.711643 | localhost | Username: zuul 2026-04-28 06:12:40.711675 | localhost | Distro: Rocky 9.7 2026-04-28 06:12:40.711703 | localhost | Provider: yul1 2026-04-28 06:12:40.711729 | localhost | Region: ca-ymq-1 2026-04-28 06:12:40.711757 | localhost | Label: rockylinux-9 2026-04-28 06:12:40.711783 | localhost | Product Name: OpenStack Nova 2026-04-28 06:12:40.711808 | localhost | Interface IP: 199.204.45.156 2026-04-28 06:12:40.723335 | 2026-04-28 06:12:40.723585 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-04-28 06:12:41.137358 | localhost -> localhost | changed 2026-04-28 06:12:41.143056 | 2026-04-28 06:12:41.143131 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-04-28 06:12:42.003502 | localhost -> localhost | changed 2026-04-28 06:12:42.010865 | 2026-04-28 06:12:42.010934 | PLAY [all] 2026-04-28 06:12:42.023014 | 2026-04-28 06:12:42.023103 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-04-28 06:12:42.251636 | rockylinux-9 -> localhost | ok 2026-04-28 06:12:42.257871 | 2026-04-28 06:12:42.257942 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-04-28 06:12:42.287370 | rockylinux-9 | ok 2026-04-28 06:12:42.306110 | rockylinux-9 | included: /var/lib/zuul/builds/f835a72235264ba491eaec59b74451f8/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-04-28 06:12:42.311242 | 2026-04-28 06:12:42.311300 | TASK [add-build-sshkey : Create Temp SSH key] 2026-04-28 06:12:44.075644 | rockylinux-9 -> localhost | Generating public/private rsa key pair. 2026-04-28 06:12:44.075812 | rockylinux-9 -> localhost | Your identification has been saved in /var/lib/zuul/builds/f835a72235264ba491eaec59b74451f8/work/f835a72235264ba491eaec59b74451f8_id_rsa 2026-04-28 06:12:44.075844 | rockylinux-9 -> localhost | Your public key has been saved in /var/lib/zuul/builds/f835a72235264ba491eaec59b74451f8/work/f835a72235264ba491eaec59b74451f8_id_rsa.pub 2026-04-28 06:12:44.075868 | rockylinux-9 -> localhost | The key fingerprint is: 2026-04-28 06:12:44.075891 | rockylinux-9 -> localhost | SHA256:4YsEdnHGTE4EkgXmtQ9jeHbOtsnPiglUsIlrWyS/7wo zuul-build-sshkey 2026-04-28 06:12:44.075931 | rockylinux-9 -> localhost | The key's randomart image is: 2026-04-28 06:12:44.075954 | rockylinux-9 -> localhost | +---[RSA 3072]----+ 2026-04-28 06:12:44.075977 | rockylinux-9 -> localhost | | =+=** | 2026-04-28 06:12:44.075999 | rockylinux-9 -> localhost | | +.B Bo | 2026-04-28 06:12:44.076020 | rockylinux-9 -> localhost | | o O X + | 2026-04-28 06:12:44.076041 | rockylinux-9 -> localhost | | * B O . | 2026-04-28 06:12:44.076062 | rockylinux-9 -> localhost | | o + . S | 2026-04-28 06:12:44.076082 | rockylinux-9 -> localhost | | . + o + + | 2026-04-28 06:12:44.076102 | rockylinux-9 -> localhost | | E o . = | 2026-04-28 06:12:44.076122 | rockylinux-9 -> localhost | | . o o o | 2026-04-28 06:12:44.076145 | rockylinux-9 -> localhost | | .o= ..o | 2026-04-28 06:12:44.076166 | rockylinux-9 -> localhost | +----[SHA256]-----+ 2026-04-28 06:12:44.076219 | rockylinux-9 -> localhost | ok: Runtime: 0:00:01.299914 2026-04-28 06:12:44.081843 | 2026-04-28 06:12:44.081910 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-04-28 06:12:44.112688 | rockylinux-9 | ok 2026-04-28 06:12:44.122049 | rockylinux-9 | included: /var/lib/zuul/builds/f835a72235264ba491eaec59b74451f8/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-04-28 06:12:44.130212 | 2026-04-28 06:12:44.130287 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-04-28 06:12:44.145569 | rockylinux-9 | skipping: Conditional result was False 2026-04-28 06:12:44.158136 | 2026-04-28 06:12:44.158261 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-04-28 06:12:44.617744 | rockylinux-9 | changed 2026-04-28 06:12:44.622913 | 2026-04-28 06:12:44.622982 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-04-28 06:12:44.881983 | rockylinux-9 | ok 2026-04-28 06:12:44.887478 | 2026-04-28 06:12:44.887546 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-04-28 06:12:45.805092 | rockylinux-9 | changed 2026-04-28 06:12:45.978310 | 2026-04-28 06:12:45.978480 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-04-28 06:12:46.936524 | rockylinux-9 | changed 2026-04-28 06:12:46.942677 | 2026-04-28 06:12:46.942745 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-04-28 06:12:46.965805 | rockylinux-9 | skipping: Conditional result was False 2026-04-28 06:12:46.975004 | 2026-04-28 06:12:46.975081 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-04-28 06:12:47.364556 | rockylinux-9 -> localhost | changed 2026-04-28 06:12:47.379057 | 2026-04-28 06:12:47.379122 | TASK [add-build-sshkey : Add back temp key] 2026-04-28 06:12:47.660440 | rockylinux-9 -> localhost | Identity added: /var/lib/zuul/builds/f835a72235264ba491eaec59b74451f8/work/f835a72235264ba491eaec59b74451f8_id_rsa (zuul-build-sshkey) 2026-04-28 06:12:47.660635 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.014808 2026-04-28 06:12:47.666669 | 2026-04-28 06:12:47.666730 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-04-28 06:12:48.035476 | rockylinux-9 | ok 2026-04-28 06:12:48.041618 | 2026-04-28 06:12:48.041706 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-04-28 06:12:48.066208 | rockylinux-9 | skipping: Conditional result was False 2026-04-28 06:12:48.080910 | 2026-04-28 06:12:48.080988 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-04-28 06:12:48.495406 | rockylinux-9 | ok 2026-04-28 06:12:48.500466 | 2026-04-28 06:12:48.500527 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-04-28 06:12:49.480708 | rockylinux-9 | Output suppressed because no_log was given 2026-04-28 06:12:49.493100 | 2026-04-28 06:12:49.493200 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-04-28 06:12:49.736574 | rockylinux-9 | ok: "logs" 2026-04-28 06:12:49.822017 | rockylinux-9 | ok: All items complete 2026-04-28 06:12:49.822124 | 2026-04-28 06:12:49.950200 | rockylinux-9 | ok: "artifacts" 2026-04-28 06:12:50.175410 | rockylinux-9 | ok: "docs" 2026-04-28 06:12:50.186830 | 2026-04-28 06:12:50.186956 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-04-28 06:12:50.456772 | rockylinux-9 | changed: "logs" 2026-04-28 06:12:50.685497 | rockylinux-9 | changed: "artifacts" 2026-04-28 06:12:50.907749 | rockylinux-9 | changed: "docs" 2026-04-28 06:12:50.929124 | 2026-04-28 06:12:50.929249 | PLAY RECAP 2026-04-28 06:12:50.929319 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-28 06:12:50.929363 | rockylinux-9 | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-04-28 06:12:50.929396 | 2026-04-28 06:12:51.145495 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-04-28 06:12:51.413088 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-04-28 06:12:52.001927 | 2026-04-28 06:12:52.002026 | PLAY [all] 2026-04-28 06:12:52.014560 | 2026-04-28 06:12:52.015107 | TASK [setup-uv : Extract archive] 2026-04-28 06:12:54.937180 | rockylinux-9 | changed 2026-04-28 06:12:54.967582 | 2026-04-28 06:12:54.967752 | TASK [setup-uv : Print version] 2026-04-28 06:12:54.990503 | [rockylinux-9] Waiting on logger 2026-04-28 06:12:56.204358 | rockylinux-9 | ok: Runtime: 0:00:00.014124 2026-04-28 06:12:56.210369 | 2026-04-28 06:12:56.210419 | PLAY RECAP 2026-04-28 06:12:56.210463 | rockylinux-9 | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-28 06:12:56.210487 | 2026-04-28 06:12:56.309568 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-04-28 06:12:56.311860 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-04-28 06:12:56.929971 | 2026-04-28 06:12:56.930212 | PLAY [all] 2026-04-28 06:12:56.940492 | 2026-04-28 06:12:56.940564 | TASK [Copy inventory file for Zuul] 2026-04-28 06:12:58.267379 | rockylinux-9 | changed 2026-04-28 06:12:58.453143 | 2026-04-28 06:12:58.453262 | TASK [Switch "ansible_host" to private IP] 2026-04-28 06:12:58.829734 | rockylinux-9 | changed: 1 replacements made 2026-04-28 06:12:58.835342 | 2026-04-28 06:12:58.835428 | TASK [Run Molecule scenario] 2026-04-28 06:12:58.856403 | [rockylinux-9] Waiting on logger 2026-04-28 06:13:05.994073 | [rockylinux-9] Waiting on logger 2026-04-28 06:13:15.398279 | [rockylinux-9] Waiting on logger 2026-04-28 06:13:25.573977 | [rockylinux-9] Waiting on logger 2026-04-28 06:13:35.754627 | [rockylinux-9] Waiting on logger 2026-04-28 06:13:45.925956 | [rockylinux-9] Waiting on logger 2026-04-28 06:13:55.083137 | [rockylinux-9] Waiting on logger 2026-04-28 06:14:05.253752 | [rockylinux-9] Waiting on logger 2026-04-28 06:14:15.429917 | [rockylinux-9] Waiting on logger 2026-04-28 06:14:25.605866 | [rockylinux-9] Waiting on logger 2026-04-28 06:14:35.782459 | [rockylinux-9] Waiting on logger 2026-04-28 06:14:45.962774 | [rockylinux-9] Waiting on logger 2026-04-28 06:14:55.141924 | [rockylinux-9] Waiting on logger 2026-04-28 06:15:05.318070 | [rockylinux-9] Waiting on logger 2026-04-28 06:15:15.493959 | [rockylinux-9] Waiting on logger 2026-04-28 06:15:25.669972 | [rockylinux-9] Waiting on logger 2026-04-28 06:15:35.845778 | [rockylinux-9] Waiting on logger 2026-04-28 06:15:45.003166 | [rockylinux-9] Waiting on logger 2026-04-28 06:15:55.174268 | [rockylinux-9] Waiting on logger 2026-04-28 06:16:05.350176 | [rockylinux-9] Waiting on logger 2026-04-28 06:16:15.526036 | [rockylinux-9] Waiting on logger 2026-04-28 06:16:25.701999 | [rockylinux-9] Waiting on logger 2026-04-28 06:16:35.878061 | [rockylinux-9] Waiting on logger 2026-04-28 06:16:45.041565 | [rockylinux-9] Waiting on logger 2026-04-28 06:16:55.210672 | [rockylinux-9] Waiting on logger 2026-04-28 06:17:05.382218 | [rockylinux-9] Waiting on logger 2026-04-28 06:17:15.557762 | [rockylinux-9] Waiting on logger 2026-04-28 06:17:25.734015 | [rockylinux-9] Waiting on logger 2026-04-28 06:17:35.910048 | [rockylinux-9] Waiting on logger 2026-04-28 06:17:44.979430 | rockylinux-9 | ok: Runtime: 0:04:44.960633 2026-04-28 06:17:44.986881 | 2026-04-28 06:17:44.986934 | PLAY RECAP 2026-04-28 06:17:44.986981 | rockylinux-9 | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-28 06:17:44.987005 | 2026-04-28 06:17:45.133721 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-04-28 06:17:45.136068 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-04-28 06:17:45.667206 | 2026-04-28 06:17:45.667331 | PLAY [all] 2026-04-28 06:17:45.677369 | 2026-04-28 06:17:45.677444 | TASK [fetch-output : Set log path for multiple nodes] 2026-04-28 06:17:45.721428 | rockylinux-9 | skipping: Conditional result was False 2026-04-28 06:17:45.730856 | 2026-04-28 06:17:45.731021 | TASK [fetch-output : Set log path for single node] 2026-04-28 06:17:45.763349 | rockylinux-9 | ok 2026-04-28 06:17:45.770276 | 2026-04-28 06:17:45.770339 | LOOP [fetch-output : Ensure local output dirs] 2026-04-28 06:17:46.118339 | rockylinux-9 -> localhost | ok: "/var/lib/zuul/builds/f835a72235264ba491eaec59b74451f8/work/logs" 2026-04-28 06:17:46.333048 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/f835a72235264ba491eaec59b74451f8/work/artifacts" 2026-04-28 06:17:46.526237 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/f835a72235264ba491eaec59b74451f8/work/docs" 2026-04-28 06:17:46.539413 | 2026-04-28 06:17:46.539577 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-04-28 06:17:47.228694 | rockylinux-9 | ok 2026-04-28 06:17:47.228929 | rockylinux-9 | changed: All items complete 2026-04-28 06:17:47.228959 | 2026-04-28 06:17:47.743315 | rockylinux-9 | changed: .d..t...... ./ 2026-04-28 06:17:48.274624 | rockylinux-9 | changed: .d..t...... ./ 2026-04-28 06:17:48.295773 | 2026-04-28 06:17:48.295883 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-04-28 06:17:48.699963 | rockylinux-9 -> localhost | ok: Item: artifacts Runtime: 0:00:00.008004 2026-04-28 06:17:48.934299 | rockylinux-9 -> localhost | ok: Item: docs Runtime: 0:00:00.007115 2026-04-28 06:17:48.947208 | 2026-04-28 06:17:49.111698 | PLAY [all] 2026-04-28 06:17:49.120547 | 2026-04-28 06:17:49.120631 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-04-28 06:17:49.552633 | rockylinux-9 | changed 2026-04-28 06:17:49.559026 | 2026-04-28 06:17:49.559075 | PLAY RECAP 2026-04-28 06:17:49.559121 | rockylinux-9 | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-04-28 06:17:49.559145 | 2026-04-28 06:17:49.675216 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-04-28 06:17:49.677373 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-04-28 06:17:50.221133 | 2026-04-28 06:17:50.221250 | PLAY [localhost] 2026-04-28 06:17:50.230901 | 2026-04-28 06:17:50.231005 | TASK [Generate Zuul manifest] 2026-04-28 06:17:50.250054 | localhost | ok 2026-04-28 06:17:50.314478 | 2026-04-28 06:17:50.314573 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-04-28 06:17:50.693523 | localhost | changed 2026-04-28 06:17:50.708432 | 2026-04-28 06:17:50.708605 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-04-28 06:17:50.812369 | localhost | ok 2026-04-28 06:17:50.819274 | 2026-04-28 06:17:50.819336 | TASK [Upload logs] 2026-04-28 06:17:50.839500 | localhost | ok 2026-04-28 06:17:50.915780 | 2026-04-28 06:17:50.916012 | TASK [Set zuul-log-path fact] 2026-04-28 06:17:50.947618 | localhost | ok 2026-04-28 06:17:50.967995 | 2026-04-28 06:17:50.968289 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-28 06:17:50.999973 | localhost | ok 2026-04-28 06:17:51.009010 | 2026-04-28 06:17:51.009085 | TASK [upload-logs : Create log directories] 2026-04-28 06:17:51.407701 | localhost | changed 2026-04-28 06:17:51.412284 | 2026-04-28 06:17:51.412350 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-04-28 06:17:52.418384 | localhost -> localhost | ok: Runtime: 0:00:00.005004 2026-04-28 06:17:52.423632 | 2026-04-28 06:17:52.423696 | TASK [upload-logs : Upload logs to log server] 2026-04-28 06:17:52.879089 | localhost | Output suppressed because no_log was given 2026-04-28 06:17:52.884134 | 2026-04-28 06:17:52.884205 | LOOP [upload-logs : Compress console log and json output] 2026-04-28 06:17:52.928987 | localhost | skipping: Conditional result was False 2026-04-28 06:17:52.935510 | localhost | skipping: Conditional result was False 2026-04-28 06:17:52.949157 | 2026-04-28 06:17:52.949424 | LOOP [upload-logs : Upload compressed console log and json output] 2026-04-28 06:17:52.993647 | localhost | skipping: Conditional result was False 2026-04-28 06:17:52.993987 | 2026-04-28 06:17:52.997729 | localhost | skipping: Conditional result was False 2026-04-28 06:17:53.015885 | 2026-04-28 06:17:53.016130 | LOOP [upload-logs : Upload console log and json output]