2026-04-18 16:36:18.378034 | Job console starting 2026-04-18 16:36:18.398447 | Updating git repos 2026-04-18 16:36:18.418981 | Cloning repos into workspace 2026-04-18 16:36:18.474464 | Restoring repo states 2026-04-18 16:36:18.482858 | Merging changes 2026-04-18 16:36:19.079060 | Checking out repos 2026-04-18 16:36:19.105612 | Preparing playbooks 2026-04-18 16:36:21.634586 | Running Ansible setup 2026-04-18 16:36:26.978697 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-04-18 16:36:27.605846 | 2026-04-18 16:36:27.606018 | PLAY [localhost] 2026-04-18 16:36:27.613089 | 2026-04-18 16:36:27.613161 | TASK [Gathering Facts] 2026-04-18 16:36:28.636275 | localhost | ok 2026-04-18 16:36:28.646231 | 2026-04-18 16:36:28.646307 | TASK [Setup log path fact] 2026-04-18 16:36:28.667680 | localhost | ok 2026-04-18 16:36:28.683464 | 2026-04-18 16:36:28.683548 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-18 16:36:28.712324 | localhost | ok 2026-04-18 16:36:28.720797 | 2026-04-18 16:36:28.720862 | TASK [emit-job-header : Print job information] 2026-04-18 16:36:28.769434 | # Job Information 2026-04-18 16:36:28.919138 | Ansible Version: 2.16.16 2026-04-18 16:36:28.919393 | Job: ansible-collection-containers-molecule-download-binaries 2026-04-18 16:36:28.919926 | Pipeline: check 2026-04-18 16:36:28.920009 | Executor: 0a8996d2b663 2026-04-18 16:36:28.920064 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/104 2026-04-18 16:36:28.920120 | Event ID: 3b9743e0-3b44-11f1-8b36-e33dc4de3ce3 2026-04-18 16:36:28.927667 | 2026-04-18 16:36:28.927768 | LOOP [emit-job-header : Print node information] 2026-04-18 16:36:29.048424 | localhost | ok: 2026-04-18 16:36:29.048729 | localhost | # Node Information 2026-04-18 16:36:29.048796 | localhost | Inventory Hostname: debian-trixie 2026-04-18 16:36:29.048844 | localhost | Hostname: np0000167391 2026-04-18 16:36:29.048887 | localhost | Username: zuul 2026-04-18 16:36:29.048936 | localhost | Distro: Debian 13.4 2026-04-18 16:36:29.049012 | localhost | Provider: yul1 2026-04-18 16:36:29.049057 | localhost | Region: ca-ymq-1 2026-04-18 16:36:29.049097 | localhost | Label: debian-trixie 2026-04-18 16:36:29.049141 | localhost | Product Name: OpenStack Nova 2026-04-18 16:36:29.049199 | localhost | Interface IP: 199.19.213.25 2026-04-18 16:36:29.067705 | 2026-04-18 16:36:29.067876 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-04-18 16:36:29.617523 | localhost -> localhost | changed 2026-04-18 16:36:29.624400 | 2026-04-18 16:36:29.624554 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-04-18 16:36:31.709291 | localhost -> localhost | changed 2026-04-18 16:36:31.721473 | 2026-04-18 16:36:31.721572 | PLAY [all] 2026-04-18 16:36:31.731502 | 2026-04-18 16:36:31.731601 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-04-18 16:36:31.971962 | debian-trixie -> localhost | ok 2026-04-18 16:36:31.976640 | 2026-04-18 16:36:31.976707 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-04-18 16:36:32.005242 | debian-trixie | ok 2026-04-18 16:36:32.030779 | debian-trixie | included: /var/lib/zuul/builds/6476442dfc8d473892458d4244f8533c/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-04-18 16:36:32.043376 | 2026-04-18 16:36:32.043461 | TASK [add-build-sshkey : Create Temp SSH key] 2026-04-18 16:36:34.565299 | debian-trixie -> localhost | Generating public/private rsa key pair. 2026-04-18 16:36:34.565452 | debian-trixie -> localhost | Your identification has been saved in /var/lib/zuul/builds/6476442dfc8d473892458d4244f8533c/work/6476442dfc8d473892458d4244f8533c_id_rsa 2026-04-18 16:36:34.565482 | debian-trixie -> localhost | Your public key has been saved in /var/lib/zuul/builds/6476442dfc8d473892458d4244f8533c/work/6476442dfc8d473892458d4244f8533c_id_rsa.pub 2026-04-18 16:36:34.565505 | debian-trixie -> localhost | The key fingerprint is: 2026-04-18 16:36:34.565527 | debian-trixie -> localhost | SHA256:2TRAOCdqNqGgZwKpL+ITz3kWBF0FcamAcAncQrqpoWc zuul-build-sshkey 2026-04-18 16:36:34.565562 | debian-trixie -> localhost | The key's randomart image is: 2026-04-18 16:36:34.565584 | debian-trixie -> localhost | +---[RSA 3072]----+ 2026-04-18 16:36:34.565609 | debian-trixie -> localhost | |o=+.+ .**o. | 2026-04-18 16:36:34.565632 | debian-trixie -> localhost | |=o.=.o+ oo | 2026-04-18 16:36:34.565653 | debian-trixie -> localhost | |=....o.+. o | 2026-04-18 16:36:34.565673 | debian-trixie -> localhost | |+o+ =. . + . | 2026-04-18 16:36:34.565694 | debian-trixie -> localhost | |+= o.. S . | 2026-04-18 16:36:34.565714 | debian-trixie -> localhost | |=o. . | 2026-04-18 16:36:34.565734 | debian-trixie -> localhost | |=.E . . | 2026-04-18 16:36:34.565755 | debian-trixie -> localhost | | = + o | 2026-04-18 16:36:34.565778 | debian-trixie -> localhost | | . o | 2026-04-18 16:36:34.565798 | debian-trixie -> localhost | +----[SHA256]-----+ 2026-04-18 16:36:34.565840 | debian-trixie -> localhost | ok: Runtime: 0:00:02.074004 2026-04-18 16:36:34.570599 | 2026-04-18 16:36:34.570666 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-04-18 16:36:34.586825 | debian-trixie | ok 2026-04-18 16:36:34.594350 | debian-trixie | included: /var/lib/zuul/builds/6476442dfc8d473892458d4244f8533c/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-04-18 16:36:34.600915 | 2026-04-18 16:36:34.601024 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-04-18 16:36:34.614183 | debian-trixie | skipping: Conditional result was False 2026-04-18 16:36:34.622009 | 2026-04-18 16:36:34.622251 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-04-18 16:36:35.184911 | debian-trixie | changed 2026-04-18 16:36:35.500690 | 2026-04-18 16:36:35.500852 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-04-18 16:36:35.710438 | debian-trixie | ok 2026-04-18 16:36:35.716493 | 2026-04-18 16:36:35.716571 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-04-18 16:36:36.267158 | debian-trixie | changed 2026-04-18 16:36:36.272994 | 2026-04-18 16:36:36.273086 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-04-18 16:36:36.813750 | debian-trixie | changed 2026-04-18 16:36:36.891724 | 2026-04-18 16:36:36.891848 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-04-18 16:36:36.919650 | debian-trixie | skipping: Conditional result was False 2026-04-18 16:36:36.924250 | 2026-04-18 16:36:36.924315 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-04-18 16:36:37.311993 | debian-trixie -> localhost | changed 2026-04-18 16:36:37.321781 | 2026-04-18 16:36:37.321851 | TASK [add-build-sshkey : Add back temp key] 2026-04-18 16:36:37.607547 | debian-trixie -> localhost | Identity added: /var/lib/zuul/builds/6476442dfc8d473892458d4244f8533c/work/6476442dfc8d473892458d4244f8533c_id_rsa (zuul-build-sshkey) 2026-04-18 16:36:37.607782 | debian-trixie -> localhost | ok: Runtime: 0:00:00.011217 2026-04-18 16:36:37.613671 | 2026-04-18 16:36:37.613749 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-04-18 16:36:37.958244 | debian-trixie | ok 2026-04-18 16:36:38.074161 | 2026-04-18 16:36:38.074360 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-04-18 16:36:38.098580 | debian-trixie | skipping: Conditional result was False 2026-04-18 16:36:38.106348 | 2026-04-18 16:36:38.106433 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-04-18 16:36:38.516270 | debian-trixie | ok 2026-04-18 16:36:38.522948 | 2026-04-18 16:36:38.523028 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-04-18 16:36:39.398745 | debian-trixie | Output suppressed because no_log was given 2026-04-18 16:36:39.407782 | 2026-04-18 16:36:39.407921 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-04-18 16:36:39.609846 | debian-trixie | ok: "logs" 2026-04-18 16:36:39.610096 | debian-trixie | ok: All items complete 2026-04-18 16:36:39.610131 | 2026-04-18 16:36:39.789345 | debian-trixie | ok: "artifacts" 2026-04-18 16:36:39.976973 | debian-trixie | ok: "docs" 2026-04-18 16:36:39.984607 | 2026-04-18 16:36:39.984719 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-04-18 16:36:40.194198 | debian-trixie | changed: "logs" 2026-04-18 16:36:40.381678 | debian-trixie | changed: "artifacts" 2026-04-18 16:36:40.575995 | debian-trixie | changed: "docs" 2026-04-18 16:36:40.597653 | 2026-04-18 16:36:40.597764 | PLAY RECAP 2026-04-18 16:36:40.597825 | debian-trixie | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-04-18 16:36:40.597864 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-18 16:36:40.597894 | 2026-04-18 16:36:40.716858 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-04-18 16:36:40.717999 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-04-18 16:36:41.296427 | 2026-04-18 16:36:41.296559 | PLAY [all] 2026-04-18 16:36:41.307742 | 2026-04-18 16:36:41.307879 | TASK [setup-uv : Extract archive] 2026-04-18 16:36:52.349638 | debian-trixie | ERROR 2026-04-18 16:36:52.350011 | debian-trixie | { 2026-04-18 16:36:52.350067 | debian-trixie | "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:52.350113 | debian-trixie | } failure 2026-04-18 16:36:52.351877 | 2026-04-18 16:36:52.351944 | PLAY RECAP 2026-04-18 16:36:52.351989 | debian-trixie | ok: 0 changed: 0 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2026-04-18 16:36:52.352013 | 2026-04-18 16:36:52.533861 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-04-18 16:36:52.535388 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-04-18 16:36:53.176325 | 2026-04-18 16:36:53.176519 | PLAY [all] 2026-04-18 16:36:53.188436 | 2026-04-18 16:36:53.188546 | TASK [fetch-output : Set log path for multiple nodes] 2026-04-18 16:36:53.244482 | debian-trixie | skipping: Conditional result was False 2026-04-18 16:36:53.254428 | 2026-04-18 16:36:53.254806 | TASK [fetch-output : Set log path for single node] 2026-04-18 16:36:53.300680 | debian-trixie | ok 2026-04-18 16:36:53.305590 | 2026-04-18 16:36:53.305661 | LOOP [fetch-output : Ensure local output dirs] 2026-04-18 16:36:53.785465 | debian-trixie -> localhost | ok: "/var/lib/zuul/builds/6476442dfc8d473892458d4244f8533c/work/logs" 2026-04-18 16:36:54.008573 | debian-trixie -> localhost | changed: "/var/lib/zuul/builds/6476442dfc8d473892458d4244f8533c/work/artifacts" 2026-04-18 16:36:54.285101 | debian-trixie -> localhost | changed: "/var/lib/zuul/builds/6476442dfc8d473892458d4244f8533c/work/docs" 2026-04-18 16:36:54.299973 | 2026-04-18 16:36:54.300124 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-04-18 16:36:55.058449 | debian-trixie | ok 2026-04-18 16:36:55.058815 | debian-trixie | changed: All items complete 2026-04-18 16:36:55.058851 | 2026-04-18 16:36:55.598613 | debian-trixie | changed: .d..t...... ./ 2026-04-18 16:36:56.117958 | debian-trixie | changed: .d..t...... ./ 2026-04-18 16:36:56.159169 | 2026-04-18 16:36:56.159362 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-04-18 16:36:56.593669 | debian-trixie -> localhost | ok: Item: artifacts Runtime: 0:00:00.008894 2026-04-18 16:36:56.811467 | debian-trixie -> localhost | ok: Item: docs Runtime: 0:00:00.007317 2026-04-18 16:36:56.824062 | 2026-04-18 16:36:56.824178 | PLAY [all] 2026-04-18 16:36:56.831045 | 2026-04-18 16:36:56.831132 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-04-18 16:36:57.290616 | debian-trixie | changed 2026-04-18 16:36:57.296670 | 2026-04-18 16:36:57.296733 | PLAY RECAP 2026-04-18 16:36:57.296779 | debian-trixie | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-04-18 16:36:57.296801 | 2026-04-18 16:36:57.400682 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-04-18 16:36:57.402114 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-04-18 16:36:58.096376 | 2026-04-18 16:36:59.468623 | PLAY [localhost] 2026-04-18 16:36:59.484591 | 2026-04-18 16:36:59.484757 | TASK [Generate Zuul manifest] 2026-04-18 16:36:59.505031 | localhost | ok 2026-04-18 16:36:59.524782 | 2026-04-18 16:36:59.524939 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-04-18 16:36:59.898857 | localhost | changed 2026-04-18 16:36:59.909881 | 2026-04-18 16:36:59.909956 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-04-18 16:37:00.274515 | localhost | ok 2026-04-18 16:37:00.283531 | 2026-04-18 16:37:00.283623 | TASK [Upload logs] 2026-04-18 16:37:00.304346 | localhost | ok 2026-04-18 16:37:00.369210 | 2026-04-18 16:37:00.369337 | TASK [Set zuul-log-path fact] 2026-04-18 16:37:00.390878 | localhost | ok 2026-04-18 16:37:00.408004 | 2026-04-18 16:37:00.408129 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-18 16:37:00.439808 | localhost | ok 2026-04-18 16:37:00.450370 | 2026-04-18 16:37:00.450503 | TASK [upload-logs : Create log directories] 2026-04-18 16:37:00.873180 | localhost | changed 2026-04-18 16:37:00.878523 | 2026-04-18 16:37:00.878656 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-04-18 16:37:01.586752 | localhost -> localhost | ok: Runtime: 0:00:00.005292 2026-04-18 16:37:01.592656 | 2026-04-18 16:37:01.592773 | TASK [upload-logs : Upload logs to log server] 2026-04-18 16:37:02.062384 | localhost | Output suppressed because no_log was given 2026-04-18 16:37:02.070398 | 2026-04-18 16:37:02.070494 | LOOP [upload-logs : Compress console log and json output] 2026-04-18 16:37:02.116264 | localhost | skipping: Conditional result was False 2026-04-18 16:37:02.124691 | localhost | skipping: Conditional result was False 2026-04-18 16:37:02.138008 | 2026-04-18 16:37:02.138216 | LOOP [upload-logs : Upload compressed console log and json output] 2026-04-18 16:37:02.183364 | localhost | skipping: Conditional result was False 2026-04-18 16:37:02.183816 | 2026-04-18 16:37:02.188071 | localhost | skipping: Conditional result was False 2026-04-18 16:37:02.201881 | 2026-04-18 16:37:02.202099 | LOOP [upload-logs : Upload console log and json output]