2026-05-19 16:21:52.409993 | Job console starting
2026-05-19 16:21:52.421413 | Updating git repos
2026-05-19 16:21:52.537311 | Cloning repos into workspace
2026-05-19 16:21:55.756992 | Restoring repo states
2026-05-19 16:21:55.775350 | Merging changes
2026-05-19 16:21:57.601973 | Checking out repos
2026-05-19 16:21:58.896458 | Preparing playbooks
2026-05-19 16:22:02.603772 | Running Ansible setup
2026-05-19 16:22:06.349312 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-05-19 16:22:06.996580 | 
2026-05-19 16:22:06.996788 | PLAY [localhost]
2026-05-19 16:22:07.004898 | 
2026-05-19 16:22:07.005027 | TASK [Gathering Facts]
2026-05-19 16:22:08.720107 | localhost | ok
2026-05-19 16:22:08.733617 | 
2026-05-19 16:22:08.733762 | TASK [Setup log path fact]
2026-05-19 16:22:08.753805 | localhost | ok
2026-05-19 16:22:08.767718 | 
2026-05-19 16:22:08.767823 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-05-19 16:22:08.796886 | localhost | ok
2026-05-19 16:22:08.804876 | 
2026-05-19 16:22:08.804981 | TASK [emit-job-header : Print job information]
2026-05-19 16:22:08.845326 | # Job Information
2026-05-19 16:22:08.845499 | Ansible Version: 2.16.18
2026-05-19 16:22:08.845547 | Job: atmosphere-molecule-keycloak
2026-05-19 16:22:08.845579 | Pipeline: check
2026-05-19 16:22:08.845608 | Executor: 2d72f0692154
2026-05-19 16:22:08.845636 | Triggered by: https://github.com/vexxhost/atmosphere/pull/3945
2026-05-19 16:22:08.845668 | Event ID: 8635b180-539e-11f1-8f4a-8b53e1584e5c
2026-05-19 16:22:08.849598 | 
2026-05-19 16:22:08.849688 | LOOP [emit-job-header : Print node information]
2026-05-19 16:22:08.958145 | localhost | ok:
2026-05-19 16:22:08.958320 | localhost | # Node Information
2026-05-19 16:22:08.958357 | localhost | Inventory Hostname: instance
2026-05-19 16:22:08.958385 | localhost | Hostname: np0000174878
2026-05-19 16:22:08.958412 | localhost | Username: zuul
2026-05-19 16:22:08.958441 | localhost | Distro: Ubuntu 22.04
2026-05-19 16:22:08.958467 | localhost | Provider: yul1
2026-05-19 16:22:08.958492 | localhost | Region: ca-ymq-1
2026-05-19 16:22:08.958517 | localhost | Label: ubuntu-jammy
2026-05-19 16:22:08.958542 | localhost | Product Name: OpenStack Nova
2026-05-19 16:22:08.958572 | localhost | Interface IP: 199.204.45.140
2026-05-19 16:22:08.976678 | 
2026-05-19 16:22:08.976829 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-05-19 16:22:09.356943 | localhost -> localhost | changed
2026-05-19 16:22:09.364622 | 
2026-05-19 16:22:09.364767 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-05-19 16:22:10.274378 | localhost -> localhost | changed
2026-05-19 16:22:10.283261 | 
2026-05-19 16:22:10.283382 | PLAY [all]
2026-05-19 16:22:10.292171 | 
2026-05-19 16:22:10.292287 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-05-19 16:22:10.676030 | instance -> localhost | ok
2026-05-19 16:22:10.685427 | 
2026-05-19 16:22:10.685541 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-05-19 16:22:10.718196 | instance | ok
2026-05-19 16:22:10.733290 | instance | included: /var/lib/zuul/builds/db451789a5c5450b89d7110402cb0002/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-05-19 16:22:10.739798 | 
2026-05-19 16:22:10.739900 | TASK [add-build-sshkey : Create Temp SSH key]
2026-05-19 16:22:11.683925 | instance -> localhost | Generating public/private rsa key pair.
2026-05-19 16:22:11.684115 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/db451789a5c5450b89d7110402cb0002/work/db451789a5c5450b89d7110402cb0002_id_rsa
2026-05-19 16:22:11.684158 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/db451789a5c5450b89d7110402cb0002/work/db451789a5c5450b89d7110402cb0002_id_rsa.pub
2026-05-19 16:22:11.684192 | instance -> localhost | The key fingerprint is:
2026-05-19 16:22:11.684224 | instance -> localhost | SHA256:e63JYGcbM2zoYEa+O8KL1KS+7uP0yD/SY6TwuTHv7bM zuul-build-sshkey
2026-05-19 16:22:11.684271 | instance -> localhost | The key's randomart image is:
2026-05-19 16:22:11.684304 | instance -> localhost | +---[RSA 3072]----+
2026-05-19 16:22:11.684343 | instance -> localhost | |                 |
2026-05-19 16:22:11.684374 | instance -> localhost | |                 |
2026-05-19 16:22:11.684404 | instance -> localhost | |                 |
2026-05-19 16:22:11.684433 | instance -> localhost | |                 |
2026-05-19 16:22:11.684462 | instance -> localhost | |    . . S        |
2026-05-19 16:22:11.684491 | instance -> localhost | |.  +.o   + .     |
2026-05-19 16:22:11.684520 | instance -> localhost | | o**. = = X .    |
2026-05-19 16:22:11.684548 | instance -> localhost | | =*BB+o= B B     |
2026-05-19 16:22:11.684579 | instance -> localhost | | +@O**E=. =      |
2026-05-19 16:22:11.684609 | instance -> localhost | +----[SHA256]-----+
2026-05-19 16:22:11.684754 | instance -> localhost | ok: Runtime: 0:00:00.467784
2026-05-19 16:22:11.689946 | 
2026-05-19 16:22:11.690010 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-05-19 16:22:11.724262 | instance | ok
2026-05-19 16:22:11.734579 | instance | included: /var/lib/zuul/builds/db451789a5c5450b89d7110402cb0002/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-05-19 16:22:11.743995 | 
2026-05-19 16:22:11.744137 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-05-19 16:22:11.768284 | instance | skipping: Conditional result was False
2026-05-19 16:22:11.777149 | 
2026-05-19 16:22:11.777292 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-05-19 16:22:12.209536 | instance | changed
2026-05-19 16:22:12.214465 | 
2026-05-19 16:22:12.214565 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-05-19 16:22:12.390914 | instance | ok
2026-05-19 16:22:12.398292 | 
2026-05-19 16:22:12.398381 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-05-19 16:22:12.855269 | instance | changed
2026-05-19 16:22:12.861738 | 
2026-05-19 16:22:12.861870 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-05-19 16:22:13.309392 | instance | changed
2026-05-19 16:22:13.316111 | 
2026-05-19 16:22:13.316195 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-05-19 16:22:13.340175 | instance | skipping: Conditional result was False
2026-05-19 16:22:13.349242 | 
2026-05-19 16:22:13.349382 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-05-19 16:22:13.719586 | instance -> localhost | changed
2026-05-19 16:22:13.736922 | 
2026-05-19 16:22:13.737016 | TASK [add-build-sshkey : Add back temp key]
2026-05-19 16:22:14.036688 | instance -> localhost | Identity added: /var/lib/zuul/builds/db451789a5c5450b89d7110402cb0002/work/db451789a5c5450b89d7110402cb0002_id_rsa (zuul-build-sshkey)
2026-05-19 16:22:14.036877 | instance -> localhost | ok: Runtime: 0:00:00.012818
2026-05-19 16:22:14.041704 | 
2026-05-19 16:22:14.041767 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-05-19 16:22:14.351038 | instance | ok
2026-05-19 16:22:16.912408 | 
2026-05-19 16:22:16.912707 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-05-19 16:22:16.936369 | instance | skipping: Conditional result was False
2026-05-19 16:22:16.952163 | 
2026-05-19 16:22:16.952279 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-05-19 16:22:17.278404 | instance | ok
2026-05-19 16:22:17.319527 | 
2026-05-19 16:22:17.407234 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-05-19 16:22:19.735854 | instance | Output suppressed because no_log was given
2026-05-19 16:22:19.744385 | 
2026-05-19 16:22:19.744451 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-05-19 16:22:19.936825 | instance | ok: "logs"
2026-05-19 16:22:19.937130 | instance | ok: All items complete
2026-05-19 16:22:19.937170 | 
2026-05-19 16:22:20.083945 | instance | ok: "artifacts"
2026-05-19 16:22:20.230281 | instance | ok: "docs"
2026-05-19 16:22:20.248669 | 
2026-05-19 16:22:20.249193 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-05-19 16:22:20.441814 | instance | changed: "logs"
2026-05-19 16:22:20.598810 | instance | changed: "artifacts"
2026-05-19 16:22:20.755331 | instance | changed: "docs"
2026-05-19 16:22:20.773244 | 
2026-05-19 16:22:20.773398 | PLAY RECAP
2026-05-19 16:22:20.773468 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-05-19 16:22:20.773512 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-05-19 16:22:20.773547 | 
2026-05-19 16:22:20.905293 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-05-19 16:22:20.911902 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-05-19 16:22:21.628512 | 
2026-05-19 16:22:21.628660 | PLAY [all]
2026-05-19 16:22:21.639670 | 
2026-05-19 16:22:21.639771 | TASK [setup-uv : Extract archive]
2026-05-19 16:22:23.749418 | instance | changed
2026-05-19 16:22:23.757485 | 
2026-05-19 16:22:23.757586 | TASK [setup-uv : Print version]
2026-05-19 16:22:24.084719 | instance | uv 0.8.13
2026-05-19 16:22:24.291583 | instance | ok: Runtime: 0:00:00.010829
2026-05-19 16:22:24.304838 | 
2026-05-19 16:22:24.304910 | TASK [Install Ansible collection siblings]
2026-05-19 16:22:24.368426 | instance | ERROR
2026-05-19 16:22:24.368696 | instance | {
2026-05-19 16:22:24.368767 | 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:22:24.368841 | instance | }
failure
2026-05-19 16:22:24.369896 | 
2026-05-19 16:22:24.369955 | PLAY RECAP
2026-05-19 16:22:24.370011 | instance | ok: 2 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-05-19 16:22:24.370044 | 
2026-05-19 16:22:24.480549 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-05-19 16:22:24.486693 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-05-19 16:22:25.141817 | 
2026-05-19 16:22:25.141983 | PLAY [all]
2026-05-19 16:22:25.156487 | 
2026-05-19 16:22:25.156631 | TASK [fetch-output : Set log path for multiple nodes]
2026-05-19 16:22:25.202267 | instance | skipping: Conditional result was False
2026-05-19 16:22:25.213085 | 
2026-05-19 16:22:25.213171 | TASK [fetch-output : Set log path for single node]
2026-05-19 16:22:25.253101 | instance | ok
2026-05-19 16:22:25.264019 | 
2026-05-19 16:22:25.264174 | LOOP [fetch-output : Ensure local output dirs]
2026-05-19 16:22:25.725355 | instance -> localhost | ok: "/var/lib/zuul/builds/db451789a5c5450b89d7110402cb0002/work/logs"
2026-05-19 16:22:25.944176 | instance -> localhost | changed: "/var/lib/zuul/builds/db451789a5c5450b89d7110402cb0002/work/artifacts"
2026-05-19 16:22:26.306287 | instance -> localhost | changed: "/var/lib/zuul/builds/db451789a5c5450b89d7110402cb0002/work/docs"
2026-05-19 16:22:26.326644 | 
2026-05-19 16:22:26.326907 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-05-19 16:22:27.022546 | instance | ok
2026-05-19 16:22:27.022769 | instance | changed: All items complete
2026-05-19 16:22:27.022810 | 
2026-05-19 16:22:27.463592 | instance | changed: .d..t...... ./
2026-05-19 16:22:28.585739 | instance | changed: .d..t...... ./
2026-05-19 16:22:28.608913 | 
2026-05-19 16:22:28.609084 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-05-19 16:22:29.191038 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.006937
2026-05-19 16:22:29.431591 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.006376
2026-05-19 16:22:29.444537 | 
2026-05-19 16:22:29.444644 | PLAY [all]
2026-05-19 16:22:29.451430 | 
2026-05-19 16:22:29.451518 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-05-19 16:22:29.853769 | instance | changed
2026-05-19 16:22:29.859346 | 
2026-05-19 16:22:29.859402 | PLAY RECAP
2026-05-19 16:22:29.859487 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-05-19 16:22:29.859534 | 
2026-05-19 16:22:29.969862 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-05-19 16:22:29.974544 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-05-19 16:22:30.651403 | 
2026-05-19 16:22:30.651572 | PLAY [localhost]
2026-05-19 16:22:30.665549 | 
2026-05-19 16:22:30.665682 | TASK [Generate Zuul manifest]
2026-05-19 16:22:30.687463 | localhost | ok
2026-05-19 16:22:30.703961 | 
2026-05-19 16:22:30.704065 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-05-19 16:22:31.108345 | localhost | changed
2026-05-19 16:22:31.122016 | 
2026-05-19 16:22:31.122193 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-05-19 16:22:31.163833 | localhost | ok
2026-05-19 16:22:31.172853 | 
2026-05-19 16:22:31.173003 | TASK [Upload logs]
2026-05-19 16:22:31.193338 | localhost | ok
2026-05-19 16:22:31.260502 | 
2026-05-19 16:22:31.260642 | TASK [Set zuul-log-path fact]
2026-05-19 16:22:31.281664 | localhost | ok
2026-05-19 16:22:31.293042 | 
2026-05-19 16:22:31.293149 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-05-19 16:22:31.320113 | localhost | ok
2026-05-19 16:22:31.328425 | 
2026-05-19 16:22:31.332189 | TASK [upload-logs : Create log directories]
2026-05-19 16:22:31.777479 | localhost | changed
2026-05-19 16:22:31.783250 | 
2026-05-19 16:22:31.846971 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-05-19 16:22:32.409433 | localhost -> localhost | ok: Runtime: 0:00:00.007368
2026-05-19 16:22:32.476498 | 
2026-05-19 16:22:32.476665 | TASK [upload-logs : Upload logs to log server]
2026-05-19 16:22:32.913868 | localhost | Output suppressed because no_log was given
2026-05-19 16:22:32.919108 | 
2026-05-19 16:22:32.919206 | LOOP [upload-logs : Compress console log and json output]
2026-05-19 16:22:32.964270 | localhost | skipping: Conditional result was False
2026-05-19 16:22:32.973851 | localhost | skipping: Conditional result was False
2026-05-19 16:22:32.982896 | 
2026-05-19 16:22:32.983044 | LOOP [upload-logs : Upload compressed console log and json output]
2026-05-19 16:22:33.026713 | localhost | skipping: Conditional result was False
2026-05-19 16:22:33.027103 | 
2026-05-19 16:22:33.030665 | localhost | skipping: Conditional result was False
2026-05-19 16:22:33.045815 | 
2026-05-19 16:22:33.073805 | LOOP [upload-logs : Upload console log and json output]