2026-08-13 07:58:24.398920 | Job console starting 2026-08-13 07:58:24.410512 | Updating git repos 2026-08-13 07:58:27.583561 | Cloning repos into workspace 2026-08-13 07:58:42.065645 | Restoring repo states 2026-08-13 07:58:55.450205 | Merging changes 2026-08-13 07:58:56.911488 | Checking out repos 2026-08-13 07:58:58.288683 | Preparing playbooks 2026-08-13 07:59:00.160731 | Running Ansible setup 2026-08-13 07:59:03.972369 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-08-13 07:59:04.671027 | 2026-08-13 07:59:04.671201 | PLAY [localhost] 2026-08-13 07:59:04.680208 | 2026-08-13 07:59:04.680327 | TASK [Gathering Facts] 2026-08-13 07:59:05.652946 | localhost | ok 2026-08-13 07:59:05.665409 | 2026-08-13 07:59:05.665520 | TASK [Setup log path fact] 2026-08-13 07:59:05.689297 | localhost | ok 2026-08-13 07:59:05.705581 | 2026-08-13 07:59:05.705790 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-08-13 07:59:05.742454 | localhost | ok 2026-08-13 07:59:05.752004 | 2026-08-13 07:59:05.752144 | TASK [emit-job-header : Print job information] 2026-08-13 07:59:05.799216 | # Job Information 2026-08-13 07:59:05.799452 | Ansible Version: 2.16.18 2026-08-13 07:59:05.799501 | Job: atmosphere-molecule-csi-rbd 2026-08-13 07:59:05.799534 | Pipeline: check 2026-08-13 07:59:05.799564 | Executor: 2d72f0692154 2026-08-13 07:59:05.799593 | Triggered by: https://github.com/vexxhost/atmosphere/pull/4093 2026-08-13 07:59:05.799628 | Event ID: b3663690-96ea-11f1-9387-c6619b1cbaa3 2026-08-13 07:59:05.804550 | 2026-08-13 07:59:05.804648 | LOOP [emit-job-header : Print node information] 2026-08-13 07:59:05.911604 | localhost | ok: 2026-08-13 07:59:05.911877 | localhost | # Node Information 2026-08-13 07:59:05.911947 | localhost | Inventory Hostname: instance 2026-08-13 07:59:05.912000 | localhost | Hostname: np0000189214 2026-08-13 07:59:05.912047 | localhost | Username: zuul 2026-08-13 07:59:05.912100 | localhost | Distro: Ubuntu 22.04 2026-08-13 07:59:05.912147 | localhost | Provider: yul1 2026-08-13 07:59:05.912194 | localhost | Region: ca-ymq-1 2026-08-13 07:59:05.912239 | localhost | Label: ubuntu-jammy 2026-08-13 07:59:05.912284 | localhost | Product Name: OpenStack Nova 2026-08-13 07:59:05.912331 | localhost | Interface IP: 199.204.45.61 2026-08-13 07:59:05.926155 | 2026-08-13 07:59:05.926288 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-08-13 07:59:06.371664 | localhost -> localhost | changed 2026-08-13 07:59:06.380077 | 2026-08-13 07:59:06.380194 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-08-13 07:59:07.345333 | localhost -> localhost | changed 2026-08-13 07:59:07.356303 | 2026-08-13 07:59:07.356398 | PLAY [all] 2026-08-13 07:59:07.365980 | 2026-08-13 07:59:07.366053 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-08-13 07:59:07.626062 | instance -> localhost | ok 2026-08-13 07:59:07.636506 | 2026-08-13 07:59:07.636620 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-08-13 07:59:07.670917 | instance | ok 2026-08-13 07:59:07.687162 | instance | included: /var/lib/zuul/builds/43e5f54a12ec4d77a20e79354b71b864/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-08-13 07:59:07.693788 | 2026-08-13 07:59:07.693877 | TASK [add-build-sshkey : Create Temp SSH key] 2026-08-13 07:59:08.801667 | instance -> localhost | Generating public/private rsa key pair. 2026-08-13 07:59:08.802186 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/43e5f54a12ec4d77a20e79354b71b864/work/43e5f54a12ec4d77a20e79354b71b864_id_rsa 2026-08-13 07:59:08.802252 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/43e5f54a12ec4d77a20e79354b71b864/work/43e5f54a12ec4d77a20e79354b71b864_id_rsa.pub 2026-08-13 07:59:08.802597 | instance -> localhost | The key fingerprint is: 2026-08-13 07:59:08.803251 | instance -> localhost | SHA256:3Hv8y4TOFh0PxwAtnWFJ4hYdv1fLY8OtpIZ1d++fteg zuul-build-sshkey 2026-08-13 07:59:08.803853 | instance -> localhost | The key's randomart image is: 2026-08-13 07:59:08.803909 | instance -> localhost | +---[RSA 3072]----+ 2026-08-13 07:59:08.804139 | instance -> localhost | | +*== | 2026-08-13 07:59:08.804178 | instance -> localhost | | ..+B. | 2026-08-13 07:59:08.804202 | instance -> localhost | | o. oo| 2026-08-13 07:59:08.804224 | instance -> localhost | | . . . = B| 2026-08-13 07:59:08.804421 | instance -> localhost | | S . ..o&=| 2026-08-13 07:59:08.804674 | instance -> localhost | | =.=ooB| 2026-08-13 07:59:08.804709 | instance -> localhost | | o *.o o| 2026-08-13 07:59:08.804731 | instance -> localhost | | =.+..+| 2026-08-13 07:59:08.805094 | instance -> localhost | | .+E+++| 2026-08-13 07:59:08.805230 | instance -> localhost | +----[SHA256]-----+ 2026-08-13 07:59:08.805332 | instance -> localhost | ok: Runtime: 0:00:00.667631 2026-08-13 07:59:08.816438 | 2026-08-13 07:59:08.816541 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-08-13 07:59:08.855588 | instance | ok 2026-08-13 07:59:08.869335 | instance | included: /var/lib/zuul/builds/43e5f54a12ec4d77a20e79354b71b864/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-08-13 07:59:08.894170 | 2026-08-13 07:59:08.894632 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-08-13 07:59:08.925044 | instance | skipping: Conditional result was False 2026-08-13 07:59:08.936131 | 2026-08-13 07:59:08.936260 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-08-13 07:59:09.575114 | instance | changed 2026-08-13 07:59:09.583273 | 2026-08-13 07:59:09.583465 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-08-13 07:59:09.812772 | instance | ok 2026-08-13 07:59:09.819850 | 2026-08-13 07:59:09.819944 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-08-13 07:59:10.355723 | instance | changed 2026-08-13 07:59:10.362535 | 2026-08-13 07:59:10.362643 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-08-13 07:59:10.814954 | instance | changed 2026-08-13 07:59:10.825000 | 2026-08-13 07:59:10.825175 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-08-13 07:59:10.851780 | instance | skipping: Conditional result was False 2026-08-13 07:59:10.865047 | 2026-08-13 07:59:10.865282 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-08-13 07:59:11.247434 | instance -> localhost | changed 2026-08-13 07:59:11.262575 | 2026-08-13 07:59:11.262703 | TASK [add-build-sshkey : Add back temp key] 2026-08-13 07:59:11.562179 | instance -> localhost | Identity added: /var/lib/zuul/builds/43e5f54a12ec4d77a20e79354b71b864/work/43e5f54a12ec4d77a20e79354b71b864_id_rsa (zuul-build-sshkey) 2026-08-13 07:59:11.562582 | instance -> localhost | ok: Runtime: 0:00:00.015083 2026-08-13 07:59:11.571357 | 2026-08-13 07:59:11.571582 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-08-13 07:59:11.892660 | instance | ok 2026-08-13 07:59:11.897825 | 2026-08-13 07:59:11.897895 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-08-13 07:59:11.924444 | instance | skipping: Conditional result was False 2026-08-13 07:59:11.938691 | 2026-08-13 07:59:11.938829 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-08-13 07:59:12.514459 | instance | ok 2026-08-13 07:59:12.522211 | 2026-08-13 07:59:12.522389 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-08-13 07:59:19.523535 | instance | Output suppressed because no_log was given 2026-08-13 07:59:19.535583 | 2026-08-13 07:59:19.535730 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-08-13 07:59:19.757592 | instance | ok: "logs" 2026-08-13 07:59:19.757795 | instance | ok: All items complete 2026-08-13 07:59:19.757824 | 2026-08-13 07:59:19.928615 | instance | ok: "artifacts" 2026-08-13 07:59:20.097147 | instance | ok: "docs" 2026-08-13 07:59:20.110880 | 2026-08-13 07:59:20.111003 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-08-13 07:59:20.316572 | instance | changed: "logs" 2026-08-13 07:59:20.464767 | instance | changed: "artifacts" 2026-08-13 07:59:20.625410 | instance | changed: "docs" 2026-08-13 07:59:20.736590 | 2026-08-13 07:59:20.736706 | PLAY RECAP 2026-08-13 07:59:20.736767 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-08-13 07:59:20.736807 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-08-13 07:59:20.736837 | 2026-08-13 07:59:20.876524 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-08-13 07:59:20.879791 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-08-13 07:59:21.627718 | 2026-08-13 07:59:21.627969 | PLAY [all] 2026-08-13 07:59:21.641865 | 2026-08-13 07:59:21.642015 | TASK [setup-uv : Extract archive] 2026-08-13 07:59:22.683616 | instance | ERROR 2026-08-13 07:59:22.754235 | instance | { 2026-08-13 07:59:22.754452 | 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: Remote end closed connection without response" 2026-08-13 07:59:22.754538 | instance | } failure 2026-08-13 07:59:22.757090 | 2026-08-13 07:59:22.757267 | PLAY RECAP 2026-08-13 07:59:22.757397 | instance | ok: 0 changed: 0 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2026-08-13 07:59:22.757477 | 2026-08-13 07:59:22.959414 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-08-13 07:59:22.965841 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-08-13 07:59:23.745010 | 2026-08-13 07:59:23.745263 | PLAY [all] 2026-08-13 07:59:23.760769 | 2026-08-13 07:59:23.761014 | TASK [fetch-output : Set log path for multiple nodes] 2026-08-13 07:59:23.808513 | instance | skipping: Conditional result was False 2026-08-13 07:59:23.817779 | 2026-08-13 07:59:23.818000 | TASK [fetch-output : Set log path for single node] 2026-08-13 07:59:23.857181 | instance | ok 2026-08-13 07:59:23.864101 | 2026-08-13 07:59:23.864298 | LOOP [fetch-output : Ensure local output dirs] 2026-08-13 07:59:24.322334 | instance -> localhost | ok: "/var/lib/zuul/builds/43e5f54a12ec4d77a20e79354b71b864/work/logs" 2026-08-13 07:59:24.553670 | instance -> localhost | changed: "/var/lib/zuul/builds/43e5f54a12ec4d77a20e79354b71b864/work/artifacts" 2026-08-13 07:59:24.789108 | instance -> localhost | changed: "/var/lib/zuul/builds/43e5f54a12ec4d77a20e79354b71b864/work/docs" 2026-08-13 07:59:24.805220 | 2026-08-13 07:59:24.805372 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-08-13 07:59:25.446156 | instance | ok 2026-08-13 07:59:25.446384 | instance | changed: All items complete 2026-08-13 07:59:25.446426 | 2026-08-13 07:59:25.908526 | instance | changed: .d..t...... ./ 2026-08-13 07:59:26.381928 | instance | changed: .d..t...... ./ 2026-08-13 07:59:26.401077 | 2026-08-13 07:59:26.401217 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-08-13 07:59:26.847932 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.008390 2026-08-13 07:59:27.105905 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.007475 2026-08-13 07:59:27.118300 | 2026-08-13 07:59:27.118703 | PLAY [all] 2026-08-13 07:59:27.124582 | 2026-08-13 07:59:27.124649 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-08-13 07:59:27.575595 | instance | changed 2026-08-13 07:59:27.583452 | 2026-08-13 07:59:27.583553 | PLAY RECAP 2026-08-13 07:59:27.583622 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-08-13 07:59:27.583661 | 2026-08-13 07:59:27.717809 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-08-13 07:59:27.723833 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-08-13 07:59:28.359673 | 2026-08-13 07:59:28.359819 | PLAY [localhost] 2026-08-13 07:59:28.370571 | 2026-08-13 07:59:28.370657 | TASK [Generate Zuul manifest] 2026-08-13 07:59:28.390515 | localhost | ok 2026-08-13 07:59:28.405891 | 2026-08-13 07:59:28.406007 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-08-13 07:59:28.777348 | localhost | changed 2026-08-13 07:59:28.788823 | 2026-08-13 07:59:28.788946 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-08-13 07:59:28.820668 | localhost | ok 2026-08-13 07:59:28.830236 | 2026-08-13 07:59:28.830347 | TASK [Upload logs] 2026-08-13 07:59:28.853885 | localhost | ok 2026-08-13 07:59:28.916870 | 2026-08-13 07:59:28.917056 | TASK [Set zuul-log-path fact] 2026-08-13 07:59:28.935884 | localhost | ok 2026-08-13 07:59:28.954500 | 2026-08-13 07:59:28.954658 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-08-13 07:59:28.985522 | localhost | ok 2026-08-13 07:59:28.994599 | 2026-08-13 07:59:28.994723 | TASK [upload-logs : Create log directories] 2026-08-13 07:59:29.416648 | localhost | changed 2026-08-13 07:59:29.421827 | 2026-08-13 07:59:29.421926 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-08-13 07:59:29.846745 | localhost -> localhost | ok: Runtime: 0:00:00.006488 2026-08-13 07:59:29.853755 | 2026-08-13 07:59:29.853834 | TASK [upload-logs : Upload logs to log server] 2026-08-13 07:59:30.381808 | localhost | Output suppressed because no_log was given 2026-08-13 07:59:30.426979 | 2026-08-13 07:59:30.427116 | LOOP [upload-logs : Compress console log and json output] 2026-08-13 07:59:30.472232 | localhost | skipping: Conditional result was False 2026-08-13 07:59:30.479243 | localhost | skipping: Conditional result was False 2026-08-13 07:59:30.492414 | 2026-08-13 07:59:30.492628 | LOOP [upload-logs : Upload compressed console log and json output] 2026-08-13 07:59:30.537586 | localhost | skipping: Conditional result was False 2026-08-13 07:59:30.537841 | 2026-08-13 07:59:30.542100 | localhost | skipping: Conditional result was False 2026-08-13 07:59:30.553848 | 2026-08-13 07:59:30.554402 | LOOP [upload-logs : Upload console log and json output]