2026-04-14 02:26:04.081664 | Job console starting 2026-04-14 02:26:04.103237 | Updating git repos 2026-04-14 02:26:04.127654 | Cloning repos into workspace 2026-04-14 02:26:04.690878 | Restoring repo states 2026-04-14 02:26:04.696964 | Merging changes 2026-04-14 02:26:05.192022 | Checking out repos 2026-04-14 02:26:05.298516 | Preparing playbooks 2026-04-14 02:26:07.793433 | Running Ansible setup 2026-04-14 02:26:11.701746 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-04-14 02:26:12.388082 | 2026-04-14 02:26:12.388221 | PLAY [localhost] 2026-04-14 02:26:12.396018 | 2026-04-14 02:26:12.396092 | TASK [Gathering Facts] 2026-04-14 02:26:13.465253 | localhost | ok 2026-04-14 02:26:13.489490 | 2026-04-14 02:26:13.490111 | TASK [Setup log path fact] 2026-04-14 02:26:13.521435 | localhost | ok 2026-04-14 02:26:13.533466 | 2026-04-14 02:26:13.533571 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-14 02:26:13.561694 | localhost | ok 2026-04-14 02:26:13.568837 | 2026-04-14 02:26:13.568941 | TASK [emit-job-header : Print job information] 2026-04-14 02:26:13.610079 | # Job Information 2026-04-14 02:26:13.610306 | Ansible Version: 2.16.16 2026-04-14 02:26:13.610366 | Job: ansible-collection-containers-molecule-docker-rockylinux-9 2026-04-14 02:26:13.610411 | Pipeline: check 2026-04-14 02:26:13.610451 | Executor: 0a8996d2b663 2026-04-14 02:26:13.610531 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/107 2026-04-14 02:26:13.610579 | Event ID: cf0dfd70-37a8-11f1-93e5-fb954d9f646c 2026-04-14 02:26:13.614697 | 2026-04-14 02:26:13.614856 | LOOP [emit-job-header : Print node information] 2026-04-14 02:26:13.711477 | localhost | ok: 2026-04-14 02:26:13.711667 | localhost | # Node Information 2026-04-14 02:26:13.711701 | localhost | Inventory Hostname: rockylinux-9 2026-04-14 02:26:13.711728 | localhost | Hostname: np0000165939 2026-04-14 02:26:13.711752 | localhost | Username: zuul 2026-04-14 02:26:13.711781 | localhost | Distro: Rocky 9.7 2026-04-14 02:26:13.711805 | localhost | Provider: yul1 2026-04-14 02:26:13.711829 | localhost | Region: ca-ymq-1 2026-04-14 02:26:13.711851 | localhost | Label: rockylinux-9 2026-04-14 02:26:13.711875 | localhost | Product Name: OpenStack Nova 2026-04-14 02:26:13.711901 | localhost | Interface IP: 199.19.213.35 2026-04-14 02:26:13.722114 | 2026-04-14 02:26:13.722345 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-04-14 02:26:14.154682 | localhost -> localhost | changed 2026-04-14 02:26:14.160393 | 2026-04-14 02:26:14.160518 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-04-14 02:26:15.224462 | localhost -> localhost | changed 2026-04-14 02:26:15.232251 | 2026-04-14 02:26:15.232328 | PLAY [all] 2026-04-14 02:26:15.240761 | 2026-04-14 02:26:15.240840 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-04-14 02:26:15.510486 | rockylinux-9 -> localhost | ok 2026-04-14 02:26:15.522298 | 2026-04-14 02:26:15.522442 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-04-14 02:26:15.559246 | rockylinux-9 | ok 2026-04-14 02:26:15.574759 | rockylinux-9 | included: /var/lib/zuul/builds/e26a7cc81fc54691ab5445d3dd0b5828/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-04-14 02:26:15.581791 | 2026-04-14 02:26:15.581865 | TASK [add-build-sshkey : Create Temp SSH key] 2026-04-14 02:26:16.707032 | rockylinux-9 -> localhost | Generating public/private rsa key pair. 2026-04-14 02:26:16.707227 | rockylinux-9 -> localhost | Your identification has been saved in /var/lib/zuul/builds/e26a7cc81fc54691ab5445d3dd0b5828/work/e26a7cc81fc54691ab5445d3dd0b5828_id_rsa 2026-04-14 02:26:16.707260 | rockylinux-9 -> localhost | Your public key has been saved in /var/lib/zuul/builds/e26a7cc81fc54691ab5445d3dd0b5828/work/e26a7cc81fc54691ab5445d3dd0b5828_id_rsa.pub 2026-04-14 02:26:16.707284 | rockylinux-9 -> localhost | The key fingerprint is: 2026-04-14 02:26:16.707307 | rockylinux-9 -> localhost | SHA256:xWXAv0V2S5TwHJZ+gbS8RwqFEMzUvfJqIpeqX6CkfyY zuul-build-sshkey 2026-04-14 02:26:16.707339 | rockylinux-9 -> localhost | The key's randomart image is: 2026-04-14 02:26:16.707360 | rockylinux-9 -> localhost | +---[RSA 3072]----+ 2026-04-14 02:26:16.707386 | rockylinux-9 -> localhost | | +==.*+++o| 2026-04-14 02:26:16.707408 | rockylinux-9 -> localhost | | +.=ooB=o| 2026-04-14 02:26:16.707428 | rockylinux-9 -> localhost | | oo =+=o| 2026-04-14 02:26:16.707448 | rockylinux-9 -> localhost | | . .o.=o.| 2026-04-14 02:26:16.707467 | rockylinux-9 -> localhost | | . .S o= ..| 2026-04-14 02:26:16.707487 | rockylinux-9 -> localhost | | o . . ... | 2026-04-14 02:26:16.707506 | rockylinux-9 -> localhost | | . . .. . | 2026-04-14 02:26:16.707525 | rockylinux-9 -> localhost | | .E +.+ o | 2026-04-14 02:26:16.707547 | rockylinux-9 -> localhost | | o*o+ o | 2026-04-14 02:26:16.707567 | rockylinux-9 -> localhost | +----[SHA256]-----+ 2026-04-14 02:26:16.707618 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.678308 2026-04-14 02:26:16.712742 | 2026-04-14 02:26:16.712803 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-04-14 02:26:16.743456 | rockylinux-9 | ok 2026-04-14 02:26:16.768611 | rockylinux-9 | included: /var/lib/zuul/builds/e26a7cc81fc54691ab5445d3dd0b5828/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-04-14 02:26:16.979547 | 2026-04-14 02:26:16.979678 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-04-14 02:26:17.024812 | rockylinux-9 | skipping: Conditional result was False 2026-04-14 02:26:17.030257 | 2026-04-14 02:26:17.030325 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-04-14 02:26:17.517982 | rockylinux-9 | changed 2026-04-14 02:26:17.523070 | 2026-04-14 02:26:17.523136 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-04-14 02:26:17.789598 | rockylinux-9 | ok 2026-04-14 02:26:17.795224 | 2026-04-14 02:26:17.795294 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-04-14 02:26:18.722769 | rockylinux-9 | changed 2026-04-14 02:26:18.730818 | 2026-04-14 02:26:18.730953 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-04-14 02:26:19.640931 | rockylinux-9 | changed 2026-04-14 02:26:19.649168 | 2026-04-14 02:26:19.649328 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-04-14 02:26:19.675440 | rockylinux-9 | skipping: Conditional result was False 2026-04-14 02:26:19.684501 | 2026-04-14 02:26:19.684632 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-04-14 02:26:20.205869 | rockylinux-9 -> localhost | changed 2026-04-14 02:26:20.218740 | 2026-04-14 02:26:20.218863 | TASK [add-build-sshkey : Add back temp key] 2026-04-14 02:26:20.906220 | rockylinux-9 -> localhost | Identity added: /var/lib/zuul/builds/e26a7cc81fc54691ab5445d3dd0b5828/work/e26a7cc81fc54691ab5445d3dd0b5828_id_rsa (zuul-build-sshkey) 2026-04-14 02:26:20.906458 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.010847 2026-04-14 02:26:20.938780 | 2026-04-14 02:26:20.938941 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-04-14 02:26:21.369124 | rockylinux-9 | ok 2026-04-14 02:26:21.375450 | 2026-04-14 02:26:21.375546 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-04-14 02:26:21.410945 | rockylinux-9 | skipping: Conditional result was False 2026-04-14 02:26:21.425029 | 2026-04-14 02:26:21.425144 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-04-14 02:26:21.825403 | rockylinux-9 | ok 2026-04-14 02:26:22.078638 | 2026-04-14 02:26:22.078808 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-04-14 02:26:23.246927 | rockylinux-9 | Output suppressed because no_log was given 2026-04-14 02:26:23.260418 | 2026-04-14 02:26:23.260519 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-04-14 02:26:23.522133 | rockylinux-9 | ok: "logs" 2026-04-14 02:26:23.522442 | rockylinux-9 | ok: All items complete 2026-04-14 02:26:23.522473 | 2026-04-14 02:26:23.742740 | rockylinux-9 | ok: "artifacts" 2026-04-14 02:26:23.953018 | rockylinux-9 | ok: "docs" 2026-04-14 02:26:23.971251 | 2026-04-14 02:26:23.971402 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-04-14 02:26:24.225857 | rockylinux-9 | changed: "logs" 2026-04-14 02:26:24.438541 | rockylinux-9 | changed: "artifacts" 2026-04-14 02:26:24.674891 | rockylinux-9 | changed: "docs" 2026-04-14 02:26:24.686463 | 2026-04-14 02:26:24.686598 | PLAY RECAP 2026-04-14 02:26:24.686646 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-14 02:26:24.686677 | rockylinux-9 | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-04-14 02:26:24.686698 | 2026-04-14 02:26:24.876127 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-04-14 02:26:24.878964 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-04-14 02:26:25.545240 | 2026-04-14 02:26:25.545439 | PLAY [all] 2026-04-14 02:26:25.557727 | 2026-04-14 02:26:25.557852 | TASK [setup-uv : Extract archive] 2026-04-14 02:26:28.447898 | rockylinux-9 | changed 2026-04-14 02:26:28.458145 | 2026-04-14 02:26:28.458284 | TASK [setup-uv : Print version] 2026-04-14 02:26:28.482551 | [rockylinux-9] Waiting on logger 2026-04-14 02:26:29.032646 | rockylinux-9 | ok: Runtime: 0:00:00.013695 2026-04-14 02:26:29.047949 | 2026-04-14 02:26:29.048082 | PLAY RECAP 2026-04-14 02:26:29.048148 | rockylinux-9 | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-14 02:26:29.048185 | 2026-04-14 02:26:29.194844 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-04-14 02:26:29.197139 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-04-14 02:26:29.808755 | 2026-04-14 02:26:29.808939 | PLAY [all] 2026-04-14 02:26:29.820297 | 2026-04-14 02:26:29.820451 | TASK [Copy inventory file for Zuul] 2026-04-14 02:26:31.164876 | rockylinux-9 | changed 2026-04-14 02:26:31.682331 | 2026-04-14 02:26:31.682530 | TASK [Switch "ansible_host" to private IP] 2026-04-14 02:26:32.034397 | rockylinux-9 | changed: 1 replacements made 2026-04-14 02:26:32.039566 | 2026-04-14 02:26:32.039661 | TASK [Run Molecule scenario] 2026-04-14 02:26:32.065220 | [rockylinux-9] Waiting on logger 2026-04-14 02:26:40.202182 | [rockylinux-9] Waiting on logger 2026-04-14 02:26:49.834849 | [rockylinux-9] Waiting on logger 2026-04-14 02:26:59.501287 | [rockylinux-9] Waiting on logger 2026-04-14 02:27:09.670218 | [rockylinux-9] Waiting on logger 2026-04-14 02:27:19.851236 | [rockylinux-9] Waiting on logger 2026-04-14 02:27:30.022056 | [rockylinux-9] Waiting on logger 2026-04-14 02:27:40.202349 | [rockylinux-9] Waiting on logger 2026-04-14 02:27:50.373948 | [rockylinux-9] Waiting on logger 2026-04-14 02:27:59.567389 | [rockylinux-9] Waiting on logger 2026-04-14 02:28:09.734145 | [rockylinux-9] Waiting on logger 2026-04-14 02:28:19.909977 | [rockylinux-9] Waiting on logger 2026-04-14 02:28:30.086218 | [rockylinux-9] Waiting on logger 2026-04-14 02:28:33.454548 | rockylinux-9 | ok: Runtime: 0:02:00.452822 2026-04-14 02:28:33.460117 | 2026-04-14 02:28:33.460170 | PLAY RECAP 2026-04-14 02:28:33.460216 | rockylinux-9 | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-14 02:28:33.460238 | 2026-04-14 02:28:33.572834 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-04-14 02:28:33.575415 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-04-14 02:28:34.139755 | 2026-04-14 02:28:34.139900 | PLAY [all] 2026-04-14 02:28:34.150863 | 2026-04-14 02:28:34.150954 | TASK [fetch-output : Set log path for multiple nodes] 2026-04-14 02:28:34.196564 | rockylinux-9 | skipping: Conditional result was False 2026-04-14 02:28:34.206848 | 2026-04-14 02:28:34.207037 | TASK [fetch-output : Set log path for single node] 2026-04-14 02:28:34.252109 | rockylinux-9 | ok 2026-04-14 02:28:34.256311 | 2026-04-14 02:28:34.256382 | LOOP [fetch-output : Ensure local output dirs] 2026-04-14 02:28:34.663064 | rockylinux-9 -> localhost | ok: "/var/lib/zuul/builds/e26a7cc81fc54691ab5445d3dd0b5828/work/logs" 2026-04-14 02:28:34.912639 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/e26a7cc81fc54691ab5445d3dd0b5828/work/artifacts" 2026-04-14 02:28:35.127162 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/e26a7cc81fc54691ab5445d3dd0b5828/work/docs" 2026-04-14 02:28:35.144013 | 2026-04-14 02:28:35.144189 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-04-14 02:28:35.864720 | rockylinux-9 | changed: .d..t...... ./ 2026-04-14 02:28:35.865461 | rockylinux-9 | changed: All items complete 2026-04-14 02:28:35.865510 | 2026-04-14 02:28:36.387833 | rockylinux-9 | changed: .d..t...... ./ 2026-04-14 02:28:36.901846 | rockylinux-9 | changed: .d..t...... ./ 2026-04-14 02:28:36.919871 | 2026-04-14 02:28:36.920066 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-04-14 02:28:37.365799 | rockylinux-9 -> localhost | ok: Item: artifacts Runtime: 0:00:00.008105 2026-04-14 02:28:37.626295 | rockylinux-9 -> localhost | ok: Item: docs Runtime: 0:00:00.008270 2026-04-14 02:28:37.641372 | 2026-04-14 02:28:37.641527 | PLAY [all] 2026-04-14 02:28:37.647552 | 2026-04-14 02:28:37.647625 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-04-14 02:28:38.157505 | rockylinux-9 | changed 2026-04-14 02:28:38.165669 | 2026-04-14 02:28:38.165725 | PLAY RECAP 2026-04-14 02:28:38.165772 | rockylinux-9 | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-04-14 02:28:38.165794 | 2026-04-14 02:28:38.303912 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-04-14 02:28:38.310436 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-04-14 02:28:38.917132 | 2026-04-14 02:28:38.917262 | PLAY [localhost] 2026-04-14 02:28:38.927201 | 2026-04-14 02:28:38.927274 | TASK [Generate Zuul manifest] 2026-04-14 02:28:38.949800 | localhost | ok 2026-04-14 02:28:38.966007 | 2026-04-14 02:28:38.966084 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-04-14 02:28:39.334591 | localhost | changed 2026-04-14 02:28:39.347857 | 2026-04-14 02:28:39.347947 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-04-14 02:28:39.378340 | localhost | ok 2026-04-14 02:28:39.386557 | 2026-04-14 02:28:39.386622 | TASK [Upload logs] 2026-04-14 02:28:39.407985 | localhost | ok 2026-04-14 02:28:39.468177 | 2026-04-14 02:28:39.468308 | TASK [Set zuul-log-path fact] 2026-04-14 02:28:39.486962 | localhost | ok 2026-04-14 02:28:39.500510 | 2026-04-14 02:28:39.500580 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-14 02:28:39.531264 | localhost | ok 2026-04-14 02:28:39.540991 | 2026-04-14 02:28:39.541079 | TASK [upload-logs : Create log directories] 2026-04-14 02:28:39.988389 | localhost | changed 2026-04-14 02:28:39.995734 | 2026-04-14 02:28:39.995838 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-04-14 02:28:40.413932 | localhost -> localhost | ok: Runtime: 0:00:00.005196 2026-04-14 02:28:40.419378 | 2026-04-14 02:28:40.419445 | TASK [upload-logs : Upload logs to log server] 2026-04-14 02:28:40.901372 | localhost | Output suppressed because no_log was given 2026-04-14 02:28:40.905161 | 2026-04-14 02:28:40.905231 | LOOP [upload-logs : Compress console log and json output] 2026-04-14 02:28:40.953848 | localhost | skipping: Conditional result was False 2026-04-14 02:28:40.960778 | localhost | skipping: Conditional result was False 2026-04-14 02:28:40.977929 | 2026-04-14 02:28:40.978076 | LOOP [upload-logs : Upload compressed console log and json output] 2026-04-14 02:28:41.025212 | localhost | skipping: Conditional result was False 2026-04-14 02:28:41.025841 | 2026-04-14 02:28:41.030106 | localhost | skipping: Conditional result was False 2026-04-14 02:28:41.044740 | 2026-04-14 02:28:41.044937 | LOOP [upload-logs : Upload console log and json output]