2026-06-05 05:56:50.207611 | Job console starting
2026-06-05 05:56:50.225527 | Updating git repos
2026-06-05 05:56:53.302501 | Cloning repos into workspace
2026-06-05 05:57:26.174643 | Restoring repo states
2026-06-05 05:57:35.445257 | Merging changes
2026-06-05 05:57:36.433188 | Checking out repos
2026-06-05 05:57:38.263556 | Preparing playbooks
2026-06-05 05:57:40.353660 | Running Ansible setup
2026-06-05 05:57:43.867272 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-06-05 05:57:44.591649 | 
2026-06-05 05:57:44.591992 | PLAY [localhost]
2026-06-05 05:57:44.599712 | 
2026-06-05 05:57:44.599782 | TASK [Gathering Facts]
2026-06-05 05:57:45.518450 | localhost | ok
2026-06-05 05:57:45.533264 | 
2026-06-05 05:57:45.533434 | TASK [Setup log path fact]
2026-06-05 05:57:45.556353 | localhost | ok
2026-06-05 05:57:45.574529 | 
2026-06-05 05:57:45.574644 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-06-05 05:57:45.601782 | localhost | ok
2026-06-05 05:57:45.607608 | 
2026-06-05 05:57:45.607675 | TASK [emit-job-header : Print job information]
2026-06-05 05:57:45.645454 | # Job Information
2026-06-05 05:57:45.645573 | Ansible Version: 2.16.18
2026-06-05 05:57:45.645605 | Job: atmosphere-molecule-aio-ovn
2026-06-05 05:57:45.645626 | Pipeline: check
2026-06-05 05:57:45.645646 | Executor: 2d72f0692154
2026-06-05 05:57:45.645666 | Triggered by: https://github.com/vexxhost/atmosphere/pull/3985
2026-06-05 05:57:45.645689 | Event ID: cc509e80-60a2-11f1-8c23-4595eac5c44c
2026-06-05 05:57:45.648378 | 
2026-06-05 05:57:45.648438 | LOOP [emit-job-header : Print node information]
2026-06-05 05:57:45.738319 | localhost | ok:
2026-06-05 05:57:45.738770 | localhost | # Node Information
2026-06-05 05:57:45.738803 | localhost | Inventory Hostname: instance
2026-06-05 05:57:45.738828 | localhost | Hostname: np0000178651
2026-06-05 05:57:45.738850 | localhost | Username: zuul
2026-06-05 05:57:45.738876 | localhost | Distro: Ubuntu 22.04
2026-06-05 05:57:45.738897 | localhost | Provider: yul1
2026-06-05 05:57:45.738917 | localhost | Region: ca-ymq-1
2026-06-05 05:57:45.738937 | localhost | Label: ubuntu-jammy-16
2026-06-05 05:57:45.738956 | localhost | Product Name: OpenStack Nova
2026-06-05 05:57:45.738976 | localhost | Interface IP: 199.204.45.210
2026-06-05 05:57:45.751571 | 
2026-06-05 05:57:45.751753 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-06-05 05:57:46.313840 | localhost -> localhost | changed
2026-06-05 05:57:46.323451 | 
2026-06-05 05:57:46.323555 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-06-05 05:57:47.979842 | localhost -> localhost | changed
2026-06-05 05:57:47.990616 | 
2026-06-05 05:57:47.990712 | PLAY [all]
2026-06-05 05:57:47.999503 | 
2026-06-05 05:57:47.999590 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-06-05 05:57:48.226551 | instance -> localhost | ok
2026-06-05 05:57:48.238190 | 
2026-06-05 05:57:48.238263 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-06-05 05:57:48.275559 | instance | ok
2026-06-05 05:57:48.299752 | instance | included: /var/lib/zuul/builds/4bb039cbe7194e40b383c5c40e08f027/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-06-05 05:57:48.313021 | 
2026-06-05 05:57:48.313117 | TASK [add-build-sshkey : Create Temp SSH key]
2026-06-05 05:57:49.445412 | instance -> localhost | Generating public/private rsa key pair.
2026-06-05 05:57:49.445626 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/4bb039cbe7194e40b383c5c40e08f027/work/4bb039cbe7194e40b383c5c40e08f027_id_rsa
2026-06-05 05:57:49.445667 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/4bb039cbe7194e40b383c5c40e08f027/work/4bb039cbe7194e40b383c5c40e08f027_id_rsa.pub
2026-06-05 05:57:49.445699 | instance -> localhost | The key fingerprint is:
2026-06-05 05:57:49.445729 | instance -> localhost | SHA256:fjjXFflyzCWbTRGQjCN8mS7UHT5zWvNZQHix/Mg9snc zuul-build-sshkey
2026-06-05 05:57:49.445770 | instance -> localhost | The key's randomart image is:
2026-06-05 05:57:49.445800 | instance -> localhost | +---[RSA 3072]----+
2026-06-05 05:57:49.445832 | instance -> localhost | |        . . *=B+.|
2026-06-05 05:57:49.445861 | instance -> localhost | |         + Bo=.+.|
2026-06-05 05:57:49.445889 | instance -> localhost | |        . + .=*++|
2026-06-05 05:57:49.445917 | instance -> localhost | |         . . .*#*|
2026-06-05 05:57:49.445967 | instance -> localhost | |        S .  oBoX|
2026-06-05 05:57:49.445996 | instance -> localhost | |       . . . .oo.|
2026-06-05 05:57:49.446023 | instance -> localhost | |        + o .. .E|
2026-06-05 05:57:49.446051 | instance -> localhost | |         +    . .|
2026-06-05 05:57:49.446081 | instance -> localhost | |                 |
2026-06-05 05:57:49.446109 | instance -> localhost | +----[SHA256]-----+
2026-06-05 05:57:49.446174 | instance -> localhost | ok: Runtime: 0:00:00.608692
2026-06-05 05:57:49.454556 | 
2026-06-05 05:57:49.454621 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-06-05 05:57:49.487572 | instance | ok
2026-06-05 05:57:49.558634 | instance | included: /var/lib/zuul/builds/4bb039cbe7194e40b383c5c40e08f027/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-06-05 05:57:49.568474 | 
2026-06-05 05:57:49.568541 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-06-05 05:57:49.594782 | instance | skipping: Conditional result was False
2026-06-05 05:57:49.602420 | 
2026-06-05 05:57:49.602493 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-06-05 05:57:50.034278 | instance | changed
2026-06-05 05:57:50.140925 | 
2026-06-05 05:57:50.141094 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-06-05 05:57:50.367291 | instance | ok
2026-06-05 05:57:50.667277 | 
2026-06-05 05:57:50.667410 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-06-05 05:57:51.136846 | instance | changed
2026-06-05 05:57:51.144115 | 
2026-06-05 05:57:51.144243 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-06-05 05:57:51.607407 | instance | changed
2026-06-05 05:57:51.616724 | 
2026-06-05 05:57:51.616822 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-06-05 05:57:51.643209 | instance | skipping: Conditional result was False
2026-06-05 05:57:51.655460 | 
2026-06-05 05:57:51.655573 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-06-05 05:57:52.017288 | instance -> localhost | changed
2026-06-05 05:57:52.031938 | 
2026-06-05 05:57:52.032023 | TASK [add-build-sshkey : Add back temp key]
2026-06-05 05:57:52.320109 | instance -> localhost | Identity added: /var/lib/zuul/builds/4bb039cbe7194e40b383c5c40e08f027/work/4bb039cbe7194e40b383c5c40e08f027_id_rsa (zuul-build-sshkey)
2026-06-05 05:57:52.320435 | instance -> localhost | ok: Runtime: 0:00:00.013124
2026-06-05 05:57:52.328133 | 
2026-06-05 05:57:52.328219 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-06-05 05:57:52.602397 | instance | ok
2026-06-05 05:57:52.694055 | 
2026-06-05 05:57:52.694254 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-06-05 05:57:52.720596 | instance | skipping: Conditional result was False
2026-06-05 05:57:52.735130 | 
2026-06-05 05:57:52.735218 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-06-05 05:57:53.025758 | instance | ok
2026-06-05 05:57:53.034024 | 
2026-06-05 05:57:53.034112 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-06-05 05:57:56.255610 | instance | Output suppressed because no_log was given
2026-06-05 05:57:56.264209 | 
2026-06-05 05:57:56.264271 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-06-05 05:57:56.458532 | instance | ok: "logs"
2026-06-05 05:57:56.458977 | instance | ok: All items complete
2026-06-05 05:57:56.459018 | 
2026-06-05 05:57:56.604857 | instance | ok: "artifacts"
2026-06-05 05:57:56.760515 | instance | ok: "docs"
2026-06-05 05:57:56.777998 | 
2026-06-05 05:57:56.778085 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-06-05 05:57:56.965735 | instance | changed: "logs"
2026-06-05 05:57:57.119814 | instance | changed: "artifacts"
2026-06-05 05:57:57.272501 | instance | changed: "docs"
2026-06-05 05:57:57.295553 | 
2026-06-05 05:57:57.295675 | PLAY RECAP
2026-06-05 05:57:57.295730 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-06-05 05:57:57.295760 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-06-05 05:57:57.295780 | 
2026-06-05 05:57:57.445593 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-06-05 05:57:57.457279 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-06-05 05:57:58.119647 | 
2026-06-05 05:57:58.119792 | PLAY [all]
2026-06-05 05:57:58.132084 | 
2026-06-05 05:57:58.132212 | TASK [setup-uv : Extract archive]
2026-06-05 05:58:00.449665 | instance | changed
2026-06-05 05:58:00.458808 | 
2026-06-05 05:58:00.458900 | TASK [setup-uv : Print version]
2026-06-05 05:58:00.811409 | instance | uv 0.8.13
2026-06-05 05:58:00.996917 | instance | ok: Runtime: 0:00:00.009909
2026-06-05 05:58:01.008773 | 
2026-06-05 05:58:01.008865 | TASK [Install Ansible collection siblings]
2026-06-05 05:58:01.632148 | instance | ERROR
2026-06-05 05:58:01.632448 | instance | {
2026-06-05 05:58:01.632480 | instance |   "log": [
2026-06-05 05:58:01.632503 | instance |     "Sibling collection ansible.netcommon at /home/zuul/src/github.com/ansible-collections/ansible.netcommon",
2026-06-05 05:58:01.632528 | instance |     "Sibling collection ansible.posix at /home/zuul/src/github.com/ansible-collections/ansible.posix",
2026-06-05 05:58:01.632548 | instance |     "Sibling collection ansible.utils at /home/zuul/src/github.com/ansible-collections/ansible.utils",
2026-06-05 05:58:01.632570 | instance |     "Sibling collection community.crypto at /home/zuul/src/github.com/ansible-collections/community.crypto",
2026-06-05 05:58:01.632589 | instance |     "Sibling collection community.general at /home/zuul/src/github.com/ansible-collections/community.general",
2026-06-05 05:58:01.632610 | instance |     "Sibling collection community.mysql at /home/zuul/src/github.com/ansible-collections/community.mysql",
2026-06-05 05:58:01.632629 | instance |     "Sibling collection kubernetes.core at /home/zuul/src/github.com/ansible-collections/kubernetes.core",
2026-06-05 05:58:01.632647 | instance |     "Sibling collection vexxhost.ceph at /home/zuul/src/github.com/vexxhost/ansible-collection-ceph",
2026-06-05 05:58:01.632666 | instance |     "Sibling collection vexxhost.containers at /home/zuul/src/github.com/vexxhost/ansible-collection-containers",
2026-06-05 05:58:01.632684 | instance |     "Sibling collection vexxhost.kubernetes at /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes",
2026-06-05 05:58:01.632703 | instance |     "Sibling collection atmosphere.common at /home/zuul/src/github.com/vexxhost/atmosphere.common",
2026-06-05 05:58:01.632720 | instance |     "Sibling collection openstack.cloud at /home/zuul/src/opendev.org/openstack/ansible-collections-openstack",
2026-06-05 05:58:01.632738 | instance |     "Resolved ansible.netcommon to v8.5.2 for >=1.2.0",
2026-06-05 05:58:01.632757 | instance |     "Resolved ansible.posix to 1.6.0 for 1.6.0",
2026-06-05 05:58:01.632776 | instance |     "Resolved ansible.utils to v6.0.2 for >=2.9.0,>=3.0.0",
2026-06-05 05:58:01.632794 | instance |     "Resolved community.crypto to 2.2.3 for 2.2.3",
2026-06-05 05:58:01.632813 | instance |     "Resolved community.general to 7.3.0 for 7.3.0",
2026-06-05 05:58:01.632831 | instance |     "Resolved community.mysql to 3.6.0 for 3.6.0",
2026-06-05 05:58:01.632848 | instance |     "Resolved kubernetes.core to 2.4.0 for 2.4.0",
2026-06-05 05:58:01.632866 | instance |     "Resolved openstack.cloud to 1.7.0 for 1.7.0",
2026-06-05 05:58:01.632884 | instance |     "Resolved atmosphere.common to v0.7.0 for >=0.6.0",
2026-06-05 05:58:01.632917 | instance |     "Resolved vexxhost.ceph to v4.0.0 for >=3.1.2",
2026-06-05 05:58:01.632946 | instance |     "Resolved vexxhost.kubernetes to v3.1.0 for >=2.3.2,>=2.5.0",
2026-06-05 05:58:01.632978 | instance |     "Resolved ansible.utils to v6.0.2 for >=2.9.0,>=3.0.0,>=6.0.0",
2026-06-05 05:58:01.632996 | instance |     "Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2"
2026-06-05 05:58:01.633014 | instance |   ],
2026-06-05 05:58:01.633031 | instance |   "msg": "No local release tag for openstack.cloud satisfies 1.7.0,>=2.0.0"
2026-06-05 05:58:01.633049 | instance | }
failure
2026-06-05 05:58:01.636374 | 
2026-06-05 05:58:01.636424 | PLAY RECAP
2026-06-05 05:58:01.636479 | instance | ok: 2 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-06-05 05:58:01.636502 | 
2026-06-05 05:58:01.793914 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-06-05 05:58:01.807391 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-06-05 05:58:02.465510 | 
2026-06-05 05:58:02.465654 | PLAY [all]
2026-06-05 05:58:02.477216 | 
2026-06-05 05:58:02.477295 | TASK [fetch-output : Set log path for multiple nodes]
2026-06-05 05:58:02.523466 | instance | skipping: Conditional result was False
2026-06-05 05:58:02.536853 | 
2026-06-05 05:58:02.537037 | TASK [fetch-output : Set log path for single node]
2026-06-05 05:58:02.582717 | instance | ok
2026-06-05 05:58:02.588500 | 
2026-06-05 05:58:02.588570 | LOOP [fetch-output : Ensure local output dirs]
2026-06-05 05:58:02.985355 | instance -> localhost | ok: "/var/lib/zuul/builds/4bb039cbe7194e40b383c5c40e08f027/work/logs"
2026-06-05 05:58:03.200302 | instance -> localhost | changed: "/var/lib/zuul/builds/4bb039cbe7194e40b383c5c40e08f027/work/artifacts"
2026-06-05 05:58:03.423812 | instance -> localhost | changed: "/var/lib/zuul/builds/4bb039cbe7194e40b383c5c40e08f027/work/docs"
2026-06-05 05:58:03.451361 | 
2026-06-05 05:58:03.451555 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-06-05 05:58:04.098771 | instance | changed: .d..t...... ./
2026-06-05 05:58:04.099299 | instance | changed: All items complete
2026-06-05 05:58:04.099373 | 
2026-06-05 05:58:04.566275 | instance | changed: .d..t...... ./
2026-06-05 05:58:05.033488 | instance | changed: .d..t...... ./
2026-06-05 05:58:05.051013 | 
2026-06-05 05:58:05.051223 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-06-05 05:58:05.506869 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.007804
2026-06-05 05:58:05.741972 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.009014
2026-06-05 05:58:05.763931 | 
2026-06-05 05:58:05.764092 | PLAY [all]
2026-06-05 05:58:05.771098 | 
2026-06-05 05:58:05.771171 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-06-05 05:58:06.202369 | instance | changed
2026-06-05 05:58:06.226636 | 
2026-06-05 05:58:06.226774 | PLAY RECAP
2026-06-05 05:58:06.226842 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-06-05 05:58:06.226888 | 
2026-06-05 05:58:06.363353 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-06-05 05:58:06.377615 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-06-05 05:58:07.041210 | 
2026-06-05 05:58:07.041842 | PLAY [localhost]
2026-06-05 05:58:07.052197 | 
2026-06-05 05:58:07.052272 | TASK [Generate Zuul manifest]
2026-06-05 05:58:07.086513 | localhost | ok
2026-06-05 05:58:07.108607 | 
2026-06-05 05:58:07.108732 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-06-05 05:58:07.518698 | localhost | changed
2026-06-05 05:58:07.531359 | 
2026-06-05 05:58:07.531468 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-06-05 05:58:07.564361 | localhost | ok
2026-06-05 05:58:07.574261 | 
2026-06-05 05:58:07.574334 | TASK [Upload logs]
2026-06-05 05:58:07.597938 | localhost | ok
2026-06-05 05:58:07.716590 | 
2026-06-05 05:58:07.716751 | TASK [Set zuul-log-path fact]
2026-06-05 05:58:07.740668 | localhost | ok
2026-06-05 05:58:07.754598 | 
2026-06-05 05:58:07.754671 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-06-05 05:58:07.798444 | localhost | ok
2026-06-05 05:58:07.806251 | 
2026-06-05 05:58:07.806320 | TASK [upload-logs : Create log directories]
2026-06-05 05:58:08.163983 | localhost | changed
2026-06-05 05:58:08.170075 | 
2026-06-05 05:58:08.170157 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-06-05 05:58:08.534582 | localhost -> localhost | ok: Runtime: 0:00:00.005843
2026-06-05 05:58:08.539781 | 
2026-06-05 05:58:08.539853 | TASK [upload-logs : Upload logs to log server]
2026-06-05 05:58:08.974895 | localhost | Output suppressed because no_log was given
2026-06-05 05:58:08.980874 | 
2026-06-05 05:58:08.981007 | LOOP [upload-logs : Compress console log and json output]
2026-06-05 05:58:09.029805 | localhost | skipping: Conditional result was False
2026-06-05 05:58:09.036467 | localhost | skipping: Conditional result was False
2026-06-05 05:58:09.047926 | 
2026-06-05 05:58:09.048133 | LOOP [upload-logs : Upload compressed console log and json output]
2026-06-05 05:58:09.092118 | localhost | skipping: Conditional result was False
2026-06-05 05:58:09.092537 | 
2026-06-05 05:58:09.095846 | localhost | skipping: Conditional result was False
2026-06-05 05:58:09.111831 | 
2026-06-05 05:58:09.112040 | LOOP [upload-logs : Upload console log and json output]