2026-05-19 16:17:44.095821 | Job console starting
2026-05-19 16:17:44.108392 | Updating git repos
2026-05-19 16:17:44.204472 | Cloning repos into workspace
2026-05-19 16:17:47.716583 | Restoring repo states
2026-05-19 16:17:47.778474 | Merging changes
2026-05-19 16:17:51.399972 | Checking out repos
2026-05-19 16:17:58.651095 | Preparing playbooks
2026-05-19 16:18:22.013283 | Running Ansible setup
2026-05-19 16:18:26.779651 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-05-19 16:18:27.409908 | 
2026-05-19 16:18:27.539368 | PLAY [localhost]
2026-05-19 16:18:27.553537 | 
2026-05-19 16:18:27.553838 | TASK [Gathering Facts]
2026-05-19 16:18:28.504590 | localhost | ok
2026-05-19 16:18:28.518640 | 
2026-05-19 16:18:28.518749 | TASK [Setup log path fact]
2026-05-19 16:18:28.539850 | localhost | ok
2026-05-19 16:18:28.550085 | 
2026-05-19 16:18:28.550186 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-05-19 16:18:28.582049 | localhost | ok
2026-05-19 16:18:28.589436 | 
2026-05-19 16:18:28.589505 | TASK [emit-job-header : Print job information]
2026-05-19 16:18:28.631871 | # Job Information
2026-05-19 16:18:28.632158 | Ansible Version: 2.16.18
2026-05-19 16:18:28.632293 | Job: atmosphere-molecule-csi-rbd
2026-05-19 16:18:28.632368 | Pipeline: check
2026-05-19 16:18:28.632421 | Executor: 2d72f0692154
2026-05-19 16:18:28.632471 | Triggered by: https://github.com/vexxhost/atmosphere/pull/3945
2026-05-19 16:18:28.632527 | Event ID: 1d651ce0-539e-11f1-8754-a319db16026e
2026-05-19 16:18:28.638490 | 
2026-05-19 16:18:28.638569 | LOOP [emit-job-header : Print node information]
2026-05-19 16:18:28.751467 | localhost | ok:
2026-05-19 16:18:28.751699 | localhost | # Node Information
2026-05-19 16:18:28.751808 | localhost | Inventory Hostname: instance
2026-05-19 16:18:28.751852 | localhost | Hostname: np0000174870
2026-05-19 16:18:28.751892 | localhost | Username: zuul
2026-05-19 16:18:28.751938 | localhost | Distro: Ubuntu 22.04
2026-05-19 16:18:28.751975 | localhost | Provider: yul1
2026-05-19 16:18:28.752011 | localhost | Region: ca-ymq-1
2026-05-19 16:18:28.752044 | localhost | Label: ubuntu-jammy
2026-05-19 16:18:28.752076 | localhost | Product Name: OpenStack Nova
2026-05-19 16:18:28.752111 | localhost | Interface IP: 199.204.45.49
2026-05-19 16:18:28.769312 | 
2026-05-19 16:18:28.769477 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-05-19 16:18:29.233549 | localhost -> localhost | changed
2026-05-19 16:18:29.240043 | 
2026-05-19 16:18:29.240169 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-05-19 16:18:30.107779 | localhost -> localhost | changed
2026-05-19 16:18:30.114118 | 
2026-05-19 16:18:30.114192 | PLAY [all]
2026-05-19 16:18:30.121616 | 
2026-05-19 16:18:30.121715 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-05-19 16:18:30.349065 | instance -> localhost | ok
2026-05-19 16:18:30.360335 | 
2026-05-19 16:18:30.360440 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-05-19 16:18:30.392657 | instance | ok
2026-05-19 16:18:30.405775 | instance | included: /var/lib/zuul/builds/301e7daeb625492db26a4bc3bc95368f/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-05-19 16:18:30.411475 | 
2026-05-19 16:18:30.412018 | TASK [add-build-sshkey : Create Temp SSH key]
2026-05-19 16:18:31.154221 | instance -> localhost | Generating public/private rsa key pair.
2026-05-19 16:18:31.154399 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/301e7daeb625492db26a4bc3bc95368f/work/301e7daeb625492db26a4bc3bc95368f_id_rsa
2026-05-19 16:18:31.154440 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/301e7daeb625492db26a4bc3bc95368f/work/301e7daeb625492db26a4bc3bc95368f_id_rsa.pub
2026-05-19 16:18:31.154471 | instance -> localhost | The key fingerprint is:
2026-05-19 16:18:31.154501 | instance -> localhost | SHA256:4S7nD3qz62azze0MwbVpD+iv9YEMh2rD/JlUtqfwBJY zuul-build-sshkey
2026-05-19 16:18:31.154544 | instance -> localhost | The key's randomart image is:
2026-05-19 16:18:31.154575 | instance -> localhost | +---[RSA 3072]----+
2026-05-19 16:18:31.154612 | instance -> localhost | |                 |
2026-05-19 16:18:31.154643 | instance -> localhost | |                 |
2026-05-19 16:18:31.154672 | instance -> localhost | |        .   .    |
2026-05-19 16:18:31.154700 | instance -> localhost | |       . o = o   |
2026-05-19 16:18:31.154730 | instance -> localhost | |        S E O    |
2026-05-19 16:18:31.154760 | instance -> localhost | |       + + X =   |
2026-05-19 16:18:31.154788 | instance -> localhost | |      . X = * +  |
2026-05-19 16:18:31.154815 | instance -> localhost | |       **B % + . |
2026-05-19 16:18:31.154845 | instance -> localhost | |      .=*BOoB .  |
2026-05-19 16:18:31.154873 | instance -> localhost | +----[SHA256]-----+
2026-05-19 16:18:31.154933 | instance -> localhost | ok: Runtime: 0:00:00.309722
2026-05-19 16:18:31.161271 | 
2026-05-19 16:18:31.161343 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-05-19 16:18:31.204224 | instance | ok
2026-05-19 16:18:31.214595 | instance | included: /var/lib/zuul/builds/301e7daeb625492db26a4bc3bc95368f/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-05-19 16:18:31.222392 | 
2026-05-19 16:18:31.222472 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-05-19 16:18:31.246586 | instance | skipping: Conditional result was False
2026-05-19 16:18:31.256841 | 
2026-05-19 16:18:31.256929 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-05-19 16:18:31.721849 | instance | changed
2026-05-19 16:18:32.282670 | 
2026-05-19 16:18:32.282843 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-05-19 16:18:32.479662 | instance | ok
2026-05-19 16:18:32.485158 | 
2026-05-19 16:18:32.485310 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-05-19 16:18:32.975111 | instance | changed
2026-05-19 16:18:32.981447 | 
2026-05-19 16:18:32.981703 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-05-19 16:18:33.441531 | instance | changed
2026-05-19 16:18:33.632510 | 
2026-05-19 16:18:33.632616 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-05-19 16:18:33.657398 | instance | skipping: Conditional result was False
2026-05-19 16:18:33.665760 | 
2026-05-19 16:18:33.665865 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-05-19 16:18:34.036760 | instance -> localhost | changed
2026-05-19 16:18:34.049410 | 
2026-05-19 16:18:34.049516 | TASK [add-build-sshkey : Add back temp key]
2026-05-19 16:18:34.349162 | instance -> localhost | Identity added: /var/lib/zuul/builds/301e7daeb625492db26a4bc3bc95368f/work/301e7daeb625492db26a4bc3bc95368f_id_rsa (zuul-build-sshkey)
2026-05-19 16:18:34.349321 | instance -> localhost | ok: Runtime: 0:00:00.010627
2026-05-19 16:18:34.354060 | 
2026-05-19 16:18:34.354118 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-05-19 16:18:34.680613 | instance | ok
2026-05-19 16:18:35.084331 | 
2026-05-19 16:18:35.084430 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-05-19 16:18:35.108333 | instance | skipping: Conditional result was False
2026-05-19 16:18:35.123407 | 
2026-05-19 16:18:35.123584 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-05-19 16:18:35.449861 | instance | ok
2026-05-19 16:18:35.455425 | 
2026-05-19 16:18:35.455519 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-05-19 16:18:37.815407 | instance | Output suppressed because no_log was given
2026-05-19 16:18:37.825098 | 
2026-05-19 16:18:37.825219 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-05-19 16:18:38.010430 | instance | ok: "logs"
2026-05-19 16:18:38.010707 | instance | ok: All items complete
2026-05-19 16:18:38.010752 | 
2026-05-19 16:18:38.153647 | instance | ok: "artifacts"
2026-05-19 16:18:38.319947 | instance | ok: "docs"
2026-05-19 16:18:38.840297 | 
2026-05-19 16:18:38.840464 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-05-19 16:18:39.038398 | instance | changed: "logs"
2026-05-19 16:18:39.193549 | instance | changed: "artifacts"
2026-05-19 16:18:39.437623 | instance | changed: "docs"
2026-05-19 16:18:39.455551 | 
2026-05-19 16:18:39.455658 | PLAY RECAP
2026-05-19 16:18:39.455728 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-05-19 16:18:39.455776 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-05-19 16:18:39.455812 | 
2026-05-19 16:18:39.611089 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-05-19 16:18:39.614708 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-05-19 16:18:40.268632 | 
2026-05-19 16:18:40.268763 | PLAY [all]
2026-05-19 16:18:40.279667 | 
2026-05-19 16:18:40.279753 | TASK [setup-uv : Extract archive]
2026-05-19 16:18:42.521544 | instance | changed
2026-05-19 16:18:42.531616 | 
2026-05-19 16:18:42.531841 | TASK [setup-uv : Print version]
2026-05-19 16:18:42.892956 | instance | uv 0.8.13
2026-05-19 16:18:44.479065 | instance | ok: Runtime: 0:00:00.012476
2026-05-19 16:18:44.490116 | 
2026-05-19 16:18:44.490219 | TASK [Install Ansible collection siblings]
2026-05-19 16:18:44.554489 | instance | ERROR
2026-05-19 16:18:44.554709 | instance | {
2026-05-19 16:18:44.554743 | 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:44.554766 | instance | }
failure
2026-05-19 16:18:44.555653 | 
2026-05-19 16:18:44.555705 | PLAY RECAP
2026-05-19 16:18:44.555749 | instance | ok: 2 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-05-19 16:18:44.555773 | 
2026-05-19 16:18:44.670933 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-05-19 16:18:44.679084 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-05-19 16:18:45.315565 | 
2026-05-19 16:18:45.315805 | PLAY [all]
2026-05-19 16:18:45.334054 | 
2026-05-19 16:18:45.334231 | TASK [fetch-output : Set log path for multiple nodes]
2026-05-19 16:18:45.369792 | instance | skipping: Conditional result was False
2026-05-19 16:18:45.379688 | 
2026-05-19 16:18:45.379813 | TASK [fetch-output : Set log path for single node]
2026-05-19 16:18:45.421846 | instance | ok
2026-05-19 16:18:45.430470 | 
2026-05-19 16:18:45.430608 | LOOP [fetch-output : Ensure local output dirs]
2026-05-19 16:18:45.807414 | instance -> localhost | ok: "/var/lib/zuul/builds/301e7daeb625492db26a4bc3bc95368f/work/logs"
2026-05-19 16:18:46.022952 | instance -> localhost | changed: "/var/lib/zuul/builds/301e7daeb625492db26a4bc3bc95368f/work/artifacts"
2026-05-19 16:18:46.236025 | instance -> localhost | changed: "/var/lib/zuul/builds/301e7daeb625492db26a4bc3bc95368f/work/docs"
2026-05-19 16:18:46.250348 | 
2026-05-19 16:18:46.250543 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-05-19 16:18:46.897251 | instance | changed: .d..t...... ./
2026-05-19 16:18:46.897485 | instance | changed: All items complete
2026-05-19 16:18:46.897542 | 
2026-05-19 16:18:47.354134 | instance | changed: .d..t...... ./
2026-05-19 16:18:47.826555 | instance | changed: .d..t...... ./
2026-05-19 16:18:47.850011 | 
2026-05-19 16:18:47.850135 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-05-19 16:18:48.314035 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.008214
2026-05-19 16:18:48.578953 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.008003
2026-05-19 16:18:48.606687 | 
2026-05-19 16:18:48.607247 | PLAY [all]
2026-05-19 16:18:48.617029 | 
2026-05-19 16:18:48.617273 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-05-19 16:18:49.111879 | instance | changed
2026-05-19 16:18:49.118302 | 
2026-05-19 16:18:49.118358 | PLAY RECAP
2026-05-19 16:18:49.118403 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-05-19 16:18:49.118425 | 
2026-05-19 16:18:49.264735 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-05-19 16:18:49.267380 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-05-19 16:18:49.899343 | 
2026-05-19 16:18:49.899480 | PLAY [localhost]
2026-05-19 16:18:49.910091 | 
2026-05-19 16:18:49.910214 | TASK [Generate Zuul manifest]
2026-05-19 16:18:49.930061 | localhost | ok
2026-05-19 16:18:49.950522 | 
2026-05-19 16:18:49.950680 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-05-19 16:18:50.330231 | localhost | changed
2026-05-19 16:18:50.341352 | 
2026-05-19 16:18:50.341437 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-05-19 16:18:50.373446 | localhost | ok
2026-05-19 16:18:50.381935 | 
2026-05-19 16:18:50.382014 | TASK [Upload logs]
2026-05-19 16:18:50.401207 | localhost | ok
2026-05-19 16:18:50.463784 | 
2026-05-19 16:18:50.463899 | TASK [Set zuul-log-path fact]
2026-05-19 16:18:50.483611 | localhost | ok
2026-05-19 16:18:50.497377 | 
2026-05-19 16:18:50.497464 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-05-19 16:18:50.527436 | localhost | ok
2026-05-19 16:18:50.535684 | 
2026-05-19 16:18:50.535757 | TASK [upload-logs : Create log directories]
2026-05-19 16:18:50.933586 | localhost | changed
2026-05-19 16:18:50.938213 | 
2026-05-19 16:18:50.938283 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-05-19 16:18:51.341290 | localhost -> localhost | ok: Runtime: 0:00:00.004485
2026-05-19 16:18:51.346136 | 
2026-05-19 16:18:51.346200 | TASK [upload-logs : Upload logs to log server]
2026-05-19 16:18:51.857492 | localhost | Output suppressed because no_log was given
2026-05-19 16:18:51.863126 | 
2026-05-19 16:18:51.863236 | LOOP [upload-logs : Compress console log and json output]
2026-05-19 16:18:51.950267 | localhost | skipping: Conditional result was False
2026-05-19 16:18:51.962242 | localhost | skipping: Conditional result was False
2026-05-19 16:18:51.978450 | 
2026-05-19 16:18:51.978621 | LOOP [upload-logs : Upload compressed console log and json output]
2026-05-19 16:18:52.055828 | localhost | skipping: Conditional result was False
2026-05-19 16:18:52.056092 | 
2026-05-19 16:18:52.061056 | localhost | skipping: Conditional result was False
2026-05-19 16:18:52.107662 | 
2026-05-19 16:18:52.107788 | LOOP [upload-logs : Upload console log and json output]