2026-05-19 16:22:54.711049 | Job console starting
2026-05-19 16:22:54.732003 | Updating git repos
2026-05-19 16:22:54.825477 | Cloning repos into workspace
2026-05-19 16:22:56.687578 | Restoring repo states
2026-05-19 16:22:56.704926 | Merging changes
2026-05-19 16:22:59.752406 | Checking out repos
2026-05-19 16:23:01.936061 | Preparing playbooks
2026-05-19 16:23:20.118997 | Running Ansible setup
2026-05-19 16:23:23.962965 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-05-19 16:23:24.664552 | 
2026-05-19 16:23:24.664707 | PLAY [localhost]
2026-05-19 16:23:24.672612 | 
2026-05-19 16:23:24.672721 | TASK [Gathering Facts]
2026-05-19 16:23:25.569120 | localhost | ok
2026-05-19 16:23:25.578491 | 
2026-05-19 16:23:25.578597 | TASK [Setup log path fact]
2026-05-19 16:23:25.599578 | localhost | ok
2026-05-19 16:23:25.614971 | 
2026-05-19 16:23:25.615128 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-05-19 16:23:25.643912 | localhost | ok
2026-05-19 16:23:25.652776 | 
2026-05-19 16:23:25.652932 | TASK [emit-job-header : Print job information]
2026-05-19 16:23:25.693014 | # Job Information
2026-05-19 16:23:25.693230 | Ansible Version: 2.16.18
2026-05-19 16:23:25.693284 | Job: atmosphere-molecule-csi-rbd
2026-05-19 16:23:25.693319 | Pipeline: check
2026-05-19 16:23:25.693350 | Executor: 2d72f0692154
2026-05-19 16:23:25.693380 | Triggered by: https://github.com/vexxhost/atmosphere/pull/3945
2026-05-19 16:23:25.693415 | Event ID: 8635b180-539e-11f1-8f4a-8b53e1584e5c
2026-05-19 16:23:25.697765 | 
2026-05-19 16:23:25.697913 | LOOP [emit-job-header : Print node information]
2026-05-19 16:23:25.784422 | localhost | ok:
2026-05-19 16:23:25.784679 | localhost | # Node Information
2026-05-19 16:23:25.784761 | localhost | Inventory Hostname: instance
2026-05-19 16:23:25.784832 | localhost | Hostname: np0000174883
2026-05-19 16:23:25.784887 | localhost | Username: zuul
2026-05-19 16:23:25.784931 | localhost | Distro: Ubuntu 22.04
2026-05-19 16:23:25.785010 | localhost | Provider: yul1
2026-05-19 16:23:25.785051 | localhost | Region: ca-ymq-1
2026-05-19 16:23:25.785082 | localhost | Label: ubuntu-jammy
2026-05-19 16:23:25.785114 | localhost | Product Name: OpenStack Nova
2026-05-19 16:23:25.785145 | localhost | Interface IP: 199.204.45.250
2026-05-19 16:23:25.811505 | 
2026-05-19 16:23:25.811618 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-05-19 16:23:26.189248 | localhost -> localhost | changed
2026-05-19 16:23:26.197817 | 
2026-05-19 16:23:26.197919 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-05-19 16:23:27.103382 | localhost -> localhost | changed
2026-05-19 16:23:27.115770 | 
2026-05-19 16:23:27.115860 | PLAY [all]
2026-05-19 16:23:27.123800 | 
2026-05-19 16:23:27.123940 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-05-19 16:23:27.350927 | instance -> localhost | ok
2026-05-19 16:23:27.363116 | 
2026-05-19 16:23:27.363222 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-05-19 16:23:27.395415 | instance | ok
2026-05-19 16:23:27.408627 | instance | included: /var/lib/zuul/builds/7f54dfe9893444e0a92139761cdc0812/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-05-19 16:23:27.414703 | 
2026-05-19 16:23:27.414788 | TASK [add-build-sshkey : Create Temp SSH key]
2026-05-19 16:23:28.579189 | instance -> localhost | Generating public/private rsa key pair.
2026-05-19 16:23:28.579387 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/7f54dfe9893444e0a92139761cdc0812/work/7f54dfe9893444e0a92139761cdc0812_id_rsa
2026-05-19 16:23:28.579431 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/7f54dfe9893444e0a92139761cdc0812/work/7f54dfe9893444e0a92139761cdc0812_id_rsa.pub
2026-05-19 16:23:28.579464 | instance -> localhost | The key fingerprint is:
2026-05-19 16:23:28.579494 | instance -> localhost | SHA256:ri6R/Ijzfvx+hODFu65LxNrSZcd6sqAvMvjK8Ji5tTE zuul-build-sshkey
2026-05-19 16:23:28.579538 | instance -> localhost | The key's randomart image is:
2026-05-19 16:23:28.579569 | instance -> localhost | +---[RSA 3072]----+
2026-05-19 16:23:28.579603 | instance -> localhost | |                 |
2026-05-19 16:23:28.579634 | instance -> localhost | |                 |
2026-05-19 16:23:28.579663 | instance -> localhost | |       .         |
2026-05-19 16:23:28.579693 | instance -> localhost | |     .. o.       |
2026-05-19 16:23:28.579721 | instance -> localhost | |   . oooSoo      |
2026-05-19 16:23:28.579749 | instance -> localhost | |    +=.+oo.      |
2026-05-19 16:23:28.579777 | instance -> localhost | |..E.o== +o.      |
2026-05-19 16:23:28.579805 | instance -> localhost | |+*=++++o.+.      |
2026-05-19 16:23:28.579836 | instance -> localhost | |*=+*+***=.       |
2026-05-19 16:23:28.579864 | instance -> localhost | +----[SHA256]-----+
2026-05-19 16:23:28.579927 | instance -> localhost | ok: Runtime: 0:00:00.751099
2026-05-19 16:23:28.587289 | 
2026-05-19 16:23:28.587375 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-05-19 16:23:28.619548 | instance | ok
2026-05-19 16:23:28.631362 | instance | included: /var/lib/zuul/builds/7f54dfe9893444e0a92139761cdc0812/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-05-19 16:23:28.639269 | 
2026-05-19 16:23:28.639363 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-05-19 16:23:28.663080 | instance | skipping: Conditional result was False
2026-05-19 16:23:28.672904 | 
2026-05-19 16:23:28.673054 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-05-19 16:23:29.132063 | instance | changed
2026-05-19 16:23:29.140466 | 
2026-05-19 16:23:29.140640 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-05-19 16:23:29.311600 | instance | ok
2026-05-19 16:23:29.316473 | 
2026-05-19 16:23:29.316558 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-05-19 16:23:29.765428 | instance | changed
2026-05-19 16:23:29.773154 | 
2026-05-19 16:23:29.773298 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-05-19 16:23:30.203043 | instance | changed
2026-05-19 16:23:30.211483 | 
2026-05-19 16:23:30.211646 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-05-19 16:23:30.236022 | instance | skipping: Conditional result was False
2026-05-19 16:23:30.246177 | 
2026-05-19 16:23:30.246275 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-05-19 16:23:30.615091 | instance -> localhost | changed
2026-05-19 16:23:30.629727 | 
2026-05-19 16:23:30.629835 | TASK [add-build-sshkey : Add back temp key]
2026-05-19 16:23:30.900679 | instance -> localhost | Identity added: /var/lib/zuul/builds/7f54dfe9893444e0a92139761cdc0812/work/7f54dfe9893444e0a92139761cdc0812_id_rsa (zuul-build-sshkey)
2026-05-19 16:23:30.900994 | instance -> localhost | ok: Runtime: 0:00:00.009788
2026-05-19 16:23:30.908026 | 
2026-05-19 16:23:30.908096 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-05-19 16:23:31.221677 | instance | ok
2026-05-19 16:23:31.229678 | 
2026-05-19 16:23:31.229798 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-05-19 16:23:31.253964 | instance | skipping: Conditional result was False
2026-05-19 16:23:31.264333 | 
2026-05-19 16:23:31.264432 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-05-19 16:23:31.589633 | instance | ok
2026-05-19 16:23:31.598032 | 
2026-05-19 16:23:31.598131 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-05-19 16:23:33.675191 | instance | Output suppressed because no_log was given
2026-05-19 16:23:33.687156 | 
2026-05-19 16:23:33.687271 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-05-19 16:23:33.875992 | instance | ok: "logs"
2026-05-19 16:23:33.876821 | instance | ok: All items complete
2026-05-19 16:23:33.876916 | 
2026-05-19 16:23:34.022575 | instance | ok: "artifacts"
2026-05-19 16:23:34.177410 | instance | ok: "docs"
2026-05-19 16:23:34.195551 | 
2026-05-19 16:23:34.195703 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-05-19 16:23:34.401711 | instance | changed: "logs"
2026-05-19 16:23:34.560074 | instance | changed: "artifacts"
2026-05-19 16:23:34.714011 | instance | changed: "docs"
2026-05-19 16:23:34.727544 | 
2026-05-19 16:23:34.727671 | PLAY RECAP
2026-05-19 16:23:34.727742 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-05-19 16:23:34.727788 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-05-19 16:23:34.727823 | 
2026-05-19 16:23:34.878529 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-05-19 16:23:34.880524 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-05-19 16:23:35.525413 | 
2026-05-19 16:23:35.525556 | PLAY [all]
2026-05-19 16:23:35.537308 | 
2026-05-19 16:23:35.537429 | TASK [setup-uv : Extract archive]
2026-05-19 16:23:37.886658 | instance | changed
2026-05-19 16:23:38.002598 | 
2026-05-19 16:23:38.002756 | TASK [setup-uv : Print version]
2026-05-19 16:23:38.388263 | instance | uv 0.8.13
2026-05-19 16:23:38.542724 | instance | ok: Runtime: 0:00:00.019858
2026-05-19 16:23:38.553846 | 
2026-05-19 16:23:38.553977 | TASK [Install Ansible collection siblings]
2026-05-19 16:23:38.612941 | instance | ERROR
2026-05-19 16:23:38.613169 | instance | {
2026-05-19 16:23:38.613201 | 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:38.613238 | instance | }
failure
2026-05-19 16:23:38.614226 | 
2026-05-19 16:23:38.614271 | PLAY RECAP
2026-05-19 16:23:38.614315 | instance | ok: 2 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-05-19 16:23:38.614338 | 
2026-05-19 16:23:38.727557 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-05-19 16:23:38.729948 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-05-19 16:23:39.372542 | 
2026-05-19 16:23:40.030259 | PLAY [all]
2026-05-19 16:23:40.048816 | 
2026-05-19 16:23:40.048934 | TASK [fetch-output : Set log path for multiple nodes]
2026-05-19 16:23:40.094768 | instance | skipping: Conditional result was False
2026-05-19 16:23:40.102490 | 
2026-05-19 16:23:40.102643 | TASK [fetch-output : Set log path for single node]
2026-05-19 16:23:40.145261 | instance | ok
2026-05-19 16:23:40.152125 | 
2026-05-19 16:23:40.152454 | LOOP [fetch-output : Ensure local output dirs]
2026-05-19 16:23:40.561528 | instance -> localhost | ok: "/var/lib/zuul/builds/7f54dfe9893444e0a92139761cdc0812/work/logs"
2026-05-19 16:23:40.779163 | instance -> localhost | changed: "/var/lib/zuul/builds/7f54dfe9893444e0a92139761cdc0812/work/artifacts"
2026-05-19 16:23:40.988110 | instance -> localhost | changed: "/var/lib/zuul/builds/7f54dfe9893444e0a92139761cdc0812/work/docs"
2026-05-19 16:23:41.006065 | 
2026-05-19 16:23:41.006206 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-05-19 16:23:41.616391 | instance | ok
2026-05-19 16:23:41.616581 | instance | changed: All items complete
2026-05-19 16:23:41.616609 | 
2026-05-19 16:23:42.076737 | instance | changed: .d..t...... ./
2026-05-19 16:23:42.505985 | instance | changed: .d..t...... ./
2026-05-19 16:23:42.524906 | 
2026-05-19 16:23:42.525096 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-05-19 16:23:43.021395 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.005649
2026-05-19 16:23:43.257000 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.006321
2026-05-19 16:23:43.272879 | 
2026-05-19 16:23:43.273061 | PLAY [all]
2026-05-19 16:23:43.279912 | 
2026-05-19 16:23:43.279996 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-05-19 16:23:43.706168 | instance | changed
2026-05-19 16:23:43.712706 | 
2026-05-19 16:23:43.713372 | PLAY RECAP
2026-05-19 16:23:43.713480 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-05-19 16:23:43.713530 | 
2026-05-19 16:23:43.837444 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-05-19 16:23:43.839612 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-05-19 16:23:44.468869 | 
2026-05-19 16:23:44.469060 | PLAY [localhost]
2026-05-19 16:23:44.479898 | 
2026-05-19 16:23:44.480070 | TASK [Generate Zuul manifest]
2026-05-19 16:23:44.502033 | localhost | ok
2026-05-19 16:23:44.520663 | 
2026-05-19 16:23:44.520793 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-05-19 16:23:44.903547 | localhost | changed
2026-05-19 16:23:44.913499 | 
2026-05-19 16:23:44.913637 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-05-19 16:23:44.943028 | localhost | ok
2026-05-19 16:23:44.954201 | 
2026-05-19 16:23:44.954361 | TASK [Upload logs]
2026-05-19 16:23:44.975701 | localhost | ok
2026-05-19 16:23:45.036919 | 
2026-05-19 16:23:45.037065 | TASK [Set zuul-log-path fact]
2026-05-19 16:23:45.057983 | localhost | ok
2026-05-19 16:23:45.075050 | 
2026-05-19 16:23:45.075181 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-05-19 16:23:45.108051 | localhost | ok
2026-05-19 16:23:45.118279 | 
2026-05-19 16:23:45.118435 | TASK [upload-logs : Create log directories]
2026-05-19 16:23:45.531109 | localhost | changed
2026-05-19 16:23:45.536557 | 
2026-05-19 16:23:45.536724 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-05-19 16:23:45.991469 | localhost -> localhost | ok: Runtime: 0:00:00.004103
2026-05-19 16:23:45.999285 | 
2026-05-19 16:23:45.999405 | TASK [upload-logs : Upload logs to log server]
2026-05-19 16:23:46.514977 | localhost | Output suppressed because no_log was given
2026-05-19 16:23:46.520948 | 
2026-05-19 16:23:46.521127 | LOOP [upload-logs : Compress console log and json output]
2026-05-19 16:23:46.615550 | localhost | skipping: Conditional result was False
2026-05-19 16:23:46.628919 | localhost | skipping: Conditional result was False
2026-05-19 16:23:46.643169 | 
2026-05-19 16:23:46.643316 | LOOP [upload-logs : Upload compressed console log and json output]
2026-05-19 16:23:46.764814 | localhost | skipping: Conditional result was False
2026-05-19 16:23:46.765219 | 
2026-05-19 16:23:46.770361 | localhost | skipping: Conditional result was False
2026-05-19 16:23:46.811631 | 
2026-05-19 16:23:46.812030 | LOOP [upload-logs : Upload console log and json output]