2026-05-26 05:42:20.635038 | Job console starting
2026-05-26 05:42:20.648718 | Updating git repos
2026-05-26 05:42:25.828615 | Cloning repos into workspace
2026-05-26 05:42:48.333186 | Restoring repo states
2026-05-26 05:42:59.113183 | Merging changes
2026-05-26 05:43:01.130489 | Checking out repos
2026-05-26 05:43:20.834002 | Preparing playbooks
2026-05-26 05:43:26.124589 | Running Ansible setup
2026-05-26 05:43:30.736690 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-05-26 05:43:31.423318 | 
2026-05-26 05:43:31.423471 | PLAY [localhost]
2026-05-26 05:43:31.431652 | 
2026-05-26 05:43:31.431775 | TASK [Gathering Facts]
2026-05-26 05:43:32.380059 | localhost | ok
2026-05-26 05:43:32.387852 | 
2026-05-26 05:43:32.387927 | TASK [Setup log path fact]
2026-05-26 05:43:32.408041 | localhost | ok
2026-05-26 05:43:32.419984 | 
2026-05-26 05:43:32.420079 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-05-26 05:43:32.451251 | localhost | ok
2026-05-26 05:43:32.459113 | 
2026-05-26 05:43:32.459198 | TASK [emit-job-header : Print job information]
2026-05-26 05:43:32.500228 | # Job Information
2026-05-26 05:43:32.500487 | Ansible Version: 2.16.18
2026-05-26 05:43:32.500555 | Job: atmosphere-molecule-aio-ovn
2026-05-26 05:43:32.500603 | Pipeline: check
2026-05-26 05:43:32.500647 | Executor: 2d72f0692154
2026-05-26 05:43:32.500690 | Triggered by: https://github.com/vexxhost/atmosphere/pull/3985
2026-05-26 05:43:32.500740 | Event ID: c15d7d80-58c4-11f1-9680-468cf01be9f3
2026-05-26 05:43:32.504901 | 
2026-05-26 05:43:32.505029 | LOOP [emit-job-header : Print node information]
2026-05-26 05:43:32.601218 | localhost | ok:
2026-05-26 05:43:32.683915 | localhost | # Node Information
2026-05-26 05:43:32.684055 | localhost | Inventory Hostname: instance
2026-05-26 05:43:32.684096 | localhost | Hostname: np0000176440
2026-05-26 05:43:32.684152 | localhost | Username: zuul
2026-05-26 05:43:32.684467 | localhost | Distro: Ubuntu 22.04
2026-05-26 05:43:32.684523 | localhost | Provider: yul1
2026-05-26 05:43:32.684557 | localhost | Region: ca-ymq-1
2026-05-26 05:43:32.684586 | localhost | Label: ubuntu-jammy-16
2026-05-26 05:43:32.684618 | localhost | Product Name: OpenStack Nova
2026-05-26 05:43:32.684985 | localhost | Interface IP: 199.19.213.111
2026-05-26 05:43:32.701313 | 
2026-05-26 05:43:32.701469 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-05-26 05:43:33.131834 | localhost -> localhost | changed
2026-05-26 05:43:33.139435 | 
2026-05-26 05:43:33.139554 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-05-26 05:43:34.262190 | localhost -> localhost | changed
2026-05-26 05:43:34.275139 | 
2026-05-26 05:43:34.275213 | PLAY [all]
2026-05-26 05:43:34.285941 | 
2026-05-26 05:43:34.286038 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-05-26 05:43:34.535518 | instance -> localhost | ok
2026-05-26 05:43:34.549348 | 
2026-05-26 05:43:34.549469 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-05-26 05:43:34.584259 | instance | ok
2026-05-26 05:43:34.600486 | instance | included: /var/lib/zuul/builds/c4554ca35c3949d78fa1df7b27b72cba/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-05-26 05:43:34.606149 | 
2026-05-26 05:43:34.606207 | TASK [add-build-sshkey : Create Temp SSH key]
2026-05-26 05:43:35.970055 | instance -> localhost | Generating public/private rsa key pair.
2026-05-26 05:43:35.970379 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/c4554ca35c3949d78fa1df7b27b72cba/work/c4554ca35c3949d78fa1df7b27b72cba_id_rsa
2026-05-26 05:43:35.970448 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/c4554ca35c3949d78fa1df7b27b72cba/work/c4554ca35c3949d78fa1df7b27b72cba_id_rsa.pub
2026-05-26 05:43:35.970520 | instance -> localhost | The key fingerprint is:
2026-05-26 05:43:35.970568 | instance -> localhost | SHA256:4JscizUe0Thj4vQXUodt1FNbtRnTG4tm55MNPZFO8L8 zuul-build-sshkey
2026-05-26 05:43:35.970631 | instance -> localhost | The key's randomart image is:
2026-05-26 05:43:35.970678 | instance -> localhost | +---[RSA 3072]----+
2026-05-26 05:43:35.970732 | instance -> localhost | |        .+o. ooo*|
2026-05-26 05:43:35.970779 | instance -> localhost | |       +..o o .B*|
2026-05-26 05:43:35.970824 | instance -> localhost | |    o O o.   .===|
2026-05-26 05:43:35.970869 | instance -> localhost | |   o = * .   +.*o|
2026-05-26 05:43:35.970913 | instance -> localhost | |    . B S   o o.=|
2026-05-26 05:43:35.970956 | instance -> localhost | |     = O       +o|
2026-05-26 05:43:35.970999 | instance -> localhost | |    . *        E.|
2026-05-26 05:43:35.971042 | instance -> localhost | |                 |
2026-05-26 05:43:35.971090 | instance -> localhost | |                 |
2026-05-26 05:43:35.971134 | instance -> localhost | +----[SHA256]-----+
2026-05-26 05:43:35.971223 | instance -> localhost | ok: Runtime: 0:00:00.856735
2026-05-26 05:43:35.979326 | 
2026-05-26 05:43:35.979389 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-05-26 05:43:36.013188 | instance | ok
2026-05-26 05:43:36.022772 | instance | included: /var/lib/zuul/builds/c4554ca35c3949d78fa1df7b27b72cba/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-05-26 05:43:36.030579 | 
2026-05-26 05:43:36.030640 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-05-26 05:43:36.056351 | instance | skipping: Conditional result was False
2026-05-26 05:43:36.066593 | 
2026-05-26 05:43:36.066762 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-05-26 05:43:36.535462 | instance | changed
2026-05-26 05:43:36.543688 | 
2026-05-26 05:43:36.543755 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-05-26 05:43:36.765772 | instance | ok
2026-05-26 05:43:36.770697 | 
2026-05-26 05:43:36.770763 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-05-26 05:43:37.235307 | instance | changed
2026-05-26 05:43:37.240380 | 
2026-05-26 05:43:37.240445 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-05-26 05:43:37.721324 | instance | changed
2026-05-26 05:43:37.726545 | 
2026-05-26 05:43:37.726609 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-05-26 05:43:37.751988 | instance | skipping: Conditional result was False
2026-05-26 05:43:37.761335 | 
2026-05-26 05:43:37.761426 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-05-26 05:43:38.120727 | instance -> localhost | changed
2026-05-26 05:43:38.135345 | 
2026-05-26 05:43:38.135417 | TASK [add-build-sshkey : Add back temp key]
2026-05-26 05:43:38.428632 | instance -> localhost | Identity added: /var/lib/zuul/builds/c4554ca35c3949d78fa1df7b27b72cba/work/c4554ca35c3949d78fa1df7b27b72cba_id_rsa (zuul-build-sshkey)
2026-05-26 05:43:38.428836 | instance -> localhost | ok: Runtime: 0:00:00.013895
2026-05-26 05:43:38.434342 | 
2026-05-26 05:43:38.434408 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-05-26 05:43:38.699350 | instance | ok
2026-05-26 05:43:38.707000 | 
2026-05-26 05:43:38.707080 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-05-26 05:43:38.731451 | instance | skipping: Conditional result was False
2026-05-26 05:43:38.745937 | 
2026-05-26 05:43:38.746007 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-05-26 05:43:39.010829 | instance | ok
2026-05-26 05:43:39.016618 | 
2026-05-26 05:43:39.016683 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-05-26 05:43:42.836749 | instance | Output suppressed because no_log was given
2026-05-26 05:43:42.845773 | 
2026-05-26 05:43:42.845881 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-05-26 05:43:43.028054 | instance | ok: "logs"
2026-05-26 05:43:43.028359 | instance | ok: All items complete
2026-05-26 05:43:43.028400 | 
2026-05-26 05:43:43.184648 | instance | ok: "artifacts"
2026-05-26 05:43:43.335121 | instance | ok: "docs"
2026-05-26 05:43:43.348802 | 
2026-05-26 05:43:43.349010 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-05-26 05:43:43.530004 | instance | changed: "logs"
2026-05-26 05:43:43.691162 | instance | changed: "artifacts"
2026-05-26 05:43:43.841986 | instance | changed: "docs"
2026-05-26 05:43:43.857589 | 
2026-05-26 05:43:43.857727 | PLAY RECAP
2026-05-26 05:43:43.857791 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-05-26 05:43:43.857831 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-05-26 05:43:43.857864 | 
2026-05-26 05:43:44.057859 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-05-26 05:43:44.066514 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-05-26 05:43:44.693307 | 
2026-05-26 05:43:46.446359 | PLAY [all]
2026-05-26 05:43:46.465307 | 
2026-05-26 05:43:46.465440 | TASK [setup-uv : Extract archive]
2026-05-26 05:43:48.834181 | instance | changed
2026-05-26 05:43:48.844142 | 
2026-05-26 05:43:48.844352 | TASK [setup-uv : Print version]
2026-05-26 05:43:49.188039 | instance | uv 0.8.13
2026-05-26 05:43:49.631863 | instance | ok: Runtime: 0:00:00.020044
2026-05-26 05:43:49.640814 | 
2026-05-26 05:43:49.640884 | TASK [Install Ansible collection siblings]
2026-05-26 05:43:50.311845 | instance | ERROR
2026-05-26 05:43:50.312110 | instance | {
2026-05-26 05:43:50.312143 | instance |   "log": [
2026-05-26 05:43:50.312166 | instance |     "Sibling collection ansible.netcommon at /home/zuul/src/github.com/ansible-collections/ansible.netcommon",
2026-05-26 05:43:50.312191 | instance |     "Sibling collection ansible.posix at /home/zuul/src/github.com/ansible-collections/ansible.posix",
2026-05-26 05:43:50.312212 | instance |     "Sibling collection ansible.utils at /home/zuul/src/github.com/ansible-collections/ansible.utils",
2026-05-26 05:43:50.312233 | instance |     "Sibling collection community.crypto at /home/zuul/src/github.com/ansible-collections/community.crypto",
2026-05-26 05:43:50.312253 | instance |     "Sibling collection community.general at /home/zuul/src/github.com/ansible-collections/community.general",
2026-05-26 05:43:50.312273 | instance |     "Sibling collection community.mysql at /home/zuul/src/github.com/ansible-collections/community.mysql",
2026-05-26 05:43:50.312292 | instance |     "Sibling collection kubernetes.core at /home/zuul/src/github.com/ansible-collections/kubernetes.core",
2026-05-26 05:43:50.312311 | instance |     "Sibling collection vexxhost.ceph at /home/zuul/src/github.com/vexxhost/ansible-collection-ceph",
2026-05-26 05:43:50.312329 | instance |     "Sibling collection vexxhost.containers at /home/zuul/src/github.com/vexxhost/ansible-collection-containers",
2026-05-26 05:43:50.312348 | instance |     "Sibling collection vexxhost.kubernetes at /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes",
2026-05-26 05:43:50.312365 | instance |     "Sibling collection atmosphere.common at /home/zuul/src/github.com/vexxhost/atmosphere.common",
2026-05-26 05:43:50.312383 | instance |     "Sibling collection openstack.cloud at /home/zuul/src/opendev.org/openstack/ansible-collections-openstack",
2026-05-26 05:43:50.312400 | instance |     "Resolved ansible.netcommon to v8.5.2 for >=1.2.0",
2026-05-26 05:43:50.312418 | instance |     "Resolved ansible.posix to 1.6.0 for 1.6.0",
2026-05-26 05:43:50.312436 | instance |     "Resolved ansible.utils to v6.0.2 for >=2.9.0,>=3.0.0",
2026-05-26 05:43:50.312454 | instance |     "Resolved community.crypto to 2.2.3 for 2.2.3",
2026-05-26 05:43:50.312471 | instance |     "Resolved community.general to 7.3.0 for 7.3.0",
2026-05-26 05:43:50.312489 | instance |     "Resolved community.mysql to 3.6.0 for 3.6.0",
2026-05-26 05:43:50.312506 | instance |     "Resolved kubernetes.core to 2.4.0 for 2.4.0",
2026-05-26 05:43:50.312523 | instance |     "Resolved openstack.cloud to 1.7.0 for 1.7.0",
2026-05-26 05:43:50.312541 | instance |     "Resolved atmosphere.common to v0.7.0 for >=0.6.0",
2026-05-26 05:43:50.312567 | instance |     "Resolved vexxhost.ceph to v4.0.0 for >=3.1.2",
2026-05-26 05:43:50.312586 | instance |     "Resolved vexxhost.kubernetes to v3.1.0 for >=2.3.2,>=2.5.0",
2026-05-26 05:43:50.312603 | instance |     "Resolved ansible.utils to v6.0.2 for >=2.9.0,>=3.0.0,>=6.0.0",
2026-05-26 05:43:50.312620 | instance |     "Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2"
2026-05-26 05:43:50.312637 | instance |   ],
2026-05-26 05:43:50.312655 | instance |   "msg": "No local release tag for openstack.cloud satisfies 1.7.0,>=2.0.0"
2026-05-26 05:43:50.312672 | instance | }
failure
2026-05-26 05:43:50.316098 | 
2026-05-26 05:43:50.316147 | PLAY RECAP
2026-05-26 05:43:50.316196 | instance | ok: 2 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-05-26 05:43:50.316218 | 
2026-05-26 05:43:50.516471 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-05-26 05:43:51.849586 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-05-26 05:43:52.517080 | 
2026-05-26 05:43:52.621655 | PLAY [all]
2026-05-26 05:43:52.641259 | 
2026-05-26 05:43:52.641406 | TASK [fetch-output : Set log path for multiple nodes]
2026-05-26 05:43:52.697572 | instance | skipping: Conditional result was False
2026-05-26 05:43:52.707101 | 
2026-05-26 05:43:52.707223 | TASK [fetch-output : Set log path for single node]
2026-05-26 05:43:52.760614 | instance | ok
2026-05-26 05:43:52.767487 | 
2026-05-26 05:43:52.767592 | LOOP [fetch-output : Ensure local output dirs]
2026-05-26 05:43:53.311907 | instance -> localhost | ok: "/var/lib/zuul/builds/c4554ca35c3949d78fa1df7b27b72cba/work/logs"
2026-05-26 05:43:53.561618 | instance -> localhost | changed: "/var/lib/zuul/builds/c4554ca35c3949d78fa1df7b27b72cba/work/artifacts"
2026-05-26 05:43:53.824701 | instance -> localhost | changed: "/var/lib/zuul/builds/c4554ca35c3949d78fa1df7b27b72cba/work/docs"
2026-05-26 05:43:53.838452 | 
2026-05-26 05:43:53.838611 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-05-26 05:43:54.524549 | instance | ok
2026-05-26 05:43:54.524805 | instance | changed: All items complete
2026-05-26 05:43:54.524851 | 
2026-05-26 05:43:54.985360 | instance | changed: .d..t...... ./
2026-05-26 05:43:55.435865 | instance | changed: .d..t...... ./
2026-05-26 05:43:55.461330 | 
2026-05-26 05:43:55.461505 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-05-26 05:43:55.918713 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.009086
2026-05-26 05:43:56.214614 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.008099
2026-05-26 05:43:56.228998 | 
2026-05-26 05:43:56.229117 | PLAY [all]
2026-05-26 05:43:56.235265 | 
2026-05-26 05:43:56.235335 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-05-26 05:43:56.685074 | instance | changed
2026-05-26 05:43:56.691887 | 
2026-05-26 05:43:56.691953 | PLAY RECAP
2026-05-26 05:43:56.691997 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-05-26 05:43:56.692019 | 
2026-05-26 05:43:56.865392 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-05-26 05:43:56.888122 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-05-26 05:43:57.549993 | 
2026-05-26 05:43:57.550124 | PLAY [localhost]
2026-05-26 05:43:57.559985 | 
2026-05-26 05:43:57.560063 | TASK [Generate Zuul manifest]
2026-05-26 05:43:57.591772 | localhost | ok
2026-05-26 05:43:57.607971 | 
2026-05-26 05:43:57.608062 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-05-26 05:43:58.015719 | localhost | changed
2026-05-26 05:43:58.027403 | 
2026-05-26 05:43:58.027506 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-05-26 05:43:58.058592 | localhost | ok
2026-05-26 05:43:58.069407 | 
2026-05-26 05:43:58.069503 | TASK [Upload logs]
2026-05-26 05:43:58.090654 | localhost | ok
2026-05-26 05:43:58.207209 | 
2026-05-26 05:43:59.072643 | TASK [Set zuul-log-path fact]
2026-05-26 05:43:59.095759 | localhost | ok
2026-05-26 05:43:59.112231 | 
2026-05-26 05:43:59.112327 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-05-26 05:43:59.144249 | localhost | ok
2026-05-26 05:43:59.153337 | 
2026-05-26 05:43:59.153405 | TASK [upload-logs : Create log directories]
2026-05-26 05:43:59.522884 | localhost | changed
2026-05-26 05:43:59.528841 | 
2026-05-26 05:43:59.528922 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-05-26 05:43:59.885759 | localhost -> localhost | ok: Runtime: 0:00:00.005635
2026-05-26 05:43:59.891141 | 
2026-05-26 05:43:59.891207 | TASK [upload-logs : Upload logs to log server]
2026-05-26 05:44:00.311302 | localhost | Output suppressed because no_log was given
2026-05-26 05:44:00.316846 | 
2026-05-26 05:44:00.316929 | LOOP [upload-logs : Compress console log and json output]
2026-05-26 05:44:00.362997 | localhost | skipping: Conditional result was False
2026-05-26 05:44:00.370548 | localhost | skipping: Conditional result was False
2026-05-26 05:44:00.381942 | 
2026-05-26 05:44:00.382109 | LOOP [upload-logs : Upload compressed console log and json output]
2026-05-26 05:44:00.418241 | localhost | skipping: Conditional result was False
2026-05-26 05:44:01.854798 | 
2026-05-26 05:44:01.856532 | localhost | skipping: Conditional result was False
2026-05-26 05:44:01.869280 | 
2026-05-26 05:44:01.869440 | LOOP [upload-logs : Upload console log and json output]