2026-05-19 16:21:55.585831 | Job console starting
2026-05-19 16:21:55.597840 | Updating git repos
2026-05-19 16:21:55.728878 | Cloning repos into workspace
2026-05-19 16:21:57.431780 | Restoring repo states
2026-05-19 16:21:57.457413 | Merging changes
2026-05-19 16:21:59.278114 | Checking out repos
2026-05-19 16:21:59.843768 | Preparing playbooks
2026-05-19 16:22:03.218513 | Running Ansible setup
2026-05-19 16:22:06.824730 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-05-19 16:22:07.478818 | 
2026-05-19 16:22:08.933345 | PLAY [localhost]
2026-05-19 16:22:08.946235 | 
2026-05-19 16:22:08.946403 | TASK [Gathering Facts]
2026-05-19 16:22:09.924172 | localhost | ok
2026-05-19 16:22:09.934581 | 
2026-05-19 16:22:09.934701 | TASK [Setup log path fact]
2026-05-19 16:22:09.952918 | localhost | ok
2026-05-19 16:22:09.966536 | 
2026-05-19 16:22:09.966655 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-05-19 16:22:09.997883 | localhost | ok
2026-05-19 16:22:10.007550 | 
2026-05-19 16:22:10.007663 | TASK [emit-job-header : Print job information]
2026-05-19 16:22:10.048344 | # Job Information
2026-05-19 16:22:10.048583 | Ansible Version: 2.16.18
2026-05-19 16:22:10.048654 | Job: atmosphere-molecule-aio-openvswitch
2026-05-19 16:22:10.048705 | Pipeline: check
2026-05-19 16:22:10.048751 | Executor: 2d72f0692154
2026-05-19 16:22:10.048796 | Triggered by: https://github.com/vexxhost/atmosphere/pull/3945
2026-05-19 16:22:10.048847 | Event ID: 8635b180-539e-11f1-8f4a-8b53e1584e5c
2026-05-19 16:22:10.053819 | 
2026-05-19 16:22:10.053948 | LOOP [emit-job-header : Print node information]
2026-05-19 16:22:10.152305 | localhost | ok:
2026-05-19 16:22:10.152461 | localhost | # Node Information
2026-05-19 16:22:10.152491 | localhost | Inventory Hostname: instance
2026-05-19 16:22:10.152513 | localhost | Hostname: np0000174880
2026-05-19 16:22:10.152534 | localhost | Username: zuul
2026-05-19 16:22:10.152556 | localhost | Distro: Ubuntu 22.04
2026-05-19 16:22:10.152576 | localhost | Provider: yul1
2026-05-19 16:22:10.152595 | localhost | Region: ca-ymq-1
2026-05-19 16:22:10.152614 | localhost | Label: ubuntu-jammy-16
2026-05-19 16:22:10.152633 | localhost | Product Name: OpenStack Nova
2026-05-19 16:22:10.152662 | localhost | Interface IP: 199.204.45.246
2026-05-19 16:22:10.169367 | 
2026-05-19 16:22:10.169535 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-05-19 16:22:10.567360 | localhost -> localhost | changed
2026-05-19 16:22:10.577025 | 
2026-05-19 16:22:10.577104 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-05-19 16:22:11.560314 | localhost -> localhost | changed
2026-05-19 16:22:11.570231 | 
2026-05-19 16:22:11.570310 | PLAY [all]
2026-05-19 16:22:11.579108 | 
2026-05-19 16:22:11.579689 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-05-19 16:22:11.816717 | instance -> localhost | ok
2026-05-19 16:22:11.825032 | 
2026-05-19 16:22:11.825189 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-05-19 16:22:11.849370 | instance | ok
2026-05-19 16:22:11.868519 | instance | included: /var/lib/zuul/builds/3097cb603fb44c4d98a939218f5a6c9b/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-05-19 16:22:11.918181 | 
2026-05-19 16:22:11.918282 | TASK [add-build-sshkey : Create Temp SSH key]
2026-05-19 16:22:12.833376 | instance -> localhost | Generating public/private rsa key pair.
2026-05-19 16:22:12.833585 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/3097cb603fb44c4d98a939218f5a6c9b/work/3097cb603fb44c4d98a939218f5a6c9b_id_rsa
2026-05-19 16:22:12.833631 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/3097cb603fb44c4d98a939218f5a6c9b/work/3097cb603fb44c4d98a939218f5a6c9b_id_rsa.pub
2026-05-19 16:22:12.833665 | instance -> localhost | The key fingerprint is:
2026-05-19 16:22:12.833697 | instance -> localhost | SHA256:9oFSCxmz4OTxxU5XQ5G5t88zBAqyBj4u3WGLJTOpgOc zuul-build-sshkey
2026-05-19 16:22:12.833742 | instance -> localhost | The key's randomart image is:
2026-05-19 16:22:12.833809 | instance -> localhost | +---[RSA 3072]----+
2026-05-19 16:22:12.833869 | instance -> localhost | |    + o..  o=+   |
2026-05-19 16:22:12.833916 | instance -> localhost | |   + + *o . o.   |
2026-05-19 16:22:12.833956 | instance -> localhost | |    o =o..   .   |
2026-05-19 16:22:12.834002 | instance -> localhost | |     . +.+  ...  |
2026-05-19 16:22:12.834046 | instance -> localhost | |.   . + S o .... |
2026-05-19 16:22:12.834082 | instance -> localhost | |...  B O . o  .. |
2026-05-19 16:22:12.834116 | instance -> localhost | | o. + @ o .   .o |
2026-05-19 16:22:12.834145 | instance -> localhost | |  Eo + o       oo|
2026-05-19 16:22:12.834676 | instance -> localhost | |    .           o|
2026-05-19 16:22:12.834723 | instance -> localhost | +----[SHA256]-----+
2026-05-19 16:22:12.834792 | instance -> localhost | ok: Runtime: 0:00:00.507589
2026-05-19 16:22:12.841196 | 
2026-05-19 16:22:12.841310 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-05-19 16:22:12.874426 | instance | ok
2026-05-19 16:22:12.891424 | instance | included: /var/lib/zuul/builds/3097cb603fb44c4d98a939218f5a6c9b/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-05-19 16:22:12.901875 | 
2026-05-19 16:22:12.901954 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-05-19 16:22:12.925519 | instance | skipping: Conditional result was False
2026-05-19 16:22:12.933666 | 
2026-05-19 16:22:12.933863 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-05-19 16:22:13.436534 | instance | changed
2026-05-19 16:22:13.442748 | 
2026-05-19 16:22:13.442832 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-05-19 16:22:13.628079 | instance | ok
2026-05-19 16:22:13.677579 | 
2026-05-19 16:22:13.677788 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-05-19 16:22:14.146540 | instance | changed
2026-05-19 16:22:14.154285 | 
2026-05-19 16:22:14.154426 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-05-19 16:22:14.612581 | instance | changed
2026-05-19 16:22:16.908697 | 
2026-05-19 16:22:16.908859 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-05-19 16:22:16.932994 | instance | skipping: Conditional result was False
2026-05-19 16:22:16.942084 | 
2026-05-19 16:22:16.942204 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-05-19 16:22:17.412203 | instance -> localhost | changed
2026-05-19 16:22:17.423895 | 
2026-05-19 16:22:17.424017 | TASK [add-build-sshkey : Add back temp key]
2026-05-19 16:22:17.694638 | instance -> localhost | Identity added: /var/lib/zuul/builds/3097cb603fb44c4d98a939218f5a6c9b/work/3097cb603fb44c4d98a939218f5a6c9b_id_rsa (zuul-build-sshkey)
2026-05-19 16:22:17.694803 | instance -> localhost | ok: Runtime: 0:00:00.012600
2026-05-19 16:22:17.700150 | 
2026-05-19 16:22:17.700241 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-05-19 16:22:17.977161 | instance | ok
2026-05-19 16:22:17.983864 | 
2026-05-19 16:22:17.984010 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-05-19 16:22:18.007780 | instance | skipping: Conditional result was False
2026-05-19 16:22:18.024889 | 
2026-05-19 16:22:18.025036 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-05-19 16:22:18.312188 | instance | ok
2026-05-19 16:22:18.319852 | 
2026-05-19 16:22:18.319944 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-05-19 16:22:20.413324 | instance | Output suppressed because no_log was given
2026-05-19 16:22:20.425381 | 
2026-05-19 16:22:20.425463 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-05-19 16:22:20.604887 | instance | ok: "logs"
2026-05-19 16:22:20.605264 | instance | ok: All items complete
2026-05-19 16:22:20.605313 | 
2026-05-19 16:22:20.766177 | instance | ok: "artifacts"
2026-05-19 16:22:20.941230 | instance | ok: "docs"
2026-05-19 16:22:20.957763 | 
2026-05-19 16:22:20.957880 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-05-19 16:22:21.136870 | instance | changed: "logs"
2026-05-19 16:22:21.310779 | instance | changed: "artifacts"
2026-05-19 16:22:21.473490 | instance | changed: "docs"
2026-05-19 16:22:21.490768 | 
2026-05-19 16:22:21.490881 | PLAY RECAP
2026-05-19 16:22:21.490929 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-05-19 16:22:21.490961 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-05-19 16:22:21.490985 | 
2026-05-19 16:22:21.611022 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-05-19 16:22:21.617776 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-05-19 16:22:22.309479 | 
2026-05-19 16:22:23.213330 | PLAY [all]
2026-05-19 16:22:23.232232 | 
2026-05-19 16:22:23.232463 | TASK [setup-uv : Extract archive]
2026-05-19 16:22:25.557528 | instance | changed
2026-05-19 16:22:25.563847 | 
2026-05-19 16:22:25.563925 | TASK [setup-uv : Print version]
2026-05-19 16:22:25.930429 | instance | uv 0.8.13
2026-05-19 16:22:26.104093 | instance | ok: Runtime: 0:00:00.013804
2026-05-19 16:22:26.113895 | 
2026-05-19 16:22:26.113959 | TASK [Install Ansible collection siblings]
2026-05-19 16:22:26.176565 | instance | ERROR
2026-05-19 16:22:26.299478 | instance | {
2026-05-19 16:22:26.299643 | 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:22:26.299708 | instance | }
failure
2026-05-19 16:22:26.301999 | 
2026-05-19 16:22:26.302097 | PLAY RECAP
2026-05-19 16:22:26.302191 | instance | ok: 2 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-05-19 16:22:26.302247 | 
2026-05-19 16:22:26.417597 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-05-19 16:22:26.520614 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-05-19 16:22:27.189674 | 
2026-05-19 16:22:27.189799 | PLAY [all]
2026-05-19 16:22:27.200900 | 
2026-05-19 16:22:27.201012 | TASK [fetch-output : Set log path for multiple nodes]
2026-05-19 16:22:27.245609 | instance | skipping: Conditional result was False
2026-05-19 16:22:27.256072 | 
2026-05-19 16:22:27.256152 | TASK [fetch-output : Set log path for single node]
2026-05-19 16:22:27.300755 | instance | ok
2026-05-19 16:22:27.305155 | 
2026-05-19 16:22:27.305220 | LOOP [fetch-output : Ensure local output dirs]
2026-05-19 16:22:28.532624 | instance -> localhost | ok: "/var/lib/zuul/builds/3097cb603fb44c4d98a939218f5a6c9b/work/logs"
2026-05-19 16:22:28.790518 | instance -> localhost | changed: "/var/lib/zuul/builds/3097cb603fb44c4d98a939218f5a6c9b/work/artifacts"
2026-05-19 16:22:29.085356 | instance -> localhost | changed: "/var/lib/zuul/builds/3097cb603fb44c4d98a939218f5a6c9b/work/docs"
2026-05-19 16:22:29.108043 | 
2026-05-19 16:22:29.108183 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-05-19 16:22:29.775841 | instance | changed: .d..t...... ./
2026-05-19 16:22:29.776101 | instance | changed: All items complete
2026-05-19 16:22:29.776146 | 
2026-05-19 16:22:30.257875 | instance | changed: .d..t...... ./
2026-05-19 16:22:30.763986 | instance | changed: .d..t...... ./
2026-05-19 16:22:30.786649 | 
2026-05-19 16:22:30.786771 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-05-19 16:22:31.230816 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.007564
2026-05-19 16:22:31.467075 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.005459
2026-05-19 16:22:31.477583 | 
2026-05-19 16:22:31.477667 | PLAY [all]
2026-05-19 16:22:31.483634 | 
2026-05-19 16:22:31.483742 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-05-19 16:22:31.924152 | instance | changed
2026-05-19 16:22:31.930428 | 
2026-05-19 16:22:31.930503 | PLAY RECAP
2026-05-19 16:22:31.930552 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-05-19 16:22:31.930576 | 
2026-05-19 16:22:32.066124 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-05-19 16:22:32.081168 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-05-19 16:22:32.750969 | 
2026-05-19 16:22:32.751379 | PLAY [localhost]
2026-05-19 16:22:32.763234 | 
2026-05-19 16:22:32.763394 | TASK [Generate Zuul manifest]
2026-05-19 16:22:32.783219 | localhost | ok
2026-05-19 16:22:32.809342 | 
2026-05-19 16:22:32.809496 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-05-19 16:22:33.196728 | localhost | changed
2026-05-19 16:22:33.213229 | 
2026-05-19 16:22:33.213366 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-05-19 16:22:33.256588 | localhost | ok
2026-05-19 16:22:33.270273 | 
2026-05-19 16:22:33.270421 | TASK [Upload logs]
2026-05-19 16:22:33.290118 | localhost | ok
2026-05-19 16:22:33.405324 | 
2026-05-19 16:22:33.405453 | TASK [Set zuul-log-path fact]
2026-05-19 16:22:33.424775 | localhost | ok
2026-05-19 16:22:33.441271 | 
2026-05-19 16:22:33.456681 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-05-19 16:22:33.490614 | localhost | ok
2026-05-19 16:22:33.500429 | 
2026-05-19 16:22:33.500604 | TASK [upload-logs : Create log directories]
2026-05-19 16:22:33.907572 | localhost | changed
2026-05-19 16:22:33.913845 | 
2026-05-19 16:22:33.913965 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-05-19 16:22:34.463418 | localhost -> localhost | ok: Runtime: 0:00:00.005313
2026-05-19 16:22:34.471067 | 
2026-05-19 16:22:34.471159 | TASK [upload-logs : Upload logs to log server]
2026-05-19 16:22:34.971018 | localhost | Output suppressed because no_log was given
2026-05-19 16:22:34.977322 | 
2026-05-19 16:22:34.977449 | LOOP [upload-logs : Compress console log and json output]
2026-05-19 16:22:35.030653 | localhost | skipping: Conditional result was False
2026-05-19 16:22:35.038716 | localhost | skipping: Conditional result was False
2026-05-19 16:22:35.054053 | 
2026-05-19 16:22:35.102578 | LOOP [upload-logs : Upload compressed console log and json output]
2026-05-19 16:22:35.153028 | localhost | skipping: Conditional result was False
2026-05-19 16:22:35.153382 | 
2026-05-19 16:22:35.161270 | localhost | skipping: Conditional result was False
2026-05-19 16:22:35.176422 | 
2026-05-19 16:22:35.176584 | LOOP [upload-logs : Upload console log and json output]