2026-05-19 16:21:53.946942 | Job console starting
2026-05-19 16:21:53.959907 | Updating git repos
2026-05-19 16:21:54.062571 | Cloning repos into workspace
2026-05-19 16:21:55.965324 | Restoring repo states
2026-05-19 16:21:55.994367 | Merging changes
2026-05-19 16:21:57.773302 | Checking out repos
2026-05-19 16:21:58.889862 | Preparing playbooks
2026-05-19 16:22:02.607312 | Running Ansible setup
2026-05-19 16:22:06.451031 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-05-19 16:22:07.133358 | 
2026-05-19 16:22:07.133499 | PLAY [localhost]
2026-05-19 16:22:07.141606 | 
2026-05-19 16:22:07.141705 | TASK [Gathering Facts]
2026-05-19 16:22:08.719524 | localhost | ok
2026-05-19 16:22:08.730581 | 
2026-05-19 16:22:08.730740 | TASK [Setup log path fact]
2026-05-19 16:22:08.750979 | localhost | ok
2026-05-19 16:22:08.761564 | 
2026-05-19 16:22:08.761641 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-05-19 16:22:08.792102 | localhost | ok
2026-05-19 16:22:08.800390 | 
2026-05-19 16:22:08.800502 | TASK [emit-job-header : Print job information]
2026-05-19 16:22:08.841976 | # Job Information
2026-05-19 16:22:08.842192 | Ansible Version: 2.16.18
2026-05-19 16:22:08.842253 | Job: atmosphere-molecule-csi-local-path-provisioner
2026-05-19 16:22:08.842293 | Pipeline: check
2026-05-19 16:22:08.842329 | Executor: 2d72f0692154
2026-05-19 16:22:08.842365 | Triggered by: https://github.com/vexxhost/atmosphere/pull/3945
2026-05-19 16:22:08.842406 | Event ID: 8635b180-539e-11f1-8f4a-8b53e1584e5c
2026-05-19 16:22:08.846386 | 
2026-05-19 16:22:08.846477 | LOOP [emit-job-header : Print node information]
2026-05-19 16:22:08.940306 | localhost | ok:
2026-05-19 16:22:08.940465 | localhost | # Node Information
2026-05-19 16:22:08.940495 | localhost | Inventory Hostname: instance
2026-05-19 16:22:08.940518 | localhost | Hostname: np0000174881
2026-05-19 16:22:08.940539 | localhost | Username: zuul
2026-05-19 16:22:08.940561 | localhost | Distro: Ubuntu 22.04
2026-05-19 16:22:08.940583 | localhost | Provider: yul1
2026-05-19 16:22:08.940604 | localhost | Region: ca-ymq-1
2026-05-19 16:22:08.940624 | localhost | Label: ubuntu-jammy
2026-05-19 16:22:08.940645 | localhost | Product Name: OpenStack Nova
2026-05-19 16:22:08.940664 | localhost | Interface IP: 199.204.45.138
2026-05-19 16:22:08.949224 | 
2026-05-19 16:22:08.949336 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-05-19 16:22:09.329506 | localhost -> localhost | changed
2026-05-19 16:22:09.334626 | 
2026-05-19 16:22:09.334702 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-05-19 16:22:10.190454 | localhost -> localhost | changed
2026-05-19 16:22:10.200552 | 
2026-05-19 16:22:10.200657 | PLAY [all]
2026-05-19 16:22:10.208974 | 
2026-05-19 16:22:10.209096 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-05-19 16:22:10.455624 | instance -> localhost | ok
2026-05-19 16:22:10.464784 | 
2026-05-19 16:22:10.464888 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-05-19 16:22:10.495927 | instance | ok
2026-05-19 16:22:10.515330 | instance | included: /var/lib/zuul/builds/dbf5726e64db4aca88c8eb0d63388b59/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-05-19 16:22:10.524200 | 
2026-05-19 16:22:10.524317 | TASK [add-build-sshkey : Create Temp SSH key]
2026-05-19 16:22:12.338055 | instance -> localhost | Generating public/private rsa key pair.
2026-05-19 16:22:12.338288 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/dbf5726e64db4aca88c8eb0d63388b59/work/dbf5726e64db4aca88c8eb0d63388b59_id_rsa
2026-05-19 16:22:12.338335 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/dbf5726e64db4aca88c8eb0d63388b59/work/dbf5726e64db4aca88c8eb0d63388b59_id_rsa.pub
2026-05-19 16:22:12.338367 | instance -> localhost | The key fingerprint is:
2026-05-19 16:22:12.338545 | instance -> localhost | SHA256:khzM11KZ7RBlR6BdeFGmYcR0Ebp2q82A8m7RplcQPu8 zuul-build-sshkey
2026-05-19 16:22:12.338622 | instance -> localhost | The key's randomart image is:
2026-05-19 16:22:12.338659 | instance -> localhost | +---[RSA 3072]----+
2026-05-19 16:22:12.338697 | instance -> localhost | |          o*+BX=*|
2026-05-19 16:22:12.338760 | instance -> localhost | |     o   o++o=+= |
2026-05-19 16:22:12.338818 | instance -> localhost | |      + o ooooo  |
2026-05-19 16:22:12.338853 | instance -> localhost | |     . + .  .+.  |
2026-05-19 16:22:12.338884 | instance -> localhost | |      + S   .o+. |
2026-05-19 16:22:12.338912 | instance -> localhost | |       .   .oo.o.|
2026-05-19 16:22:12.338939 | instance -> localhost | |         . .+.o. |
2026-05-19 16:22:12.338967 | instance -> localhost | |          oo .=E |
2026-05-19 16:22:12.338999 | instance -> localhost | |          oo.. o |
2026-05-19 16:22:12.339027 | instance -> localhost | +----[SHA256]-----+
2026-05-19 16:22:12.339091 | instance -> localhost | ok: Runtime: 0:00:01.342341
2026-05-19 16:22:12.347232 | 
2026-05-19 16:22:12.347391 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-05-19 16:22:12.374913 | instance | ok
2026-05-19 16:22:12.384612 | instance | included: /var/lib/zuul/builds/dbf5726e64db4aca88c8eb0d63388b59/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-05-19 16:22:12.396744 | 
2026-05-19 16:22:12.396879 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-05-19 16:22:12.421418 | instance | skipping: Conditional result was False
2026-05-19 16:22:12.429410 | 
2026-05-19 16:22:12.429588 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-05-19 16:22:12.878170 | instance | changed
2026-05-19 16:22:12.884142 | 
2026-05-19 16:22:12.884206 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-05-19 16:22:13.068056 | instance | ok
2026-05-19 16:22:13.075329 | 
2026-05-19 16:22:13.075433 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-05-19 16:22:13.543323 | instance | changed
2026-05-19 16:22:13.549927 | 
2026-05-19 16:22:13.550017 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-05-19 16:22:14.008118 | instance | changed
2026-05-19 16:22:14.013198 | 
2026-05-19 16:22:14.013275 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-05-19 16:22:14.036833 | instance | skipping: Conditional result was False
2026-05-19 16:22:14.046949 | 
2026-05-19 16:22:14.047103 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-05-19 16:22:16.913393 | instance -> localhost | changed
2026-05-19 16:22:16.925339 | 
2026-05-19 16:22:16.925428 | TASK [add-build-sshkey : Add back temp key]
2026-05-19 16:22:17.217121 | instance -> localhost | Identity added: /var/lib/zuul/builds/dbf5726e64db4aca88c8eb0d63388b59/work/dbf5726e64db4aca88c8eb0d63388b59_id_rsa (zuul-build-sshkey)
2026-05-19 16:22:17.217436 | instance -> localhost | ok: Runtime: 0:00:00.013361
2026-05-19 16:22:17.223966 | 
2026-05-19 16:22:17.224037 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-05-19 16:22:17.542569 | instance | ok
2026-05-19 16:22:17.552014 | 
2026-05-19 16:22:17.552162 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-05-19 16:22:17.576095 | instance | skipping: Conditional result was False
2026-05-19 16:22:17.586998 | 
2026-05-19 16:22:17.587141 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-05-19 16:22:17.912676 | instance | ok
2026-05-19 16:22:17.919768 | 
2026-05-19 16:22:17.919874 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-05-19 16:22:19.964014 | instance | Output suppressed because no_log was given
2026-05-19 16:22:19.975704 | 
2026-05-19 16:22:19.975848 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-05-19 16:22:20.161055 | instance | ok: "logs"
2026-05-19 16:22:20.161323 | instance | ok: All items complete
2026-05-19 16:22:20.161364 | 
2026-05-19 16:22:20.317025 | instance | ok: "artifacts"
2026-05-19 16:22:20.481506 | instance | ok: "docs"
2026-05-19 16:22:20.503117 | 
2026-05-19 16:22:20.503283 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-05-19 16:22:20.700246 | instance | changed: "logs"
2026-05-19 16:22:20.851879 | instance | changed: "artifacts"
2026-05-19 16:22:21.013407 | instance | changed: "docs"
2026-05-19 16:22:21.024111 | 
2026-05-19 16:22:21.025450 | PLAY RECAP
2026-05-19 16:22:21.025663 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-05-19 16:22:21.025715 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-05-19 16:22:21.025751 | 
2026-05-19 16:22:21.169069 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-05-19 16:22:21.171032 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-05-19 16:22:21.809098 | 
2026-05-19 16:22:21.809242 | PLAY [all]
2026-05-19 16:22:21.821523 | 
2026-05-19 16:22:21.821634 | TASK [setup-uv : Extract archive]
2026-05-19 16:22:24.090839 | instance | changed
2026-05-19 16:22:24.098119 | 
2026-05-19 16:22:24.098254 | TASK [setup-uv : Print version]
2026-05-19 16:22:24.440272 | instance | uv 0.8.13
2026-05-19 16:22:24.635836 | instance | ok: Runtime: 0:00:00.012878
2026-05-19 16:22:24.647946 | 
2026-05-19 16:22:24.648028 | TASK [Install Ansible collection siblings]
2026-05-19 16:22:24.731775 | instance | ERROR
2026-05-19 16:22:24.732015 | instance | {
2026-05-19 16:22:24.732060 | 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.732097 | instance | }
failure
2026-05-19 16:22:24.733295 | 
2026-05-19 16:22:24.733353 | PLAY RECAP
2026-05-19 16:22:24.733415 | instance | ok: 2 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-05-19 16:22:24.733449 | 
2026-05-19 16:22:24.861423 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-05-19 16:22:24.866536 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-05-19 16:22:25.484316 | 
2026-05-19 16:22:25.484480 | PLAY [all]
2026-05-19 16:22:25.496029 | 
2026-05-19 16:22:25.496108 | TASK [fetch-output : Set log path for multiple nodes]
2026-05-19 16:22:25.540273 | instance | skipping: Conditional result was False
2026-05-19 16:22:25.550578 | 
2026-05-19 16:22:25.550749 | TASK [fetch-output : Set log path for single node]
2026-05-19 16:22:25.594487 | instance | ok
2026-05-19 16:22:25.601369 | 
2026-05-19 16:22:25.601470 | LOOP [fetch-output : Ensure local output dirs]
2026-05-19 16:22:25.992125 | instance -> localhost | ok: "/var/lib/zuul/builds/dbf5726e64db4aca88c8eb0d63388b59/work/logs"
2026-05-19 16:22:26.335739 | instance -> localhost | changed: "/var/lib/zuul/builds/dbf5726e64db4aca88c8eb0d63388b59/work/artifacts"
2026-05-19 16:22:26.557449 | instance -> localhost | changed: "/var/lib/zuul/builds/dbf5726e64db4aca88c8eb0d63388b59/work/docs"
2026-05-19 16:22:26.577426 | 
2026-05-19 16:22:26.577612 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-05-19 16:22:27.225126 | instance | changed: .d..t...... ./
2026-05-19 16:22:27.225433 | instance | changed: All items complete
2026-05-19 16:22:27.225478 | 
2026-05-19 16:22:28.591040 | instance | changed: .d..t...... ./
2026-05-19 16:22:29.078831 | instance | changed: .d..t...... ./
2026-05-19 16:22:29.106412 | 
2026-05-19 16:22:29.106545 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-05-19 16:22:29.581333 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.006189
2026-05-19 16:22:29.812697 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.006542
2026-05-19 16:22:29.830793 | 
2026-05-19 16:22:29.830911 | PLAY [all]
2026-05-19 16:22:29.839737 | 
2026-05-19 16:22:29.839877 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-05-19 16:22:30.291116 | instance | changed
2026-05-19 16:22:30.309205 | 
2026-05-19 16:22:30.309293 | PLAY RECAP
2026-05-19 16:22:30.309338 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-05-19 16:22:30.309361 | 
2026-05-19 16:22:30.415083 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-05-19 16:22:30.420127 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-05-19 16:22:31.089248 | 
2026-05-19 16:22:31.089449 | PLAY [localhost]
2026-05-19 16:22:31.103157 | 
2026-05-19 16:22:31.103319 | TASK [Generate Zuul manifest]
2026-05-19 16:22:31.122969 | localhost | ok
2026-05-19 16:22:31.143791 | 
2026-05-19 16:22:31.143975 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-05-19 16:22:31.532784 | localhost | changed
2026-05-19 16:22:31.545505 | 
2026-05-19 16:22:31.545668 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-05-19 16:22:31.589597 | localhost | ok
2026-05-19 16:22:31.598670 | 
2026-05-19 16:22:31.598783 | TASK [Upload logs]
2026-05-19 16:22:31.618546 | localhost | ok
2026-05-19 16:22:31.691864 | 
2026-05-19 16:22:31.692004 | TASK [Set zuul-log-path fact]
2026-05-19 16:22:31.710261 | localhost | ok
2026-05-19 16:22:31.725864 | 
2026-05-19 16:22:31.726002 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-05-19 16:22:31.759157 | localhost | ok
2026-05-19 16:22:31.770880 | 
2026-05-19 16:22:31.771006 | TASK [upload-logs : Create log directories]
2026-05-19 16:22:32.270332 | localhost | changed
2026-05-19 16:22:32.278716 | 
2026-05-19 16:22:32.278824 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-05-19 16:22:32.748223 | localhost -> localhost | ok: Runtime: 0:00:00.004340
2026-05-19 16:22:32.755166 | 
2026-05-19 16:22:32.755272 | TASK [upload-logs : Upload logs to log server]
2026-05-19 16:22:33.238385 | localhost | Output suppressed because no_log was given
2026-05-19 16:22:33.241882 | 
2026-05-19 16:22:33.241943 | LOOP [upload-logs : Compress console log and json output]
2026-05-19 16:22:33.342002 | localhost | skipping: Conditional result was False
2026-05-19 16:22:33.353648 | localhost | skipping: Conditional result was False
2026-05-19 16:22:33.364633 | 
2026-05-19 16:22:33.364782 | LOOP [upload-logs : Upload compressed console log and json output]
2026-05-19 16:22:33.451427 | localhost | skipping: Conditional result was False
2026-05-19 16:22:33.456697 | 
2026-05-19 16:22:33.458088 | localhost | skipping: Conditional result was False
2026-05-19 16:22:33.503340 | 
2026-05-19 16:22:33.503482 | LOOP [upload-logs : Upload console log and json output]