2026-06-08 08:11:03.225483 | Job console starting 2026-06-08 08:11:03.236900 | Updating git repos 2026-06-08 08:11:05.276092 | Cloning repos into workspace 2026-06-08 08:11:30.864210 | Restoring repo states 2026-06-08 08:11:37.888152 | Merging changes 2026-06-08 08:11:40.904597 | Checking out repos 2026-06-08 08:11:46.721444 | Preparing playbooks 2026-06-08 08:12:03.259075 | Running Ansible setup 2026-06-08 08:12:08.820049 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-06-08 08:12:09.447267 | 2026-06-08 08:12:09.447514 | PLAY [localhost] 2026-06-08 08:12:09.455215 | 2026-06-08 08:12:09.455294 | TASK [Gathering Facts] 2026-06-08 08:12:10.356841 | localhost | ok 2026-06-08 08:12:10.368262 | 2026-06-08 08:12:10.368366 | TASK [Setup log path fact] 2026-06-08 08:12:10.388605 | localhost | ok 2026-06-08 08:12:10.400565 | 2026-06-08 08:12:10.400639 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-06-08 08:12:10.433241 | localhost | ok 2026-06-08 08:12:10.443186 | 2026-06-08 08:12:10.443289 | TASK [emit-job-header : Print job information] 2026-06-08 08:12:10.489733 | # Job Information 2026-06-08 08:12:10.490047 | Ansible Version: 2.16.18 2026-06-08 08:12:10.490151 | Job: atmosphere-molecule-csi-local-path-provisioner 2026-06-08 08:12:10.490217 | Pipeline: check 2026-06-08 08:12:10.490268 | Executor: 2d72f0692154 2026-06-08 08:12:10.490371 | Triggered by: https://github.com/vexxhost/atmosphere/pull/4002 2026-06-08 08:12:10.490425 | Event ID: 3bb3d0b0-6311-11f1-9c9b-c80327718c46 2026-06-08 08:12:10.493971 | 2026-06-08 08:12:10.494046 | LOOP [emit-job-header : Print node information] 2026-06-08 08:12:10.605041 | localhost | ok: 2026-06-08 08:12:10.605321 | localhost | # Node Information 2026-06-08 08:12:10.605384 | localhost | Inventory Hostname: instance 2026-06-08 08:12:10.605435 | localhost | Hostname: np0000178888 2026-06-08 08:12:10.605482 | localhost | Username: zuul 2026-06-08 08:12:10.605537 | localhost | Distro: Ubuntu 22.04 2026-06-08 08:12:10.605587 | localhost | Provider: yul1 2026-06-08 08:12:10.605637 | localhost | Region: ca-ymq-1 2026-06-08 08:12:10.605688 | localhost | Label: ubuntu-jammy 2026-06-08 08:12:10.605736 | localhost | Product Name: OpenStack Nova 2026-06-08 08:12:10.605784 | localhost | Interface IP: 199.204.45.222 2026-06-08 08:12:10.619825 | 2026-06-08 08:12:10.619969 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-06-08 08:12:11.248730 | localhost -> localhost | changed 2026-06-08 08:12:11.260177 | 2026-06-08 08:12:11.260332 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-06-08 08:12:12.361033 | localhost -> localhost | changed 2026-06-08 08:12:12.378558 | 2026-06-08 08:12:12.378640 | PLAY [all] 2026-06-08 08:12:12.390794 | 2026-06-08 08:12:12.390880 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-06-08 08:12:12.628126 | instance -> localhost | ok 2026-06-08 08:12:12.638423 | 2026-06-08 08:12:12.638592 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-06-08 08:12:12.676904 | instance | ok 2026-06-08 08:12:12.691540 | instance | included: /var/lib/zuul/builds/032e9dbedc76452198d12b1c7926a223/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-06-08 08:12:12.697181 | 2026-06-08 08:12:12.697241 | TASK [add-build-sshkey : Create Temp SSH key] 2026-06-08 08:12:13.553360 | instance -> localhost | Generating public/private rsa key pair. 2026-06-08 08:12:13.553575 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/032e9dbedc76452198d12b1c7926a223/work/032e9dbedc76452198d12b1c7926a223_id_rsa 2026-06-08 08:12:13.553620 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/032e9dbedc76452198d12b1c7926a223/work/032e9dbedc76452198d12b1c7926a223_id_rsa.pub 2026-06-08 08:12:13.553654 | instance -> localhost | The key fingerprint is: 2026-06-08 08:12:13.553686 | instance -> localhost | SHA256:yBq3y5cDS22TTKLP3PR7JIatpY3zrv3IrbbTJCC+BA8 zuul-build-sshkey 2026-06-08 08:12:13.553729 | instance -> localhost | The key's randomart image is: 2026-06-08 08:12:13.553761 | instance -> localhost | +---[RSA 3072]----+ 2026-06-08 08:12:13.553803 | instance -> localhost | | | 2026-06-08 08:12:13.553835 | instance -> localhost | | | 2026-06-08 08:12:13.553866 | instance -> localhost | | | 2026-06-08 08:12:13.553896 | instance -> localhost | | E.o.o | 2026-06-08 08:12:13.553926 | instance -> localhost | | .*+*S= | 2026-06-08 08:12:13.553956 | instance -> localhost | | .+*.O * o | 2026-06-08 08:12:13.553986 | instance -> localhost | | .*.B @ * | 2026-06-08 08:12:13.554015 | instance -> localhost | | .*.Xo=oo | 2026-06-08 08:12:13.554050 | instance -> localhost | | o..BXBo | 2026-06-08 08:12:13.554081 | instance -> localhost | +----[SHA256]-----+ 2026-06-08 08:12:13.554150 | instance -> localhost | ok: Runtime: 0:00:00.434092 2026-06-08 08:12:13.564592 | 2026-06-08 08:12:13.564711 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-06-08 08:12:13.601440 | instance | ok 2026-06-08 08:12:13.610965 | instance | included: /var/lib/zuul/builds/032e9dbedc76452198d12b1c7926a223/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-06-08 08:12:13.620915 | 2026-06-08 08:12:13.621003 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-06-08 08:12:13.645676 | instance | skipping: Conditional result was False 2026-06-08 08:12:13.657989 | 2026-06-08 08:12:13.658096 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-06-08 08:12:14.142487 | instance | changed 2026-06-08 08:12:14.148519 | 2026-06-08 08:12:14.148582 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-06-08 08:12:14.332971 | instance | ok 2026-06-08 08:12:14.340469 | 2026-06-08 08:12:14.340555 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-06-08 08:12:14.785473 | instance | changed 2026-06-08 08:12:14.790451 | 2026-06-08 08:12:14.790523 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-06-08 08:12:15.224259 | instance | changed 2026-06-08 08:12:15.230509 | 2026-06-08 08:12:15.230604 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-06-08 08:12:15.254234 | instance | skipping: Conditional result was False 2026-06-08 08:12:15.260140 | 2026-06-08 08:12:15.260223 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-06-08 08:12:15.638788 | instance -> localhost | changed 2026-06-08 08:12:15.649278 | 2026-06-08 08:12:15.649407 | TASK [add-build-sshkey : Add back temp key] 2026-06-08 08:12:15.933279 | instance -> localhost | Identity added: /var/lib/zuul/builds/032e9dbedc76452198d12b1c7926a223/work/032e9dbedc76452198d12b1c7926a223_id_rsa (zuul-build-sshkey) 2026-06-08 08:12:15.933536 | instance -> localhost | ok: Runtime: 0:00:00.018854 2026-06-08 08:12:15.938935 | 2026-06-08 08:12:15.938999 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-06-08 08:12:16.277258 | instance | ok 2026-06-08 08:12:16.282738 | 2026-06-08 08:12:16.282807 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-06-08 08:12:16.311647 | instance | skipping: Conditional result was False 2026-06-08 08:12:16.328832 | 2026-06-08 08:12:16.329008 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-06-08 08:12:16.698198 | instance | ok 2026-06-08 08:12:16.706633 | 2026-06-08 08:12:16.706717 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-06-08 08:12:20.001864 | instance | Output suppressed because no_log was given 2026-06-08 08:12:20.022543 | 2026-06-08 08:12:20.022664 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-06-08 08:12:20.232753 | instance | ok: "logs" 2026-06-08 08:12:20.233653 | instance | ok: All items complete 2026-06-08 08:12:20.233697 | 2026-06-08 08:12:20.377775 | instance | ok: "artifacts" 2026-06-08 08:12:20.530982 | instance | ok: "docs" 2026-06-08 08:12:20.558270 | 2026-06-08 08:12:20.558445 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-06-08 08:12:20.778314 | instance | changed: "logs" 2026-06-08 08:12:20.946379 | instance | changed: "artifacts" 2026-06-08 08:12:21.100987 | instance | changed: "docs" 2026-06-08 08:12:21.120149 | 2026-06-08 08:12:21.120417 | PLAY RECAP 2026-06-08 08:12:21.120495 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-06-08 08:12:21.120552 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-06-08 08:12:21.120590 | 2026-06-08 08:12:21.282327 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-06-08 08:12:21.287003 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-06-08 08:12:21.970942 | 2026-06-08 08:12:21.971114 | PLAY [all] 2026-06-08 08:12:21.982497 | 2026-06-08 08:12:21.982625 | TASK [setup-uv : Extract archive] 2026-06-08 08:12:32.926575 | instance | ERROR 2026-06-08 08:12:32.926818 | instance | { 2026-06-08 08:12:32.926858 | instance | "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-06-08 08:12:32.926889 | instance | } failure 2026-06-08 08:12:32.928321 | 2026-06-08 08:12:32.928366 | PLAY RECAP 2026-06-08 08:12:32.928410 | instance | ok: 0 changed: 0 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2026-06-08 08:12:32.928434 | 2026-06-08 08:12:33.068315 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-06-08 08:12:33.071018 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-06-08 08:12:33.892218 | 2026-06-08 08:12:33.892407 | PLAY [all] 2026-06-08 08:12:33.906438 | 2026-06-08 08:12:33.906809 | TASK [fetch-output : Set log path for multiple nodes] 2026-06-08 08:12:33.963973 | instance | skipping: Conditional result was False 2026-06-08 08:12:33.971866 | 2026-06-08 08:12:33.972042 | TASK [fetch-output : Set log path for single node] 2026-06-08 08:12:34.027223 | instance | ok 2026-06-08 08:12:34.033249 | 2026-06-08 08:12:34.033335 | LOOP [fetch-output : Ensure local output dirs] 2026-06-08 08:12:34.453559 | instance -> localhost | ok: "/var/lib/zuul/builds/032e9dbedc76452198d12b1c7926a223/work/logs" 2026-06-08 08:12:34.701498 | instance -> localhost | changed: "/var/lib/zuul/builds/032e9dbedc76452198d12b1c7926a223/work/artifacts" 2026-06-08 08:12:34.966051 | instance -> localhost | changed: "/var/lib/zuul/builds/032e9dbedc76452198d12b1c7926a223/work/docs" 2026-06-08 08:12:34.984906 | 2026-06-08 08:12:34.985132 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-06-08 08:12:35.667623 | instance | changed: .d..t...... ./ 2026-06-08 08:12:35.667898 | instance | changed: All items complete 2026-06-08 08:12:35.667937 | 2026-06-08 08:12:36.195871 | instance | changed: .d..t...... ./ 2026-06-08 08:12:36.634258 | instance | changed: .d..t...... ./ 2026-06-08 08:12:36.656880 | 2026-06-08 08:12:36.657054 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-06-08 08:12:37.131772 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.007927 2026-06-08 08:12:37.734830 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.008289 2026-06-08 08:12:37.744675 | 2026-06-08 08:12:37.745168 | PLAY [all] 2026-06-08 08:12:37.751566 | 2026-06-08 08:12:37.751645 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-06-08 08:12:38.191802 | instance | changed 2026-06-08 08:12:38.198673 | 2026-06-08 08:12:38.198727 | PLAY RECAP 2026-06-08 08:12:38.198775 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-06-08 08:12:38.198799 | 2026-06-08 08:12:38.354307 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-06-08 08:12:38.356433 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-06-08 08:12:38.922003 | 2026-06-08 08:12:38.922455 | PLAY [localhost] 2026-06-08 08:12:38.932156 | 2026-06-08 08:12:38.932223 | TASK [Generate Zuul manifest] 2026-06-08 08:12:38.955085 | localhost | ok 2026-06-08 08:12:38.970952 | 2026-06-08 08:12:38.971027 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-06-08 08:12:39.418753 | localhost | changed 2026-06-08 08:12:39.430455 | 2026-06-08 08:12:39.430561 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-06-08 08:12:39.459333 | localhost | ok 2026-06-08 08:12:39.471435 | 2026-06-08 08:12:39.471561 | TASK [Upload logs] 2026-06-08 08:12:39.495578 | localhost | ok 2026-06-08 08:12:39.557048 | 2026-06-08 08:12:39.557176 | TASK [Set zuul-log-path fact] 2026-06-08 08:12:39.577358 | localhost | ok 2026-06-08 08:12:39.590280 | 2026-06-08 08:12:39.590366 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-06-08 08:12:39.641927 | localhost | ok 2026-06-08 08:12:39.651506 | 2026-06-08 08:12:39.651601 | TASK [upload-logs : Create log directories] 2026-06-08 08:12:40.118475 | localhost | changed 2026-06-08 08:12:40.123240 | 2026-06-08 08:12:40.123311 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-06-08 08:12:40.577435 | localhost -> localhost | ok: Runtime: 0:00:00.008875 2026-06-08 08:12:40.584855 | 2026-06-08 08:12:40.586224 | TASK [upload-logs : Upload logs to log server] 2026-06-08 08:12:41.177210 | localhost | Output suppressed because no_log was given 2026-06-08 08:12:41.219607 | 2026-06-08 08:12:41.219743 | LOOP [upload-logs : Compress console log and json output] 2026-06-08 08:12:41.266177 | localhost | skipping: Conditional result was False 2026-06-08 08:12:41.273606 | localhost | skipping: Conditional result was False 2026-06-08 08:12:41.283375 | 2026-06-08 08:12:41.283615 | LOOP [upload-logs : Upload compressed console log and json output] 2026-06-08 08:12:41.323333 | localhost | skipping: Conditional result was False 2026-06-08 08:12:41.323713 | 2026-06-08 08:12:41.327597 | localhost | skipping: Conditional result was False 2026-06-08 08:12:41.336911 | 2026-06-08 08:12:41.337122 | LOOP [upload-logs : Upload console log and json output]