2026-05-19 16:20:14.103724 | Job console starting
2026-05-19 16:20:14.118526 | Updating git repos
2026-05-19 16:20:14.265537 | Cloning repos into workspace
2026-05-19 16:20:16.030794 | Restoring repo states
2026-05-19 16:20:16.062263 | Merging changes
2026-05-19 16:20:17.723390 | Checking out repos
2026-05-19 16:20:18.525815 | Preparing playbooks
2026-05-19 16:20:22.714914 | Running Ansible setup
2026-05-19 16:20:42.584223 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-05-19 16:20:43.357120 | 
2026-05-19 16:20:43.357275 | PLAY [localhost]
2026-05-19 16:20:43.366783 | 
2026-05-19 16:20:43.366894 | TASK [Gathering Facts]
2026-05-19 16:20:44.375607 | localhost | ok
2026-05-19 16:20:44.386125 | 
2026-05-19 16:20:44.386291 | TASK [Setup log path fact]
2026-05-19 16:20:44.406456 | localhost | ok
2026-05-19 16:20:44.420788 | 
2026-05-19 16:20:44.420898 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-05-19 16:20:44.451265 | localhost | ok
2026-05-19 16:20:44.461729 | 
2026-05-19 16:20:44.461885 | TASK [emit-job-header : Print job information]
2026-05-19 16:20:44.503006 | # Job Information
2026-05-19 16:20:44.503187 | Ansible Version: 2.16.18
2026-05-19 16:20:44.503237 | Job: atmosphere-molecule-aio-openvswitch
2026-05-19 16:20:44.503273 | Pipeline: check
2026-05-19 16:20:44.503305 | Executor: 2d72f0692154
2026-05-19 16:20:44.503335 | Triggered by: https://github.com/vexxhost/atmosphere/pull/3945
2026-05-19 16:20:44.503373 | Event ID: 8635b180-539e-11f1-8f4a-8b53e1584e5c
2026-05-19 16:20:44.507588 | 
2026-05-19 16:20:44.507697 | LOOP [emit-job-header : Print node information]
2026-05-19 16:20:44.622189 | localhost | ok:
2026-05-19 16:20:44.622333 | localhost | # Node Information
2026-05-19 16:20:44.622364 | localhost | Inventory Hostname: instance
2026-05-19 16:20:44.622387 | localhost | Hostname: np0000174872
2026-05-19 16:20:44.622407 | localhost | Username: zuul
2026-05-19 16:20:44.622430 | localhost | Distro: Ubuntu 22.04
2026-05-19 16:20:44.622450 | localhost | Provider: yul1
2026-05-19 16:20:44.622469 | localhost | Region: ca-ymq-1
2026-05-19 16:20:44.622487 | localhost | Label: ubuntu-jammy-16
2026-05-19 16:20:44.622505 | localhost | Product Name: OpenStack Nova
2026-05-19 16:20:44.622524 | localhost | Interface IP: 199.204.45.140
2026-05-19 16:20:44.633821 | 
2026-05-19 16:20:44.633933 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-05-19 16:20:45.056618 | localhost -> localhost | changed
2026-05-19 16:20:45.065593 | 
2026-05-19 16:20:45.065717 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-05-19 16:20:46.073717 | localhost -> localhost | changed
2026-05-19 16:20:46.082210 | 
2026-05-19 16:20:46.082301 | PLAY [all]
2026-05-19 16:20:46.094059 | 
2026-05-19 16:20:46.094150 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-05-19 16:20:46.334235 | instance -> localhost | ok
2026-05-19 16:20:46.340770 | 
2026-05-19 16:20:46.340877 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-05-19 16:20:46.370937 | instance | ok
2026-05-19 16:20:46.385878 | instance | included: /var/lib/zuul/builds/9102c97e791743bdb4579e54974a9462/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-05-19 16:20:46.394171 | 
2026-05-19 16:20:46.394310 | TASK [add-build-sshkey : Create Temp SSH key]
2026-05-19 16:20:47.818504 | instance -> localhost | Generating public/private rsa key pair.
2026-05-19 16:20:47.818687 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/9102c97e791743bdb4579e54974a9462/work/9102c97e791743bdb4579e54974a9462_id_rsa
2026-05-19 16:20:47.818718 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/9102c97e791743bdb4579e54974a9462/work/9102c97e791743bdb4579e54974a9462_id_rsa.pub
2026-05-19 16:20:47.818741 | instance -> localhost | The key fingerprint is:
2026-05-19 16:20:47.818763 | instance -> localhost | SHA256:3igPZZ0RF7+Lx6+v8tuRHoGN2hmNXeldbOHBqBZpe+4 zuul-build-sshkey
2026-05-19 16:20:47.818800 | instance -> localhost | The key's randomart image is:
2026-05-19 16:20:47.818822 | instance -> localhost | +---[RSA 3072]----+
2026-05-19 16:20:47.818848 | instance -> localhost | |          . +.oo |
2026-05-19 16:20:47.818870 | instance -> localhost | |           * o.o+|
2026-05-19 16:20:47.818890 | instance -> localhost | |          o + .o=|
2026-05-19 16:20:47.818910 | instance -> localhost | |         . * .Oo+|
2026-05-19 16:20:47.818929 | instance -> localhost | |        S + o=.*.|
2026-05-19 16:20:47.818948 | instance -> localhost | |       + o  o+o.o|
2026-05-19 16:20:47.818967 | instance -> localhost | |      o o ..oo++ |
2026-05-19 16:20:47.818987 | instance -> localhost | |       +    .E.oo|
2026-05-19 16:20:47.819009 | instance -> localhost | |        .    o=*+|
2026-05-19 16:20:47.819029 | instance -> localhost | +----[SHA256]-----+
2026-05-19 16:20:47.819087 | instance -> localhost | ok: Runtime: 0:00:00.963983
2026-05-19 16:20:47.824503 | 
2026-05-19 16:20:47.824584 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-05-19 16:20:47.859593 | instance | ok
2026-05-19 16:20:47.870125 | instance | included: /var/lib/zuul/builds/9102c97e791743bdb4579e54974a9462/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-05-19 16:20:47.878085 | 
2026-05-19 16:20:47.878190 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-05-19 16:20:47.902575 | instance | skipping: Conditional result was False
2026-05-19 16:20:47.912884 | 
2026-05-19 16:20:47.913102 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-05-19 16:20:48.333368 | instance | changed
2026-05-19 16:20:48.340462 | 
2026-05-19 16:20:48.340556 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-05-19 16:20:48.526330 | instance | ok
2026-05-19 16:20:48.575035 | 
2026-05-19 16:20:48.575541 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-05-19 16:20:49.115946 | instance | changed
2026-05-19 16:20:49.122894 | 
2026-05-19 16:20:49.122965 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-05-19 16:20:49.613785 | instance | changed
2026-05-19 16:20:49.620903 | 
2026-05-19 16:20:49.620974 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-05-19 16:20:49.644945 | instance | skipping: Conditional result was False
2026-05-19 16:20:49.656329 | 
2026-05-19 16:20:49.656436 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-05-19 16:20:50.016110 | instance -> localhost | changed
2026-05-19 16:20:50.034181 | 
2026-05-19 16:20:50.034296 | TASK [add-build-sshkey : Add back temp key]
2026-05-19 16:20:50.331664 | instance -> localhost | Identity added: /var/lib/zuul/builds/9102c97e791743bdb4579e54974a9462/work/9102c97e791743bdb4579e54974a9462_id_rsa (zuul-build-sshkey)
2026-05-19 16:20:50.331885 | instance -> localhost | ok: Runtime: 0:00:00.011043
2026-05-19 16:20:50.338859 | 
2026-05-19 16:20:50.338962 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-05-19 16:20:50.616316 | instance | ok
2026-05-19 16:20:50.621840 | 
2026-05-19 16:20:50.621963 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-05-19 16:20:50.648053 | instance | skipping: Conditional result was False
2026-05-19 16:20:50.663528 | 
2026-05-19 16:20:50.663619 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-05-19 16:20:50.966578 | instance | ok
2026-05-19 16:20:50.972372 | 
2026-05-19 16:20:50.972460 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-05-19 16:20:53.127232 | instance | Output suppressed because no_log was given
2026-05-19 16:20:53.136071 | 
2026-05-19 16:20:53.136135 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-05-19 16:20:53.352483 | instance | ok: "logs"
2026-05-19 16:20:53.352743 | instance | ok: All items complete
2026-05-19 16:20:53.352785 | 
2026-05-19 16:20:53.515162 | instance | ok: "artifacts"
2026-05-19 16:20:53.672487 | instance | ok: "docs"
2026-05-19 16:20:53.682956 | 
2026-05-19 16:20:53.738343 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-05-19 16:20:53.922607 | instance | changed: "logs"
2026-05-19 16:20:54.083609 | instance | changed: "artifacts"
2026-05-19 16:20:54.252947 | instance | changed: "docs"
2026-05-19 16:20:54.274136 | 
2026-05-19 16:20:54.274330 | PLAY RECAP
2026-05-19 16:20:54.274413 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-05-19 16:20:54.274459 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-05-19 16:20:54.274496 | 
2026-05-19 16:20:54.453650 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-05-19 16:20:54.465435 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-05-19 16:20:55.109757 | 
2026-05-19 16:20:55.109936 | PLAY [all]
2026-05-19 16:20:55.125635 | 
2026-05-19 16:20:55.126508 | TASK [setup-uv : Extract archive]
2026-05-19 16:20:57.551269 | instance | changed
2026-05-19 16:20:57.556756 | 
2026-05-19 16:20:57.556829 | TASK [setup-uv : Print version]
2026-05-19 16:20:57.893954 | instance | uv 0.8.13
2026-05-19 16:20:58.087941 | instance | ok: Runtime: 0:00:00.013281
2026-05-19 16:20:58.097778 | 
2026-05-19 16:20:58.097912 | TASK [Install Ansible collection siblings]
2026-05-19 16:20:58.160371 | instance | ERROR
2026-05-19 16:20:58.160675 | instance | {
2026-05-19 16:20:58.160730 | 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:20:58.160774 | instance | }
failure
2026-05-19 16:20:58.162328 | 
2026-05-19 16:20:58.162401 | PLAY RECAP
2026-05-19 16:20:58.162476 | instance | ok: 2 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-05-19 16:20:58.162519 | 
2026-05-19 16:20:58.287947 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-05-19 16:20:58.299760 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-05-19 16:20:58.932467 | 
2026-05-19 16:20:58.932623 | PLAY [all]
2026-05-19 16:20:58.944561 | 
2026-05-19 16:20:58.944688 | TASK [fetch-output : Set log path for multiple nodes]
2026-05-19 16:20:58.989300 | instance | skipping: Conditional result was False
2026-05-19 16:20:58.999156 | 
2026-05-19 16:20:58.999258 | TASK [fetch-output : Set log path for single node]
2026-05-19 16:20:59.034543 | instance | ok
2026-05-19 16:20:59.040001 | 
2026-05-19 16:20:59.040091 | LOOP [fetch-output : Ensure local output dirs]
2026-05-19 16:20:59.454387 | instance -> localhost | ok: "/var/lib/zuul/builds/9102c97e791743bdb4579e54974a9462/work/logs"
2026-05-19 16:20:59.669282 | instance -> localhost | changed: "/var/lib/zuul/builds/9102c97e791743bdb4579e54974a9462/work/artifacts"
2026-05-19 16:20:59.888412 | instance -> localhost | changed: "/var/lib/zuul/builds/9102c97e791743bdb4579e54974a9462/work/docs"
2026-05-19 16:20:59.907593 | 
2026-05-19 16:20:59.907728 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-05-19 16:21:00.586856 | instance | changed: .d..t...... ./
2026-05-19 16:21:00.587047 | instance | changed: All items complete
2026-05-19 16:21:00.587077 | 
2026-05-19 16:21:01.038163 | instance | changed: .d..t...... ./
2026-05-19 16:21:01.496856 | instance | changed: .d..t...... ./
2026-05-19 16:21:01.517975 | 
2026-05-19 16:21:01.518099 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-05-19 16:21:01.947624 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.006539
2026-05-19 16:21:02.224918 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.008898
2026-05-19 16:21:02.240239 | 
2026-05-19 16:21:02.240330 | PLAY [all]
2026-05-19 16:21:02.246487 | 
2026-05-19 16:21:02.246573 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-05-19 16:21:02.697979 | instance | changed
2026-05-19 16:21:02.704688 | 
2026-05-19 16:21:02.704760 | PLAY RECAP
2026-05-19 16:21:02.704810 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-05-19 16:21:02.704837 | 
2026-05-19 16:21:02.840523 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-05-19 16:21:02.852113 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-05-19 16:21:03.599419 | 
2026-05-19 16:21:03.599839 | PLAY [localhost]
2026-05-19 16:21:03.615368 | 
2026-05-19 16:21:03.615539 | TASK [Generate Zuul manifest]
2026-05-19 16:21:03.635690 | localhost | ok
2026-05-19 16:21:03.653233 | 
2026-05-19 16:21:03.655311 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-05-19 16:21:04.062042 | localhost | changed
2026-05-19 16:21:04.074851 | 
2026-05-19 16:21:04.074959 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-05-19 16:21:04.106541 | localhost | ok
2026-05-19 16:21:04.116544 | 
2026-05-19 16:21:04.116626 | TASK [Upload logs]
2026-05-19 16:21:04.138946 | localhost | ok
2026-05-19 16:21:04.273499 | 
2026-05-19 16:21:04.273652 | TASK [Set zuul-log-path fact]
2026-05-19 16:21:04.293150 | localhost | ok
2026-05-19 16:21:04.309748 | 
2026-05-19 16:21:04.309905 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-05-19 16:21:04.342453 | localhost | ok
2026-05-19 16:21:04.353253 | 
2026-05-19 16:21:04.353356 | TASK [upload-logs : Create log directories]
2026-05-19 16:21:04.884829 | localhost | changed
2026-05-19 16:21:04.891295 | 
2026-05-19 16:21:04.891367 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-05-19 16:21:05.326265 | localhost -> localhost | ok: Runtime: 0:00:00.005431
2026-05-19 16:21:05.331872 | 
2026-05-19 16:21:05.331944 | TASK [upload-logs : Upload logs to log server]
2026-05-19 16:21:05.823758 | localhost | Output suppressed because no_log was given
2026-05-19 16:21:05.829970 | 
2026-05-19 16:21:05.830125 | LOOP [upload-logs : Compress console log and json output]
2026-05-19 16:21:05.877049 | localhost | skipping: Conditional result was False
2026-05-19 16:21:05.884920 | localhost | skipping: Conditional result was False
2026-05-19 16:21:05.897446 | 
2026-05-19 16:21:05.897654 | LOOP [upload-logs : Upload compressed console log and json output]
2026-05-19 16:21:05.953012 | localhost | skipping: Conditional result was False
2026-05-19 16:21:05.953327 | 
2026-05-19 16:21:05.959156 | localhost | skipping: Conditional result was False
2026-05-19 16:21:05.973805 | 
2026-05-19 16:21:05.974031 | LOOP [upload-logs : Upload console log and json output]