2026-05-19 16:22:46.534246 | Job console starting
2026-05-19 16:22:46.547070 | Updating git repos
2026-05-19 16:22:46.686564 | Cloning repos into workspace
2026-05-19 16:22:48.579049 | Restoring repo states
2026-05-19 16:22:48.600051 | Merging changes
2026-05-19 16:22:51.216570 | Checking out repos
2026-05-19 16:22:53.858113 | Preparing playbooks
2026-05-19 16:23:06.393745 | Running Ansible setup
2026-05-19 16:23:12.139182 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-05-19 16:23:12.861966 | 
2026-05-19 16:23:12.862162 | PLAY [localhost]
2026-05-19 16:23:12.870792 | 
2026-05-19 16:23:12.870894 | TASK [Gathering Facts]
2026-05-19 16:23:13.790662 | localhost | ok
2026-05-19 16:23:13.800756 | 
2026-05-19 16:23:13.800916 | TASK [Setup log path fact]
2026-05-19 16:23:13.821100 | localhost | ok
2026-05-19 16:23:13.832846 | 
2026-05-19 16:23:14.520786 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-05-19 16:23:14.552549 | localhost | ok
2026-05-19 16:23:14.559433 | 
2026-05-19 16:23:14.559520 | TASK [emit-job-header : Print job information]
2026-05-19 16:23:14.601999 | # Job Information
2026-05-19 16:23:14.602232 | Ansible Version: 2.16.18
2026-05-19 16:23:14.602284 | Job: atmosphere-molecule-aio-ovn
2026-05-19 16:23:14.602320 | Pipeline: check
2026-05-19 16:23:14.602353 | Executor: 2d72f0692154
2026-05-19 16:23:14.602386 | Triggered by: https://github.com/vexxhost/atmosphere/pull/3945
2026-05-19 16:23:14.602423 | Event ID: 8635b180-539e-11f1-8f4a-8b53e1584e5c
2026-05-19 16:23:14.606821 | 
2026-05-19 16:23:14.606922 | LOOP [emit-job-header : Print node information]
2026-05-19 16:23:14.698471 | localhost | ok:
2026-05-19 16:23:14.698618 | localhost | # Node Information
2026-05-19 16:23:14.698647 | localhost | Inventory Hostname: instance
2026-05-19 16:23:14.698669 | localhost | Hostname: np0000174882
2026-05-19 16:23:14.698688 | localhost | Username: zuul
2026-05-19 16:23:14.698711 | localhost | Distro: Ubuntu 22.04
2026-05-19 16:23:14.698732 | localhost | Provider: yul1
2026-05-19 16:23:14.698751 | localhost | Region: ca-ymq-1
2026-05-19 16:23:14.698769 | localhost | Label: ubuntu-jammy-16
2026-05-19 16:23:14.698787 | localhost | Product Name: OpenStack Nova
2026-05-19 16:23:14.698805 | localhost | Interface IP: 199.204.45.233
2026-05-19 16:23:14.716340 | 
2026-05-19 16:23:14.716528 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-05-19 16:23:15.089717 | localhost -> localhost | changed
2026-05-19 16:23:15.097631 | 
2026-05-19 16:23:15.097753 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-05-19 16:23:16.557132 | localhost -> localhost | changed
2026-05-19 16:23:16.567062 | 
2026-05-19 16:23:16.567117 | PLAY [all]
2026-05-19 16:23:16.574487 | 
2026-05-19 16:23:16.574556 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-05-19 16:23:16.832347 | instance -> localhost | ok
2026-05-19 16:23:16.845369 | 
2026-05-19 16:23:16.845458 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-05-19 16:23:16.881188 | instance | ok
2026-05-19 16:23:16.895529 | instance | included: /var/lib/zuul/builds/71f6b307d44247968ecb3178b84ef2bd/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-05-19 16:23:16.901196 | 
2026-05-19 16:23:16.901260 | TASK [add-build-sshkey : Create Temp SSH key]
2026-05-19 16:23:18.109412 | instance -> localhost | Generating public/private rsa key pair.
2026-05-19 16:23:18.109598 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/71f6b307d44247968ecb3178b84ef2bd/work/71f6b307d44247968ecb3178b84ef2bd_id_rsa
2026-05-19 16:23:18.109641 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/71f6b307d44247968ecb3178b84ef2bd/work/71f6b307d44247968ecb3178b84ef2bd_id_rsa.pub
2026-05-19 16:23:18.109672 | instance -> localhost | The key fingerprint is:
2026-05-19 16:23:18.109702 | instance -> localhost | SHA256:UgCLirICOooNegrZx/v/KjrbpEyycW66F/HSLOkz8cI zuul-build-sshkey
2026-05-19 16:23:18.109749 | instance -> localhost | The key's randomart image is:
2026-05-19 16:23:18.109779 | instance -> localhost | +---[RSA 3072]----+
2026-05-19 16:23:18.109811 | instance -> localhost | |    ...          |
2026-05-19 16:23:18.109841 | instance -> localhost | |   . . .         |
2026-05-19 16:23:18.109871 | instance -> localhost | |  . .   .        |
2026-05-19 16:23:18.109898 | instance -> localhost | |..  .  .         |
2026-05-19 16:23:18.109926 | instance -> localhost | |=    *. S        |
2026-05-19 16:23:18.109954 | instance -> localhost | |++ .* +.         |
2026-05-19 16:23:18.109981 | instance -> localhost | |O +o=*.          |
2026-05-19 16:23:18.110008 | instance -> localhost | |*+.XE=o          |
2026-05-19 16:23:18.110040 | instance -> localhost | |=o==BOoooo.      |
2026-05-19 16:23:18.110068 | instance -> localhost | +----[SHA256]-----+
2026-05-19 16:23:18.110134 | instance -> localhost | ok: Runtime: 0:00:00.724648
2026-05-19 16:23:18.116217 | 
2026-05-19 16:23:18.116294 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-05-19 16:23:18.149306 | instance | ok
2026-05-19 16:23:18.161970 | instance | included: /var/lib/zuul/builds/71f6b307d44247968ecb3178b84ef2bd/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-05-19 16:23:18.171045 | 
2026-05-19 16:23:18.171120 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-05-19 16:23:18.195785 | instance | skipping: Conditional result was False
2026-05-19 16:23:18.205517 | 
2026-05-19 16:23:18.205680 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-05-19 16:23:18.634814 | instance | changed
2026-05-19 16:23:18.640070 | 
2026-05-19 16:23:18.640157 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-05-19 16:23:18.820167 | instance | ok
2026-05-19 16:23:19.101802 | 
2026-05-19 16:23:19.101938 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-05-19 16:23:19.568608 | instance | changed
2026-05-19 16:23:19.576285 | 
2026-05-19 16:23:19.576643 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-05-19 16:23:20.053676 | instance | changed
2026-05-19 16:23:20.060096 | 
2026-05-19 16:23:20.060193 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-05-19 16:23:20.084541 | instance | skipping: Conditional result was False
2026-05-19 16:23:20.101193 | 
2026-05-19 16:23:20.101381 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-05-19 16:23:20.636451 | instance -> localhost | changed
2026-05-19 16:23:20.656193 | 
2026-05-19 16:23:20.656347 | TASK [add-build-sshkey : Add back temp key]
2026-05-19 16:23:20.923565 | instance -> localhost | Identity added: /var/lib/zuul/builds/71f6b307d44247968ecb3178b84ef2bd/work/71f6b307d44247968ecb3178b84ef2bd_id_rsa (zuul-build-sshkey)
2026-05-19 16:23:20.923738 | instance -> localhost | ok: Runtime: 0:00:00.008482
2026-05-19 16:23:20.929145 | 
2026-05-19 16:23:20.929217 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-05-19 16:23:21.216899 | instance | ok
2026-05-19 16:23:21.224558 | 
2026-05-19 16:23:21.224676 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-05-19 16:23:21.248897 | instance | skipping: Conditional result was False
2026-05-19 16:23:21.266383 | 
2026-05-19 16:23:21.266526 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-05-19 16:23:21.554660 | instance | ok
2026-05-19 16:23:21.735848 | 
2026-05-19 16:23:21.735967 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-05-19 16:23:24.305519 | instance | Output suppressed because no_log was given
2026-05-19 16:23:24.318013 | 
2026-05-19 16:23:24.318101 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-05-19 16:23:24.511373 | instance | ok: "logs"
2026-05-19 16:23:24.511629 | instance | ok: All items complete
2026-05-19 16:23:24.511670 | 
2026-05-19 16:23:24.666311 | instance | ok: "artifacts"
2026-05-19 16:23:24.828990 | instance | ok: "docs"
2026-05-19 16:23:24.838401 | 
2026-05-19 16:23:24.838557 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-05-19 16:23:25.023613 | instance | changed: "logs"
2026-05-19 16:23:25.178727 | instance | changed: "artifacts"
2026-05-19 16:23:25.337626 | instance | changed: "docs"
2026-05-19 16:23:25.352147 | 
2026-05-19 16:23:25.352258 | PLAY RECAP
2026-05-19 16:23:25.352318 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-05-19 16:23:25.352357 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-05-19 16:23:25.352390 | 
2026-05-19 16:23:25.473798 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-05-19 16:23:25.483348 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-05-19 16:23:26.225604 | 
2026-05-19 16:23:26.225733 | PLAY [all]
2026-05-19 16:23:26.237033 | 
2026-05-19 16:23:26.237124 | TASK [setup-uv : Extract archive]
2026-05-19 16:23:29.549471 | instance | changed
2026-05-19 16:23:29.560011 | 
2026-05-19 16:23:29.560254 | TASK [setup-uv : Print version]
2026-05-19 16:23:29.912377 | instance | uv 0.8.13
2026-05-19 16:23:30.104897 | instance | ok: Runtime: 0:00:00.012450
2026-05-19 16:23:30.114417 | 
2026-05-19 16:23:30.114535 | TASK [Install Ansible collection siblings]
2026-05-19 16:23:30.178880 | instance | ERROR
2026-05-19 16:23:30.179218 | instance | {
2026-05-19 16:23:30.179281 | 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:23:30.179326 | instance | }
failure
2026-05-19 16:23:30.180702 | 
2026-05-19 16:23:30.180777 | PLAY RECAP
2026-05-19 16:23:30.180855 | instance | ok: 2 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-05-19 16:23:30.180900 | 
2026-05-19 16:23:30.283350 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-05-19 16:23:30.300200 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-05-19 16:23:30.990679 | 
2026-05-19 16:23:30.990820 | PLAY [all]
2026-05-19 16:23:31.005552 | 
2026-05-19 16:23:31.005704 | TASK [fetch-output : Set log path for multiple nodes]
2026-05-19 16:23:31.050258 | instance | skipping: Conditional result was False
2026-05-19 16:23:31.062854 | 
2026-05-19 16:23:31.063064 | TASK [fetch-output : Set log path for single node]
2026-05-19 16:23:31.109072 | instance | ok
2026-05-19 16:23:31.113654 | 
2026-05-19 16:23:31.113720 | LOOP [fetch-output : Ensure local output dirs]
2026-05-19 16:23:31.513336 | instance -> localhost | ok: "/var/lib/zuul/builds/71f6b307d44247968ecb3178b84ef2bd/work/logs"
2026-05-19 16:23:31.725959 | instance -> localhost | changed: "/var/lib/zuul/builds/71f6b307d44247968ecb3178b84ef2bd/work/artifacts"
2026-05-19 16:23:31.930602 | instance -> localhost | changed: "/var/lib/zuul/builds/71f6b307d44247968ecb3178b84ef2bd/work/docs"
2026-05-19 16:23:31.952039 | 
2026-05-19 16:23:31.952156 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-05-19 16:23:32.563880 | instance | changed: .d..t...... ./
2026-05-19 16:23:32.564125 | instance | changed: All items complete
2026-05-19 16:23:32.564171 | 
2026-05-19 16:23:33.010800 | instance | changed: .d..t...... ./
2026-05-19 16:23:33.447667 | instance | changed: .d..t...... ./
2026-05-19 16:23:33.467592 | 
2026-05-19 16:23:33.467739 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-05-19 16:23:33.937132 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.006229
2026-05-19 16:23:34.220932 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.007144
2026-05-19 16:23:34.243427 | 
2026-05-19 16:23:34.243529 | PLAY [all]
2026-05-19 16:23:34.250266 | 
2026-05-19 16:23:34.250352 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-05-19 16:23:34.695531 | instance | changed
2026-05-19 16:23:34.703708 | 
2026-05-19 16:23:34.703800 | PLAY RECAP
2026-05-19 16:23:34.703863 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-05-19 16:23:34.703902 | 
2026-05-19 16:23:34.856516 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-05-19 16:23:34.867720 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-05-19 16:23:35.576776 | 
2026-05-19 16:23:35.576944 | PLAY [localhost]
2026-05-19 16:23:35.588395 | 
2026-05-19 16:23:35.588538 | TASK [Generate Zuul manifest]
2026-05-19 16:23:35.620900 | localhost | ok
2026-05-19 16:23:35.638579 | 
2026-05-19 16:23:35.638708 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-05-19 16:23:36.224182 | localhost | changed
2026-05-19 16:23:36.241934 | 
2026-05-19 16:23:36.242086 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-05-19 16:23:36.287105 | localhost | ok
2026-05-19 16:23:36.299313 | 
2026-05-19 16:23:36.299428 | TASK [Upload logs]
2026-05-19 16:23:36.320654 | localhost | ok
2026-05-19 16:23:36.444909 | 
2026-05-19 16:23:36.445169 | TASK [Set zuul-log-path fact]
2026-05-19 16:23:36.466324 | localhost | ok
2026-05-19 16:23:36.481485 | 
2026-05-19 16:23:36.481612 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-05-19 16:23:36.511639 | localhost | ok
2026-05-19 16:23:36.520866 | 
2026-05-19 16:23:36.521106 | TASK [upload-logs : Create log directories]
2026-05-19 16:23:36.941734 | localhost | changed
2026-05-19 16:23:36.949017 | 
2026-05-19 16:23:36.949140 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-05-19 16:23:37.347138 | localhost -> localhost | ok: Runtime: 0:00:00.004212
2026-05-19 16:23:37.352370 | 
2026-05-19 16:23:37.352490 | TASK [upload-logs : Upload logs to log server]
2026-05-19 16:23:38.080253 | localhost | Output suppressed because no_log was given
2026-05-19 16:23:38.084074 | 
2026-05-19 16:23:38.084321 | LOOP [upload-logs : Compress console log and json output]
2026-05-19 16:23:38.135109 | localhost | skipping: Conditional result was False
2026-05-19 16:23:38.144011 | localhost | skipping: Conditional result was False
2026-05-19 16:23:38.155305 | 
2026-05-19 16:23:38.155546 | LOOP [upload-logs : Upload compressed console log and json output]
2026-05-19 16:23:38.199916 | localhost | skipping: Conditional result was False
2026-05-19 16:23:38.200395 | 
2026-05-19 16:23:38.203961 | localhost | skipping: Conditional result was False
2026-05-19 16:23:38.219901 | 
2026-05-19 16:23:38.220040 | LOOP [upload-logs : Upload console log and json output]