2026-04-03 00:52:46.515430 | Job console starting 2026-04-03 00:52:46.534927 | Updating git repos 2026-04-03 00:52:46.579598 | Cloning repos into workspace 2026-04-03 00:52:46.723320 | Restoring repo states 2026-04-03 00:52:46.731980 | Merging changes 2026-04-03 00:52:47.188475 | Checking out repos 2026-04-03 00:52:47.798860 | Preparing playbooks 2026-04-03 00:52:50.082585 | Running Ansible setup 2026-04-03 00:52:54.055165 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-04-03 00:52:54.718961 | 2026-04-03 00:52:54.719119 | PLAY [localhost] 2026-04-03 00:52:54.728269 | 2026-04-03 00:52:54.728348 | TASK [Gathering Facts] 2026-04-03 00:52:55.623169 | localhost | ok 2026-04-03 00:52:55.631577 | 2026-04-03 00:52:55.631660 | TASK [Setup log path fact] 2026-04-03 00:52:55.654425 | localhost | ok 2026-04-03 00:52:55.669477 | 2026-04-03 00:52:55.669695 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-03 00:52:55.700618 | localhost | ok 2026-04-03 00:52:55.711413 | 2026-04-03 00:52:55.712027 | TASK [emit-job-header : Print job information] 2026-04-03 00:52:55.756365 | # Job Information 2026-04-03 00:52:55.756837 | Ansible Version: 2.16.16 2026-04-03 00:52:55.756890 | Job: ansible-collection-containers-molecule-nerdctl-rockylinux-9 2026-04-03 00:52:55.756924 | Pipeline: check 2026-04-03 00:52:55.756973 | Executor: 0a8996d2b663 2026-04-03 00:52:55.757006 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/103 2026-04-03 00:52:55.757039 | Event ID: 48923fe0-2ef7-11f1-94a5-904b5104f847 2026-04-03 00:52:55.762459 | 2026-04-03 00:52:55.762598 | LOOP [emit-job-header : Print node information] 2026-04-03 00:52:55.844945 | localhost | ok: 2026-04-03 00:52:55.845411 | localhost | # Node Information 2026-04-03 00:52:55.845442 | localhost | Inventory Hostname: rockylinux-9 2026-04-03 00:52:55.845465 | localhost | Hostname: np0000163286 2026-04-03 00:52:55.845485 | localhost | Username: zuul 2026-04-03 00:52:55.845507 | localhost | Distro: Rocky 9.7 2026-04-03 00:52:55.845527 | localhost | Provider: yul1 2026-04-03 00:52:55.845546 | localhost | Region: ca-ymq-1 2026-04-03 00:52:55.845565 | localhost | Label: rockylinux-9 2026-04-03 00:52:55.845583 | localhost | Product Name: OpenStack Nova 2026-04-03 00:52:55.845602 | localhost | Interface IP: 199.204.45.122 2026-04-03 00:52:55.856055 | 2026-04-03 00:52:55.856177 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-04-03 00:52:56.215398 | localhost -> localhost | changed 2026-04-03 00:52:56.224160 | 2026-04-03 00:52:56.224256 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-04-03 00:52:57.120719 | localhost -> localhost | changed 2026-04-03 00:52:57.126485 | 2026-04-03 00:52:57.126539 | PLAY [all] 2026-04-03 00:52:57.133545 | 2026-04-03 00:52:57.133608 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-04-03 00:52:57.415114 | rockylinux-9 -> localhost | ok 2026-04-03 00:52:57.421020 | 2026-04-03 00:52:57.421101 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-04-03 00:52:57.455934 | rockylinux-9 | ok 2026-04-03 00:52:57.495169 | rockylinux-9 | included: /var/lib/zuul/builds/43ae9f3923d543259274a336a1a9dbd3/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-04-03 00:52:57.507313 | 2026-04-03 00:52:57.507472 | TASK [add-build-sshkey : Create Temp SSH key] 2026-04-03 00:52:58.677383 | rockylinux-9 -> localhost | Generating public/private rsa key pair. 2026-04-03 00:52:58.677563 | rockylinux-9 -> localhost | Your identification has been saved in /var/lib/zuul/builds/43ae9f3923d543259274a336a1a9dbd3/work/43ae9f3923d543259274a336a1a9dbd3_id_rsa 2026-04-03 00:52:58.677595 | rockylinux-9 -> localhost | Your public key has been saved in /var/lib/zuul/builds/43ae9f3923d543259274a336a1a9dbd3/work/43ae9f3923d543259274a336a1a9dbd3_id_rsa.pub 2026-04-03 00:52:58.677618 | rockylinux-9 -> localhost | The key fingerprint is: 2026-04-03 00:52:58.677640 | rockylinux-9 -> localhost | SHA256:xyC9snDH0QD0D3l3J1T8sKutDVHfmmGEwmUQgBtObMM zuul-build-sshkey 2026-04-03 00:52:58.677670 | rockylinux-9 -> localhost | The key's randomart image is: 2026-04-03 00:52:58.677691 | rockylinux-9 -> localhost | +---[RSA 3072]----+ 2026-04-03 00:52:58.677714 | rockylinux-9 -> localhost | | +ooo.ooo .o.| 2026-04-03 00:52:58.677735 | rockylinux-9 -> localhost | | Eo = o o. .| 2026-04-03 00:52:58.677755 | rockylinux-9 -> localhost | | +.+B = o =oo| 2026-04-03 00:52:58.677775 | rockylinux-9 -> localhost | | oo O o +.+o| 2026-04-03 00:52:58.677795 | rockylinux-9 -> localhost | | . o S + . o.o| 2026-04-03 00:52:58.677814 | rockylinux-9 -> localhost | | o + . o.+ | 2026-04-03 00:52:58.677833 | rockylinux-9 -> localhost | | . .oo | 2026-04-03 00:52:58.677853 | rockylinux-9 -> localhost | | .o. | 2026-04-03 00:52:58.677875 | rockylinux-9 -> localhost | | ... | 2026-04-03 00:52:58.677895 | rockylinux-9 -> localhost | +----[SHA256]-----+ 2026-04-03 00:52:58.677949 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.713605 2026-04-03 00:52:58.684525 | 2026-04-03 00:52:58.684620 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-04-03 00:52:58.732552 | rockylinux-9 | ok 2026-04-03 00:52:58.744601 | rockylinux-9 | included: /var/lib/zuul/builds/43ae9f3923d543259274a336a1a9dbd3/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-04-03 00:52:58.753720 | 2026-04-03 00:52:58.753790 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-04-03 00:52:58.769299 | rockylinux-9 | skipping: Conditional result was False 2026-04-03 00:52:58.780750 | 2026-04-03 00:52:58.780866 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-04-03 00:52:59.617340 | rockylinux-9 | changed 2026-04-03 00:52:59.622462 | 2026-04-03 00:52:59.622530 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-04-03 00:53:00.554354 | rockylinux-9 | ok 2026-04-03 00:53:00.559702 | 2026-04-03 00:53:00.559768 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-04-03 00:53:05.278100 | rockylinux-9 | changed 2026-04-03 00:53:05.286869 | 2026-04-03 00:53:05.286972 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-04-03 00:53:06.239528 | rockylinux-9 | changed 2026-04-03 00:53:06.246586 | 2026-04-03 00:53:06.246652 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-04-03 00:53:06.272236 | rockylinux-9 | skipping: Conditional result was False 2026-04-03 00:53:06.283717 | 2026-04-03 00:53:06.283797 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-04-03 00:53:06.745353 | rockylinux-9 -> localhost | changed 2026-04-03 00:53:06.761276 | 2026-04-03 00:53:06.761382 | TASK [add-build-sshkey : Add back temp key] 2026-04-03 00:53:07.048638 | rockylinux-9 -> localhost | Identity added: /var/lib/zuul/builds/43ae9f3923d543259274a336a1a9dbd3/work/43ae9f3923d543259274a336a1a9dbd3_id_rsa (zuul-build-sshkey) 2026-04-03 00:53:07.048831 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.014789 2026-04-03 00:53:07.054983 | 2026-04-03 00:53:07.055049 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-04-03 00:53:07.503717 | rockylinux-9 | ok 2026-04-03 00:53:07.510066 | 2026-04-03 00:53:07.510133 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-04-03 00:53:07.535932 | rockylinux-9 | skipping: Conditional result was False 2026-04-03 00:53:07.554072 | 2026-04-03 00:53:07.554203 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-04-03 00:53:07.955394 | rockylinux-9 | ok 2026-04-03 00:53:07.963478 | 2026-04-03 00:53:07.963566 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-04-03 00:53:08.846978 | rockylinux-9 | Output suppressed because no_log was given 2026-04-03 00:53:08.865537 | 2026-04-03 00:53:08.865661 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-04-03 00:53:09.127131 | rockylinux-9 | ok: "logs" 2026-04-03 00:53:09.127482 | rockylinux-9 | ok: All items complete 2026-04-03 00:53:09.127524 | 2026-04-03 00:53:09.351927 | rockylinux-9 | ok: "artifacts" 2026-04-03 00:53:09.582589 | rockylinux-9 | ok: "docs" 2026-04-03 00:53:09.604390 | 2026-04-03 00:53:09.604564 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-04-03 00:53:09.871262 | rockylinux-9 | changed: "logs" 2026-04-03 00:53:10.125307 | rockylinux-9 | changed: "artifacts" 2026-04-03 00:53:10.360884 | rockylinux-9 | changed: "docs" 2026-04-03 00:53:10.376038 | 2026-04-03 00:53:10.376181 | PLAY RECAP 2026-04-03 00:53:10.376232 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-03 00:53:10.376263 | rockylinux-9 | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-04-03 00:53:10.376286 | 2026-04-03 00:53:10.559463 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-04-03 00:53:10.561977 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-04-03 00:53:11.145439 | 2026-04-03 00:53:11.145646 | PLAY [all] 2026-04-03 00:53:11.158239 | 2026-04-03 00:53:11.158322 | TASK [setup-uv : Extract archive] 2026-04-03 00:53:13.957898 | rockylinux-9 | changed 2026-04-03 00:53:13.963743 | 2026-04-03 00:53:13.963849 | TASK [setup-uv : Print version] 2026-04-03 00:53:13.987928 | [rockylinux-9] Waiting on logger 2026-04-03 00:53:14.501831 | rockylinux-9 | ok: Runtime: 0:00:00.009122 2026-04-03 00:53:14.515293 | 2026-04-03 00:53:14.515423 | PLAY RECAP 2026-04-03 00:53:14.515491 | rockylinux-9 | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-03 00:53:14.515528 | 2026-04-03 00:53:14.678202 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-04-03 00:53:14.680834 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-04-03 00:53:15.281393 | 2026-04-03 00:53:15.281532 | PLAY [all] 2026-04-03 00:53:15.291874 | 2026-04-03 00:53:15.291948 | TASK [Copy inventory file for Zuul] 2026-04-03 00:53:16.591815 | rockylinux-9 | changed 2026-04-03 00:53:16.599262 | 2026-04-03 00:53:16.599415 | TASK [Switch "ansible_host" to private IP] 2026-04-03 00:53:16.969880 | rockylinux-9 | changed: 1 replacements made 2026-04-03 00:53:16.979226 | 2026-04-03 00:53:16.979324 | TASK [Run Molecule scenario] 2026-04-03 00:53:17.005067 | [rockylinux-9] Waiting on logger 2026-04-03 00:53:25.350257 | [rockylinux-9] Waiting on logger 2026-04-03 00:53:35.526211 | [rockylinux-9] Waiting on logger 2026-04-03 00:53:45.701902 | [rockylinux-9] Waiting on logger 2026-04-03 00:53:55.881939 | [rockylinux-9] Waiting on logger 2026-04-03 00:54:05.035085 | [rockylinux-9] Waiting on logger 2026-04-03 00:54:15.206195 | [rockylinux-9] Waiting on logger 2026-04-03 00:54:25.382096 | [rockylinux-9] Waiting on logger 2026-04-03 00:54:35.558412 | [rockylinux-9] Waiting on logger 2026-04-03 00:54:45.734147 | [rockylinux-9] Waiting on logger 2026-04-03 00:54:55.366126 | [rockylinux-9] Waiting on logger 2026-04-03 00:55:00.973919 | rockylinux-9 | ok: Runtime: 0:01:43.436817 2026-04-03 00:55:00.979896 | 2026-04-03 00:55:00.979958 | PLAY RECAP 2026-04-03 00:55:00.980019 | rockylinux-9 | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-03 00:55:00.980048 | 2026-04-03 00:55:01.081487 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-04-03 00:55:01.083980 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-04-03 00:55:01.641462 | 2026-04-03 00:55:01.641588 | PLAY [all] 2026-04-03 00:55:01.652017 | 2026-04-03 00:55:01.652100 | TASK [fetch-output : Set log path for multiple nodes] 2026-04-03 00:55:01.697697 | rockylinux-9 | skipping: Conditional result was False 2026-04-03 00:55:01.705917 | 2026-04-03 00:55:01.706047 | TASK [fetch-output : Set log path for single node] 2026-04-03 00:55:01.749219 | rockylinux-9 | ok 2026-04-03 00:55:01.756600 | 2026-04-03 00:55:01.756671 | LOOP [fetch-output : Ensure local output dirs] 2026-04-03 00:55:02.172480 | rockylinux-9 -> localhost | ok: "/var/lib/zuul/builds/43ae9f3923d543259274a336a1a9dbd3/work/logs" 2026-04-03 00:55:02.376709 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/43ae9f3923d543259274a336a1a9dbd3/work/artifacts" 2026-04-03 00:55:02.582465 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/43ae9f3923d543259274a336a1a9dbd3/work/docs" 2026-04-03 00:55:02.600002 | 2026-04-03 00:55:02.600126 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-04-03 00:55:03.318301 | rockylinux-9 | ok 2026-04-03 00:55:03.318551 | rockylinux-9 | changed: All items complete 2026-04-03 00:55:03.318590 | 2026-04-03 00:55:03.854927 | rockylinux-9 | changed: .d..t...... ./ 2026-04-03 00:55:04.360166 | rockylinux-9 | changed: .d..t...... ./ 2026-04-03 00:55:04.379376 | 2026-04-03 00:55:04.379504 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-04-03 00:55:04.844081 | rockylinux-9 -> localhost | ok: Item: artifacts Runtime: 0:00:00.008767 2026-04-03 00:55:05.065734 | rockylinux-9 -> localhost | ok: Item: docs Runtime: 0:00:00.008524 2026-04-03 00:55:05.078278 | 2026-04-03 00:55:05.078407 | PLAY [all] 2026-04-03 00:55:05.084506 | 2026-04-03 00:55:05.084569 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-04-03 00:55:05.576439 | rockylinux-9 | changed 2026-04-03 00:55:05.581965 | 2026-04-03 00:55:05.582017 | PLAY RECAP 2026-04-03 00:55:05.582060 | rockylinux-9 | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-04-03 00:55:05.582082 | 2026-04-03 00:55:05.690934 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-04-03 00:55:05.692397 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-04-03 00:55:06.286584 | 2026-04-03 00:55:06.286742 | PLAY [localhost] 2026-04-03 00:55:06.296989 | 2026-04-03 00:55:06.297065 | TASK [Generate Zuul manifest] 2026-04-03 00:55:06.318596 | localhost | ok 2026-04-03 00:55:06.336507 | 2026-04-03 00:55:06.336627 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-04-03 00:55:06.656582 | localhost | changed 2026-04-03 00:55:06.669831 | 2026-04-03 00:55:06.670051 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-04-03 00:55:06.702008 | localhost | ok 2026-04-03 00:55:06.710856 | 2026-04-03 00:55:06.710942 | TASK [Upload logs] 2026-04-03 00:55:06.731522 | localhost | ok 2026-04-03 00:55:06.792793 | 2026-04-03 00:55:06.792935 | TASK [Set zuul-log-path fact] 2026-04-03 00:55:06.813075 | localhost | ok 2026-04-03 00:55:06.825768 | 2026-04-03 00:55:06.825832 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-03 00:55:06.858206 | localhost | ok 2026-04-03 00:55:06.867993 | 2026-04-03 00:55:06.868059 | TASK [upload-logs : Create log directories] 2026-04-03 00:55:07.296919 | localhost | changed 2026-04-03 00:55:07.303734 | 2026-04-03 00:55:07.303834 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-04-03 00:55:07.701428 | localhost -> localhost | ok: Runtime: 0:00:00.007459 2026-04-03 00:55:07.708362 | 2026-04-03 00:55:07.708451 | TASK [upload-logs : Upload logs to log server] 2026-04-03 00:55:08.167531 | localhost | Output suppressed because no_log was given 2026-04-03 00:55:08.172127 | 2026-04-03 00:55:08.172251 | LOOP [upload-logs : Compress console log and json output] 2026-04-03 00:55:08.223052 | localhost | skipping: Conditional result was False 2026-04-03 00:55:08.230198 | localhost | skipping: Conditional result was False 2026-04-03 00:55:08.247858 | 2026-04-03 00:55:08.248089 | LOOP [upload-logs : Upload compressed console log and json output] 2026-04-03 00:55:08.302667 | localhost | skipping: Conditional result was False 2026-04-03 00:55:08.303058 | 2026-04-03 00:55:08.303823 | localhost | skipping: Conditional result was False 2026-04-03 00:55:08.313984 | 2026-04-03 00:55:08.314144 | LOOP [upload-logs : Upload console log and json output]