2026-05-19 16:22:59.606253 | Job console starting
2026-05-19 16:22:59.619834 | Updating git repos
2026-05-19 16:22:59.749292 | Cloning repos into workspace
2026-05-19 16:23:17.817726 | Restoring repo states
2026-05-19 16:23:17.968580 | Merging changes
2026-05-19 16:23:19.496491 | Checking out repos
2026-05-19 16:23:20.094500 | Preparing playbooks
2026-05-19 16:23:23.559909 | Running Ansible setup
2026-05-19 16:23:27.068226 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-05-19 16:23:27.688645 | 
2026-05-19 16:23:27.688788 | PLAY [localhost]
2026-05-19 16:23:27.697477 | 
2026-05-19 16:23:27.697587 | TASK [Gathering Facts]
2026-05-19 16:23:28.556614 | localhost | ok
2026-05-19 16:23:28.567682 | 
2026-05-19 16:23:28.567824 | TASK [Setup log path fact]
2026-05-19 16:23:28.586950 | localhost | ok
2026-05-19 16:23:28.601774 | 
2026-05-19 16:23:28.602070 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-05-19 16:23:28.630487 | localhost | ok
2026-05-19 16:23:28.638943 | 
2026-05-19 16:23:28.639044 | TASK [emit-job-header : Print job information]
2026-05-19 16:23:28.678162 | # Job Information
2026-05-19 16:23:28.678364 | Ansible Version: 2.16.18
2026-05-19 16:23:28.678410 | Job: atmosphere-molecule-keycloak
2026-05-19 16:23:28.678444 | Pipeline: check
2026-05-19 16:23:28.678472 | Executor: 2d72f0692154
2026-05-19 16:23:28.678501 | Triggered by: https://github.com/vexxhost/atmosphere/pull/3945
2026-05-19 16:23:28.678532 | Event ID: 8635b180-539e-11f1-8f4a-8b53e1584e5c
2026-05-19 16:23:28.682363 | 
2026-05-19 16:23:28.682477 | LOOP [emit-job-header : Print node information]
2026-05-19 16:23:28.775775 | localhost | ok:
2026-05-19 16:23:28.775918 | localhost | # Node Information
2026-05-19 16:23:28.775948 | localhost | Inventory Hostname: instance
2026-05-19 16:23:28.775970 | localhost | Hostname: np0000174884
2026-05-19 16:23:28.775991 | localhost | Username: zuul
2026-05-19 16:23:28.776014 | localhost | Distro: Ubuntu 22.04
2026-05-19 16:23:28.776035 | localhost | Provider: yul1
2026-05-19 16:23:28.776055 | localhost | Region: ca-ymq-1
2026-05-19 16:23:28.776074 | localhost | Label: ubuntu-jammy
2026-05-19 16:23:28.776093 | localhost | Product Name: OpenStack Nova
2026-05-19 16:23:28.776112 | localhost | Interface IP: 199.204.45.30
2026-05-19 16:23:28.788904 | 
2026-05-19 16:23:28.804049 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-05-19 16:23:29.184243 | localhost -> localhost | changed
2026-05-19 16:23:29.191923 | 
2026-05-19 16:23:29.192059 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-05-19 16:23:30.056427 | localhost -> localhost | changed
2026-05-19 16:23:30.062648 | 
2026-05-19 16:23:30.062708 | PLAY [all]
2026-05-19 16:23:30.070251 | 
2026-05-19 16:23:30.070323 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-05-19 16:23:30.317653 | instance -> localhost | ok
2026-05-19 16:23:30.325516 | 
2026-05-19 16:23:30.325631 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-05-19 16:23:30.351106 | instance | ok
2026-05-19 16:23:30.366366 | instance | included: /var/lib/zuul/builds/cd3933f3e65e4d65bf78cd4ed756d574/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-05-19 16:23:30.376296 | 
2026-05-19 16:23:30.376382 | TASK [add-build-sshkey : Create Temp SSH key]
2026-05-19 16:23:31.311098 | instance -> localhost | Generating public/private rsa key pair.
2026-05-19 16:23:31.311588 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/cd3933f3e65e4d65bf78cd4ed756d574/work/cd3933f3e65e4d65bf78cd4ed756d574_id_rsa
2026-05-19 16:23:31.311653 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/cd3933f3e65e4d65bf78cd4ed756d574/work/cd3933f3e65e4d65bf78cd4ed756d574_id_rsa.pub
2026-05-19 16:23:31.311697 | instance -> localhost | The key fingerprint is:
2026-05-19 16:23:31.311740 | instance -> localhost | SHA256:6b5AAYX+2aQMJDFk4uTDSw1RVnczGlI0vXlhKn/Wu/Q zuul-build-sshkey
2026-05-19 16:23:31.311796 | instance -> localhost | The key's randomart image is:
2026-05-19 16:23:31.311831 | instance -> localhost | +---[RSA 3072]----+
2026-05-19 16:23:31.311873 | instance -> localhost | |.=B+o=+*.+       |
2026-05-19 16:23:31.311908 | instance -> localhost | |=o=.o.o =.oo     |
2026-05-19 16:23:31.311941 | instance -> localhost | | * =  ..  = .    |
2026-05-19 16:23:31.311973 | instance -> localhost | |. o o  o.= .     |
2026-05-19 16:23:31.312003 | instance -> localhost | | .   +.=S . .    |
2026-05-19 16:23:31.312033 | instance -> localhost | |     .=... o .   |
2026-05-19 16:23:31.312062 | instance -> localhost | |      . . o  ..  |
2026-05-19 16:23:31.312158 | instance -> localhost | |       o    ...  |
2026-05-19 16:23:31.312227 | instance -> localhost | |        o.   ..E |
2026-05-19 16:23:31.312293 | instance -> localhost | +----[SHA256]-----+
2026-05-19 16:23:31.312413 | instance -> localhost | ok: Runtime: 0:00:00.527151
2026-05-19 16:23:31.320769 | 
2026-05-19 16:23:31.320939 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-05-19 16:23:31.344974 | instance | ok
2026-05-19 16:23:31.357074 | instance | included: /var/lib/zuul/builds/cd3933f3e65e4d65bf78cd4ed756d574/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-05-19 16:23:31.364933 | 
2026-05-19 16:23:31.365042 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-05-19 16:23:31.388816 | instance | skipping: Conditional result was False
2026-05-19 16:23:31.399754 | 
2026-05-19 16:23:31.399888 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-05-19 16:23:31.837750 | instance | changed
2026-05-19 16:23:31.844826 | 
2026-05-19 16:23:31.844912 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-05-19 16:23:32.026669 | instance | ok
2026-05-19 16:23:32.037532 | 
2026-05-19 16:23:32.039587 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-05-19 16:23:32.503074 | instance | changed
2026-05-19 16:23:32.511423 | 
2026-05-19 16:23:32.511569 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-05-19 16:23:32.978764 | instance | changed
2026-05-19 16:23:32.987087 | 
2026-05-19 16:23:32.987189 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-05-19 16:23:33.010711 | instance | skipping: Conditional result was False
2026-05-19 16:23:33.022234 | 
2026-05-19 16:23:33.022335 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-05-19 16:23:33.401388 | instance -> localhost | changed
2026-05-19 16:23:33.418470 | 
2026-05-19 16:23:33.418612 | TASK [add-build-sshkey : Add back temp key]
2026-05-19 16:23:33.727007 | instance -> localhost | Identity added: /var/lib/zuul/builds/cd3933f3e65e4d65bf78cd4ed756d574/work/cd3933f3e65e4d65bf78cd4ed756d574_id_rsa (zuul-build-sshkey)
2026-05-19 16:23:33.727187 | instance -> localhost | ok: Runtime: 0:00:00.014567
2026-05-19 16:23:33.732313 | 
2026-05-19 16:23:33.732380 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-05-19 16:23:34.037391 | instance | ok
2026-05-19 16:23:34.044520 | 
2026-05-19 16:23:34.044643 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-05-19 16:23:34.068713 | instance | skipping: Conditional result was False
2026-05-19 16:23:34.083438 | 
2026-05-19 16:23:34.083553 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-05-19 16:23:34.417369 | instance | ok
2026-05-19 16:23:34.521815 | 
2026-05-19 16:23:34.525143 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-05-19 16:23:36.464930 | instance | Output suppressed because no_log was given
2026-05-19 16:23:36.484986 | 
2026-05-19 16:23:36.485106 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-05-19 16:23:36.672114 | instance | ok: "logs"
2026-05-19 16:23:36.672421 | instance | ok: All items complete
2026-05-19 16:23:36.672466 | 
2026-05-19 16:23:36.823184 | instance | ok: "artifacts"
2026-05-19 16:23:36.976115 | instance | ok: "docs"
2026-05-19 16:23:37.001128 | 
2026-05-19 16:23:37.001278 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-05-19 16:23:37.181559 | instance | changed: "logs"
2026-05-19 16:23:37.335253 | instance | changed: "artifacts"
2026-05-19 16:23:37.489787 | instance | changed: "docs"
2026-05-19 16:23:37.505251 | 
2026-05-19 16:23:37.505350 | PLAY RECAP
2026-05-19 16:23:37.505396 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-05-19 16:23:37.505424 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-05-19 16:23:37.505445 | 
2026-05-19 16:23:37.640090 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-05-19 16:23:37.646059 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-05-19 16:23:38.328017 | 
2026-05-19 16:23:38.328178 | PLAY [all]
2026-05-19 16:23:38.342776 | 
2026-05-19 16:23:38.342916 | TASK [setup-uv : Extract archive]
2026-05-19 16:23:40.597275 | instance | changed
2026-05-19 16:23:40.604264 | 
2026-05-19 16:23:40.604422 | TASK [setup-uv : Print version]
2026-05-19 16:23:40.948420 | instance | uv 0.8.13
2026-05-19 16:23:41.149222 | instance | ok: Runtime: 0:00:00.013539
2026-05-19 16:23:41.162217 | 
2026-05-19 16:23:41.162345 | TASK [Install Ansible collection siblings]
2026-05-19 16:23:41.226122 | instance | ERROR
2026-05-19 16:23:41.226389 | instance | {
2026-05-19 16:23:41.226435 | 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:41.226470 | instance | }
failure
2026-05-19 16:23:41.229066 | 
2026-05-19 16:23:41.229142 | PLAY RECAP
2026-05-19 16:23:41.229221 | instance | ok: 2 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-05-19 16:23:41.229266 | 
2026-05-19 16:23:41.349113 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-05-19 16:23:41.352132 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-05-19 16:23:42.003019 | 
2026-05-19 16:23:42.003142 | PLAY [all]
2026-05-19 16:23:42.015148 | 
2026-05-19 16:23:42.015253 | TASK [fetch-output : Set log path for multiple nodes]
2026-05-19 16:23:42.050751 | instance | skipping: Conditional result was False
2026-05-19 16:23:42.058253 | 
2026-05-19 16:23:42.058519 | TASK [fetch-output : Set log path for single node]
2026-05-19 16:23:42.091379 | instance | ok
2026-05-19 16:23:42.098657 | 
2026-05-19 16:23:42.098831 | LOOP [fetch-output : Ensure local output dirs]
2026-05-19 16:23:42.488873 | instance -> localhost | ok: "/var/lib/zuul/builds/cd3933f3e65e4d65bf78cd4ed756d574/work/logs"
2026-05-19 16:23:42.718567 | instance -> localhost | changed: "/var/lib/zuul/builds/cd3933f3e65e4d65bf78cd4ed756d574/work/artifacts"
2026-05-19 16:23:43.001785 | instance -> localhost | changed: "/var/lib/zuul/builds/cd3933f3e65e4d65bf78cd4ed756d574/work/docs"
2026-05-19 16:23:43.018353 | 
2026-05-19 16:23:43.018532 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-05-19 16:23:43.668192 | instance | ok
2026-05-19 16:23:43.668454 | instance | changed: All items complete
2026-05-19 16:23:43.668498 | 
2026-05-19 16:23:44.137698 | instance | changed: .d..t...... ./
2026-05-19 16:23:44.606567 | instance | changed: .d..t...... ./
2026-05-19 16:23:44.626990 | 
2026-05-19 16:23:44.627131 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-05-19 16:23:45.086671 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.007280
2026-05-19 16:23:45.335096 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.007304
2026-05-19 16:23:45.357362 | 
2026-05-19 16:23:45.357523 | PLAY [all]
2026-05-19 16:23:45.365192 | 
2026-05-19 16:23:45.365337 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-05-19 16:23:45.867976 | instance | changed
2026-05-19 16:23:45.875990 | 
2026-05-19 16:23:45.876068 | PLAY RECAP
2026-05-19 16:23:45.876132 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-05-19 16:23:45.876163 | 
2026-05-19 16:23:46.026317 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-05-19 16:23:46.033161 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-05-19 16:23:46.697559 | 
2026-05-19 16:23:46.697717 | PLAY [localhost]
2026-05-19 16:23:46.709467 | 
2026-05-19 16:23:46.709615 | TASK [Generate Zuul manifest]
2026-05-19 16:23:46.732827 | localhost | ok
2026-05-19 16:23:46.749579 | 
2026-05-19 16:23:46.749722 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-05-19 16:23:47.124970 | localhost | changed
2026-05-19 16:23:47.136338 | 
2026-05-19 16:23:47.136495 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-05-19 16:23:47.165670 | localhost | ok
2026-05-19 16:23:47.175169 | 
2026-05-19 16:23:47.175276 | TASK [Upload logs]
2026-05-19 16:23:47.194131 | localhost | ok
2026-05-19 16:23:47.258664 | 
2026-05-19 16:23:47.258783 | TASK [Set zuul-log-path fact]
2026-05-19 16:23:47.280978 | localhost | ok
2026-05-19 16:23:47.294287 | 
2026-05-19 16:23:47.294373 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-05-19 16:23:47.336435 | localhost | ok
2026-05-19 16:23:47.347204 | 
2026-05-19 16:23:47.347373 | TASK [upload-logs : Create log directories]
2026-05-19 16:23:47.784822 | localhost | changed
2026-05-19 16:23:47.792309 | 
2026-05-19 16:23:47.792444 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-05-19 16:23:48.241723 | localhost -> localhost | ok: Runtime: 0:00:00.005460
2026-05-19 16:23:48.303170 | 
2026-05-19 16:23:48.303286 | TASK [upload-logs : Upload logs to log server]
2026-05-19 16:23:48.753230 | localhost | Output suppressed because no_log was given
2026-05-19 16:23:48.759538 | 
2026-05-19 16:23:48.759608 | LOOP [upload-logs : Compress console log and json output]
2026-05-19 16:23:48.805520 | localhost | skipping: Conditional result was False
2026-05-19 16:23:48.813756 | localhost | skipping: Conditional result was False
2026-05-19 16:23:48.823166 | 
2026-05-19 16:23:48.823323 | LOOP [upload-logs : Upload compressed console log and json output]
2026-05-19 16:23:48.862364 | localhost | skipping: Conditional result was False
2026-05-19 16:23:48.862821 | 
2026-05-19 16:23:48.866793 | localhost | skipping: Conditional result was False
2026-05-19 16:23:48.878169 | 
2026-05-19 16:23:48.878374 | LOOP [upload-logs : Upload console log and json output]