2026-05-19 16:17:39.986447 | Job console starting
2026-05-19 16:17:39.997993 | Updating git repos
2026-05-19 16:17:40.102160 | Cloning repos into workspace
2026-05-19 16:17:43.529454 | Restoring repo states
2026-05-19 16:17:43.549546 | Merging changes
2026-05-19 16:17:46.295953 | Checking out repos
2026-05-19 16:17:47.215695 | Preparing playbooks
2026-05-19 16:18:06.433872 | Running Ansible setup
2026-05-19 16:18:12.443583 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-05-19 16:18:13.115257 | 
2026-05-19 16:18:13.269953 | PLAY [localhost]
2026-05-19 16:18:13.284043 | 
2026-05-19 16:18:13.284158 | TASK [Gathering Facts]
2026-05-19 16:18:14.196449 | localhost | ok
2026-05-19 16:18:14.208785 | 
2026-05-19 16:18:14.208903 | TASK [Setup log path fact]
2026-05-19 16:18:14.228743 | localhost | ok
2026-05-19 16:18:14.240873 | 
2026-05-19 16:18:14.241007 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-05-19 16:18:14.269719 | localhost | ok
2026-05-19 16:18:14.276497 | 
2026-05-19 16:18:14.276581 | TASK [emit-job-header : Print job information]
2026-05-19 16:18:14.316633 | # Job Information
2026-05-19 16:18:14.316820 | Ansible Version: 2.16.18
2026-05-19 16:18:14.316868 | Job: atmosphere-molecule-keycloak
2026-05-19 16:18:14.316904 | Pipeline: check
2026-05-19 16:18:14.316956 | Executor: 2d72f0692154
2026-05-19 16:18:14.317010 | Triggered by: https://github.com/vexxhost/atmosphere/pull/3945
2026-05-19 16:18:14.317050 | Event ID: 1d651ce0-539e-11f1-8754-a319db16026e
2026-05-19 16:18:14.320945 | 
2026-05-19 16:18:14.321053 | LOOP [emit-job-header : Print node information]
2026-05-19 16:18:14.420224 | localhost | ok:
2026-05-19 16:18:14.420413 | localhost | # Node Information
2026-05-19 16:18:14.420453 | localhost | Inventory Hostname: instance
2026-05-19 16:18:14.420490 | localhost | Hostname: np0000174871
2026-05-19 16:18:14.420560 | localhost | Username: zuul
2026-05-19 16:18:14.420593 | localhost | Distro: Ubuntu 22.04
2026-05-19 16:18:14.420621 | localhost | Provider: yul1
2026-05-19 16:18:14.420647 | localhost | Region: ca-ymq-1
2026-05-19 16:18:14.420673 | localhost | Label: ubuntu-jammy
2026-05-19 16:18:14.420698 | localhost | Product Name: OpenStack Nova
2026-05-19 16:18:14.420724 | localhost | Interface IP: 199.204.45.109
2026-05-19 16:18:14.437430 | 
2026-05-19 16:18:14.437564 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-05-19 16:18:14.828535 | localhost -> localhost | changed
2026-05-19 16:18:14.836696 | 
2026-05-19 16:18:14.836845 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-05-19 16:18:17.049601 | localhost -> localhost | changed
2026-05-19 16:18:17.059715 | 
2026-05-19 16:18:17.059810 | PLAY [all]
2026-05-19 16:18:17.068878 | 
2026-05-19 16:18:17.068982 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-05-19 16:18:17.288030 | instance -> localhost | ok
2026-05-19 16:18:17.293546 | 
2026-05-19 16:18:17.293607 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-05-19 16:18:17.324341 | instance | ok
2026-05-19 16:18:17.341284 | instance | included: /var/lib/zuul/builds/c12d483b1bcd403bb45a07ced96802c7/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-05-19 16:18:17.349488 | 
2026-05-19 16:18:17.349572 | TASK [add-build-sshkey : Create Temp SSH key]
2026-05-19 16:18:19.039756 | instance -> localhost | Generating public/private rsa key pair.
2026-05-19 16:18:19.039928 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/c12d483b1bcd403bb45a07ced96802c7/work/c12d483b1bcd403bb45a07ced96802c7_id_rsa
2026-05-19 16:18:19.039958 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/c12d483b1bcd403bb45a07ced96802c7/work/c12d483b1bcd403bb45a07ced96802c7_id_rsa.pub
2026-05-19 16:18:19.039986 | instance -> localhost | The key fingerprint is:
2026-05-19 16:18:19.040017 | instance -> localhost | SHA256:yNTH+Ujfp+vaEY7E6GtKTyzgKGRStDFOQcJG3y1hx+I zuul-build-sshkey
2026-05-19 16:18:19.040061 | instance -> localhost | The key's randomart image is:
2026-05-19 16:18:19.040083 | instance -> localhost | +---[RSA 3072]----+
2026-05-19 16:18:19.040110 | instance -> localhost | |+oB. o..         |
2026-05-19 16:18:19.040131 | instance -> localhost | | B.+o.+o . .     |
2026-05-19 16:18:19.040152 | instance -> localhost | |. +..oo.. =      |
2026-05-19 16:18:19.040171 | instance -> localhost | | .   E.. o * .   |
2026-05-19 16:18:19.040191 | instance -> localhost | |. o   + S o = o .|
2026-05-19 16:18:19.040211 | instance -> localhost | | +   o . o . o + |
2026-05-19 16:18:19.040230 | instance -> localhost | |  . . . o + . +  |
2026-05-19 16:18:19.040249 | instance -> localhost | |   .   . +.. . o |
2026-05-19 16:18:19.040271 | instance -> localhost | |        .oo .o+  |
2026-05-19 16:18:19.040291 | instance -> localhost | +----[SHA256]-----+
2026-05-19 16:18:19.040346 | instance -> localhost | ok: Runtime: 0:00:01.269927
2026-05-19 16:18:19.046227 | 
2026-05-19 16:18:19.046308 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-05-19 16:18:19.078085 | instance | ok
2026-05-19 16:18:19.087677 | instance | included: /var/lib/zuul/builds/c12d483b1bcd403bb45a07ced96802c7/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-05-19 16:18:19.096419 | 
2026-05-19 16:18:19.096497 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-05-19 16:18:19.120699 | instance | skipping: Conditional result was False
2026-05-19 16:18:19.129898 | 
2026-05-19 16:18:19.129987 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-05-19 16:18:19.574789 | instance | changed
2026-05-19 16:18:19.582783 | 
2026-05-19 16:18:19.582941 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-05-19 16:18:19.791807 | instance | ok
2026-05-19 16:18:19.798182 | 
2026-05-19 16:18:19.798319 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-05-19 16:18:20.316396 | instance | changed
2026-05-19 16:18:20.322316 | 
2026-05-19 16:18:20.322394 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-05-19 16:18:20.787288 | instance | changed
2026-05-19 16:18:20.792962 | 
2026-05-19 16:18:20.793088 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-05-19 16:18:20.816607 | instance | skipping: Conditional result was False
2026-05-19 16:18:20.824763 | 
2026-05-19 16:18:20.824881 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-05-19 16:18:21.497177 | instance -> localhost | changed
2026-05-19 16:18:21.512105 | 
2026-05-19 16:18:21.512208 | TASK [add-build-sshkey : Add back temp key]
2026-05-19 16:18:21.801156 | instance -> localhost | Identity added: /var/lib/zuul/builds/c12d483b1bcd403bb45a07ced96802c7/work/c12d483b1bcd403bb45a07ced96802c7_id_rsa (zuul-build-sshkey)
2026-05-19 16:18:21.801364 | instance -> localhost | ok: Runtime: 0:00:00.015007
2026-05-19 16:18:21.807891 | 
2026-05-19 16:18:21.807985 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-05-19 16:18:22.125490 | instance | ok
2026-05-19 16:18:22.130979 | 
2026-05-19 16:18:22.131058 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-05-19 16:18:22.156081 | instance | skipping: Conditional result was False
2026-05-19 16:18:22.172546 | 
2026-05-19 16:18:22.172665 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-05-19 16:18:22.524511 | instance | ok
2026-05-19 16:18:23.640850 | 
2026-05-19 16:18:23.640982 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-05-19 16:18:25.667598 | instance | Output suppressed because no_log was given
2026-05-19 16:18:25.681555 | 
2026-05-19 16:18:25.681667 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-05-19 16:18:25.869442 | instance | ok: "logs"
2026-05-19 16:18:25.869691 | instance | ok: All items complete
2026-05-19 16:18:25.869733 | 
2026-05-19 16:18:26.047322 | instance | ok: "artifacts"
2026-05-19 16:18:26.206615 | instance | ok: "docs"
2026-05-19 16:18:26.219702 | 
2026-05-19 16:18:26.219828 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-05-19 16:18:26.401208 | instance | changed: "logs"
2026-05-19 16:18:26.559397 | instance | changed: "artifacts"
2026-05-19 16:18:26.712071 | instance | changed: "docs"
2026-05-19 16:18:26.731470 | 
2026-05-19 16:18:26.731565 | PLAY RECAP
2026-05-19 16:18:26.731615 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-05-19 16:18:26.731644 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-05-19 16:18:26.731667 | 
2026-05-19 16:18:26.852496 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-05-19 16:18:26.859072 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-05-19 16:18:27.501914 | 
2026-05-19 16:18:27.539630 | PLAY [all]
2026-05-19 16:18:27.564457 | 
2026-05-19 16:18:27.564654 | TASK [setup-uv : Extract archive]
2026-05-19 16:18:30.953520 | instance | changed
2026-05-19 16:18:30.961598 | 
2026-05-19 16:18:30.961748 | TASK [setup-uv : Print version]
2026-05-19 16:18:31.315022 | instance | uv 0.8.13
2026-05-19 16:18:31.498643 | instance | ok: Runtime: 0:00:00.011355
2026-05-19 16:18:31.509876 | 
2026-05-19 16:18:31.509986 | TASK [Install Ansible collection siblings]
2026-05-19 16:18:31.575198 | instance | ERROR
2026-05-19 16:18:32.273836 | instance | {
2026-05-19 16:18:32.274002 | 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:32.274076 | instance | }
failure
2026-05-19 16:18:32.275764 | 
2026-05-19 16:18:32.275877 | PLAY RECAP
2026-05-19 16:18:32.275977 | instance | ok: 2 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-05-19 16:18:32.276029 | 
2026-05-19 16:18:32.399476 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-05-19 16:18:32.404199 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-05-19 16:18:33.040050 | 
2026-05-19 16:18:33.040170 | PLAY [all]
2026-05-19 16:18:33.052539 | 
2026-05-19 16:18:33.052651 | TASK [fetch-output : Set log path for multiple nodes]
2026-05-19 16:18:33.098776 | instance | skipping: Conditional result was False
2026-05-19 16:18:33.107784 | 
2026-05-19 16:18:33.107907 | TASK [fetch-output : Set log path for single node]
2026-05-19 16:18:33.150500 | instance | ok
2026-05-19 16:18:33.157062 | 
2026-05-19 16:18:33.157139 | LOOP [fetch-output : Ensure local output dirs]
2026-05-19 16:18:33.818133 | instance -> localhost | ok: "/var/lib/zuul/builds/c12d483b1bcd403bb45a07ced96802c7/work/logs"
2026-05-19 16:18:34.059210 | instance -> localhost | changed: "/var/lib/zuul/builds/c12d483b1bcd403bb45a07ced96802c7/work/artifacts"
2026-05-19 16:18:34.289728 | instance -> localhost | changed: "/var/lib/zuul/builds/c12d483b1bcd403bb45a07ced96802c7/work/docs"
2026-05-19 16:18:34.307725 | 
2026-05-19 16:18:34.307841 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-05-19 16:18:34.950393 | instance | ok
2026-05-19 16:18:35.080915 | instance | changed: All items complete
2026-05-19 16:18:35.081073 | 
2026-05-19 16:18:35.420887 | instance | changed: .d..t...... ./
2026-05-19 16:18:35.888003 | instance | changed: .d..t...... ./
2026-05-19 16:18:35.914589 | 
2026-05-19 16:18:35.914732 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-05-19 16:18:36.481479 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.005655
2026-05-19 16:18:36.948440 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.220147
2026-05-19 16:18:36.968566 | 
2026-05-19 16:18:36.968668 | PLAY [all]
2026-05-19 16:18:36.975327 | 
2026-05-19 16:18:36.975432 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-05-19 16:18:37.412222 | instance | changed
2026-05-19 16:18:37.420799 | 
2026-05-19 16:18:37.420919 | PLAY RECAP
2026-05-19 16:18:37.420979 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-05-19 16:18:37.421034 | 
2026-05-19 16:18:37.562116 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-05-19 16:18:37.664074 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-05-19 16:18:38.319485 | 
2026-05-19 16:18:38.819959 | PLAY [localhost]
2026-05-19 16:18:38.836819 | 
2026-05-19 16:18:38.836968 | TASK [Generate Zuul manifest]
2026-05-19 16:18:38.858724 | localhost | ok
2026-05-19 16:18:38.877214 | 
2026-05-19 16:18:38.877356 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-05-19 16:18:39.608107 | localhost | changed
2026-05-19 16:18:39.621252 | 
2026-05-19 16:18:39.621378 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-05-19 16:18:39.671833 | localhost | ok
2026-05-19 16:18:39.681137 | 
2026-05-19 16:18:39.681260 | TASK [Upload logs]
2026-05-19 16:18:39.702217 | localhost | ok
2026-05-19 16:18:39.767924 | 
2026-05-19 16:18:39.768043 | TASK [Set zuul-log-path fact]
2026-05-19 16:18:39.788554 | localhost | ok
2026-05-19 16:18:39.800483 | 
2026-05-19 16:18:39.800578 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-05-19 16:18:39.830097 | localhost | ok
2026-05-19 16:18:39.839090 | 
2026-05-19 16:18:39.839210 | TASK [upload-logs : Create log directories]
2026-05-19 16:18:40.255435 | localhost | changed
2026-05-19 16:18:40.261500 | 
2026-05-19 16:18:40.261577 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-05-19 16:18:40.658665 | localhost -> localhost | ok: Runtime: 0:00:00.004797
2026-05-19 16:18:40.695119 | 
2026-05-19 16:18:40.695229 | TASK [upload-logs : Upload logs to log server]
2026-05-19 16:18:41.154855 | localhost | Output suppressed because no_log was given
2026-05-19 16:18:41.160094 | 
2026-05-19 16:18:41.160184 | LOOP [upload-logs : Compress console log and json output]
2026-05-19 16:18:41.204463 | localhost | skipping: Conditional result was False
2026-05-19 16:18:41.211206 | localhost | skipping: Conditional result was False
2026-05-19 16:18:41.223448 | 
2026-05-19 16:18:41.223655 | LOOP [upload-logs : Upload compressed console log and json output]
2026-05-19 16:18:41.267005 | localhost | skipping: Conditional result was False
2026-05-19 16:18:41.267440 | 
2026-05-19 16:18:41.271187 | localhost | skipping: Conditional result was False
2026-05-19 16:18:41.287228 | 
2026-05-19 16:18:41.287430 | LOOP [upload-logs : Upload console log and json output]