2026-10-03 22:00:28.653050 | Job console starting
2026-10-03 22:00:28.663988 | Updating git repos
2026-10-03 22:03:26.886992 | Cloning repos into workspace
2026-10-03 22:03:44.653423 | Restoring repo states
2026-10-03 22:03:48.437752 | Merging changes
2026-10-03 22:03:49.493182 | Checking out repos
2026-10-03 22:03:50.978782 | Preparing playbooks
2026-10-03 22:03:52.717362 | Running Ansible setup
2026-10-03 22:03:56.293373 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-10-03 22:03:56.955185 | 
2026-10-03 22:03:56.955349 | PLAY [localhost]
2026-10-03 22:03:56.962853 | 
2026-10-03 22:03:56.962918 | TASK [Gathering Facts]
2026-10-03 22:03:57.881748 | localhost | ok
2026-10-03 22:03:57.888915 | 
2026-10-03 22:03:57.888990 | TASK [Setup log path fact]
2026-10-03 22:03:57.906416 | localhost | ok
2026-10-03 22:03:57.918920 | 
2026-10-03 22:03:57.919021 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-10-03 22:03:57.948102 | localhost | ok
2026-10-03 22:03:57.955722 | 
2026-10-03 22:03:57.955819 | TASK [emit-job-header : Print job information]
2026-10-03 22:03:57.996698 | # Job Information
2026-10-03 22:03:57.996953 | Ansible Version: 2.16.18
2026-10-03 22:03:57.997020 | Job: atmosphere-molecule-aio-ovn
2026-10-03 22:03:57.997064 | Pipeline: check
2026-10-03 22:03:57.997104 | Executor: 2d72f0692154
2026-10-03 22:03:57.997141 | Triggered by: https://github.com/vexxhost/atmosphere/pull/4092
2026-10-03 22:03:57.997186 | Event ID: b324f010-bf74-11f1-8a59-fa9c9447b97b
2026-10-03 22:03:58.002791 | 
2026-10-03 22:03:58.002921 | LOOP [emit-job-header : Print node information]
2026-10-03 22:03:58.099256 | localhost | ok:
2026-10-03 22:03:58.100434 | localhost | # Node Information
2026-10-03 22:03:58.100652 | localhost | Inventory Hostname: instance
2026-10-03 22:03:58.100770 | localhost | Hostname: np0000194553
2026-10-03 22:03:58.100835 | localhost | Username: zuul
2026-10-03 22:03:58.100909 | localhost | Distro: Ubuntu 22.04
2026-10-03 22:03:58.100975 | localhost | Provider: yul1
2026-10-03 22:03:58.101039 | localhost | Region: ca-ymq-1
2026-10-03 22:03:58.101101 | localhost | Label: ubuntu-jammy-16
2026-10-03 22:03:58.101159 | localhost | Product Name: OpenStack Nova
2026-10-03 22:03:58.101217 | localhost | Interface IP: 162.253.55.153
2026-10-03 22:03:58.122196 | 
2026-10-03 22:03:58.122421 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-10-03 22:03:58.492321 | localhost -> localhost | changed
2026-10-03 22:03:58.502007 | 
2026-10-03 22:03:58.502150 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-10-03 22:03:59.483976 | localhost -> localhost | changed
2026-10-03 22:03:59.495111 | 
2026-10-03 22:03:59.495314 | PLAY [all]
2026-10-03 22:03:59.509699 | 
2026-10-03 22:03:59.509828 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-10-03 22:03:59.747130 | instance -> localhost | ok
2026-10-03 22:03:59.759333 | 
2026-10-03 22:03:59.759482 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-10-03 22:03:59.792697 | instance | ok
2026-10-03 22:03:59.817206 | instance | included: /var/lib/zuul/builds/e1fe661842574ff4ba009dd55145fc5a/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-10-03 22:03:59.825021 | 
2026-10-03 22:03:59.825101 | TASK [add-build-sshkey : Create Temp SSH key]
2026-10-03 22:04:01.281616 | instance -> localhost | Generating public/private rsa key pair.
2026-10-03 22:04:01.281875 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/e1fe661842574ff4ba009dd55145fc5a/work/e1fe661842574ff4ba009dd55145fc5a_id_rsa
2026-10-03 22:04:01.281933 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/e1fe661842574ff4ba009dd55145fc5a/work/e1fe661842574ff4ba009dd55145fc5a_id_rsa.pub
2026-10-03 22:04:01.281975 | instance -> localhost | The key fingerprint is:
2026-10-03 22:04:01.282015 | instance -> localhost | SHA256:8Nkjp0QPZyp1THkts20mrD3vTHPav+xkIXS/N4fi7jE zuul-build-sshkey
2026-10-03 22:04:01.282069 | instance -> localhost | The key's randomart image is:
2026-10-03 22:04:01.282109 | instance -> localhost | +---[RSA 3072]----+
2026-10-03 22:04:01.282155 | instance -> localhost | |          .. .   |
2026-10-03 22:04:01.282197 | instance -> localhost | |         o. + .  |
2026-10-03 22:04:01.282235 | instance -> localhost | |      . + =o * . |
2026-10-03 22:04:01.282301 | instance -> localhost | |       = X  = = .|
2026-10-03 22:04:01.282342 | instance -> localhost | |      . S =o = ..|
2026-10-03 22:04:01.282380 | instance -> localhost | |       o +..o ..o|
2026-10-03 22:04:01.282416 | instance -> localhost | |        .   Eo+=+|
2026-10-03 22:04:01.282452 | instance -> localhost | |           . *==+|
2026-10-03 22:04:01.282493 | instance -> localhost | |           o+.=+=|
2026-10-03 22:04:01.282532 | instance -> localhost | +----[SHA256]-----+
2026-10-03 22:04:01.282626 | instance -> localhost | ok: Runtime: 0:00:00.998127
2026-10-03 22:04:01.293987 | 
2026-10-03 22:04:01.294111 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-10-03 22:04:01.327746 | instance | ok
2026-10-03 22:04:01.344053 | instance | included: /var/lib/zuul/builds/e1fe661842574ff4ba009dd55145fc5a/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-10-03 22:04:01.355066 | 
2026-10-03 22:04:01.355165 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-10-03 22:04:01.380316 | instance | skipping: Conditional result was False
2026-10-03 22:04:01.390527 | 
2026-10-03 22:04:01.390664 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-10-03 22:04:01.859636 | instance | changed
2026-10-03 22:04:01.870721 | 
2026-10-03 22:04:01.870852 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-10-03 22:04:02.107049 | instance | ok
2026-10-03 22:04:02.111566 | 
2026-10-03 22:04:02.111626 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-10-03 22:04:02.601057 | instance | changed
2026-10-03 22:04:02.611055 | 
2026-10-03 22:04:02.611191 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-10-03 22:04:03.104836 | instance | changed
2026-10-03 22:04:03.114377 | 
2026-10-03 22:04:03.114498 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-10-03 22:04:03.139634 | instance | skipping: Conditional result was False
2026-10-03 22:04:03.151723 | 
2026-10-03 22:04:03.151847 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-10-03 22:04:03.491193 | instance -> localhost | changed
2026-10-03 22:04:03.510811 | 
2026-10-03 22:04:03.510940 | TASK [add-build-sshkey : Add back temp key]
2026-10-03 22:04:03.850773 | instance -> localhost | Identity added: /var/lib/zuul/builds/e1fe661842574ff4ba009dd55145fc5a/work/e1fe661842574ff4ba009dd55145fc5a_id_rsa (zuul-build-sshkey)
2026-10-03 22:04:03.851048 | instance -> localhost | ok: Runtime: 0:00:00.015010
2026-10-03 22:04:03.860151 | 
2026-10-03 22:04:03.860290 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-10-03 22:04:04.145272 | instance | ok
2026-10-03 22:04:04.153333 | 
2026-10-03 22:04:04.153427 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-10-03 22:04:04.197394 | instance | skipping: Conditional result was False
2026-10-03 22:04:04.211557 | 
2026-10-03 22:04:04.211672 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-10-03 22:04:04.547346 | instance | ok
2026-10-03 22:04:04.553965 | 
2026-10-03 22:04:04.554041 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-10-03 22:04:07.999332 | instance | Output suppressed because no_log was given
2026-10-03 22:04:08.016116 | 
2026-10-03 22:04:08.016244 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-10-03 22:04:08.238483 | instance | ok: "logs"
2026-10-03 22:04:08.238858 | instance | ok: All items complete
2026-10-03 22:04:08.238928 | 
2026-10-03 22:04:08.381869 | instance | ok: "artifacts"
2026-10-03 22:04:08.520539 | instance | ok: "docs"
2026-10-03 22:04:08.538727 | 
2026-10-03 22:04:08.538929 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-10-03 22:04:08.724633 | instance | changed: "logs"
2026-10-03 22:04:08.866718 | instance | changed: "artifacts"
2026-10-03 22:04:09.004032 | instance | changed: "docs"
2026-10-03 22:04:09.024073 | 
2026-10-03 22:04:09.024217 | PLAY RECAP
2026-10-03 22:04:09.024335 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-10-03 22:04:09.024391 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-10-03 22:04:09.024433 | 
2026-10-03 22:04:09.162332 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-10-03 22:04:09.175329 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-10-03 22:04:09.773447 | 
2026-10-03 22:04:09.773561 | PLAY [all]
2026-10-03 22:04:09.783272 | 
2026-10-03 22:04:09.783344 | TASK [setup-uv : Extract archive]
2026-10-03 22:04:18.068666 | instance | ERROR
2026-10-03 22:04:18.069090 | instance | {
2026-10-03 22:04:18.069176 | instance |   "msg": "Invalid archive '/home/zuul/.ansible/tmp/ansible-tmp-1791065049.8140686-5-75144093853107/uv-x86_64-unknown-linux-gnupnd88i6p.tar.gz', the file is 0 bytes"
2026-10-03 22:04:18.069271 | instance | }
failure
2026-10-03 22:04:18.071737 | 
2026-10-03 22:04:18.071831 | PLAY RECAP
2026-10-03 22:04:18.071919 | instance | ok: 0 changed: 0 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-10-03 22:04:18.071967 | 
2026-10-03 22:04:18.206453 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-10-03 22:04:18.225930 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-10-03 22:04:18.824833 | 
2026-10-03 22:04:18.824949 | PLAY [all]
2026-10-03 22:04:18.834676 | 
2026-10-03 22:04:18.834744 | TASK [fetch-output : Set log path for multiple nodes]
2026-10-03 22:04:18.888518 | instance | skipping: Conditional result was False
2026-10-03 22:04:18.894463 | 
2026-10-03 22:04:18.894540 | TASK [fetch-output : Set log path for single node]
2026-10-03 22:04:18.946043 | instance | ok
2026-10-03 22:04:18.955681 | 
2026-10-03 22:04:18.955816 | LOOP [fetch-output : Ensure local output dirs]
2026-10-03 22:04:19.350256 | instance -> localhost | ok: "/var/lib/zuul/builds/e1fe661842574ff4ba009dd55145fc5a/work/logs"
2026-10-03 22:04:19.580017 | instance -> localhost | changed: "/var/lib/zuul/builds/e1fe661842574ff4ba009dd55145fc5a/work/artifacts"
2026-10-03 22:04:19.821563 | instance -> localhost | changed: "/var/lib/zuul/builds/e1fe661842574ff4ba009dd55145fc5a/work/docs"
2026-10-03 22:04:19.844031 | 
2026-10-03 22:04:19.844224 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-10-03 22:04:20.492876 | instance | changed: .d..t...... ./
2026-10-03 22:04:20.493161 | instance | changed: All items complete
2026-10-03 22:04:20.493215 | 
2026-10-03 22:04:20.991664 | instance | changed: .d..t...... ./
2026-10-03 22:04:21.476537 | instance | changed: .d..t...... ./
2026-10-03 22:04:21.493012 | 
2026-10-03 22:04:21.493465 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-10-03 22:04:21.996351 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.007452
2026-10-03 22:04:22.272907 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.006493
2026-10-03 22:04:22.290142 | 
2026-10-03 22:04:22.290324 | PLAY [all]
2026-10-03 22:04:22.302637 | 
2026-10-03 22:04:22.302775 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-10-03 22:04:22.731664 | instance | changed
2026-10-03 22:04:22.743528 | 
2026-10-03 22:04:22.743643 | PLAY RECAP
2026-10-03 22:04:22.743724 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-10-03 22:04:22.743765 | 
2026-10-03 22:04:22.872774 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-10-03 22:04:22.882706 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-10-03 22:04:23.486955 | 
2026-10-03 22:04:23.487126 | PLAY [localhost]
2026-10-03 22:04:23.497167 | 
2026-10-03 22:04:23.497264 | TASK [Generate Zuul manifest]
2026-10-03 22:04:23.524569 | localhost | ok
2026-10-03 22:04:23.539464 | 
2026-10-03 22:04:23.539582 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-10-03 22:04:23.911009 | localhost | changed
2026-10-03 22:04:24.083828 | 
2026-10-03 22:04:24.084427 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-10-03 22:04:24.119703 | localhost | ok
2026-10-03 22:04:24.129729 | 
2026-10-03 22:04:24.129869 | TASK [Upload logs]
2026-10-03 22:04:24.149004 | localhost | ok
2026-10-03 22:04:24.335446 | 
2026-10-03 22:04:24.335601 | TASK [Set zuul-log-path fact]
2026-10-03 22:04:24.353571 | localhost | ok
2026-10-03 22:04:24.367732 | 
2026-10-03 22:04:24.367853 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-10-03 22:04:24.408381 | localhost | ok
2026-10-03 22:04:24.421352 | 
2026-10-03 22:04:24.421508 | TASK [upload-logs : Create log directories]
2026-10-03 22:04:24.821158 | localhost | changed
2026-10-03 22:04:24.829945 | 
2026-10-03 22:04:24.830093 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-10-03 22:04:25.301188 | localhost -> localhost | ok: Runtime: 0:00:00.005098
2026-10-03 22:04:25.312840 | 
2026-10-03 22:04:25.313099 | TASK [upload-logs : Upload logs to log server]
2026-10-03 22:04:25.751131 | localhost | Output suppressed because no_log was given
2026-10-03 22:04:25.757942 | 
2026-10-03 22:04:25.758073 | LOOP [upload-logs : Compress console log and json output]
2026-10-03 22:04:25.825818 | localhost | skipping: Conditional result was False
2026-10-03 22:04:25.835006 | localhost | skipping: Conditional result was False
2026-10-03 22:04:25.848438 | 
2026-10-03 22:04:25.848656 | LOOP [upload-logs : Upload compressed console log and json output]
2026-10-03 22:04:25.905113 | localhost | skipping: Conditional result was False
2026-10-03 22:04:25.905552 | 
2026-10-03 22:04:25.910437 | localhost | skipping: Conditional result was False
2026-10-03 22:04:25.924889 | 
2026-10-03 22:04:25.925056 | LOOP [upload-logs : Upload console log and json output]