2026-05-21 02:33:16.649891 | Job console starting 2026-05-21 02:33:16.663086 | Updating git repos 2026-05-21 02:33:16.693071 | Cloning repos into workspace 2026-05-21 02:33:16.737312 | Restoring repo states 2026-05-21 02:33:16.742840 | Merging changes 2026-05-21 02:33:17.018341 | Checking out repos 2026-05-21 02:33:17.052734 | Preparing playbooks 2026-05-21 02:33:21.433940 | Running Ansible setup 2026-05-21 02:33:25.803177 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-05-21 02:33:26.425422 | 2026-05-21 02:33:26.425580 | PLAY [localhost] 2026-05-21 02:33:26.494182 | 2026-05-21 02:33:26.494464 | TASK [Gathering Facts] 2026-05-21 02:33:27.449234 | localhost | ok 2026-05-21 02:33:27.457864 | 2026-05-21 02:33:27.457937 | TASK [Setup log path fact] 2026-05-21 02:33:27.478799 | localhost | ok 2026-05-21 02:33:27.492048 | 2026-05-21 02:33:27.492183 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-21 02:33:27.527008 | localhost | ok 2026-05-21 02:33:27.534523 | 2026-05-21 02:33:27.534630 | TASK [emit-job-header : Print job information] 2026-05-21 02:33:27.568441 | # Job Information 2026-05-21 02:33:27.569110 | Ansible Version: 2.16.18 2026-05-21 02:33:27.569169 | Job: ansible-collection-containers-molecule-nerdctl-rockylinux-9 2026-05-21 02:33:27.569207 | Pipeline: check 2026-05-21 02:33:27.569238 | Executor: 2d72f0692154 2026-05-21 02:33:27.569269 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/126 2026-05-21 02:33:27.569304 | Event ID: fae3e460-54bc-11f1-8133-32d0a9e16fea 2026-05-21 02:33:27.574657 | 2026-05-21 02:33:27.574816 | LOOP [emit-job-header : Print node information] 2026-05-21 02:33:27.672672 | localhost | ok: 2026-05-21 02:33:27.672813 | localhost | # Node Information 2026-05-21 02:33:27.672841 | localhost | Inventory Hostname: rockylinux-9 2026-05-21 02:33:27.672863 | localhost | Hostname: np0000175701 2026-05-21 02:33:27.672883 | localhost | Username: zuul 2026-05-21 02:33:27.672905 | localhost | Distro: Rocky 9.7 2026-05-21 02:33:27.672925 | localhost | Provider: yul1 2026-05-21 02:33:27.672945 | localhost | Region: ca-ymq-1 2026-05-21 02:33:27.672997 | localhost | Label: rockylinux-9 2026-05-21 02:33:27.673019 | localhost | Product Name: OpenStack Nova 2026-05-21 02:33:27.673038 | localhost | Interface IP: 199.19.213.17 2026-05-21 02:33:27.685530 | 2026-05-21 02:33:27.685690 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-05-21 02:33:28.087267 | localhost -> localhost | changed 2026-05-21 02:33:28.092623 | 2026-05-21 02:33:28.092707 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-05-21 02:33:28.984490 | localhost -> localhost | changed 2026-05-21 02:33:28.993598 | 2026-05-21 02:33:28.993691 | PLAY [all] 2026-05-21 02:33:29.002124 | 2026-05-21 02:33:29.002267 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-05-21 02:33:29.249536 | rockylinux-9 -> localhost | ok 2026-05-21 02:33:29.256280 | 2026-05-21 02:33:29.256369 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-05-21 02:33:29.285375 | rockylinux-9 | ok 2026-05-21 02:33:29.298564 | rockylinux-9 | included: /var/lib/zuul/builds/2e5a2b8a7da14738965827746999e55b/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-05-21 02:33:29.305614 | 2026-05-21 02:33:29.305702 | TASK [add-build-sshkey : Create Temp SSH key] 2026-05-21 02:33:31.254616 | rockylinux-9 -> localhost | Generating public/private rsa key pair. 2026-05-21 02:33:31.254793 | rockylinux-9 -> localhost | Your identification has been saved in /var/lib/zuul/builds/2e5a2b8a7da14738965827746999e55b/work/2e5a2b8a7da14738965827746999e55b_id_rsa 2026-05-21 02:33:31.254824 | rockylinux-9 -> localhost | Your public key has been saved in /var/lib/zuul/builds/2e5a2b8a7da14738965827746999e55b/work/2e5a2b8a7da14738965827746999e55b_id_rsa.pub 2026-05-21 02:33:31.254847 | rockylinux-9 -> localhost | The key fingerprint is: 2026-05-21 02:33:31.254869 | rockylinux-9 -> localhost | SHA256:J9o+oAzntGa/WCmQ2U7K/FruD5ALyJwUrKoerSka21s zuul-build-sshkey 2026-05-21 02:33:31.254902 | rockylinux-9 -> localhost | The key's randomart image is: 2026-05-21 02:33:31.254923 | rockylinux-9 -> localhost | +---[RSA 3072]----+ 2026-05-21 02:33:31.254947 | rockylinux-9 -> localhost | |.. | 2026-05-21 02:33:31.254969 | rockylinux-9 -> localhost | | .. | 2026-05-21 02:33:31.254990 | rockylinux-9 -> localhost | |.. | 2026-05-21 02:33:31.255010 | rockylinux-9 -> localhost | |* .= | 2026-05-21 02:33:31.255030 | rockylinux-9 -> localhost | |++* o S . | 2026-05-21 02:33:31.255050 | rockylinux-9 -> localhost | |.+oBo .+ o | 2026-05-21 02:33:31.255069 | rockylinux-9 -> localhost | |o.=BEo+.. | 2026-05-21 02:33:31.255089 | rockylinux-9 -> localhost | |o++=B= .. | 2026-05-21 02:33:31.255112 | rockylinux-9 -> localhost | |*++*=o+... | 2026-05-21 02:33:31.255133 | rockylinux-9 -> localhost | +----[SHA256]-----+ 2026-05-21 02:33:31.255183 | rockylinux-9 -> localhost | ok: Runtime: 0:00:01.384161 2026-05-21 02:33:31.263957 | 2026-05-21 02:33:31.264025 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-05-21 02:33:31.293840 | rockylinux-9 | ok 2026-05-21 02:33:31.302508 | rockylinux-9 | included: /var/lib/zuul/builds/2e5a2b8a7da14738965827746999e55b/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-05-21 02:33:31.311835 | 2026-05-21 02:33:31.311897 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-05-21 02:33:31.336189 | rockylinux-9 | skipping: Conditional result was False 2026-05-21 02:33:31.343006 | 2026-05-21 02:33:31.343076 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-05-21 02:33:31.847009 | rockylinux-9 | changed 2026-05-21 02:33:31.853751 | 2026-05-21 02:33:31.853848 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-05-21 02:33:32.118703 | rockylinux-9 | ok 2026-05-21 02:33:32.123755 | 2026-05-21 02:33:32.123824 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-05-21 02:33:33.070146 | rockylinux-9 | changed 2026-05-21 02:33:33.077686 | 2026-05-21 02:33:33.077775 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-05-21 02:33:34.018380 | rockylinux-9 | changed 2026-05-21 02:33:34.032430 | 2026-05-21 02:33:34.032541 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-05-21 02:33:34.057366 | rockylinux-9 | skipping: Conditional result was False 2026-05-21 02:33:34.063184 | 2026-05-21 02:33:34.066549 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-05-21 02:33:34.461488 | rockylinux-9 -> localhost | changed 2026-05-21 02:33:34.474473 | 2026-05-21 02:33:34.474642 | TASK [add-build-sshkey : Add back temp key] 2026-05-21 02:33:34.780805 | rockylinux-9 -> localhost | Identity added: /var/lib/zuul/builds/2e5a2b8a7da14738965827746999e55b/work/2e5a2b8a7da14738965827746999e55b_id_rsa (zuul-build-sshkey) 2026-05-21 02:33:34.781285 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.015087 2026-05-21 02:33:34.793446 | 2026-05-21 02:33:34.793667 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-05-21 02:33:35.174508 | rockylinux-9 | ok 2026-05-21 02:33:35.179449 | 2026-05-21 02:33:35.179541 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-05-21 02:33:35.205515 | rockylinux-9 | skipping: Conditional result was False 2026-05-21 02:33:35.218525 | 2026-05-21 02:33:35.218668 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-05-21 02:33:35.622190 | rockylinux-9 | ok 2026-05-21 02:33:35.627563 | 2026-05-21 02:33:35.627640 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-05-21 02:33:36.627331 | rockylinux-9 | Output suppressed because no_log was given 2026-05-21 02:33:36.659308 | 2026-05-21 02:33:36.659391 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-05-21 02:33:36.920407 | rockylinux-9 | ok: "logs" 2026-05-21 02:33:36.920713 | rockylinux-9 | ok: All items complete 2026-05-21 02:33:36.920741 | 2026-05-21 02:33:37.141899 | rockylinux-9 | ok: "artifacts" 2026-05-21 02:33:37.397870 | rockylinux-9 | ok: "docs" 2026-05-21 02:33:37.415809 | 2026-05-21 02:33:37.415985 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-05-21 02:33:37.669070 | rockylinux-9 | changed: "logs" 2026-05-21 02:33:37.999177 | rockylinux-9 | changed: "artifacts" 2026-05-21 02:33:38.184364 | rockylinux-9 | changed: "docs" 2026-05-21 02:33:38.197143 | 2026-05-21 02:33:38.197243 | PLAY RECAP 2026-05-21 02:33:38.197284 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-05-21 02:33:38.197484 | rockylinux-9 | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-05-21 02:33:38.197520 | 2026-05-21 02:33:38.415782 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-05-21 02:33:38.420578 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-05-21 02:33:39.078779 | 2026-05-21 02:33:39.078927 | PLAY [all] 2026-05-21 02:33:39.091207 | 2026-05-21 02:33:39.091306 | TASK [setup-uv : Extract archive] 2026-05-21 02:33:41.828898 | rockylinux-9 | changed 2026-05-21 02:33:41.840801 | 2026-05-21 02:33:41.840911 | TASK [setup-uv : Print version] 2026-05-21 02:33:41.886377 | [rockylinux-9] Waiting on logger 2026-05-21 02:33:42.424352 | rockylinux-9 | ok: Runtime: 0:00:00.014060 2026-05-21 02:33:42.438922 | 2026-05-21 02:33:42.439088 | TASK [Install Ansible collection siblings] 2026-05-21 02:33:42.916448 | rockylinux-9 | ok: 2026-05-21 02:33:42.916611 | rockylinux-9 | Skipping ansible.posix: no checked-out sibling 2026-05-21 02:33:42.916642 | rockylinux-9 | Skipping community.general: no checked-out sibling 2026-05-21 02:33:42.919034 | 2026-05-21 02:33:42.919085 | PLAY RECAP 2026-05-21 02:33:42.919133 | rockylinux-9 | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-05-21 02:33:42.919155 | 2026-05-21 02:33:43.060069 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-05-21 02:33:43.062410 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-05-21 02:33:43.759748 | 2026-05-21 02:33:43.759868 | PLAY [all] 2026-05-21 02:33:43.771473 | 2026-05-21 02:33:43.771638 | TASK [Copy inventory file for Zuul] 2026-05-21 02:33:45.097771 | rockylinux-9 | changed 2026-05-21 02:33:45.105103 | 2026-05-21 02:33:45.105205 | TASK [Switch "ansible_host" to private IP] 2026-05-21 02:33:45.519059 | rockylinux-9 | changed: 1 replacements made 2026-05-21 02:33:45.526033 | 2026-05-21 02:33:45.526126 | TASK [Run Molecule scenario] 2026-05-21 02:33:45.556813 | [rockylinux-9] Waiting on logger 2026-05-21 02:33:53.705915 | [rockylinux-9] Waiting on logger 2026-05-21 02:34:03.878154 | [rockylinux-9] Waiting on logger 2026-05-21 02:34:13.034119 | [rockylinux-9] Waiting on logger 2026-05-21 02:34:23.205798 | [rockylinux-9] Waiting on logger 2026-05-21 02:34:33.382225 | [rockylinux-9] Waiting on logger 2026-05-21 02:34:43.558342 | [rockylinux-9] Waiting on logger 2026-05-21 02:34:53.733999 | [rockylinux-9] Waiting on logger 2026-05-21 02:35:02.898279 | [rockylinux-9] Waiting on logger 2026-05-21 02:35:13.062004 | [rockylinux-9] Waiting on logger 2026-05-21 02:35:23.238135 | [rockylinux-9] Waiting on logger 2026-05-21 02:35:33.414264 | [rockylinux-9] Waiting on logger 2026-05-21 02:35:43.590003 | [rockylinux-9] Waiting on logger 2026-05-21 02:35:48.178865 | rockylinux-9 | ok: Runtime: 0:02:01.375011 2026-05-21 02:35:48.186092 | 2026-05-21 02:35:48.186141 | PLAY RECAP 2026-05-21 02:35:48.186183 | rockylinux-9 | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-05-21 02:35:48.186206 | 2026-05-21 02:35:48.306755 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-05-21 02:35:48.308063 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-05-21 02:35:48.888932 | 2026-05-21 02:35:48.889208 | PLAY [all] 2026-05-21 02:35:48.899409 | 2026-05-21 02:35:48.899480 | TASK [fetch-output : Set log path for multiple nodes] 2026-05-21 02:35:48.944302 | rockylinux-9 | skipping: Conditional result was False 2026-05-21 02:35:48.954377 | 2026-05-21 02:35:48.954558 | TASK [fetch-output : Set log path for single node] 2026-05-21 02:35:48.998350 | rockylinux-9 | ok 2026-05-21 02:35:49.003740 | 2026-05-21 02:35:49.003813 | LOOP [fetch-output : Ensure local output dirs] 2026-05-21 02:35:49.382662 | rockylinux-9 -> localhost | ok: "/var/lib/zuul/builds/2e5a2b8a7da14738965827746999e55b/work/logs" 2026-05-21 02:35:49.583016 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/2e5a2b8a7da14738965827746999e55b/work/artifacts" 2026-05-21 02:35:49.800323 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/2e5a2b8a7da14738965827746999e55b/work/docs" 2026-05-21 02:35:49.821451 | 2026-05-21 02:35:49.821584 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-05-21 02:35:50.596422 | rockylinux-9 | changed: .d..t...... ./ 2026-05-21 02:35:50.596663 | rockylinux-9 | changed: All items complete 2026-05-21 02:35:50.596702 | 2026-05-21 02:35:51.124681 | rockylinux-9 | changed: .d..t...... ./ 2026-05-21 02:35:51.629157 | rockylinux-9 | changed: .d..t...... ./ 2026-05-21 02:35:51.646697 | 2026-05-21 02:35:51.646818 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-05-21 02:35:52.072807 | rockylinux-9 -> localhost | ok: Item: artifacts Runtime: 0:00:00.006183 2026-05-21 02:35:52.305101 | rockylinux-9 -> localhost | ok: Item: docs Runtime: 0:00:00.006562 2026-05-21 02:35:52.323872 | 2026-05-21 02:35:52.324124 | PLAY [all] 2026-05-21 02:35:52.334924 | 2026-05-21 02:35:52.335060 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-05-21 02:35:52.821369 | rockylinux-9 | changed 2026-05-21 02:35:52.828695 | 2026-05-21 02:35:52.828762 | PLAY RECAP 2026-05-21 02:35:52.828822 | rockylinux-9 | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-05-21 02:35:52.828854 | 2026-05-21 02:35:52.942656 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-05-21 02:35:52.944986 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-05-21 02:35:53.536938 | 2026-05-21 02:35:53.537126 | PLAY [localhost] 2026-05-21 02:35:53.547628 | 2026-05-21 02:35:53.547725 | TASK [Generate Zuul manifest] 2026-05-21 02:35:53.568077 | localhost | ok 2026-05-21 02:35:53.584147 | 2026-05-21 02:35:53.584269 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-05-21 02:35:54.296376 | localhost | changed 2026-05-21 02:35:54.307696 | 2026-05-21 02:35:54.307781 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-05-21 02:35:54.755644 | localhost | ok 2026-05-21 02:35:54.763945 | 2026-05-21 02:35:54.764021 | TASK [Upload logs] 2026-05-21 02:35:54.782184 | localhost | ok 2026-05-21 02:35:54.873704 | 2026-05-21 02:35:54.873846 | TASK [Set zuul-log-path fact] 2026-05-21 02:35:54.895034 | localhost | ok 2026-05-21 02:35:54.911831 | 2026-05-21 02:35:54.911998 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-21 02:35:54.943802 | localhost | ok 2026-05-21 02:35:54.953057 | 2026-05-21 02:35:54.953128 | TASK [upload-logs : Create log directories] 2026-05-21 02:35:55.361139 | localhost | changed 2026-05-21 02:35:55.369521 | 2026-05-21 02:35:55.369630 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-05-21 02:35:55.977728 | localhost -> localhost | ok: Runtime: 0:00:00.004984 2026-05-21 02:35:55.984227 | 2026-05-21 02:35:55.984294 | TASK [upload-logs : Upload logs to log server] 2026-05-21 02:35:56.467292 | localhost | Output suppressed because no_log was given 2026-05-21 02:35:56.474087 | 2026-05-21 02:35:56.474174 | LOOP [upload-logs : Compress console log and json output] 2026-05-21 02:35:56.519385 | localhost | skipping: Conditional result was False 2026-05-21 02:35:56.527482 | localhost | skipping: Conditional result was False 2026-05-21 02:35:56.540017 | 2026-05-21 02:35:56.540224 | LOOP [upload-logs : Upload compressed console log and json output] 2026-05-21 02:35:56.584679 | localhost | skipping: Conditional result was False 2026-05-21 02:35:56.678736 | 2026-05-21 02:35:56.680856 | localhost | skipping: Conditional result was False 2026-05-21 02:35:56.696255 | 2026-05-21 02:35:56.696515 | LOOP [upload-logs : Upload console log and json output]