2026-04-18 16:35:33.183994 | Job console starting 2026-04-18 16:35:33.209201 | Updating git repos 2026-04-18 16:35:33.240971 | Cloning repos into workspace 2026-04-18 16:35:50.754293 | Restoring repo states 2026-04-18 16:35:50.762099 | Merging changes 2026-04-18 16:35:52.013315 | Checking out repos 2026-04-18 16:35:52.389574 | Preparing playbooks 2026-04-18 16:36:00.165507 | Running Ansible setup 2026-04-18 16:36:06.153844 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-04-18 16:36:06.903268 | 2026-04-18 16:36:06.903403 | PLAY [localhost] 2026-04-18 16:36:06.911288 | 2026-04-18 16:36:06.911370 | TASK [Gathering Facts] 2026-04-18 16:36:07.837981 | localhost | ok 2026-04-18 16:36:07.844714 | 2026-04-18 16:36:07.844792 | TASK [Setup log path fact] 2026-04-18 16:36:07.873798 | localhost | ok 2026-04-18 16:36:07.883756 | 2026-04-18 16:36:07.883822 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-18 16:36:07.914886 | localhost | ok 2026-04-18 16:36:07.920693 | 2026-04-18 16:36:07.920758 | TASK [emit-job-header : Print job information] 2026-04-18 16:36:07.958080 | # Job Information 2026-04-18 16:36:07.958201 | Ansible Version: 2.16.16 2026-04-18 16:36:07.958234 | Job: ansible-collection-containers-molecule-docker-ubuntu-focal 2026-04-18 16:36:07.958257 | Pipeline: check 2026-04-18 16:36:07.958277 | Executor: 0a8996d2b663 2026-04-18 16:36:07.958297 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/108 2026-04-18 16:36:07.958320 | Event ID: 36cd11a0-3b44-11f1-896b-295e87a423e5 2026-04-18 16:36:07.961081 | 2026-04-18 16:36:07.961145 | LOOP [emit-job-header : Print node information] 2026-04-18 16:36:08.068514 | localhost | ok: 2026-04-18 16:36:08.068696 | localhost | # Node Information 2026-04-18 16:36:08.068731 | localhost | Inventory Hostname: ubuntu-focal 2026-04-18 16:36:08.068761 | localhost | Hostname: np0000167343 2026-04-18 16:36:08.068786 | localhost | Username: zuul 2026-04-18 16:36:08.068815 | localhost | Distro: Ubuntu 20.04 2026-04-18 16:36:08.068841 | localhost | Provider: yul1 2026-04-18 16:36:08.068867 | localhost | Region: ca-ymq-1 2026-04-18 16:36:08.068894 | localhost | Label: ubuntu-focal 2026-04-18 16:36:08.068918 | localhost | Product Name: OpenStack Nova 2026-04-18 16:36:08.068943 | localhost | Interface IP: 199.19.213.81 2026-04-18 16:36:08.082874 | 2026-04-18 16:36:08.082995 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-04-18 16:36:08.477537 | localhost -> localhost | changed 2026-04-18 16:36:08.485367 | 2026-04-18 16:36:08.485904 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-04-18 16:36:09.474671 | localhost -> localhost | changed 2026-04-18 16:36:09.480204 | 2026-04-18 16:36:09.480295 | PLAY [all] 2026-04-18 16:36:09.499128 | 2026-04-18 16:36:09.499252 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-04-18 16:36:09.797446 | ubuntu-focal -> localhost | ok 2026-04-18 16:36:09.804455 | 2026-04-18 16:36:09.804557 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-04-18 16:36:09.824324 | ubuntu-focal | ok 2026-04-18 16:36:09.837227 | ubuntu-focal | included: /var/lib/zuul/builds/f96395f0aa5744cf8cfd86edab2b819b/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-04-18 16:36:09.843418 | 2026-04-18 16:36:09.843510 | TASK [add-build-sshkey : Create Temp SSH key] 2026-04-18 16:36:10.693489 | ubuntu-focal -> localhost | Generating public/private rsa key pair. 2026-04-18 16:36:10.693643 | ubuntu-focal -> localhost | Your identification has been saved in /var/lib/zuul/builds/f96395f0aa5744cf8cfd86edab2b819b/work/f96395f0aa5744cf8cfd86edab2b819b_id_rsa 2026-04-18 16:36:10.693676 | ubuntu-focal -> localhost | Your public key has been saved in /var/lib/zuul/builds/f96395f0aa5744cf8cfd86edab2b819b/work/f96395f0aa5744cf8cfd86edab2b819b_id_rsa.pub 2026-04-18 16:36:10.693699 | ubuntu-focal -> localhost | The key fingerprint is: 2026-04-18 16:36:10.693721 | ubuntu-focal -> localhost | SHA256:wMn4lMi9Z6A6q1V9qrP9j+YdBASPiOwIAwRukHr2t1I zuul-build-sshkey 2026-04-18 16:36:10.693754 | ubuntu-focal -> localhost | The key's randomart image is: 2026-04-18 16:36:10.693776 | ubuntu-focal -> localhost | +---[RSA 3072]----+ 2026-04-18 16:36:10.693803 | ubuntu-focal -> localhost | |*o ... | 2026-04-18 16:36:10.693826 | ubuntu-focal -> localhost | |=. o B * | 2026-04-18 16:36:10.693846 | ubuntu-focal -> localhost | |+oo = @ o | 2026-04-18 16:36:10.693867 | ubuntu-focal -> localhost | |++o = + . | 2026-04-18 16:36:10.693887 | ubuntu-focal -> localhost | |.o..o + S . | 2026-04-18 16:36:10.693907 | ubuntu-focal -> localhost | | o. E= . | 2026-04-18 16:36:10.693926 | ubuntu-focal -> localhost | | + o.. . | 2026-04-18 16:36:10.693945 | ubuntu-focal -> localhost | | . ooo. .o . | 2026-04-18 16:36:10.693967 | ubuntu-focal -> localhost | |... o+.++.o | 2026-04-18 16:36:10.693987 | ubuntu-focal -> localhost | +----[SHA256]-----+ 2026-04-18 16:36:10.694034 | ubuntu-focal -> localhost | ok: Runtime: 0:00:00.383016 2026-04-18 16:36:10.699680 | 2026-04-18 16:36:10.699745 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-04-18 16:36:10.718228 | ubuntu-focal | ok 2026-04-18 16:36:10.728311 | ubuntu-focal | included: /var/lib/zuul/builds/f96395f0aa5744cf8cfd86edab2b819b/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-04-18 16:36:10.740194 | 2026-04-18 16:36:10.740294 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-04-18 16:36:10.763762 | ubuntu-focal | skipping: Conditional result was False 2026-04-18 16:36:10.769230 | 2026-04-18 16:36:10.769382 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-04-18 16:36:11.214830 | ubuntu-focal | changed 2026-04-18 16:36:11.220053 | 2026-04-18 16:36:11.220153 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-04-18 16:36:11.430153 | ubuntu-focal | ok 2026-04-18 16:36:11.848995 | 2026-04-18 16:36:11.849155 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-04-18 16:36:12.309877 | ubuntu-focal | changed 2026-04-18 16:36:12.315847 | 2026-04-18 16:36:12.315920 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-04-18 16:36:12.769504 | ubuntu-focal | changed 2026-04-18 16:36:12.777602 | 2026-04-18 16:36:12.777686 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-04-18 16:36:12.801688 | ubuntu-focal | skipping: Conditional result was False 2026-04-18 16:36:12.813822 | 2026-04-18 16:36:13.422532 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-04-18 16:36:13.788202 | ubuntu-focal -> localhost | changed 2026-04-18 16:36:13.803748 | 2026-04-18 16:36:13.803909 | TASK [add-build-sshkey : Add back temp key] 2026-04-18 16:36:14.142404 | ubuntu-focal -> localhost | Identity added: /var/lib/zuul/builds/f96395f0aa5744cf8cfd86edab2b819b/work/f96395f0aa5744cf8cfd86edab2b819b_id_rsa (zuul-build-sshkey) 2026-04-18 16:36:14.142607 | ubuntu-focal -> localhost | ok: Runtime: 0:00:00.013734 2026-04-18 16:36:14.148506 | 2026-04-18 16:36:14.148570 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-04-18 16:36:14.507244 | ubuntu-focal | ok 2026-04-18 16:36:14.731917 | 2026-04-18 16:36:14.732040 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-04-18 16:36:14.759367 | ubuntu-focal | skipping: Conditional result was False 2026-04-18 16:36:14.787622 | 2026-04-18 16:36:14.787797 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-04-18 16:36:15.109499 | ubuntu-focal | ok 2026-04-18 16:36:15.119089 | 2026-04-18 16:36:15.119226 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-04-18 16:36:16.174413 | ubuntu-focal | Output suppressed because no_log was given 2026-04-18 16:36:16.187465 | 2026-04-18 16:36:16.187623 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-04-18 16:36:16.356243 | ubuntu-focal | ok: "logs" 2026-04-18 16:36:16.356516 | ubuntu-focal | ok: All items complete 2026-04-18 16:36:16.356561 | 2026-04-18 16:36:16.500917 | ubuntu-focal | ok: "artifacts" 2026-04-18 16:36:16.656173 | ubuntu-focal | ok: "docs" 2026-04-18 16:36:16.666997 | 2026-04-18 16:36:16.667181 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-04-18 16:36:16.855573 | ubuntu-focal | changed: "logs" 2026-04-18 16:36:17.022716 | ubuntu-focal | changed: "artifacts" 2026-04-18 16:36:17.131837 | ubuntu-focal | changed: "docs" 2026-04-18 16:36:17.143777 | 2026-04-18 16:36:17.143905 | PLAY RECAP 2026-04-18 16:36:17.143964 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-18 16:36:17.144006 | ubuntu-focal | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-04-18 16:36:17.144036 | 2026-04-18 16:36:17.288859 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-04-18 16:36:17.290758 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-04-18 16:36:17.904110 | 2026-04-18 16:36:17.904236 | PLAY [all] 2026-04-18 16:36:17.915650 | 2026-04-18 16:36:17.915783 | TASK [setup-uv : Extract archive] 2026-04-18 16:36:28.968655 | ubuntu-focal | ERROR 2026-04-18 16:36:28.968888 | ubuntu-focal | { 2026-04-18 16:36:28.968929 | ubuntu-focal | "msg": "Failure downloading https://github.com/astral-sh/uv/releases/download/0.8.13/uv-x86_64-unknown-linux-gnu.tar.gz, Connection failure: The read operation timed out" 2026-04-18 16:36:28.968980 | ubuntu-focal | } failure 2026-04-18 16:36:28.970196 | 2026-04-18 16:36:28.970241 | PLAY RECAP 2026-04-18 16:36:28.970283 | ubuntu-focal | ok: 0 changed: 0 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2026-04-18 16:36:28.970309 | 2026-04-18 16:36:29.083136 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-04-18 16:36:29.112353 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-04-18 16:36:29.894908 | 2026-04-18 16:36:29.895071 | PLAY [all] 2026-04-18 16:36:29.911153 | 2026-04-18 16:36:29.911237 | TASK [fetch-output : Set log path for multiple nodes] 2026-04-18 16:36:29.956716 | ubuntu-focal | skipping: Conditional result was False 2026-04-18 16:36:31.172718 | 2026-04-18 16:36:31.172881 | TASK [fetch-output : Set log path for single node] 2026-04-18 16:36:31.204696 | ubuntu-focal | ok 2026-04-18 16:36:31.210403 | 2026-04-18 16:36:31.210470 | LOOP [fetch-output : Ensure local output dirs] 2026-04-18 16:36:31.630761 | ubuntu-focal -> localhost | ok: "/var/lib/zuul/builds/f96395f0aa5744cf8cfd86edab2b819b/work/logs" 2026-04-18 16:36:31.854443 | ubuntu-focal -> localhost | changed: "/var/lib/zuul/builds/f96395f0aa5744cf8cfd86edab2b819b/work/artifacts" 2026-04-18 16:36:32.106117 | ubuntu-focal -> localhost | changed: "/var/lib/zuul/builds/f96395f0aa5744cf8cfd86edab2b819b/work/docs" 2026-04-18 16:36:32.124408 | 2026-04-18 16:36:32.124526 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-04-18 16:36:34.583330 | ubuntu-focal | changed: .d..t...... ./ 2026-04-18 16:36:34.583584 | ubuntu-focal | changed: All items complete 2026-04-18 16:36:34.583612 | 2026-04-18 16:36:35.028655 | ubuntu-focal | changed: .d..t...... ./ 2026-04-18 16:36:35.567264 | ubuntu-focal | changed: .d..t...... ./ 2026-04-18 16:36:35.599163 | 2026-04-18 16:36:35.599377 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-04-18 16:36:36.234288 | ubuntu-focal -> localhost | ok: Item: artifacts Runtime: 0:00:00.028155 2026-04-18 16:36:36.469738 | ubuntu-focal -> localhost | ok: Item: docs Runtime: 0:00:00.005535 2026-04-18 16:36:36.486564 | 2026-04-18 16:36:36.486699 | PLAY [all] 2026-04-18 16:36:36.494090 | 2026-04-18 16:36:36.494183 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-04-18 16:36:36.887672 | ubuntu-focal | changed 2026-04-18 16:36:36.893660 | 2026-04-18 16:36:36.893779 | PLAY RECAP 2026-04-18 16:36:36.893857 | ubuntu-focal | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-04-18 16:36:36.893884 | 2026-04-18 16:36:37.091311 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-04-18 16:36:37.092777 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-04-18 16:36:37.761276 | 2026-04-18 16:36:38.064353 | PLAY [localhost] 2026-04-18 16:36:38.082074 | 2026-04-18 16:36:38.082209 | TASK [Generate Zuul manifest] 2026-04-18 16:36:38.104472 | localhost | ok 2026-04-18 16:36:38.123960 | 2026-04-18 16:36:38.124165 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-04-18 16:36:38.491811 | localhost | changed 2026-04-18 16:36:38.507800 | 2026-04-18 16:36:38.507933 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-04-18 16:36:39.115188 | localhost | ok 2026-04-18 16:36:39.126225 | 2026-04-18 16:36:39.126378 | TASK [Upload logs] 2026-04-18 16:36:39.146789 | localhost | ok 2026-04-18 16:36:39.209127 | 2026-04-18 16:36:39.209263 | TASK [Set zuul-log-path fact] 2026-04-18 16:36:39.229712 | localhost | ok 2026-04-18 16:36:39.248683 | 2026-04-18 16:36:39.248859 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-18 16:36:39.282632 | localhost | ok 2026-04-18 16:36:39.291852 | 2026-04-18 16:36:39.291958 | TASK [upload-logs : Create log directories] 2026-04-18 16:36:39.720909 | localhost | changed 2026-04-18 16:36:39.727757 | 2026-04-18 16:36:39.727830 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-04-18 16:36:40.174482 | localhost -> localhost | ok: Runtime: 0:00:00.009407 2026-04-18 16:36:40.180026 | 2026-04-18 16:36:40.180096 | TASK [upload-logs : Upload logs to log server] 2026-04-18 16:36:40.647665 | localhost | Output suppressed because no_log was given 2026-04-18 16:36:40.651279 | 2026-04-18 16:36:40.651344 | LOOP [upload-logs : Compress console log and json output] 2026-04-18 16:36:40.704756 | localhost | skipping: Conditional result was False 2026-04-18 16:36:40.712887 | localhost | skipping: Conditional result was False 2026-04-18 16:36:40.720236 | 2026-04-18 16:36:40.720313 | LOOP [upload-logs : Upload compressed console log and json output] 2026-04-18 16:36:40.761574 | localhost | skipping: Conditional result was False 2026-04-18 16:36:40.761889 | 2026-04-18 16:36:40.766041 | localhost | skipping: Conditional result was False 2026-04-18 16:36:40.782726 | 2026-04-18 16:36:40.782863 | LOOP [upload-logs : Upload console log and json output]