2026-05-26 15:41:55.888202 | Job console starting 2026-05-26 15:41:55.898583 | Updating git repos 2026-05-26 15:42:01.292718 | Cloning repos into workspace 2026-05-26 15:43:05.697021 | Restoring repo states 2026-05-26 15:43:17.636781 | Merging changes 2026-05-26 15:43:21.687275 | Checking out repos 2026-05-26 15:43:31.397379 | Preparing playbooks 2026-05-26 15:43:59.504146 | Running Ansible setup 2026-05-26 15:44:04.614568 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-05-26 15:44:05.265334 | 2026-05-26 15:44:05.265904 | PLAY [localhost] 2026-05-26 15:44:05.274546 | 2026-05-26 15:44:05.274673 | TASK [Gathering Facts] 2026-05-26 15:44:06.923567 | localhost | ok 2026-05-26 15:44:06.934592 | 2026-05-26 15:44:06.934733 | TASK [Setup log path fact] 2026-05-26 15:44:06.966764 | localhost | ok 2026-05-26 15:44:06.983728 | 2026-05-26 15:44:06.983916 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-26 15:44:07.026294 | localhost | ok 2026-05-26 15:44:07.036153 | 2026-05-26 15:44:07.036311 | TASK [emit-job-header : Print job information] 2026-05-26 15:44:07.080287 | # Job Information 2026-05-26 15:44:07.080576 | Ansible Version: 2.16.18 2026-05-26 15:44:07.080647 | Job: atmosphere-molecule-csi-local-path-provisioner 2026-05-26 15:44:07.080697 | Pipeline: check 2026-05-26 15:44:07.080741 | Executor: 2d72f0692154 2026-05-26 15:44:07.080785 | Triggered by: https://github.com/vexxhost/atmosphere/pull/3995 2026-05-26 15:44:07.080831 | Event ID: 47f885c0-5919-11f1-801d-9695ea07e128 2026-05-26 15:44:07.086973 | 2026-05-26 15:44:07.087116 | LOOP [emit-job-header : Print node information] 2026-05-26 15:44:07.191708 | localhost | ok: 2026-05-26 15:44:07.191933 | localhost | # Node Information 2026-05-26 15:44:07.191975 | localhost | Inventory Hostname: instance 2026-05-26 15:44:07.192006 | localhost | Hostname: np0000176673 2026-05-26 15:44:07.192033 | localhost | Username: zuul 2026-05-26 15:44:07.192065 | localhost | Distro: Ubuntu 22.04 2026-05-26 15:44:07.192096 | localhost | Provider: yul1 2026-05-26 15:44:07.192128 | localhost | Region: ca-ymq-1 2026-05-26 15:44:07.192157 | localhost | Label: ubuntu-jammy 2026-05-26 15:44:07.192187 | localhost | Product Name: OpenStack Nova 2026-05-26 15:44:07.192216 | localhost | Interface IP: 199.19.213.5 2026-05-26 15:44:07.205015 | 2026-05-26 15:44:07.205203 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-05-26 15:44:07.676944 | localhost -> localhost | changed 2026-05-26 15:44:07.682395 | 2026-05-26 15:44:07.682491 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-05-26 15:44:08.577795 | localhost -> localhost | changed 2026-05-26 15:44:08.588597 | 2026-05-26 15:44:08.588764 | PLAY [all] 2026-05-26 15:44:08.600457 | 2026-05-26 15:44:08.600634 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-05-26 15:44:08.859789 | instance -> localhost | ok 2026-05-26 15:44:08.870990 | 2026-05-26 15:44:08.871058 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-05-26 15:44:08.905536 | instance | ok 2026-05-26 15:44:08.919539 | instance | included: /var/lib/zuul/builds/656e9af4a88043459291e16093fd1e09/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-05-26 15:44:08.925489 | 2026-05-26 15:44:08.925574 | TASK [add-build-sshkey : Create Temp SSH key] 2026-05-26 15:44:10.928463 | instance -> localhost | Generating public/private rsa key pair. 2026-05-26 15:44:10.928638 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/656e9af4a88043459291e16093fd1e09/work/656e9af4a88043459291e16093fd1e09_id_rsa 2026-05-26 15:44:10.928668 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/656e9af4a88043459291e16093fd1e09/work/656e9af4a88043459291e16093fd1e09_id_rsa.pub 2026-05-26 15:44:10.928692 | instance -> localhost | The key fingerprint is: 2026-05-26 15:44:10.928714 | instance -> localhost | SHA256:di5/DlLGbEPyFH41Oiytns5TNSnvcKjHN4ENPy+7WwE zuul-build-sshkey 2026-05-26 15:44:10.928747 | instance -> localhost | The key's randomart image is: 2026-05-26 15:44:10.928769 | instance -> localhost | +---[RSA 3072]----+ 2026-05-26 15:44:10.928794 | instance -> localhost | | . o | 2026-05-26 15:44:10.928817 | instance -> localhost | | . + o . | 2026-05-26 15:44:10.928838 | instance -> localhost | | . = *E . | 2026-05-26 15:44:10.928859 | instance -> localhost | | B +o.= | 2026-05-26 15:44:10.928879 | instance -> localhost | | S @ X o | 2026-05-26 15:44:10.928899 | instance -> localhost | | . B o= B .| 2026-05-26 15:44:10.928919 | instance -> localhost | | o =+ + +.| 2026-05-26 15:44:10.928938 | instance -> localhost | | *o.+ =..| 2026-05-26 15:44:10.928976 | instance -> localhost | | +=..+* | 2026-05-26 15:44:10.928999 | instance -> localhost | +----[SHA256]-----+ 2026-05-26 15:44:10.929049 | instance -> localhost | ok: Runtime: 0:00:01.530340 2026-05-26 15:44:10.935198 | 2026-05-26 15:44:10.935280 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-05-26 15:44:10.964362 | instance | ok 2026-05-26 15:44:10.972757 | instance | included: /var/lib/zuul/builds/656e9af4a88043459291e16093fd1e09/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-05-26 15:44:11.020019 | 2026-05-26 15:44:11.020181 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-05-26 15:44:11.047390 | instance | skipping: Conditional result was False 2026-05-26 15:44:11.057616 | 2026-05-26 15:44:11.057757 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-05-26 15:44:11.548574 | instance | changed 2026-05-26 15:44:11.570619 | 2026-05-26 15:44:11.570923 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-05-26 15:44:11.760586 | instance | ok 2026-05-26 15:44:11.765808 | 2026-05-26 15:44:11.765876 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-05-26 15:44:12.239664 | instance | changed 2026-05-26 15:44:12.245052 | 2026-05-26 15:44:12.245126 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-05-26 15:44:12.770889 | instance | changed 2026-05-26 15:44:12.779472 | 2026-05-26 15:44:12.779576 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-05-26 15:44:12.805342 | instance | skipping: Conditional result was False 2026-05-26 15:44:12.817770 | 2026-05-26 15:44:12.817907 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-05-26 15:44:13.243896 | instance -> localhost | changed 2026-05-26 15:44:13.254386 | 2026-05-26 15:44:13.254456 | TASK [add-build-sshkey : Add back temp key] 2026-05-26 15:44:13.609143 | instance -> localhost | Identity added: /var/lib/zuul/builds/656e9af4a88043459291e16093fd1e09/work/656e9af4a88043459291e16093fd1e09_id_rsa (zuul-build-sshkey) 2026-05-26 15:44:13.609391 | instance -> localhost | ok: Runtime: 0:00:00.014149 2026-05-26 15:44:13.616739 | 2026-05-26 15:44:13.616834 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-05-26 15:44:13.966376 | instance | ok 2026-05-26 15:44:13.973220 | 2026-05-26 15:44:13.973366 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-05-26 15:44:13.998757 | instance | skipping: Conditional result was False 2026-05-26 15:44:14.015407 | 2026-05-26 15:44:14.015554 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-05-26 15:44:14.367550 | instance | ok 2026-05-26 15:44:14.375185 | 2026-05-26 15:44:14.375277 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-05-26 15:44:35.264050 | instance | Output suppressed because no_log was given 2026-05-26 15:44:35.287298 | 2026-05-26 15:44:35.287549 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-05-26 15:44:35.470187 | instance | ok: "logs" 2026-05-26 15:44:35.470480 | instance | ok: All items complete 2026-05-26 15:44:35.470513 | 2026-05-26 15:44:35.615100 | instance | ok: "artifacts" 2026-05-26 15:44:35.764355 | instance | ok: "docs" 2026-05-26 15:44:35.775561 | 2026-05-26 15:44:35.775752 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-05-26 15:44:35.961104 | instance | changed: "logs" 2026-05-26 15:44:36.118799 | instance | changed: "artifacts" 2026-05-26 15:44:36.271699 | instance | changed: "docs" 2026-05-26 15:44:36.301250 | 2026-05-26 15:44:36.301420 | PLAY RECAP 2026-05-26 15:44:36.301481 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-05-26 15:44:36.301512 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-05-26 15:44:36.301536 | 2026-05-26 15:44:36.486802 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-05-26 15:44:36.490887 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-05-26 15:44:37.102793 | 2026-05-26 15:44:37.102997 | PLAY [all] 2026-05-26 15:44:37.115252 | 2026-05-26 15:44:37.115420 | TASK [setup-uv : Extract archive] 2026-05-26 15:44:39.837257 | instance | ERROR 2026-05-26 15:44:39.837562 | instance | { 2026-05-26 15:44:39.837621 | instance | "msg": "Invalid archive '/home/zuul/.ansible/tmp/ansible-tmp-1779810277.157386-5-163199470096930/uv-x86_64-unknown-linux-gnu3_0lrfap.tar.gz', the file is 0 bytes" 2026-05-26 15:44:39.837665 | instance | } failure 2026-05-26 15:44:39.839212 | 2026-05-26 15:44:39.839272 | PLAY RECAP 2026-05-26 15:44:39.839328 | instance | ok: 0 changed: 0 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2026-05-26 15:44:39.839362 | 2026-05-26 15:44:39.969688 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-05-26 15:44:39.973462 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-05-26 15:44:40.602082 | 2026-05-26 15:44:40.602243 | PLAY [all] 2026-05-26 15:44:40.614461 | 2026-05-26 15:44:40.614550 | TASK [fetch-output : Set log path for multiple nodes] 2026-05-26 15:44:40.670114 | instance | skipping: Conditional result was False 2026-05-26 15:44:40.679718 | 2026-05-26 15:44:40.679845 | TASK [fetch-output : Set log path for single node] 2026-05-26 15:44:40.725531 | instance | ok 2026-05-26 15:44:40.732030 | 2026-05-26 15:44:40.732164 | LOOP [fetch-output : Ensure local output dirs] 2026-05-26 15:44:41.154430 | instance -> localhost | ok: "/var/lib/zuul/builds/656e9af4a88043459291e16093fd1e09/work/logs" 2026-05-26 15:44:41.429398 | instance -> localhost | changed: "/var/lib/zuul/builds/656e9af4a88043459291e16093fd1e09/work/artifacts" 2026-05-26 15:44:41.650152 | instance -> localhost | changed: "/var/lib/zuul/builds/656e9af4a88043459291e16093fd1e09/work/docs" 2026-05-26 15:44:41.665703 | 2026-05-26 15:44:41.665885 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-05-26 15:44:42.339276 | instance | changed: .d..t...... ./ 2026-05-26 15:44:42.339556 | instance | changed: All items complete 2026-05-26 15:44:42.339596 | 2026-05-26 15:44:42.805045 | instance | changed: .d..t...... ./ 2026-05-26 15:44:43.264066 | instance | changed: .d..t...... ./ 2026-05-26 15:44:43.284912 | 2026-05-26 15:44:43.285081 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-05-26 15:44:43.807606 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.007233 2026-05-26 15:44:44.049457 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.007717 2026-05-26 15:44:44.063560 | 2026-05-26 15:44:44.063683 | PLAY [all] 2026-05-26 15:44:44.069604 | 2026-05-26 15:44:44.069691 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-05-26 15:44:44.533246 | instance | changed 2026-05-26 15:44:44.540711 | 2026-05-26 15:44:44.540844 | PLAY RECAP 2026-05-26 15:44:44.540896 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-05-26 15:44:44.540920 | 2026-05-26 15:44:44.717860 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-05-26 15:44:44.719957 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-05-26 15:44:45.433126 | 2026-05-26 15:44:45.433394 | PLAY [localhost] 2026-05-26 15:44:45.446307 | 2026-05-26 15:44:45.446498 | TASK [Generate Zuul manifest] 2026-05-26 15:44:45.468924 | localhost | ok 2026-05-26 15:44:45.486890 | 2026-05-26 15:44:45.486989 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-05-26 15:44:45.926720 | localhost | changed 2026-05-26 15:44:45.938660 | 2026-05-26 15:44:45.938843 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-05-26 15:44:45.971389 | localhost | ok 2026-05-26 15:44:45.981086 | 2026-05-26 15:44:45.981213 | TASK [Upload logs] 2026-05-26 15:44:46.005594 | localhost | ok 2026-05-26 15:44:46.068065 | 2026-05-26 15:44:46.068219 | TASK [Set zuul-log-path fact] 2026-05-26 15:44:46.091748 | localhost | ok 2026-05-26 15:44:46.106818 | 2026-05-26 15:44:46.106968 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-26 15:44:46.140846 | localhost | ok 2026-05-26 15:44:46.150497 | 2026-05-26 15:44:46.150608 | TASK [upload-logs : Create log directories] 2026-05-26 15:44:46.610228 | localhost | changed 2026-05-26 15:44:46.618312 | 2026-05-26 15:44:46.618425 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-05-26 15:44:47.111656 | localhost -> localhost | ok: Runtime: 0:00:00.007244 2026-05-26 15:44:47.122642 | 2026-05-26 15:44:47.122824 | TASK [upload-logs : Upload logs to log server] 2026-05-26 15:44:47.711482 | localhost | Output suppressed because no_log was given 2026-05-26 15:44:47.761524 | 2026-05-26 15:44:47.761675 | LOOP [upload-logs : Compress console log and json output] 2026-05-26 15:44:47.812686 | localhost | skipping: Conditional result was False 2026-05-26 15:44:47.821293 | localhost | skipping: Conditional result was False 2026-05-26 15:44:47.836496 | 2026-05-26 15:44:47.836680 | LOOP [upload-logs : Upload compressed console log and json output] 2026-05-26 15:44:47.877985 | localhost | skipping: Conditional result was False 2026-05-26 15:44:47.878448 | 2026-05-26 15:44:47.881088 | localhost | skipping: Conditional result was False 2026-05-26 15:44:47.891501 | 2026-05-26 15:44:47.891671 | LOOP [upload-logs : Upload console log and json output]