2026-06-02 06:44:27.318691 | Job console starting
2026-06-02 06:44:27.335251 | Updating git repos
2026-06-02 06:44:29.287644 | Cloning repos into workspace
2026-06-02 06:44:45.696062 | Restoring repo states
2026-06-02 06:44:55.435251 | Merging changes
2026-06-02 06:44:57.233818 | Checking out repos
2026-06-02 06:45:04.180102 | Preparing playbooks
2026-06-02 06:45:10.274430 | Running Ansible setup
2026-06-02 06:45:13.791601 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-06-02 06:45:14.522264 | 
2026-06-02 06:45:14.522404 | PLAY [localhost]
2026-06-02 06:45:14.529796 | 
2026-06-02 06:45:14.529873 | TASK [Gathering Facts]
2026-06-02 06:45:15.732925 | localhost | ok
2026-06-02 06:45:15.740700 | 
2026-06-02 06:45:15.740821 | TASK [Setup log path fact]
2026-06-02 06:45:15.761165 | localhost | ok
2026-06-02 06:45:15.784837 | 
2026-06-02 06:45:15.784985 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-06-02 06:45:15.823136 | localhost | ok
2026-06-02 06:45:15.829990 | 
2026-06-02 06:45:15.830077 | TASK [emit-job-header : Print job information]
2026-06-02 06:45:15.892701 | # Job Information
2026-06-02 06:45:15.892989 | Ansible Version: 2.16.18
2026-06-02 06:45:15.893064 | Job: atmosphere-molecule-aio-ovn
2026-06-02 06:45:15.893102 | Pipeline: check
2026-06-02 06:45:15.893134 | Executor: 2d72f0692154
2026-06-02 06:45:15.893164 | Triggered by: https://github.com/vexxhost/atmosphere/pull/3985
2026-06-02 06:45:15.893201 | Event ID: 16ba3c80-5e4e-11f1-89c3-bd66d3bd5fd1
2026-06-02 06:45:15.908772 | 
2026-06-02 06:45:15.908909 | LOOP [emit-job-header : Print node information]
2026-06-02 06:45:16.128373 | localhost | ok:
2026-06-02 06:45:16.128557 | localhost | # Node Information
2026-06-02 06:45:16.128602 | localhost | Inventory Hostname: instance
2026-06-02 06:45:16.128638 | localhost | Hostname: np0000177995
2026-06-02 06:45:16.128671 | localhost | Username: zuul
2026-06-02 06:45:16.128709 | localhost | Distro: Ubuntu 22.04
2026-06-02 06:45:16.128742 | localhost | Provider: yul1
2026-06-02 06:45:16.128774 | localhost | Region: ca-ymq-1
2026-06-02 06:45:16.128806 | localhost | Label: ubuntu-jammy-16
2026-06-02 06:45:16.128837 | localhost | Product Name: OpenStack Nova
2026-06-02 06:45:16.128869 | localhost | Interface IP: 199.204.45.132
2026-06-02 06:45:16.141754 | 
2026-06-02 06:45:16.141868 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-06-02 06:45:16.697423 | localhost -> localhost | changed
2026-06-02 06:45:16.702889 | 
2026-06-02 06:45:16.702958 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-06-02 06:45:17.977837 | localhost -> localhost | changed
2026-06-02 06:45:17.983892 | 
2026-06-02 06:45:17.983950 | PLAY [all]
2026-06-02 06:45:17.991357 | 
2026-06-02 06:45:17.991422 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-06-02 06:45:18.256073 | instance -> localhost | ok
2026-06-02 06:45:18.267276 | 
2026-06-02 06:45:18.267365 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-06-02 06:45:18.303726 | instance | ok
2026-06-02 06:45:18.331386 | instance | included: /var/lib/zuul/builds/4b02ac65415c4bc8ac80e3250491e575/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-06-02 06:45:18.342891 | 
2026-06-02 06:45:18.342975 | TASK [add-build-sshkey : Create Temp SSH key]
2026-06-02 06:45:19.379799 | instance -> localhost | Generating public/private rsa key pair.
2026-06-02 06:45:19.379942 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/4b02ac65415c4bc8ac80e3250491e575/work/4b02ac65415c4bc8ac80e3250491e575_id_rsa
2026-06-02 06:45:19.379971 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/4b02ac65415c4bc8ac80e3250491e575/work/4b02ac65415c4bc8ac80e3250491e575_id_rsa.pub
2026-06-02 06:45:19.379994 | instance -> localhost | The key fingerprint is:
2026-06-02 06:45:19.380016 | instance -> localhost | SHA256:eiiLLcsD8GDGQCJND3lEwaBRvCZD93oCXUlssfHto/U zuul-build-sshkey
2026-06-02 06:45:19.380044 | instance -> localhost | The key's randomart image is:
2026-06-02 06:45:19.380067 | instance -> localhost | +---[RSA 3072]----+
2026-06-02 06:45:19.380093 | instance -> localhost | |=*=**=o          |
2026-06-02 06:45:19.380115 | instance -> localhost | |++++o=+ .        |
2026-06-02 06:45:19.380136 | instance -> localhost | |= oo=. . .       |
2026-06-02 06:45:19.380156 | instance -> localhost | |==o. .  .        |
2026-06-02 06:45:19.380176 | instance -> localhost | |+*. .   S+       |
2026-06-02 06:45:19.380196 | instance -> localhost | |. .o . oo o      |
2026-06-02 06:45:19.380218 | instance -> localhost | | .  + o..  E     |
2026-06-02 06:45:19.380240 | instance -> localhost | | .oo o .         |
2026-06-02 06:45:19.380262 | instance -> localhost | |  ++o            |
2026-06-02 06:45:19.380282 | instance -> localhost | +----[SHA256]-----+
2026-06-02 06:45:19.380327 | instance -> localhost | ok: Runtime: 0:00:00.469684
2026-06-02 06:45:19.386292 | 
2026-06-02 06:45:19.386374 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-06-02 06:45:19.417865 | instance | ok
2026-06-02 06:45:19.426178 | instance | included: /var/lib/zuul/builds/4b02ac65415c4bc8ac80e3250491e575/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-06-02 06:45:19.434684 | 
2026-06-02 06:45:19.434747 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-06-02 06:45:19.458022 | instance | skipping: Conditional result was False
2026-06-02 06:45:19.610922 | 
2026-06-02 06:45:19.611017 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-06-02 06:45:20.058956 | instance | changed
2026-06-02 06:45:20.152616 | 
2026-06-02 06:45:20.152708 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-06-02 06:45:20.375604 | instance | ok
2026-06-02 06:45:20.380364 | 
2026-06-02 06:45:20.380425 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-06-02 06:45:20.838384 | instance | changed
2026-06-02 06:45:20.845226 | 
2026-06-02 06:45:20.845314 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-06-02 06:45:21.292250 | instance | changed
2026-06-02 06:45:21.300443 | 
2026-06-02 06:45:21.300532 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-06-02 06:45:21.314018 | instance | skipping: Conditional result was False
2026-06-02 06:45:21.326756 | 
2026-06-02 06:45:21.326829 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-06-02 06:45:21.655115 | instance -> localhost | changed
2026-06-02 06:45:21.669986 | 
2026-06-02 06:45:21.670228 | TASK [add-build-sshkey : Add back temp key]
2026-06-02 06:45:22.075050 | instance -> localhost | Identity added: /var/lib/zuul/builds/4b02ac65415c4bc8ac80e3250491e575/work/4b02ac65415c4bc8ac80e3250491e575_id_rsa (zuul-build-sshkey)
2026-06-02 06:45:22.075277 | instance -> localhost | ok: Runtime: 0:00:00.012508
2026-06-02 06:45:22.082367 | 
2026-06-02 06:45:22.082731 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-06-02 06:45:22.345436 | instance | ok
2026-06-02 06:45:22.362242 | 
2026-06-02 06:45:22.362382 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-06-02 06:45:22.387694 | instance | skipping: Conditional result was False
2026-06-02 06:45:22.490667 | 
2026-06-02 06:45:22.491157 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-06-02 06:45:22.762553 | instance | ok
2026-06-02 06:45:22.768180 | 
2026-06-02 06:45:22.768244 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-06-02 06:45:27.256022 | instance | Output suppressed because no_log was given
2026-06-02 06:45:27.270909 | 
2026-06-02 06:45:27.271015 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-06-02 06:45:27.503914 | instance | ok: "logs"
2026-06-02 06:45:27.504130 | instance | ok: All items complete
2026-06-02 06:45:27.504158 | 
2026-06-02 06:45:27.655380 | instance | ok: "artifacts"
2026-06-02 06:45:27.809770 | instance | ok: "docs"
2026-06-02 06:45:27.825227 | 
2026-06-02 06:45:27.825342 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-06-02 06:45:28.018007 | instance | changed: "logs"
2026-06-02 06:45:28.171911 | instance | changed: "artifacts"
2026-06-02 06:45:28.309818 | instance | changed: "docs"
2026-06-02 06:45:28.401805 | 
2026-06-02 06:45:28.401901 | PLAY RECAP
2026-06-02 06:45:28.401947 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-06-02 06:45:28.401976 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-06-02 06:45:28.401998 | 
2026-06-02 06:45:28.559613 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-06-02 06:45:28.575896 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-06-02 06:45:29.238555 | 
2026-06-02 06:45:29.239118 | PLAY [all]
2026-06-02 06:45:29.250157 | 
2026-06-02 06:45:29.250337 | TASK [setup-uv : Extract archive]
2026-06-02 06:45:31.611897 | instance | changed
2026-06-02 06:45:31.729803 | 
2026-06-02 06:45:31.729982 | TASK [setup-uv : Print version]
2026-06-02 06:45:32.095603 | instance | uv 0.8.13
2026-06-02 06:45:32.281439 | instance | ok: Runtime: 0:00:00.010909
2026-06-02 06:45:32.291641 | 
2026-06-02 06:45:32.291713 | TASK [Install Ansible collection siblings]
2026-06-02 06:45:32.943224 | instance | ERROR
2026-06-02 06:45:32.943459 | instance | {
2026-06-02 06:45:32.943491 | instance |   "log": [
2026-06-02 06:45:32.943512 | instance |     "Sibling collection ansible.netcommon at /home/zuul/src/github.com/ansible-collections/ansible.netcommon",
2026-06-02 06:45:32.943535 | instance |     "Sibling collection ansible.posix at /home/zuul/src/github.com/ansible-collections/ansible.posix",
2026-06-02 06:45:32.943554 | instance |     "Sibling collection ansible.utils at /home/zuul/src/github.com/ansible-collections/ansible.utils",
2026-06-02 06:45:32.943576 | instance |     "Sibling collection community.crypto at /home/zuul/src/github.com/ansible-collections/community.crypto",
2026-06-02 06:45:32.943594 | instance |     "Sibling collection community.general at /home/zuul/src/github.com/ansible-collections/community.general",
2026-06-02 06:45:32.943614 | instance |     "Sibling collection community.mysql at /home/zuul/src/github.com/ansible-collections/community.mysql",
2026-06-02 06:45:32.943632 | instance |     "Sibling collection kubernetes.core at /home/zuul/src/github.com/ansible-collections/kubernetes.core",
2026-06-02 06:45:32.943650 | instance |     "Sibling collection vexxhost.ceph at /home/zuul/src/github.com/vexxhost/ansible-collection-ceph",
2026-06-02 06:45:32.943667 | instance |     "Sibling collection vexxhost.containers at /home/zuul/src/github.com/vexxhost/ansible-collection-containers",
2026-06-02 06:45:32.943684 | instance |     "Sibling collection vexxhost.kubernetes at /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes",
2026-06-02 06:45:32.943701 | instance |     "Sibling collection atmosphere.common at /home/zuul/src/github.com/vexxhost/atmosphere.common",
2026-06-02 06:45:32.943717 | instance |     "Sibling collection openstack.cloud at /home/zuul/src/opendev.org/openstack/ansible-collections-openstack",
2026-06-02 06:45:32.943734 | instance |     "Resolved ansible.netcommon to v8.5.2 for >=1.2.0",
2026-06-02 06:45:32.943752 | instance |     "Resolved ansible.posix to 1.6.0 for 1.6.0",
2026-06-02 06:45:32.943770 | instance |     "Resolved ansible.utils to v6.0.2 for >=2.9.0,>=3.0.0",
2026-06-02 06:45:32.943788 | instance |     "Resolved community.crypto to 2.2.3 for 2.2.3",
2026-06-02 06:45:32.943805 | instance |     "Resolved community.general to 7.3.0 for 7.3.0",
2026-06-02 06:45:32.943822 | instance |     "Resolved community.mysql to 3.6.0 for 3.6.0",
2026-06-02 06:45:32.943839 | instance |     "Resolved kubernetes.core to 2.4.0 for 2.4.0",
2026-06-02 06:45:32.943856 | instance |     "Resolved openstack.cloud to 1.7.0 for 1.7.0",
2026-06-02 06:45:32.943874 | instance |     "Resolved atmosphere.common to v0.7.0 for >=0.6.0",
2026-06-02 06:45:32.943902 | instance |     "Resolved vexxhost.ceph to v4.0.0 for >=3.1.2",
2026-06-02 06:45:32.943920 | instance |     "Resolved vexxhost.kubernetes to v3.1.0 for >=2.3.2,>=2.5.0",
2026-06-02 06:45:32.943937 | instance |     "Resolved ansible.utils to v6.0.2 for >=2.9.0,>=3.0.0,>=6.0.0",
2026-06-02 06:45:32.943954 | instance |     "Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2"
2026-06-02 06:45:32.943971 | instance |   ],
2026-06-02 06:45:32.943988 | instance |   "msg": "No local release tag for openstack.cloud satisfies 1.7.0,>=2.0.0"
2026-06-02 06:45:32.944005 | instance | }
failure
2026-06-02 06:45:32.946850 | 
2026-06-02 06:45:32.946895 | PLAY RECAP
2026-06-02 06:45:32.946940 | instance | ok: 2 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-06-02 06:45:32.946961 | 
2026-06-02 06:45:33.070502 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-06-02 06:45:33.388829 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-06-02 06:45:34.029150 | 
2026-06-02 06:45:34.110924 | PLAY [all]
2026-06-02 06:45:34.130265 | 
2026-06-02 06:45:34.130389 | TASK [fetch-output : Set log path for multiple nodes]
2026-06-02 06:45:34.175312 | instance | skipping: Conditional result was False
2026-06-02 06:45:34.185192 | 
2026-06-02 06:45:34.185390 | TASK [fetch-output : Set log path for single node]
2026-06-02 06:45:34.219377 | instance | ok
2026-06-02 06:45:34.224180 | 
2026-06-02 06:45:34.224250 | LOOP [fetch-output : Ensure local output dirs]
2026-06-02 06:45:34.845353 | instance -> localhost | ok: "/var/lib/zuul/builds/4b02ac65415c4bc8ac80e3250491e575/work/logs"
2026-06-02 06:45:35.050713 | instance -> localhost | changed: "/var/lib/zuul/builds/4b02ac65415c4bc8ac80e3250491e575/work/artifacts"
2026-06-02 06:45:35.527128 | instance -> localhost | changed: "/var/lib/zuul/builds/4b02ac65415c4bc8ac80e3250491e575/work/docs"
2026-06-02 06:45:35.540816 | 
2026-06-02 06:45:35.540937 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-06-02 06:45:36.196628 | instance | changed: .d..t...... ./
2026-06-02 06:45:36.196816 | instance | changed: All items complete
2026-06-02 06:45:36.196845 | 
2026-06-02 06:45:36.664169 | instance | changed: .d..t...... ./
2026-06-02 06:45:37.121340 | instance | changed: .d..t...... ./
2026-06-02 06:45:37.142582 | 
2026-06-02 06:45:37.142693 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-06-02 06:45:38.195333 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.239854
2026-06-02 06:45:38.533304 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.088581
2026-06-02 06:45:38.545160 | 
2026-06-02 06:45:38.545246 | PLAY [all]
2026-06-02 06:45:38.555743 | 
2026-06-02 06:45:38.555883 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-06-02 06:45:38.989230 | instance | changed
2026-06-02 06:45:38.999928 | 
2026-06-02 06:45:38.999979 | PLAY RECAP
2026-06-02 06:45:39.000022 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-06-02 06:45:39.000044 | 
2026-06-02 06:45:39.123307 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-06-02 06:45:39.265635 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-06-02 06:45:39.927233 | 
2026-06-02 06:45:39.927425 | PLAY [localhost]
2026-06-02 06:45:39.937462 | 
2026-06-02 06:45:39.937594 | TASK [Generate Zuul manifest]
2026-06-02 06:45:39.972324 | localhost | ok
2026-06-02 06:45:39.992513 | 
2026-06-02 06:45:39.992716 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-06-02 06:45:40.508094 | localhost | changed
2026-06-02 06:45:40.518765 | 
2026-06-02 06:45:40.518861 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-06-02 06:45:40.577890 | localhost | ok
2026-06-02 06:45:40.591945 | 
2026-06-02 06:45:40.592081 | TASK [Upload logs]
2026-06-02 06:45:40.611266 | localhost | ok
2026-06-02 06:45:40.718653 | 
2026-06-02 06:45:40.795967 | TASK [Set zuul-log-path fact]
2026-06-02 06:45:40.820799 | localhost | ok
2026-06-02 06:45:40.848129 | 
2026-06-02 06:45:40.848279 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-06-02 06:45:40.882527 | localhost | ok
2026-06-02 06:45:41.023893 | 
2026-06-02 06:45:41.024025 | TASK [upload-logs : Create log directories]
2026-06-02 06:45:41.635605 | localhost | changed
2026-06-02 06:45:41.640573 | 
2026-06-02 06:45:41.640659 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-06-02 06:45:42.279455 | localhost -> localhost | ok: Runtime: 0:00:00.130632
2026-06-02 06:45:42.285816 | 
2026-06-02 06:45:42.285946 | TASK [upload-logs : Upload logs to log server]
2026-06-02 06:45:43.024276 | localhost | Output suppressed because no_log was given
2026-06-02 06:45:43.252508 | 
2026-06-02 06:45:43.252655 | LOOP [upload-logs : Compress console log and json output]
2026-06-02 06:45:43.304115 | localhost | skipping: Conditional result was False
2026-06-02 06:45:43.311564 | localhost | skipping: Conditional result was False
2026-06-02 06:45:43.330696 | 
2026-06-02 06:45:43.330878 | LOOP [upload-logs : Upload compressed console log and json output]
2026-06-02 06:45:43.379595 | localhost | skipping: Conditional result was False
2026-06-02 06:45:43.379896 | 
2026-06-02 06:45:43.383679 | localhost | skipping: Conditional result was False
2026-06-02 06:45:43.403563 | 
2026-06-02 06:45:43.403767 | LOOP [upload-logs : Upload console log and json output]