2026-07-09 16:33:37.877380 | Job console starting
2026-07-09 16:33:37.892445 | Updating git repos
2026-07-09 16:33:41.988946 | Cloning repos into workspace
2026-07-09 16:34:00.435626 | Restoring repo states
2026-07-09 16:34:09.048985 | Merging changes
2026-07-09 16:34:10.655540 | Checking out repos
2026-07-09 16:34:15.932080 | Preparing playbooks
2026-07-09 16:34:24.739527 | Running Ansible setup
2026-07-09 16:34:29.256867 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-07-09 16:34:29.883468 | 
2026-07-09 16:34:35.036860 | PLAY [localhost]
2026-07-09 16:34:35.052400 | 
2026-07-09 16:34:35.052722 | TASK [Gathering Facts]
2026-07-09 16:34:39.802663 | localhost | ok
2026-07-09 16:34:39.813360 | 
2026-07-09 16:34:39.813510 | TASK [Setup log path fact]
2026-07-09 16:34:39.833435 | localhost | ok
2026-07-09 16:34:39.845117 | 
2026-07-09 16:34:39.845271 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-07-09 16:34:39.879728 | localhost | ok
2026-07-09 16:34:39.889756 | 
2026-07-09 16:34:39.889926 | TASK [emit-job-header : Print job information]
2026-07-09 16:34:39.931451 | # Job Information
2026-07-09 16:34:39.931707 | Ansible Version: 2.16.18
2026-07-09 16:34:39.931761 | Job: atmosphere-molecule-csi-local-path-provisioner
2026-07-09 16:34:39.931800 | Pipeline: check
2026-07-09 16:34:39.931832 | Executor: 2d72f0692154
2026-07-09 16:34:39.931862 | Triggered by: https://github.com/vexxhost/atmosphere/pull/4094
2026-07-09 16:34:39.931896 | Event ID: 52482be0-7bb3-11f1-92d2-fca0dc7625bd
2026-07-09 16:34:39.936543 | 
2026-07-09 16:34:39.936661 | LOOP [emit-job-header : Print node information]
2026-07-09 16:34:40.052919 | localhost | ok:
2026-07-09 16:34:40.053270 | localhost | # Node Information
2026-07-09 16:34:40.053334 | localhost | Inventory Hostname: instance
2026-07-09 16:34:40.053383 | localhost | Hostname: np0000184757
2026-07-09 16:34:40.053428 | localhost | Username: zuul
2026-07-09 16:34:40.053478 | localhost | Distro: Ubuntu 22.04
2026-07-09 16:34:40.053521 | localhost | Provider: yul1
2026-07-09 16:34:40.053565 | localhost | Region: ca-ymq-1
2026-07-09 16:34:40.053610 | localhost | Label: ubuntu-jammy
2026-07-09 16:34:40.053653 | localhost | Product Name: OpenStack Nova
2026-07-09 16:34:40.053694 | localhost | Interface IP: 199.19.213.119
2026-07-09 16:34:40.061244 | 
2026-07-09 16:34:40.061337 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-07-09 16:34:40.578032 | localhost -> localhost | changed
2026-07-09 16:34:40.588223 | 
2026-07-09 16:34:40.591454 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-07-09 16:34:41.897242 | localhost -> localhost | changed
2026-07-09 16:34:41.907106 | 
2026-07-09 16:34:41.907255 | PLAY [all]
2026-07-09 16:34:41.920732 | 
2026-07-09 16:34:41.920898 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-07-09 16:34:42.182900 | instance -> localhost | ok
2026-07-09 16:34:42.192696 | 
2026-07-09 16:34:42.193313 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-07-09 16:34:42.226641 | instance | ok
2026-07-09 16:34:42.254434 | instance | included: /var/lib/zuul/builds/eb88255f5d424d42a208f87027e4343d/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-07-09 16:34:42.261475 | 
2026-07-09 16:34:42.261543 | TASK [add-build-sshkey : Create Temp SSH key]
2026-07-09 16:34:44.433901 | instance -> localhost | Generating public/private rsa key pair.
2026-07-09 16:34:44.434251 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/eb88255f5d424d42a208f87027e4343d/work/eb88255f5d424d42a208f87027e4343d_id_rsa
2026-07-09 16:34:44.434285 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/eb88255f5d424d42a208f87027e4343d/work/eb88255f5d424d42a208f87027e4343d_id_rsa.pub
2026-07-09 16:34:44.434309 | instance -> localhost | The key fingerprint is:
2026-07-09 16:34:44.434331 | instance -> localhost | SHA256:ZQwLKPNLbcs2HoNTgk5Ano7HHOSol38aOGtN+/uDYMQ zuul-build-sshkey
2026-07-09 16:34:44.434363 | instance -> localhost | The key's randomart image is:
2026-07-09 16:34:44.434399 | instance -> localhost | +---[RSA 3072]----+
2026-07-09 16:34:44.434438 | instance -> localhost | |...  .. .        |
2026-07-09 16:34:44.434464 | instance -> localhost | |o++ .  . +       |
2026-07-09 16:34:44.434487 | instance -> localhost | |.+o* .  . +      |
2026-07-09 16:34:44.434508 | instance -> localhost | |+oooE +  o       |
2026-07-09 16:34:44.434529 | instance -> localhost | |o+*o B .S        |
2026-07-09 16:34:44.434550 | instance -> localhost | | o.oB B          |
2026-07-09 16:34:44.434570 | instance -> localhost | |  o+o*.=         |
2026-07-09 16:34:44.434590 | instance -> localhost | |  .oo+o .        |
2026-07-09 16:34:44.434613 | instance -> localhost | | .. ..oo..       |
2026-07-09 16:34:44.434634 | instance -> localhost | +----[SHA256]-----+
2026-07-09 16:34:44.434688 | instance -> localhost | ok: Runtime: 0:00:01.511016
2026-07-09 16:34:44.451806 | 
2026-07-09 16:34:44.451988 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-07-09 16:34:44.501508 | instance | ok
2026-07-09 16:34:44.522569 | instance | included: /var/lib/zuul/builds/eb88255f5d424d42a208f87027e4343d/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-07-09 16:34:44.532382 | 
2026-07-09 16:34:44.532452 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-07-09 16:34:44.558263 | instance | skipping: Conditional result was False
2026-07-09 16:34:44.573433 | 
2026-07-09 16:34:44.574464 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-07-09 16:34:45.039680 | instance | changed
2026-07-09 16:34:45.194551 | 
2026-07-09 16:34:45.194688 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-07-09 16:34:45.386703 | instance | ok
2026-07-09 16:34:45.393831 | 
2026-07-09 16:34:45.393954 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-07-09 16:34:45.863468 | instance | changed
2026-07-09 16:34:46.008753 | 
2026-07-09 16:34:46.008896 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-07-09 16:34:46.470952 | instance | changed
2026-07-09 16:34:46.479414 | 
2026-07-09 16:34:46.479495 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-07-09 16:34:46.505501 | instance | skipping: Conditional result was False
2026-07-09 16:34:46.786528 | 
2026-07-09 16:34:46.786671 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-07-09 16:34:47.216896 | instance -> localhost | changed
2026-07-09 16:34:47.231645 | 
2026-07-09 16:34:47.231774 | TASK [add-build-sshkey : Add back temp key]
2026-07-09 16:34:47.887222 | instance -> localhost | Identity added: /var/lib/zuul/builds/eb88255f5d424d42a208f87027e4343d/work/eb88255f5d424d42a208f87027e4343d_id_rsa (zuul-build-sshkey)
2026-07-09 16:34:47.887476 | instance -> localhost | ok: Runtime: 0:00:00.020204
2026-07-09 16:34:47.893675 | 
2026-07-09 16:34:47.893741 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-07-09 16:34:48.254576 | instance | ok
2026-07-09 16:34:48.261879 | 
2026-07-09 16:34:48.414943 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-07-09 16:34:48.442370 | instance | skipping: Conditional result was False
2026-07-09 16:34:48.460369 | 
2026-07-09 16:34:48.460537 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-07-09 16:34:48.828641 | instance | ok
2026-07-09 16:34:49.152689 | 
2026-07-09 16:34:49.152830 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-07-09 16:34:53.561736 | instance | Output suppressed because no_log was given
2026-07-09 16:34:53.570652 | 
2026-07-09 16:34:53.570754 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-07-09 16:34:53.760365 | instance | ok: "logs"
2026-07-09 16:34:53.760645 | instance | ok: All items complete
2026-07-09 16:34:53.760690 | 
2026-07-09 16:34:53.913076 | instance | ok: "artifacts"
2026-07-09 16:34:54.066917 | instance | ok: "docs"
2026-07-09 16:34:54.086333 | 
2026-07-09 16:34:54.086487 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-07-09 16:34:54.274191 | instance | changed: "logs"
2026-07-09 16:34:54.436918 | instance | changed: "artifacts"
2026-07-09 16:34:54.593705 | instance | changed: "docs"
2026-07-09 16:34:54.609447 | 
2026-07-09 16:34:54.609583 | PLAY RECAP
2026-07-09 16:34:54.609650 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-07-09 16:34:54.609692 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-07-09 16:34:54.609726 | 
2026-07-09 16:34:54.759775 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-07-09 16:34:54.763961 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-07-09 16:34:55.407948 | 
2026-07-09 16:34:55.408157 | PLAY [all]
2026-07-09 16:34:55.429343 | 
2026-07-09 16:34:55.429499 | TASK [setup-uv : Extract archive]
2026-07-09 16:34:56.371154 | instance | ERROR
2026-07-09 16:34:56.386370 | instance | {
2026-07-09 16:34:56.386437 | instance |   "msg": "Invalid archive '/home/zuul/.ansible/tmp/ansible-tmp-1783614895.478001-5-85823840626886/uv-x86_64-unknown-linux-gnu4qqm6r7w.tar.gz', the file is 0 bytes"
2026-07-09 16:34:56.386482 | instance | }
failure
2026-07-09 16:34:56.388099 | 
2026-07-09 16:34:56.388151 | PLAY RECAP
2026-07-09 16:34:56.388209 | instance | ok: 0 changed: 0 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-07-09 16:34:56.388243 | 
2026-07-09 16:34:56.569841 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-07-09 16:34:56.592477 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-07-09 16:34:57.235694 | 
2026-07-09 16:34:57.250370 | PLAY [all]
2026-07-09 16:34:57.266361 | 
2026-07-09 16:34:57.266437 | TASK [fetch-output : Set log path for multiple nodes]
2026-07-09 16:34:57.311370 | instance | skipping: Conditional result was False
2026-07-09 16:34:57.320996 | 
2026-07-09 16:34:57.364478 | TASK [fetch-output : Set log path for single node]
2026-07-09 16:34:57.409424 | instance | ok
2026-07-09 16:34:57.414391 | 
2026-07-09 16:34:57.414465 | LOOP [fetch-output : Ensure local output dirs]
2026-07-09 16:34:57.996893 | instance -> localhost | ok: "/var/lib/zuul/builds/eb88255f5d424d42a208f87027e4343d/work/logs"
2026-07-09 16:34:58.334248 | instance -> localhost | changed: "/var/lib/zuul/builds/eb88255f5d424d42a208f87027e4343d/work/artifacts"
2026-07-09 16:34:58.629439 | instance -> localhost | changed: "/var/lib/zuul/builds/eb88255f5d424d42a208f87027e4343d/work/docs"
2026-07-09 16:34:58.654110 | 
2026-07-09 16:34:58.654378 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-07-09 16:34:59.350986 | instance | ok
2026-07-09 16:34:59.486308 | instance | changed: All items complete
2026-07-09 16:34:59.486522 | 
2026-07-09 16:34:59.854571 | instance | changed: .d..t...... ./
2026-07-09 16:35:00.322521 | instance | changed: .d..t...... ./
2026-07-09 16:35:00.355345 | 
2026-07-09 16:35:00.355494 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-07-09 16:35:00.933953 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.007837
2026-07-09 16:35:01.173860 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.006131
2026-07-09 16:35:01.189347 | 
2026-07-09 16:35:01.189494 | PLAY [all]
2026-07-09 16:35:01.195901 | 
2026-07-09 16:35:01.195973 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-07-09 16:35:01.652386 | instance | changed
2026-07-09 16:35:01.658471 | 
2026-07-09 16:35:01.658530 | PLAY RECAP
2026-07-09 16:35:01.658574 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-07-09 16:35:01.658597 | 
2026-07-09 16:35:01.793096 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-07-09 16:35:01.795665 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-07-09 16:35:02.455526 | 
2026-07-09 16:35:02.455724 | PLAY [localhost]
2026-07-09 16:35:02.467595 | 
2026-07-09 16:35:02.467747 | TASK [Generate Zuul manifest]
2026-07-09 16:35:02.490335 | localhost | ok
2026-07-09 16:35:02.508693 | 
2026-07-09 16:35:02.508846 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-07-09 16:35:02.894882 | localhost | changed
2026-07-09 16:35:02.910773 | 
2026-07-09 16:35:02.910902 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-07-09 16:35:02.942525 | localhost | ok
2026-07-09 16:35:02.952164 | 
2026-07-09 16:35:03.318653 | TASK [Upload logs]
2026-07-09 16:35:03.355279 | localhost | ok
2026-07-09 16:35:03.411098 | 
2026-07-09 16:35:03.617536 | TASK [Set zuul-log-path fact]
2026-07-09 16:35:03.645764 | localhost | ok
2026-07-09 16:35:03.657681 | 
2026-07-09 16:35:03.657760 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-07-09 16:35:03.690910 | localhost | ok
2026-07-09 16:35:03.700979 | 
2026-07-09 16:35:03.701078 | TASK [upload-logs : Create log directories]
2026-07-09 16:35:04.301419 | localhost | changed
2026-07-09 16:35:04.310494 | 
2026-07-09 16:35:04.310589 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-07-09 16:35:05.290798 | localhost -> localhost | ok: Runtime: 0:00:00.105441
2026-07-09 16:35:05.340145 | 
2026-07-09 16:35:05.340898 | TASK [upload-logs : Upload logs to log server]
2026-07-09 16:35:06.074408 | localhost | Output suppressed because no_log was given
2026-07-09 16:35:06.128494 | 
2026-07-09 16:35:06.128635 | LOOP [upload-logs : Compress console log and json output]
2026-07-09 16:35:06.180882 | localhost | skipping: Conditional result was False
2026-07-09 16:35:06.190981 | localhost | skipping: Conditional result was False
2026-07-09 16:35:06.203755 | 
2026-07-09 16:35:06.203910 | LOOP [upload-logs : Upload compressed console log and json output]
2026-07-09 16:35:06.254588 | localhost | skipping: Conditional result was False
2026-07-09 16:35:06.255973 | 
2026-07-09 16:35:06.259378 | localhost | skipping: Conditional result was False
2026-07-09 16:35:06.269253 | 
2026-07-09 16:35:06.269432 | LOOP [upload-logs : Upload console log and json output]