2026-05-19 16:17:39.012023 | Job console starting
2026-05-19 16:17:39.031020 | Updating git repos
2026-05-19 16:17:39.135703 | Cloning repos into workspace
2026-05-19 16:17:43.333467 | Restoring repo states
2026-05-19 16:17:43.366926 | Merging changes
2026-05-19 16:17:45.987509 | Checking out repos
2026-05-19 16:17:47.191481 | Preparing playbooks
2026-05-19 16:18:06.334320 | Running Ansible setup
2026-05-19 16:18:10.451919 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-05-19 16:18:11.110001 | 
2026-05-19 16:18:11.110129 | PLAY [localhost]
2026-05-19 16:18:11.117718 | 
2026-05-19 16:18:11.117788 | TASK [Gathering Facts]
2026-05-19 16:18:12.428568 | localhost | ok
2026-05-19 16:18:12.438890 | 
2026-05-19 16:18:12.439002 | TASK [Setup log path fact]
2026-05-19 16:18:12.456710 | localhost | ok
2026-05-19 16:18:12.471820 | 
2026-05-19 16:18:12.471906 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-05-19 16:18:12.502834 | localhost | ok
2026-05-19 16:18:12.511661 | 
2026-05-19 16:18:12.511760 | TASK [emit-job-header : Print job information]
2026-05-19 16:18:12.552597 | # Job Information
2026-05-19 16:18:12.552782 | Ansible Version: 2.16.18
2026-05-19 16:18:12.552826 | Job: atmosphere-molecule-aio-openvswitch
2026-05-19 16:18:12.552856 | Pipeline: check
2026-05-19 16:18:12.552884 | Executor: 2d72f0692154
2026-05-19 16:18:12.552911 | Triggered by: https://github.com/vexxhost/atmosphere/pull/3945
2026-05-19 16:18:12.552944 | Event ID: 1d651ce0-539e-11f1-8754-a319db16026e
2026-05-19 16:18:12.557711 | 
2026-05-19 16:18:12.557826 | LOOP [emit-job-header : Print node information]
2026-05-19 16:18:12.664344 | localhost | ok:
2026-05-19 16:18:12.664527 | localhost | # Node Information
2026-05-19 16:18:12.664569 | localhost | Inventory Hostname: instance
2026-05-19 16:18:12.664604 | localhost | Hostname: np0000174867
2026-05-19 16:18:12.664635 | localhost | Username: zuul
2026-05-19 16:18:12.664670 | localhost | Distro: Ubuntu 22.04
2026-05-19 16:18:12.664702 | localhost | Provider: yul1
2026-05-19 16:18:12.664732 | localhost | Region: ca-ymq-1
2026-05-19 16:18:12.664762 | localhost | Label: ubuntu-jammy-16
2026-05-19 16:18:12.664791 | localhost | Product Name: OpenStack Nova
2026-05-19 16:18:12.664821 | localhost | Interface IP: 199.204.45.250
2026-05-19 16:18:12.674137 | 
2026-05-19 16:18:12.674248 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-05-19 16:18:13.301528 | localhost -> localhost | changed
2026-05-19 16:18:13.308654 | 
2026-05-19 16:18:13.308782 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-05-19 16:18:14.192139 | localhost -> localhost | changed
2026-05-19 16:18:14.205974 | 
2026-05-19 16:18:14.206174 | PLAY [all]
2026-05-19 16:18:14.220106 | 
2026-05-19 16:18:14.220208 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-05-19 16:18:14.448461 | instance -> localhost | ok
2026-05-19 16:18:14.456651 | 
2026-05-19 16:18:14.456814 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-05-19 16:18:14.489956 | instance | ok
2026-05-19 16:18:14.504148 | instance | included: /var/lib/zuul/builds/47f80cf6d8bd463e9fedfdd0eb983714/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-05-19 16:18:14.524059 | 
2026-05-19 16:18:14.524151 | TASK [add-build-sshkey : Create Temp SSH key]
2026-05-19 16:18:15.291594 | instance -> localhost | Generating public/private rsa key pair.
2026-05-19 16:18:15.291780 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/47f80cf6d8bd463e9fedfdd0eb983714/work/47f80cf6d8bd463e9fedfdd0eb983714_id_rsa
2026-05-19 16:18:15.291820 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/47f80cf6d8bd463e9fedfdd0eb983714/work/47f80cf6d8bd463e9fedfdd0eb983714_id_rsa.pub
2026-05-19 16:18:15.291846 | instance -> localhost | The key fingerprint is:
2026-05-19 16:18:15.291868 | instance -> localhost | SHA256:SgYF37epcUtbI7vyZeNBidBBbkdTK5nXTvy1mrxfI/g zuul-build-sshkey
2026-05-19 16:18:15.291904 | instance -> localhost | The key's randomart image is:
2026-05-19 16:18:15.291926 | instance -> localhost | +---[RSA 3072]----+
2026-05-19 16:18:15.291955 | instance -> localhost | |    ...  .o o..  |
2026-05-19 16:18:15.291982 | instance -> localhost | |     o . o o + + |
2026-05-19 16:18:15.292006 | instance -> localhost | |    . . o = = o =|
2026-05-19 16:18:15.292032 | instance -> localhost | |     .   + = + o+|
2026-05-19 16:18:15.292058 | instance -> localhost | |      o S B =  .o|
2026-05-19 16:18:15.292085 | instance -> localhost | |     o . = Bo.o  |
2026-05-19 16:18:15.292108 | instance -> localhost | |      . . +.== ..|
2026-05-19 16:18:15.292128 | instance -> localhost | |        .  =.oo o|
2026-05-19 16:18:15.292150 | instance -> localhost | |         oo .E.. |
2026-05-19 16:18:15.292169 | instance -> localhost | +----[SHA256]-----+
2026-05-19 16:18:15.292234 | instance -> localhost | ok: Runtime: 0:00:00.344614
2026-05-19 16:18:15.298475 | 
2026-05-19 16:18:15.298564 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-05-19 16:18:15.331276 | instance | ok
2026-05-19 16:18:15.342818 | instance | included: /var/lib/zuul/builds/47f80cf6d8bd463e9fedfdd0eb983714/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-05-19 16:18:15.352336 | 
2026-05-19 16:18:15.352428 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-05-19 16:18:15.376167 | instance | skipping: Conditional result was False
2026-05-19 16:18:15.384605 | 
2026-05-19 16:18:15.384741 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-05-19 16:18:15.919153 | instance | changed
2026-05-19 16:18:15.925424 | 
2026-05-19 16:18:15.925490 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-05-19 16:18:16.087638 | instance | ok
2026-05-19 16:18:16.128075 | 
2026-05-19 16:18:16.128207 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-05-19 16:18:16.604626 | instance | changed
2026-05-19 16:18:16.612339 | 
2026-05-19 16:18:16.612434 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-05-19 16:18:17.051965 | instance | changed
2026-05-19 16:18:17.057584 | 
2026-05-19 16:18:17.057885 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-05-19 16:18:17.081653 | instance | skipping: Conditional result was False
2026-05-19 16:18:17.090784 | 
2026-05-19 16:18:17.090904 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-05-19 16:18:17.421299 | instance -> localhost | changed
2026-05-19 16:18:17.438984 | 
2026-05-19 16:18:17.439134 | TASK [add-build-sshkey : Add back temp key]
2026-05-19 16:18:17.705861 | instance -> localhost | Identity added: /var/lib/zuul/builds/47f80cf6d8bd463e9fedfdd0eb983714/work/47f80cf6d8bd463e9fedfdd0eb983714_id_rsa (zuul-build-sshkey)
2026-05-19 16:18:17.706234 | instance -> localhost | ok: Runtime: 0:00:00.010982
2026-05-19 16:18:17.714255 | 
2026-05-19 16:18:17.714457 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-05-19 16:18:17.992920 | instance | ok
2026-05-19 16:18:18.978151 | 
2026-05-19 16:18:18.978324 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-05-19 16:18:19.003418 | instance | skipping: Conditional result was False
2026-05-19 16:18:19.022178 | 
2026-05-19 16:18:19.022290 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-05-19 16:18:19.296929 | instance | ok
2026-05-19 16:18:19.305342 | 
2026-05-19 16:18:19.305418 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-05-19 16:18:21.326247 | instance | Output suppressed because no_log was given
2026-05-19 16:18:21.340080 | 
2026-05-19 16:18:21.340209 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-05-19 16:18:21.534212 | instance | ok: "logs"
2026-05-19 16:18:21.534579 | instance | ok: All items complete
2026-05-19 16:18:21.534615 | 
2026-05-19 16:18:21.694381 | instance | ok: "artifacts"
2026-05-19 16:18:21.849262 | instance | ok: "docs"
2026-05-19 16:18:21.870492 | 
2026-05-19 16:18:21.870879 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-05-19 16:18:22.058020 | instance | changed: "logs"
2026-05-19 16:18:22.203135 | instance | changed: "artifacts"
2026-05-19 16:18:22.367294 | instance | changed: "docs"
2026-05-19 16:18:23.616448 | 
2026-05-19 16:18:23.616567 | PLAY RECAP
2026-05-19 16:18:23.616632 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-05-19 16:18:23.616676 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-05-19 16:18:23.616712 | 
2026-05-19 16:18:23.753125 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-05-19 16:18:23.763433 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-05-19 16:18:24.453560 | 
2026-05-19 16:18:24.453721 | PLAY [all]
2026-05-19 16:18:24.465284 | 
2026-05-19 16:18:24.465390 | TASK [setup-uv : Extract archive]
2026-05-19 16:18:26.697144 | instance | changed
2026-05-19 16:18:26.704560 | 
2026-05-19 16:18:26.704641 | TASK [setup-uv : Print version]
2026-05-19 16:18:27.043834 | instance | uv 0.8.13
2026-05-19 16:18:27.551090 | instance | ok: Runtime: 0:00:00.012349
2026-05-19 16:18:27.561845 | 
2026-05-19 16:18:27.561958 | TASK [Install Ansible collection siblings]
2026-05-19 16:18:27.643562 | instance | ERROR
2026-05-19 16:18:27.643804 | instance | {
2026-05-19 16:18:27.643848 | instance |   "msg": "Unexpected templating type error occurred on ({{ (zuul.build_refs | default(zuul.buildset_refs | default([])))\n   + (zuul.items | default([])) }}): can only concatenate list (not \"builtin_function_or_method\") to list. can only concatenate list (not \"builtin_function_or_method\") to list"
2026-05-19 16:18:27.643882 | instance | }
failure
2026-05-19 16:18:27.646695 | 
2026-05-19 16:18:27.646767 | PLAY RECAP
2026-05-19 16:18:27.646827 | instance | ok: 2 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-05-19 16:18:27.646861 | 
2026-05-19 16:18:27.775672 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-05-19 16:18:27.790678 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-05-19 16:18:28.447996 | 
2026-05-19 16:18:28.448520 | PLAY [all]
2026-05-19 16:18:28.461020 | 
2026-05-19 16:18:28.461167 | TASK [fetch-output : Set log path for multiple nodes]
2026-05-19 16:18:28.515657 | instance | skipping: Conditional result was False
2026-05-19 16:18:28.523277 | 
2026-05-19 16:18:28.523422 | TASK [fetch-output : Set log path for single node]
2026-05-19 16:18:28.569800 | instance | ok
2026-05-19 16:18:28.578666 | 
2026-05-19 16:18:28.578832 | LOOP [fetch-output : Ensure local output dirs]
2026-05-19 16:18:28.970390 | instance -> localhost | ok: "/var/lib/zuul/builds/47f80cf6d8bd463e9fedfdd0eb983714/work/logs"
2026-05-19 16:18:29.188651 | instance -> localhost | changed: "/var/lib/zuul/builds/47f80cf6d8bd463e9fedfdd0eb983714/work/artifacts"
2026-05-19 16:18:29.404761 | instance -> localhost | changed: "/var/lib/zuul/builds/47f80cf6d8bd463e9fedfdd0eb983714/work/docs"
2026-05-19 16:18:29.424677 | 
2026-05-19 16:18:29.424827 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-05-19 16:18:30.041828 | instance | changed: .d..t...... ./
2026-05-19 16:18:30.042007 | instance | changed: All items complete
2026-05-19 16:18:30.042035 | 
2026-05-19 16:18:30.491252 | instance | changed: .d..t...... ./
2026-05-19 16:18:30.968023 | instance | changed: .d..t...... ./
2026-05-19 16:18:30.993937 | 
2026-05-19 16:18:30.994059 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-05-19 16:18:31.443233 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.007678
2026-05-19 16:18:32.285895 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.006186
2026-05-19 16:18:32.298921 | 
2026-05-19 16:18:32.299025 | PLAY [all]
2026-05-19 16:18:32.305897 | 
2026-05-19 16:18:32.306014 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-05-19 16:18:32.747975 | instance | changed
2026-05-19 16:18:32.755269 | 
2026-05-19 16:18:32.755336 | PLAY RECAP
2026-05-19 16:18:32.755383 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-05-19 16:18:32.755406 | 
2026-05-19 16:18:32.876654 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-05-19 16:18:32.896923 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-05-19 16:18:33.606408 | 
2026-05-19 16:18:33.620313 | PLAY [localhost]
2026-05-19 16:18:33.640928 | 
2026-05-19 16:18:33.641222 | TASK [Generate Zuul manifest]
2026-05-19 16:18:33.662487 | localhost | ok
2026-05-19 16:18:33.678289 | 
2026-05-19 16:18:33.678419 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-05-19 16:18:34.107930 | localhost | changed
2026-05-19 16:18:34.121938 | 
2026-05-19 16:18:34.122071 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-05-19 16:18:34.180939 | localhost | ok
2026-05-19 16:18:34.188165 | 
2026-05-19 16:18:34.188290 | TASK [Upload logs]
2026-05-19 16:18:34.205778 | localhost | ok
2026-05-19 16:18:34.312506 | 
2026-05-19 16:18:34.312631 | TASK [Set zuul-log-path fact]
2026-05-19 16:18:34.332474 | localhost | ok
2026-05-19 16:18:34.345912 | 
2026-05-19 16:18:34.346019 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-05-19 16:18:34.376231 | localhost | ok
2026-05-19 16:18:34.385316 | 
2026-05-19 16:18:34.385394 | TASK [upload-logs : Create log directories]
2026-05-19 16:18:35.405204 | localhost | changed
2026-05-19 16:18:35.410676 | 
2026-05-19 16:18:35.410784 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-05-19 16:18:35.782063 | localhost -> localhost | ok: Runtime: 0:00:00.004150
2026-05-19 16:18:35.789630 | 
2026-05-19 16:18:35.789754 | TASK [upload-logs : Upload logs to log server]
2026-05-19 16:18:36.362075 | localhost | Output suppressed because no_log was given
2026-05-19 16:18:36.367283 | 
2026-05-19 16:18:36.367381 | LOOP [upload-logs : Compress console log and json output]
2026-05-19 16:18:36.422213 | localhost | skipping: Conditional result was False
2026-05-19 16:18:36.429889 | localhost | skipping: Conditional result was False
2026-05-19 16:18:36.442247 | 
2026-05-19 16:18:36.442362 | LOOP [upload-logs : Upload compressed console log and json output]
2026-05-19 16:18:36.491032 | localhost | skipping: Conditional result was False
2026-05-19 16:18:36.491296 | 
2026-05-19 16:18:36.497822 | localhost | skipping: Conditional result was False
2026-05-19 16:18:36.513827 | 
2026-05-19 16:18:36.513979 | LOOP [upload-logs : Upload console log and json output]