2026-05-26 15:41:58.863709 | Job console starting
2026-05-26 15:41:58.875303 | Updating git repos
2026-05-26 15:42:03.766924 | Cloning repos into workspace
2026-05-26 15:43:11.501651 | Restoring repo states
2026-05-26 15:43:23.331625 | Merging changes
2026-05-26 15:43:25.985306 | Checking out repos
2026-05-26 15:43:34.049835 | Preparing playbooks
2026-05-26 15:43:59.548341 | Running Ansible setup
2026-05-26 15:44:04.599584 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-05-26 15:44:05.356685 | 
2026-05-26 15:44:05.356867 | PLAY [localhost]
2026-05-26 15:44:05.365711 | 
2026-05-26 15:44:05.365800 | TASK [Gathering Facts]
2026-05-26 15:44:06.923541 | localhost | ok
2026-05-26 15:44:06.935934 | 
2026-05-26 15:44:06.936107 | TASK [Setup log path fact]
2026-05-26 15:44:06.956719 | localhost | ok
2026-05-26 15:44:06.968235 | 
2026-05-26 15:44:06.968378 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-05-26 15:44:06.998970 | localhost | ok
2026-05-26 15:44:07.007756 | 
2026-05-26 15:44:07.007936 | TASK [emit-job-header : Print job information]
2026-05-26 15:44:07.050946 | # Job Information
2026-05-26 15:44:07.051223 | Ansible Version: 2.16.18
2026-05-26 15:44:07.051296 | Job: atmosphere-molecule-csi-rbd
2026-05-26 15:44:07.051346 | Pipeline: check
2026-05-26 15:44:07.051390 | Executor: 2d72f0692154
2026-05-26 15:44:07.051437 | Triggered by: https://github.com/vexxhost/atmosphere/pull/3995
2026-05-26 15:44:07.051489 | Event ID: 47f885c0-5919-11f1-801d-9695ea07e128
2026-05-26 15:44:07.056527 | 
2026-05-26 15:44:07.056636 | LOOP [emit-job-header : Print node information]
2026-05-26 15:44:07.149702 | localhost | ok:
2026-05-26 15:44:07.150032 | localhost | # Node Information
2026-05-26 15:44:07.150094 | localhost | Inventory Hostname: instance
2026-05-26 15:44:07.150142 | localhost | Hostname: np0000176674
2026-05-26 15:44:07.150185 | localhost | Username: zuul
2026-05-26 15:44:07.150234 | localhost | Distro: Ubuntu 22.04
2026-05-26 15:44:07.150277 | localhost | Provider: yul1
2026-05-26 15:44:07.150320 | localhost | Region: ca-ymq-1
2026-05-26 15:44:07.150360 | localhost | Label: ubuntu-jammy
2026-05-26 15:44:07.150401 | localhost | Product Name: OpenStack Nova
2026-05-26 15:44:07.150440 | localhost | Interface IP: 199.19.213.11
2026-05-26 15:44:07.156323 | 
2026-05-26 15:44:07.156411 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-05-26 15:44:07.586812 | localhost -> localhost | changed
2026-05-26 15:44:07.594646 | 
2026-05-26 15:44:07.594746 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-05-26 15:44:08.610537 | localhost -> localhost | changed
2026-05-26 15:44:08.620379 | 
2026-05-26 15:44:08.620527 | PLAY [all]
2026-05-26 15:44:08.643267 | 
2026-05-26 15:44:08.643421 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-05-26 15:44:08.882559 | instance -> localhost | ok
2026-05-26 15:44:08.890368 | 
2026-05-26 15:44:08.890470 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-05-26 15:44:08.924702 | instance | ok
2026-05-26 15:44:08.941207 | instance | included: /var/lib/zuul/builds/cc14b8c84b5142dfaf4b00691e773042/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-05-26 15:44:08.946878 | 
2026-05-26 15:44:08.946949 | TASK [add-build-sshkey : Create Temp SSH key]
2026-05-26 15:44:10.600778 | instance -> localhost | Generating public/private rsa key pair.
2026-05-26 15:44:10.601049 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/cc14b8c84b5142dfaf4b00691e773042/work/cc14b8c84b5142dfaf4b00691e773042_id_rsa
2026-05-26 15:44:10.601083 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/cc14b8c84b5142dfaf4b00691e773042/work/cc14b8c84b5142dfaf4b00691e773042_id_rsa.pub
2026-05-26 15:44:10.601108 | instance -> localhost | The key fingerprint is:
2026-05-26 15:44:10.601131 | instance -> localhost | SHA256:5m7hndNeDJPj8fg0jjQFBPkroGMVzxOTZ3xgOTV7sYM zuul-build-sshkey
2026-05-26 15:44:10.601175 | instance -> localhost | The key's randomart image is:
2026-05-26 15:44:10.601197 | instance -> localhost | +---[RSA 3072]----+
2026-05-26 15:44:10.601225 | instance -> localhost | |          .=++o. |
2026-05-26 15:44:10.601248 | instance -> localhost | |        . =o* ooo|
2026-05-26 15:44:10.601268 | instance -> localhost | |         + *.E.o.|
2026-05-26 15:44:10.601288 | instance -> localhost | |        o + .o ..|
2026-05-26 15:44:10.601308 | instance -> localhost | |       oS. .*..  |
2026-05-26 15:44:10.601327 | instance -> localhost | |      +o. ...X   |
2026-05-26 15:44:10.601347 | instance -> localhost | |     . o.o += *  |
2026-05-26 15:44:10.601366 | instance -> localhost | |       .o +..B . |
2026-05-26 15:44:10.601388 | instance -> localhost | |       ..  oo o  |
2026-05-26 15:44:10.601409 | instance -> localhost | +----[SHA256]-----+
2026-05-26 15:44:10.601468 | instance -> localhost | ok: Runtime: 0:00:01.199336
2026-05-26 15:44:10.608029 | 
2026-05-26 15:44:10.608219 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-05-26 15:44:10.643522 | instance | ok
2026-05-26 15:44:10.655011 | instance | included: /var/lib/zuul/builds/cc14b8c84b5142dfaf4b00691e773042/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-05-26 15:44:10.662610 | 
2026-05-26 15:44:10.662699 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-05-26 15:44:10.686957 | instance | skipping: Conditional result was False
2026-05-26 15:44:10.697571 | 
2026-05-26 15:44:10.697695 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-05-26 15:44:11.193119 | instance | changed
2026-05-26 15:44:11.201646 | 
2026-05-26 15:44:11.201763 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-05-26 15:44:11.385214 | instance | ok
2026-05-26 15:44:11.392458 | 
2026-05-26 15:44:11.392567 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-05-26 15:44:11.887747 | instance | changed
2026-05-26 15:44:11.895131 | 
2026-05-26 15:44:11.895320 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-05-26 15:44:12.385508 | instance | changed
2026-05-26 15:44:12.393381 | 
2026-05-26 15:44:12.393460 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-05-26 15:44:12.418504 | instance | skipping: Conditional result was False
2026-05-26 15:44:12.427490 | 
2026-05-26 15:44:12.427637 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-05-26 15:44:12.879132 | instance -> localhost | changed
2026-05-26 15:44:12.889528 | 
2026-05-26 15:44:12.889640 | TASK [add-build-sshkey : Add back temp key]
2026-05-26 15:44:13.208925 | instance -> localhost | Identity added: /var/lib/zuul/builds/cc14b8c84b5142dfaf4b00691e773042/work/cc14b8c84b5142dfaf4b00691e773042_id_rsa (zuul-build-sshkey)
2026-05-26 15:44:13.209216 | instance -> localhost | ok: Runtime: 0:00:00.013609
2026-05-26 15:44:13.217239 | 
2026-05-26 15:44:13.217392 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-05-26 15:44:13.564340 | instance | ok
2026-05-26 15:44:13.570440 | 
2026-05-26 15:44:13.570577 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-05-26 15:44:13.595313 | instance | skipping: Conditional result was False
2026-05-26 15:44:13.609143 | 
2026-05-26 15:44:13.609276 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-05-26 15:44:13.935401 | instance | ok
2026-05-26 15:44:13.951438 | 
2026-05-26 15:44:13.951612 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-05-26 15:44:35.193003 | instance | Output suppressed because no_log was given
2026-05-26 15:44:35.205606 | 
2026-05-26 15:44:35.205735 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-05-26 15:44:35.398178 | instance | ok: "logs"
2026-05-26 15:44:35.398417 | instance | ok: All items complete
2026-05-26 15:44:35.398446 | 
2026-05-26 15:44:35.544664 | instance | ok: "artifacts"
2026-05-26 15:44:35.708145 | instance | ok: "docs"
2026-05-26 15:44:35.726488 | 
2026-05-26 15:44:35.726696 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-05-26 15:44:35.930864 | instance | changed: "logs"
2026-05-26 15:44:36.088464 | instance | changed: "artifacts"
2026-05-26 15:44:36.256696 | instance | changed: "docs"
2026-05-26 15:44:36.286912 | 
2026-05-26 15:44:36.287059 | PLAY RECAP
2026-05-26 15:44:36.287110 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-05-26 15:44:36.287139 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-05-26 15:44:36.287161 | 
2026-05-26 15:44:36.440155 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-05-26 15:44:36.445010 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-05-26 15:44:37.133333 | 
2026-05-26 15:44:37.133840 | PLAY [all]
2026-05-26 15:44:37.145820 | 
2026-05-26 15:44:37.145989 | TASK [setup-uv : Extract archive]
2026-05-26 15:44:39.835938 | instance | ERROR
2026-05-26 15:44:39.836208 | instance | {
2026-05-26 15:44:39.836247 | instance |   "msg": "Invalid archive '/home/zuul/.ansible/tmp/ansible-tmp-1779810277.1879897-5-77163353756906/uv-x86_64-unknown-linux-gnuav61yiu3.tar.gz', the file is 0 bytes"
2026-05-26 15:44:39.836277 | instance | }
failure
2026-05-26 15:44:39.837858 | 
2026-05-26 15:44:39.837909 | PLAY RECAP
2026-05-26 15:44:39.837955 | instance | ok: 0 changed: 0 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-05-26 15:44:39.837978 | 
2026-05-26 15:44:40.008608 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-05-26 15:44:40.014086 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-05-26 15:44:40.628569 | 
2026-05-26 15:44:40.628734 | PLAY [all]
2026-05-26 15:44:40.640916 | 
2026-05-26 15:44:40.641037 | TASK [fetch-output : Set log path for multiple nodes]
2026-05-26 15:44:40.686670 | instance | skipping: Conditional result was False
2026-05-26 15:44:40.695781 | 
2026-05-26 15:44:40.695890 | TASK [fetch-output : Set log path for single node]
2026-05-26 15:44:40.741110 | instance | ok
2026-05-26 15:44:40.747870 | 
2026-05-26 15:44:40.747971 | LOOP [fetch-output : Ensure local output dirs]
2026-05-26 15:44:41.158557 | instance -> localhost | ok: "/var/lib/zuul/builds/cc14b8c84b5142dfaf4b00691e773042/work/logs"
2026-05-26 15:44:41.374514 | instance -> localhost | changed: "/var/lib/zuul/builds/cc14b8c84b5142dfaf4b00691e773042/work/artifacts"
2026-05-26 15:44:41.630963 | instance -> localhost | changed: "/var/lib/zuul/builds/cc14b8c84b5142dfaf4b00691e773042/work/docs"
2026-05-26 15:44:41.651176 | 
2026-05-26 15:44:41.651368 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-05-26 15:44:42.306511 | instance | changed: .d..t...... ./
2026-05-26 15:44:42.306769 | instance | changed: All items complete
2026-05-26 15:44:42.306808 | 
2026-05-26 15:44:42.802830 | instance | changed: .d..t...... ./
2026-05-26 15:44:43.256292 | instance | changed: .d..t...... ./
2026-05-26 15:44:43.298407 | 
2026-05-26 15:44:43.298572 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-05-26 15:44:43.807839 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.007121
2026-05-26 15:44:44.081730 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.020686
2026-05-26 15:44:44.105788 | 
2026-05-26 15:44:44.105931 | PLAY [all]
2026-05-26 15:44:44.112525 | 
2026-05-26 15:44:44.112590 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-05-26 15:44:44.551250 | instance | changed
2026-05-26 15:44:44.557750 | 
2026-05-26 15:44:44.557830 | PLAY RECAP
2026-05-26 15:44:44.557877 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-05-26 15:44:44.557901 | 
2026-05-26 15:44:44.748650 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-05-26 15:44:44.754404 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-05-26 15:44:45.444643 | 
2026-05-26 15:44:45.444871 | PLAY [localhost]
2026-05-26 15:44:45.456633 | 
2026-05-26 15:44:45.456814 | TASK [Generate Zuul manifest]
2026-05-26 15:44:45.481238 | localhost | ok
2026-05-26 15:44:45.502921 | 
2026-05-26 15:44:45.503053 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-05-26 15:44:45.915316 | localhost | changed
2026-05-26 15:44:45.935086 | 
2026-05-26 15:44:45.935253 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-05-26 15:44:45.970336 | localhost | ok
2026-05-26 15:44:45.978841 | 
2026-05-26 15:44:45.978933 | TASK [Upload logs]
2026-05-26 15:44:46.001421 | localhost | ok
2026-05-26 15:44:46.063392 | 
2026-05-26 15:44:46.063533 | TASK [Set zuul-log-path fact]
2026-05-26 15:44:46.083352 | localhost | ok
2026-05-26 15:44:46.098037 | 
2026-05-26 15:44:46.098149 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-05-26 15:44:46.131607 | localhost | ok
2026-05-26 15:44:46.144470 | 
2026-05-26 15:44:46.144630 | TASK [upload-logs : Create log directories]
2026-05-26 15:44:46.616666 | localhost | changed
2026-05-26 15:44:46.624079 | 
2026-05-26 15:44:46.624260 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-05-26 15:44:47.122068 | localhost -> localhost | ok: Runtime: 0:00:00.007126
2026-05-26 15:44:47.129388 | 
2026-05-26 15:44:47.129493 | TASK [upload-logs : Upload logs to log server]
2026-05-26 15:44:47.643665 | localhost | Output suppressed because no_log was given
2026-05-26 15:44:47.725375 | 
2026-05-26 15:44:47.726558 | LOOP [upload-logs : Compress console log and json output]
2026-05-26 15:44:47.775128 | localhost | skipping: Conditional result was False
2026-05-26 15:44:47.783266 | localhost | skipping: Conditional result was False
2026-05-26 15:44:47.790784 | 
2026-05-26 15:44:47.791033 | LOOP [upload-logs : Upload compressed console log and json output]
2026-05-26 15:44:47.839631 | localhost | skipping: Conditional result was False
2026-05-26 15:44:47.839912 | 
2026-05-26 15:44:47.846454 | localhost | skipping: Conditional result was False
2026-05-26 15:44:47.856676 | 
2026-05-26 15:44:47.856876 | LOOP [upload-logs : Upload console log and json output]