2026-05-19 16:20:14.358829 | Job console starting
2026-05-19 16:20:14.425926 | Updating git repos
2026-05-19 16:20:14.679835 | Cloning repos into workspace
2026-05-19 16:20:16.370142 | Restoring repo states
2026-05-19 16:20:16.397883 | Merging changes
2026-05-19 16:20:19.845629 | Checking out repos
2026-05-19 16:20:20.517766 | Preparing playbooks
2026-05-19 16:20:34.309467 | Running Ansible setup
2026-05-19 16:20:42.670352 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-05-19 16:20:43.404529 | 
2026-05-19 16:20:43.404668 | PLAY [localhost]
2026-05-19 16:20:43.412778 | 
2026-05-19 16:20:43.412880 | TASK [Gathering Facts]
2026-05-19 16:20:44.399739 | localhost | ok
2026-05-19 16:20:44.407900 | 
2026-05-19 16:20:44.408019 | TASK [Setup log path fact]
2026-05-19 16:20:44.427972 | localhost | ok
2026-05-19 16:20:44.446871 | 
2026-05-19 16:20:44.447027 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-05-19 16:20:44.477232 | localhost | ok
2026-05-19 16:20:44.486212 | 
2026-05-19 16:20:44.486319 | TASK [emit-job-header : Print job information]
2026-05-19 16:20:44.526946 | # Job Information
2026-05-19 16:20:44.527123 | Ansible Version: 2.16.18
2026-05-19 16:20:44.527174 | Job: atmosphere-molecule-aio-ovn
2026-05-19 16:20:44.527236 | Pipeline: check
2026-05-19 16:20:44.527281 | Executor: 2d72f0692154
2026-05-19 16:20:44.527315 | Triggered by: https://github.com/vexxhost/atmosphere/pull/3945
2026-05-19 16:20:44.527352 | Event ID: 8635b180-539e-11f1-8f4a-8b53e1584e5c
2026-05-19 16:20:44.531393 | 
2026-05-19 16:20:44.531486 | LOOP [emit-job-header : Print node information]
2026-05-19 16:20:44.628583 | localhost | ok:
2026-05-19 16:20:44.628752 | localhost | # Node Information
2026-05-19 16:20:44.628796 | localhost | Inventory Hostname: instance
2026-05-19 16:20:44.628825 | localhost | Hostname: np0000174873
2026-05-19 16:20:44.628848 | localhost | Username: zuul
2026-05-19 16:20:44.628875 | localhost | Distro: Ubuntu 22.04
2026-05-19 16:20:44.628898 | localhost | Provider: yul1
2026-05-19 16:20:44.628920 | localhost | Region: ca-ymq-1
2026-05-19 16:20:44.628942 | localhost | Label: ubuntu-jammy-16
2026-05-19 16:20:44.628963 | localhost | Product Name: OpenStack Nova
2026-05-19 16:20:44.628984 | localhost | Interface IP: 199.204.45.229
2026-05-19 16:20:44.637934 | 
2026-05-19 16:20:44.638023 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-05-19 16:20:45.028245 | localhost -> localhost | changed
2026-05-19 16:20:45.033353 | 
2026-05-19 16:20:45.033427 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-05-19 16:20:45.977893 | localhost -> localhost | changed
2026-05-19 16:20:45.988083 | 
2026-05-19 16:20:45.988162 | PLAY [all]
2026-05-19 16:20:45.996494 | 
2026-05-19 16:20:45.996581 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-05-19 16:20:46.255574 | instance -> localhost | ok
2026-05-19 16:20:46.264110 | 
2026-05-19 16:20:46.264257 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-05-19 16:20:46.298678 | instance | ok
2026-05-19 16:20:46.314895 | instance | included: /var/lib/zuul/builds/7ac624d8887c4573ad8d97a46c1fcf13/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-05-19 16:20:46.321094 | 
2026-05-19 16:20:46.321228 | TASK [add-build-sshkey : Create Temp SSH key]
2026-05-19 16:20:47.503968 | instance -> localhost | Generating public/private rsa key pair.
2026-05-19 16:20:47.504189 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/7ac624d8887c4573ad8d97a46c1fcf13/work/7ac624d8887c4573ad8d97a46c1fcf13_id_rsa
2026-05-19 16:20:47.504250 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/7ac624d8887c4573ad8d97a46c1fcf13/work/7ac624d8887c4573ad8d97a46c1fcf13_id_rsa.pub
2026-05-19 16:20:47.504300 | instance -> localhost | The key fingerprint is:
2026-05-19 16:20:47.504340 | instance -> localhost | SHA256:51s7yY5bE/J7xcYYBPrNFHzLAvyyCW6l212cb4On37A zuul-build-sshkey
2026-05-19 16:20:47.504401 | instance -> localhost | The key's randomart image is:
2026-05-19 16:20:47.504471 | instance -> localhost | +---[RSA 3072]----+
2026-05-19 16:20:47.504527 | instance -> localhost | |          . .o.  |
2026-05-19 16:20:47.504571 | instance -> localhost | |           +  o..|
2026-05-19 16:20:47.504612 | instance -> localhost | |          . o.o..|
2026-05-19 16:20:47.504653 | instance -> localhost | |         . + *.o |
2026-05-19 16:20:47.504692 | instance -> localhost | |        S * * ==.|
2026-05-19 16:20:47.504732 | instance -> localhost | |         * = ..+=|
2026-05-19 16:20:47.504768 | instance -> localhost | |        . +.*.+o.|
2026-05-19 16:20:47.504808 | instance -> localhost | |         . *+*.=+|
2026-05-19 16:20:47.504852 | instance -> localhost | |          +o++Eoo|
2026-05-19 16:20:47.504894 | instance -> localhost | +----[SHA256]-----+
2026-05-19 16:20:47.504986 | instance -> localhost | ok: Runtime: 0:00:00.725325
2026-05-19 16:20:47.512054 | 
2026-05-19 16:20:47.512157 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-05-19 16:20:47.545753 | instance | ok
2026-05-19 16:20:47.556982 | instance | included: /var/lib/zuul/builds/7ac624d8887c4573ad8d97a46c1fcf13/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-05-19 16:20:47.564664 | 
2026-05-19 16:20:47.564748 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-05-19 16:20:47.588641 | instance | skipping: Conditional result was False
2026-05-19 16:20:47.597137 | 
2026-05-19 16:20:47.597240 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-05-19 16:20:48.070369 | instance | changed
2026-05-19 16:20:48.077835 | 
2026-05-19 16:20:48.077961 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-05-19 16:20:48.264202 | instance | ok
2026-05-19 16:20:48.304811 | 
2026-05-19 16:20:48.305058 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-05-19 16:20:48.763103 | instance | changed
2026-05-19 16:20:48.778746 | 
2026-05-19 16:20:48.779099 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-05-19 16:20:49.228900 | instance | changed
2026-05-19 16:20:49.236755 | 
2026-05-19 16:20:49.236866 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-05-19 16:20:49.261577 | instance | skipping: Conditional result was False
2026-05-19 16:20:49.271810 | 
2026-05-19 16:20:49.271935 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-05-19 16:20:49.591044 | instance -> localhost | changed
2026-05-19 16:20:49.610657 | 
2026-05-19 16:20:49.611181 | TASK [add-build-sshkey : Add back temp key]
2026-05-19 16:20:49.899726 | instance -> localhost | Identity added: /var/lib/zuul/builds/7ac624d8887c4573ad8d97a46c1fcf13/work/7ac624d8887c4573ad8d97a46c1fcf13_id_rsa (zuul-build-sshkey)
2026-05-19 16:20:49.899939 | instance -> localhost | ok: Runtime: 0:00:00.013404
2026-05-19 16:20:49.905986 | 
2026-05-19 16:20:49.906048 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-05-19 16:20:50.173221 | instance | ok
2026-05-19 16:20:50.179024 | 
2026-05-19 16:20:50.179112 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-05-19 16:20:50.202821 | instance | skipping: Conditional result was False
2026-05-19 16:20:50.217756 | 
2026-05-19 16:20:50.217880 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-05-19 16:20:50.507284 | instance | ok
2026-05-19 16:20:50.514949 | 
2026-05-19 16:20:50.515037 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-05-19 16:20:52.540837 | instance | Output suppressed because no_log was given
2026-05-19 16:20:52.550449 | 
2026-05-19 16:20:52.550537 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-05-19 16:20:52.751897 | instance | ok: "logs"
2026-05-19 16:20:52.752184 | instance | ok: All items complete
2026-05-19 16:20:52.752228 | 
2026-05-19 16:20:52.913013 | instance | ok: "artifacts"
2026-05-19 16:20:53.141276 | instance | ok: "docs"
2026-05-19 16:20:53.157464 | 
2026-05-19 16:20:53.157627 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-05-19 16:20:53.346449 | instance | changed: "logs"
2026-05-19 16:20:53.507475 | instance | changed: "artifacts"
2026-05-19 16:20:53.683346 | instance | changed: "docs"
2026-05-19 16:20:53.759169 | 
2026-05-19 16:20:53.759267 | PLAY RECAP
2026-05-19 16:20:53.759313 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-05-19 16:20:53.759342 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-05-19 16:20:53.759363 | 
2026-05-19 16:20:53.875740 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-05-19 16:20:53.882498 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-05-19 16:20:54.556461 | 
2026-05-19 16:20:54.556599 | PLAY [all]
2026-05-19 16:20:54.568298 | 
2026-05-19 16:20:54.568423 | TASK [setup-uv : Extract archive]
2026-05-19 16:20:57.035538 | instance | changed
2026-05-19 16:20:57.044138 | 
2026-05-19 16:20:57.044305 | TASK [setup-uv : Print version]
2026-05-19 16:20:57.391318 | instance | uv 0.8.13
2026-05-19 16:20:57.586882 | instance | ok: Runtime: 0:00:00.011122
2026-05-19 16:20:57.597290 | 
2026-05-19 16:20:57.597374 | TASK [Install Ansible collection siblings]
2026-05-19 16:20:57.661401 | instance | ERROR
2026-05-19 16:20:57.661728 | instance | {
2026-05-19 16:20:57.661765 | 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:20:57.661789 | instance | }
failure
2026-05-19 16:20:57.662966 | 
2026-05-19 16:20:57.663015 | PLAY RECAP
2026-05-19 16:20:57.663069 | instance | ok: 2 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-05-19 16:20:57.663093 | 
2026-05-19 16:20:57.899162 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-05-19 16:20:57.914513 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-05-19 16:20:58.625156 | 
2026-05-19 16:20:58.625287 | PLAY [all]
2026-05-19 16:20:58.636699 | 
2026-05-19 16:20:58.636795 | TASK [fetch-output : Set log path for multiple nodes]
2026-05-19 16:20:58.681107 | instance | skipping: Conditional result was False
2026-05-19 16:20:58.688259 | 
2026-05-19 16:20:58.688399 | TASK [fetch-output : Set log path for single node]
2026-05-19 16:20:58.734229 | instance | ok
2026-05-19 16:20:58.739782 | 
2026-05-19 16:20:58.739902 | LOOP [fetch-output : Ensure local output dirs]
2026-05-19 16:20:59.144663 | instance -> localhost | ok: "/var/lib/zuul/builds/7ac624d8887c4573ad8d97a46c1fcf13/work/logs"
2026-05-19 16:20:59.342701 | instance -> localhost | changed: "/var/lib/zuul/builds/7ac624d8887c4573ad8d97a46c1fcf13/work/artifacts"
2026-05-19 16:20:59.562111 | instance -> localhost | changed: "/var/lib/zuul/builds/7ac624d8887c4573ad8d97a46c1fcf13/work/docs"
2026-05-19 16:20:59.579742 | 
2026-05-19 16:20:59.579863 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-05-19 16:21:00.200712 | instance | ok
2026-05-19 16:21:00.200942 | instance | changed: All items complete
2026-05-19 16:21:00.201030 | 
2026-05-19 16:21:00.631409 | instance | changed: .d..t...... ./
2026-05-19 16:21:01.105109 | instance | changed: .d..t...... ./
2026-05-19 16:21:01.118876 | 
2026-05-19 16:21:01.118996 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-05-19 16:21:01.538502 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.005018
2026-05-19 16:21:01.779218 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.008635
2026-05-19 16:21:01.802076 | 
2026-05-19 16:21:01.802297 | PLAY [all]
2026-05-19 16:21:01.811663 | 
2026-05-19 16:21:01.811827 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-05-19 16:21:02.244185 | instance | changed
2026-05-19 16:21:02.251266 | 
2026-05-19 16:21:02.251375 | PLAY RECAP
2026-05-19 16:21:02.251433 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-05-19 16:21:02.251458 | 
2026-05-19 16:21:02.376916 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-05-19 16:21:02.392012 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-05-19 16:21:03.065177 | 
2026-05-19 16:21:03.065652 | PLAY [localhost]
2026-05-19 16:21:03.077415 | 
2026-05-19 16:21:03.077582 | TASK [Generate Zuul manifest]
2026-05-19 16:21:03.099835 | localhost | ok
2026-05-19 16:21:03.117166 | 
2026-05-19 16:21:03.117309 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-05-19 16:21:03.481436 | localhost | changed
2026-05-19 16:21:03.494241 | 
2026-05-19 16:21:03.494349 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-05-19 16:21:03.523800 | localhost | ok
2026-05-19 16:21:03.532079 | 
2026-05-19 16:21:03.532205 | TASK [Upload logs]
2026-05-19 16:21:03.554566 | localhost | ok
2026-05-19 16:21:03.677324 | 
2026-05-19 16:21:03.677481 | TASK [Set zuul-log-path fact]
2026-05-19 16:21:03.699811 | localhost | ok
2026-05-19 16:21:03.711421 | 
2026-05-19 16:21:03.714627 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-05-19 16:21:03.746767 | localhost | ok
2026-05-19 16:21:03.753854 | 
2026-05-19 16:21:03.753936 | TASK [upload-logs : Create log directories]
2026-05-19 16:21:04.144049 | localhost | changed
2026-05-19 16:21:04.149980 | 
2026-05-19 16:21:04.150100 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-05-19 16:21:04.629391 | localhost -> localhost | ok: Runtime: 0:00:00.005211
2026-05-19 16:21:04.636615 | 
2026-05-19 16:21:04.636743 | TASK [upload-logs : Upload logs to log server]
2026-05-19 16:21:05.142421 | localhost | Output suppressed because no_log was given
2026-05-19 16:21:05.146425 | 
2026-05-19 16:21:05.146505 | LOOP [upload-logs : Compress console log and json output]
2026-05-19 16:21:05.189770 | localhost | skipping: Conditional result was False
2026-05-19 16:21:05.198438 | localhost | skipping: Conditional result was False
2026-05-19 16:21:05.215629 | 
2026-05-19 16:21:05.215988 | LOOP [upload-logs : Upload compressed console log and json output]
2026-05-19 16:21:05.268407 | localhost | skipping: Conditional result was False
2026-05-19 16:21:05.268892 | 
2026-05-19 16:21:05.273555 | localhost | skipping: Conditional result was False
2026-05-19 16:21:05.282470 | 
2026-05-19 16:21:05.282739 | LOOP [upload-logs : Upload console log and json output]