2026-05-19 16:17:36.920505 | Job console starting
2026-05-19 16:17:36.930755 | Updating git repos
2026-05-19 16:17:37.034389 | Cloning repos into workspace
2026-05-19 16:17:41.710652 | Restoring repo states
2026-05-19 16:17:41.729681 | Merging changes
2026-05-19 16:17:44.318843 | Checking out repos
2026-05-19 16:17:46.768382 | Preparing playbooks
2026-05-19 16:17:59.989739 | Running Ansible setup
2026-05-19 16:18:07.450543 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-05-19 16:18:08.151500 | 
2026-05-19 16:18:08.151686 | PLAY [localhost]
2026-05-19 16:18:08.163047 | 
2026-05-19 16:18:08.163186 | TASK [Gathering Facts]
2026-05-19 16:18:09.178196 | localhost | ok
2026-05-19 16:18:09.189059 | 
2026-05-19 16:18:09.189193 | TASK [Setup log path fact]
2026-05-19 16:18:09.209192 | localhost | ok
2026-05-19 16:18:09.222119 | 
2026-05-19 16:18:09.222244 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-05-19 16:18:09.253374 | localhost | ok
2026-05-19 16:18:09.260371 | 
2026-05-19 16:18:09.260445 | TASK [emit-job-header : Print job information]
2026-05-19 16:18:09.300689 | # Job Information
2026-05-19 16:18:09.300928 | Ansible Version: 2.16.18
2026-05-19 16:18:09.301001 | Job: atmosphere-molecule-aio-ovn
2026-05-19 16:18:09.301068 | Pipeline: check
2026-05-19 16:18:09.301142 | Executor: 2d72f0692154
2026-05-19 16:18:09.301189 | Triggered by: https://github.com/vexxhost/atmosphere/pull/3945
2026-05-19 16:18:09.301227 | Event ID: 1d651ce0-539e-11f1-8754-a319db16026e
2026-05-19 16:18:09.305416 | 
2026-05-19 16:18:09.305489 | LOOP [emit-job-header : Print node information]
2026-05-19 16:18:09.398779 | localhost | ok:
2026-05-19 16:18:09.399278 | localhost | # Node Information
2026-05-19 16:18:09.399328 | localhost | Inventory Hostname: instance
2026-05-19 16:18:09.399362 | localhost | Hostname: np0000174868
2026-05-19 16:18:09.399394 | localhost | Username: zuul
2026-05-19 16:18:09.399428 | localhost | Distro: Ubuntu 22.04
2026-05-19 16:18:09.399459 | localhost | Provider: yul1
2026-05-19 16:18:09.399488 | localhost | Region: ca-ymq-1
2026-05-19 16:18:09.399518 | localhost | Label: ubuntu-jammy-16
2026-05-19 16:18:09.399546 | localhost | Product Name: OpenStack Nova
2026-05-19 16:18:09.399574 | localhost | Interface IP: 199.204.45.23
2026-05-19 16:18:09.411734 | 
2026-05-19 16:18:09.411857 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-05-19 16:18:09.825456 | localhost -> localhost | changed
2026-05-19 16:18:09.835577 | 
2026-05-19 16:18:09.835737 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-05-19 16:18:10.830877 | localhost -> localhost | changed
2026-05-19 16:18:10.841300 | 
2026-05-19 16:18:10.841374 | PLAY [all]
2026-05-19 16:18:10.848788 | 
2026-05-19 16:18:10.848871 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-05-19 16:18:11.076180 | instance -> localhost | ok
2026-05-19 16:18:11.087962 | 
2026-05-19 16:18:11.088048 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-05-19 16:18:11.122580 | instance | ok
2026-05-19 16:18:11.137025 | instance | included: /var/lib/zuul/builds/77aa260a6c474e95b56af86ea1ddd6ca/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-05-19 16:18:11.142618 | 
2026-05-19 16:18:11.142718 | TASK [add-build-sshkey : Create Temp SSH key]
2026-05-19 16:18:12.575168 | instance -> localhost | Generating public/private rsa key pair.
2026-05-19 16:18:12.575356 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/77aa260a6c474e95b56af86ea1ddd6ca/work/77aa260a6c474e95b56af86ea1ddd6ca_id_rsa
2026-05-19 16:18:12.575401 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/77aa260a6c474e95b56af86ea1ddd6ca/work/77aa260a6c474e95b56af86ea1ddd6ca_id_rsa.pub
2026-05-19 16:18:12.575437 | instance -> localhost | The key fingerprint is:
2026-05-19 16:18:12.575471 | instance -> localhost | SHA256:muDpQ5eVP2oePHpQDlbRwxOj7OxUOX+x93eWCt086ks zuul-build-sshkey
2026-05-19 16:18:12.575519 | instance -> localhost | The key's randomart image is:
2026-05-19 16:18:12.575553 | instance -> localhost | +---[RSA 3072]----+
2026-05-19 16:18:12.575592 | instance -> localhost | |        .+o.     |
2026-05-19 16:18:12.575625 | instance -> localhost | |       ...=o     |
2026-05-19 16:18:12.575656 | instance -> localhost | |       .o.+o  .  |
2026-05-19 16:18:12.575688 | instance -> localhost | |      oo+. o   o |
2026-05-19 16:18:12.575718 | instance -> localhost | |    .. *S.  . o .|
2026-05-19 16:18:12.575750 | instance -> localhost | |   ..o+*. o ..o.o|
2026-05-19 16:18:12.575780 | instance -> localhost | |   .o.o.=. o E ==|
2026-05-19 16:18:12.575811 | instance -> localhost | |   ..  .+o  o o.+|
2026-05-19 16:18:12.575846 | instance -> localhost | |    ...+.   .=.  |
2026-05-19 16:18:12.575877 | instance -> localhost | +----[SHA256]-----+
2026-05-19 16:18:12.575950 | instance -> localhost | ok: Runtime: 0:00:01.014474
2026-05-19 16:18:12.584265 | 
2026-05-19 16:18:12.584348 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-05-19 16:18:12.611664 | instance | ok
2026-05-19 16:18:12.624997 | instance | included: /var/lib/zuul/builds/77aa260a6c474e95b56af86ea1ddd6ca/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-05-19 16:18:12.636517 | 
2026-05-19 16:18:12.636606 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-05-19 16:18:12.660885 | instance | skipping: Conditional result was False
2026-05-19 16:18:12.680713 | 
2026-05-19 16:18:12.680810 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-05-19 16:18:13.095002 | instance | changed
2026-05-19 16:18:13.280692 | 
2026-05-19 16:18:13.280835 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-05-19 16:18:13.466912 | instance | ok
2026-05-19 16:18:13.509157 | 
2026-05-19 16:18:13.509296 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-05-19 16:18:14.008154 | instance | changed
2026-05-19 16:18:14.015669 | 
2026-05-19 16:18:14.015775 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-05-19 16:18:14.510439 | instance | changed
2026-05-19 16:18:14.516608 | 
2026-05-19 16:18:14.516669 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-05-19 16:18:14.539807 | instance | skipping: Conditional result was False
2026-05-19 16:18:14.550037 | 
2026-05-19 16:18:14.550181 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-05-19 16:18:14.870166 | instance -> localhost | changed
2026-05-19 16:18:14.885810 | 
2026-05-19 16:18:14.885885 | TASK [add-build-sshkey : Add back temp key]
2026-05-19 16:18:15.150206 | instance -> localhost | Identity added: /var/lib/zuul/builds/77aa260a6c474e95b56af86ea1ddd6ca/work/77aa260a6c474e95b56af86ea1ddd6ca_id_rsa (zuul-build-sshkey)
2026-05-19 16:18:15.150373 | instance -> localhost | ok: Runtime: 0:00:00.011677
2026-05-19 16:18:15.155542 | 
2026-05-19 16:18:15.155602 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-05-19 16:18:15.414991 | instance | ok
2026-05-19 16:18:15.421310 | 
2026-05-19 16:18:15.421372 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-05-19 16:18:15.444076 | instance | skipping: Conditional result was False
2026-05-19 16:18:15.459261 | 
2026-05-19 16:18:15.459339 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-05-19 16:18:15.741990 | instance | ok
2026-05-19 16:18:15.749851 | 
2026-05-19 16:18:15.749948 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-05-19 16:18:17.965865 | instance | Output suppressed because no_log was given
2026-05-19 16:18:18.993926 | 
2026-05-19 16:18:18.994081 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-05-19 16:18:19.230440 | instance | ok: "logs"
2026-05-19 16:18:19.231066 | instance | ok: All items complete
2026-05-19 16:18:19.231108 | 
2026-05-19 16:18:19.396799 | instance | ok: "artifacts"
2026-05-19 16:18:19.580196 | instance | ok: "docs"
2026-05-19 16:18:19.599841 | 
2026-05-19 16:18:19.599991 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-05-19 16:18:19.788851 | instance | changed: "logs"
2026-05-19 16:18:19.944681 | instance | changed: "artifacts"
2026-05-19 16:18:20.097358 | instance | changed: "docs"
2026-05-19 16:18:20.110021 | 
2026-05-19 16:18:20.110167 | PLAY RECAP
2026-05-19 16:18:20.110231 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-05-19 16:18:20.110279 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-05-19 16:18:20.110315 | 
2026-05-19 16:18:20.286588 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-05-19 16:18:20.296882 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-05-19 16:18:21.010767 | 
2026-05-19 16:18:21.299963 | PLAY [all]
2026-05-19 16:18:21.316613 | 
2026-05-19 16:18:21.316763 | TASK [setup-uv : Extract archive]
2026-05-19 16:18:23.597208 | instance | changed
2026-05-19 16:18:23.608854 | 
2026-05-19 16:18:23.609043 | TASK [setup-uv : Print version]
2026-05-19 16:18:23.951586 | instance | uv 0.8.13
2026-05-19 16:18:24.146326 | instance | ok: Runtime: 0:00:00.010416
2026-05-19 16:18:24.156167 | 
2026-05-19 16:18:24.156268 | TASK [Install Ansible collection siblings]
2026-05-19 16:18:24.220987 | instance | ERROR
2026-05-19 16:18:24.221257 | instance | {
2026-05-19 16:18:24.221309 | 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:24.221350 | instance | }
failure
2026-05-19 16:18:24.222524 | 
2026-05-19 16:18:24.222580 | PLAY RECAP
2026-05-19 16:18:24.222637 | instance | ok: 2 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-05-19 16:18:24.222672 | 
2026-05-19 16:18:24.357220 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-05-19 16:18:24.365108 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-05-19 16:18:25.046971 | 
2026-05-19 16:18:25.047101 | PLAY [all]
2026-05-19 16:18:25.058923 | 
2026-05-19 16:18:25.059012 | TASK [fetch-output : Set log path for multiple nodes]
2026-05-19 16:18:25.103411 | instance | skipping: Conditional result was False
2026-05-19 16:18:25.114062 | 
2026-05-19 16:18:25.114153 | TASK [fetch-output : Set log path for single node]
2026-05-19 16:18:25.159002 | instance | ok
2026-05-19 16:18:25.163658 | 
2026-05-19 16:18:25.163726 | LOOP [fetch-output : Ensure local output dirs]
2026-05-19 16:18:25.544413 | instance -> localhost | ok: "/var/lib/zuul/builds/77aa260a6c474e95b56af86ea1ddd6ca/work/logs"
2026-05-19 16:18:25.753069 | instance -> localhost | changed: "/var/lib/zuul/builds/77aa260a6c474e95b56af86ea1ddd6ca/work/artifacts"
2026-05-19 16:18:25.962047 | instance -> localhost | changed: "/var/lib/zuul/builds/77aa260a6c474e95b56af86ea1ddd6ca/work/docs"
2026-05-19 16:18:25.979765 | 
2026-05-19 16:18:25.980272 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-05-19 16:18:26.583815 | instance | changed: .d..t...... ./
2026-05-19 16:18:26.584190 | instance | changed: All items complete
2026-05-19 16:18:26.584235 | 
2026-05-19 16:18:27.068333 | instance | changed: .d..t...... ./
2026-05-19 16:18:27.627974 | instance | changed: .d..t...... ./
2026-05-19 16:18:27.662998 | 
2026-05-19 16:18:27.663166 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-05-19 16:18:28.141808 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.005622
2026-05-19 16:18:28.377017 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.006842
2026-05-19 16:18:28.395779 | 
2026-05-19 16:18:28.395909 | PLAY [all]
2026-05-19 16:18:28.403052 | 
2026-05-19 16:18:28.403138 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-05-19 16:18:28.797202 | instance | changed
2026-05-19 16:18:28.805345 | 
2026-05-19 16:18:28.805455 | PLAY RECAP
2026-05-19 16:18:28.805515 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-05-19 16:18:28.805546 | 
2026-05-19 16:18:28.917035 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-05-19 16:18:28.927560 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-05-19 16:18:29.611800 | 
2026-05-19 16:18:29.612316 | PLAY [localhost]
2026-05-19 16:18:29.625721 | 
2026-05-19 16:18:29.625887 | TASK [Generate Zuul manifest]
2026-05-19 16:18:29.646923 | localhost | ok
2026-05-19 16:18:29.663902 | 
2026-05-19 16:18:29.664041 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-05-19 16:18:30.019957 | localhost | changed
2026-05-19 16:18:30.032716 | 
2026-05-19 16:18:30.032816 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-05-19 16:18:30.083889 | localhost | ok
2026-05-19 16:18:30.094579 | 
2026-05-19 16:18:30.094678 | TASK [Upload logs]
2026-05-19 16:18:30.113714 | localhost | ok
2026-05-19 16:18:30.207356 | 
2026-05-19 16:18:30.207495 | TASK [Set zuul-log-path fact]
2026-05-19 16:18:30.229239 | localhost | ok
2026-05-19 16:18:30.241720 | 
2026-05-19 16:18:30.241805 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-05-19 16:18:30.272711 | localhost | ok
2026-05-19 16:18:30.281472 | 
2026-05-19 16:18:30.281568 | TASK [upload-logs : Create log directories]
2026-05-19 16:18:30.651272 | localhost | changed
2026-05-19 16:18:30.655912 | 
2026-05-19 16:18:30.655983 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-05-19 16:18:31.031779 | localhost -> localhost | ok: Runtime: 0:00:00.004609
2026-05-19 16:18:31.036806 | 
2026-05-19 16:18:31.036877 | TASK [upload-logs : Upload logs to log server]
2026-05-19 16:18:31.482235 | localhost | Output suppressed because no_log was given
2026-05-19 16:18:31.487580 | 
2026-05-19 16:18:31.487715 | LOOP [upload-logs : Compress console log and json output]
2026-05-19 16:18:31.535370 | localhost | skipping: Conditional result was False
2026-05-19 16:18:31.546766 | localhost | skipping: Conditional result was False
2026-05-19 16:18:31.560652 | 
2026-05-19 16:18:31.560839 | LOOP [upload-logs : Upload compressed console log and json output]
2026-05-19 16:18:31.607771 | localhost | skipping: Conditional result was False
2026-05-19 16:18:32.273402 | 
2026-05-19 16:18:32.274644 | localhost | skipping: Conditional result was False
2026-05-19 16:18:32.286071 | 
2026-05-19 16:18:32.286221 | LOOP [upload-logs : Upload console log and json output]