2026-06-05 05:53:51.173861 | Job console starting
2026-06-05 05:53:51.184922 | Updating git repos
2026-06-05 05:53:53.669897 | Cloning repos into workspace
2026-06-05 05:54:07.230486 | Restoring repo states
2026-06-05 05:54:17.929398 | Merging changes
2026-06-05 05:54:18.979473 | Checking out repos
2026-06-05 05:54:21.067401 | Preparing playbooks
2026-06-05 05:54:25.214099 | Running Ansible setup
2026-06-05 05:54:28.775449 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-06-05 05:54:29.446792 | 
2026-06-05 05:54:29.446961 | PLAY [localhost]
2026-06-05 05:54:29.454935 | 
2026-06-05 05:54:29.455024 | TASK [Gathering Facts]
2026-06-05 05:54:30.465785 | localhost | ok
2026-06-05 05:54:30.477688 | 
2026-06-05 05:54:30.477791 | TASK [Setup log path fact]
2026-06-05 05:54:30.499140 | localhost | ok
2026-06-05 05:54:30.512378 | 
2026-06-05 05:54:30.512466 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-06-05 05:54:30.542854 | localhost | ok
2026-06-05 05:54:30.552198 | 
2026-06-05 05:54:30.552294 | TASK [emit-job-header : Print job information]
2026-06-05 05:54:30.593333 | # Job Information
2026-06-05 05:54:30.593561 | Ansible Version: 2.16.18
2026-06-05 05:54:30.593633 | Job: atmosphere-molecule-aio-ovn
2026-06-05 05:54:30.593683 | Pipeline: check
2026-06-05 05:54:30.593730 | Executor: 2d72f0692154
2026-06-05 05:54:30.593775 | Triggered by: https://github.com/vexxhost/atmosphere/pull/3985
2026-06-05 05:54:30.593826 | Event ID: cc509e80-60a2-11f1-8c23-4595eac5c44c
2026-06-05 05:54:30.598609 | 
2026-06-05 05:54:30.598702 | LOOP [emit-job-header : Print node information]
2026-06-05 05:54:30.693672 | localhost | ok:
2026-06-05 05:54:30.694107 | localhost | # Node Information
2026-06-05 05:54:30.694173 | localhost | Inventory Hostname: instance
2026-06-05 05:54:30.694209 | localhost | Hostname: np0000178642
2026-06-05 05:54:30.694237 | localhost | Username: zuul
2026-06-05 05:54:30.694272 | localhost | Distro: Ubuntu 22.04
2026-06-05 05:54:30.694300 | localhost | Provider: yul1
2026-06-05 05:54:30.694327 | localhost | Region: ca-ymq-1
2026-06-05 05:54:30.694353 | localhost | Label: ubuntu-jammy-16
2026-06-05 05:54:30.694379 | localhost | Product Name: OpenStack Nova
2026-06-05 05:54:30.694405 | localhost | Interface IP: 199.204.45.223
2026-06-05 05:54:30.706357 | 
2026-06-05 05:54:30.706515 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-06-05 05:54:31.126116 | localhost -> localhost | changed
2026-06-05 05:54:31.133099 | 
2026-06-05 05:54:31.133168 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-06-05 05:54:32.078447 | localhost -> localhost | changed
2026-06-05 05:54:32.090219 | 
2026-06-05 05:54:32.090323 | PLAY [all]
2026-06-05 05:54:32.102179 | 
2026-06-05 05:54:32.102350 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-06-05 05:54:32.322952 | instance -> localhost | ok
2026-06-05 05:54:32.333801 | 
2026-06-05 05:54:32.333897 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-06-05 05:54:32.367952 | instance | ok
2026-06-05 05:54:32.383968 | instance | included: /var/lib/zuul/builds/aa062f1c9c4140e5b19d7e31144ce790/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-06-05 05:54:32.389609 | 
2026-06-05 05:54:32.389671 | TASK [add-build-sshkey : Create Temp SSH key]
2026-06-05 05:54:33.186890 | instance -> localhost | Generating public/private rsa key pair.
2026-06-05 05:54:33.187158 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/aa062f1c9c4140e5b19d7e31144ce790/work/aa062f1c9c4140e5b19d7e31144ce790_id_rsa
2026-06-05 05:54:33.187198 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/aa062f1c9c4140e5b19d7e31144ce790/work/aa062f1c9c4140e5b19d7e31144ce790_id_rsa.pub
2026-06-05 05:54:33.187229 | instance -> localhost | The key fingerprint is:
2026-06-05 05:54:33.187258 | instance -> localhost | SHA256:2WlbIdtCCFBuF1xn7YbbnyWF7nL40tGvbZ0LWJJ4eIY zuul-build-sshkey
2026-06-05 05:54:33.187302 | instance -> localhost | The key's randomart image is:
2026-06-05 05:54:33.187332 | instance -> localhost | +---[RSA 3072]----+
2026-06-05 05:54:33.187366 | instance -> localhost | |    .oo.... o.   |
2026-06-05 05:54:33.187397 | instance -> localhost | |     . ..o o  .  |
2026-06-05 05:54:33.187425 | instance -> localhost | |      o o o .o . |
2026-06-05 05:54:33.187453 | instance -> localhost | |     . . ++=o.+ .|
2026-06-05 05:54:33.187480 | instance -> localhost | |        SE**o* o |
2026-06-05 05:54:33.187507 | instance -> localhost | |         .++= = o|
2026-06-05 05:54:33.187533 | instance -> localhost | |          .. = +*|
2026-06-05 05:54:33.187560 | instance -> localhost | |            + =+=|
2026-06-05 05:54:33.187589 | instance -> localhost | |             =o+o|
2026-06-05 05:54:33.187617 | instance -> localhost | +----[SHA256]-----+
2026-06-05 05:54:33.187680 | instance -> localhost | ok: Runtime: 0:00:00.380627
2026-06-05 05:54:33.195996 | 
2026-06-05 05:54:33.196083 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-06-05 05:54:33.241475 | instance | ok
2026-06-05 05:54:33.251511 | instance | included: /var/lib/zuul/builds/aa062f1c9c4140e5b19d7e31144ce790/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-06-05 05:54:33.259309 | 
2026-06-05 05:54:33.259376 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-06-05 05:54:33.284345 | instance | skipping: Conditional result was False
2026-06-05 05:54:33.294324 | 
2026-06-05 05:54:33.294414 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-06-05 05:54:33.757308 | instance | changed
2026-06-05 05:54:33.763545 | 
2026-06-05 05:54:33.763612 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-06-05 05:54:33.989672 | instance | ok
2026-06-05 05:54:33.994651 | 
2026-06-05 05:54:33.994717 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-06-05 05:54:34.454248 | instance | changed
2026-06-05 05:54:34.459362 | 
2026-06-05 05:54:34.459424 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-06-05 05:54:34.934071 | instance | changed
2026-06-05 05:54:34.939500 | 
2026-06-05 05:54:34.939571 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-06-05 05:54:34.964926 | instance | skipping: Conditional result was False
2026-06-05 05:54:34.977168 | 
2026-06-05 05:54:34.977359 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-06-05 05:54:35.296513 | instance -> localhost | changed
2026-06-05 05:54:35.309466 | 
2026-06-05 05:54:35.309571 | TASK [add-build-sshkey : Add back temp key]
2026-06-05 05:54:35.592903 | instance -> localhost | Identity added: /var/lib/zuul/builds/aa062f1c9c4140e5b19d7e31144ce790/work/aa062f1c9c4140e5b19d7e31144ce790_id_rsa (zuul-build-sshkey)
2026-06-05 05:54:35.593180 | instance -> localhost | ok: Runtime: 0:00:00.014797
2026-06-05 05:54:35.598336 | 
2026-06-05 05:54:35.598398 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-06-05 05:54:35.879371 | instance | ok
2026-06-05 05:54:35.886421 | 
2026-06-05 05:54:35.886487 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-06-05 05:54:35.910939 | instance | skipping: Conditional result was False
2026-06-05 05:54:35.925193 | 
2026-06-05 05:54:35.925260 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-06-05 05:54:36.232451 | instance | ok
2026-06-05 05:54:36.238316 | 
2026-06-05 05:54:36.238382 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-06-05 05:54:39.674224 | instance | Output suppressed because no_log was given
2026-06-05 05:54:39.684879 | 
2026-06-05 05:54:39.684988 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-06-05 05:54:39.897530 | instance | ok: "logs"
2026-06-05 05:54:40.085271 | instance | ok: All items complete
2026-06-05 05:54:40.085414 | 
2026-06-05 05:54:40.090486 | instance | ok: "artifacts"
2026-06-05 05:54:40.193516 | instance | ok: "docs"
2026-06-05 05:54:40.211933 | 
2026-06-05 05:54:40.212130 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-06-05 05:54:40.399903 | instance | changed: "logs"
2026-06-05 05:54:40.551796 | instance | changed: "artifacts"
2026-06-05 05:54:40.714984 | instance | changed: "docs"
2026-06-05 05:54:40.739795 | 
2026-06-05 05:54:40.739954 | PLAY RECAP
2026-06-05 05:54:40.740020 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-06-05 05:54:40.740059 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-06-05 05:54:40.740091 | 
2026-06-05 05:54:40.931679 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-06-05 05:54:40.938781 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-06-05 05:54:41.570413 | 
2026-06-05 05:54:41.577361 | PLAY [all]
2026-06-05 05:54:41.592508 | 
2026-06-05 05:54:41.592584 | TASK [setup-uv : Extract archive]
2026-06-05 05:54:44.038445 | instance | changed
2026-06-05 05:54:44.048847 | 
2026-06-05 05:54:44.049071 | TASK [setup-uv : Print version]
2026-06-05 05:54:44.391733 | instance | uv 0.8.13
2026-06-05 05:54:44.587217 | instance | ok: Runtime: 0:00:00.014344
2026-06-05 05:54:44.598958 | 
2026-06-05 05:54:44.599240 | TASK [Install Ansible collection siblings]
2026-06-05 05:54:45.229968 | instance | ERROR
2026-06-05 05:54:45.230260 | instance | {
2026-06-05 05:54:45.230290 | instance |   "log": [
2026-06-05 05:54:45.230311 | instance |     "Sibling collection ansible.netcommon at /home/zuul/src/github.com/ansible-collections/ansible.netcommon",
2026-06-05 05:54:45.230335 | instance |     "Sibling collection ansible.posix at /home/zuul/src/github.com/ansible-collections/ansible.posix",
2026-06-05 05:54:45.230354 | instance |     "Sibling collection ansible.utils at /home/zuul/src/github.com/ansible-collections/ansible.utils",
2026-06-05 05:54:45.230377 | instance |     "Sibling collection community.crypto at /home/zuul/src/github.com/ansible-collections/community.crypto",
2026-06-05 05:54:45.230396 | instance |     "Sibling collection community.general at /home/zuul/src/github.com/ansible-collections/community.general",
2026-06-05 05:54:45.230418 | instance |     "Sibling collection community.mysql at /home/zuul/src/github.com/ansible-collections/community.mysql",
2026-06-05 05:54:45.230437 | instance |     "Sibling collection kubernetes.core at /home/zuul/src/github.com/ansible-collections/kubernetes.core",
2026-06-05 05:54:45.230454 | instance |     "Sibling collection vexxhost.ceph at /home/zuul/src/github.com/vexxhost/ansible-collection-ceph",
2026-06-05 05:54:45.230472 | instance |     "Sibling collection vexxhost.containers at /home/zuul/src/github.com/vexxhost/ansible-collection-containers",
2026-06-05 05:54:45.230489 | instance |     "Sibling collection vexxhost.kubernetes at /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes",
2026-06-05 05:54:45.230507 | instance |     "Sibling collection atmosphere.common at /home/zuul/src/github.com/vexxhost/atmosphere.common",
2026-06-05 05:54:45.230524 | instance |     "Sibling collection openstack.cloud at /home/zuul/src/opendev.org/openstack/ansible-collections-openstack",
2026-06-05 05:54:45.230541 | instance |     "Resolved ansible.netcommon to v8.5.2 for >=1.2.0",
2026-06-05 05:54:45.230559 | instance |     "Resolved ansible.posix to 1.6.0 for 1.6.0",
2026-06-05 05:54:45.230576 | instance |     "Resolved ansible.utils to v6.0.2 for >=2.9.0,>=3.0.0",
2026-06-05 05:54:45.230593 | instance |     "Resolved community.crypto to 2.2.3 for 2.2.3",
2026-06-05 05:54:45.230610 | instance |     "Resolved community.general to 7.3.0 for 7.3.0",
2026-06-05 05:54:45.230627 | instance |     "Resolved community.mysql to 3.6.0 for 3.6.0",
2026-06-05 05:54:45.230644 | instance |     "Resolved kubernetes.core to 2.4.0 for 2.4.0",
2026-06-05 05:54:45.230661 | instance |     "Resolved openstack.cloud to 1.7.0 for 1.7.0",
2026-06-05 05:54:45.230691 | instance |     "Resolved atmosphere.common to v0.7.0 for >=0.6.0",
2026-06-05 05:54:45.230736 | instance |     "Resolved vexxhost.ceph to v4.0.0 for >=3.1.2",
2026-06-05 05:54:45.230756 | instance |     "Resolved vexxhost.kubernetes to v3.1.0 for >=2.3.2,>=2.5.0",
2026-06-05 05:54:45.230774 | instance |     "Resolved ansible.utils to v6.0.2 for >=2.9.0,>=3.0.0,>=6.0.0",
2026-06-05 05:54:45.230805 | instance |     "Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2"
2026-06-05 05:54:45.230824 | instance |   ],
2026-06-05 05:54:45.230841 | instance |   "msg": "No local release tag for openstack.cloud satisfies 1.7.0,>=2.0.0"
2026-06-05 05:54:45.230858 | instance | }
failure
2026-06-05 05:54:45.234280 | 
2026-06-05 05:54:45.234328 | PLAY RECAP
2026-06-05 05:54:45.234374 | instance | ok: 2 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-06-05 05:54:45.234396 | 
2026-06-05 05:54:45.389592 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-06-05 05:54:45.498677 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-06-05 05:54:46.152937 | 
2026-06-05 05:54:46.153130 | PLAY [all]
2026-06-05 05:54:46.164868 | 
2026-06-05 05:54:46.164982 | TASK [fetch-output : Set log path for multiple nodes]
2026-06-05 05:54:46.210796 | instance | skipping: Conditional result was False
2026-06-05 05:54:46.221723 | 
2026-06-05 05:54:46.221839 | TASK [fetch-output : Set log path for single node]
2026-06-05 05:54:46.265450 | instance | ok
2026-06-05 05:54:46.272263 | 
2026-06-05 05:54:46.272360 | LOOP [fetch-output : Ensure local output dirs]
2026-06-05 05:54:46.799298 | instance -> localhost | ok: "/var/lib/zuul/builds/aa062f1c9c4140e5b19d7e31144ce790/work/logs"
2026-06-05 05:54:47.019786 | instance -> localhost | changed: "/var/lib/zuul/builds/aa062f1c9c4140e5b19d7e31144ce790/work/artifacts"
2026-06-05 05:54:47.234212 | instance -> localhost | changed: "/var/lib/zuul/builds/aa062f1c9c4140e5b19d7e31144ce790/work/docs"
2026-06-05 05:54:47.249425 | 
2026-06-05 05:54:47.249641 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-06-05 05:54:47.880685 | instance | ok
2026-06-05 05:54:47.880938 | instance | changed: All items complete
2026-06-05 05:54:47.881005 | 
2026-06-05 05:54:48.377067 | instance | changed: .d..t...... ./
2026-06-05 05:54:48.833806 | instance | changed: .d..t...... ./
2026-06-05 05:54:48.862880 | 
2026-06-05 05:54:48.863157 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-06-05 05:54:49.653488 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.009135
2026-06-05 05:54:49.891115 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.021408
2026-06-05 05:54:49.906056 | 
2026-06-05 05:54:49.906174 | PLAY [all]
2026-06-05 05:54:49.912293 | 
2026-06-05 05:54:49.912359 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-06-05 05:54:50.331733 | instance | changed
2026-06-05 05:54:50.337673 | 
2026-06-05 05:54:50.337731 | PLAY RECAP
2026-06-05 05:54:50.337804 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-06-05 05:54:50.337827 | 
2026-06-05 05:54:50.482872 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-06-05 05:54:50.495575 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-06-05 05:54:51.139683 | 
2026-06-05 05:54:51.140225 | PLAY [localhost]
2026-06-05 05:54:51.151062 | 
2026-06-05 05:54:51.151136 | TASK [Generate Zuul manifest]
2026-06-05 05:54:51.171275 | localhost | ok
2026-06-05 05:54:51.190561 | 
2026-06-05 05:54:51.190670 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-06-05 05:54:51.568497 | localhost | changed
2026-06-05 05:54:51.581810 | 
2026-06-05 05:54:51.581890 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-06-05 05:54:51.611997 | localhost | ok
2026-06-05 05:54:51.621816 | 
2026-06-05 05:54:51.621908 | TASK [Upload logs]
2026-06-05 05:54:51.653583 | localhost | ok
2026-06-05 05:54:51.766150 | 
2026-06-05 05:54:51.766331 | TASK [Set zuul-log-path fact]
2026-06-05 05:54:51.787676 | localhost | ok
2026-06-05 05:54:51.805891 | 
2026-06-05 05:54:51.806012 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-06-05 05:54:51.837992 | localhost | ok
2026-06-05 05:54:51.847733 | 
2026-06-05 05:54:51.847833 | TASK [upload-logs : Create log directories]
2026-06-05 05:54:52.242494 | localhost | changed
2026-06-05 05:54:52.247396 | 
2026-06-05 05:54:52.247477 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-06-05 05:54:52.620313 | localhost -> localhost | ok: Runtime: 0:00:00.006140
2026-06-05 05:54:52.625687 | 
2026-06-05 05:54:52.625755 | TASK [upload-logs : Upload logs to log server]
2026-06-05 05:54:53.053556 | localhost | Output suppressed because no_log was given
2026-06-05 05:54:53.059248 | 
2026-06-05 05:54:53.059341 | LOOP [upload-logs : Compress console log and json output]
2026-06-05 05:54:53.111416 | localhost | skipping: Conditional result was False
2026-06-05 05:54:53.119329 | localhost | skipping: Conditional result was False
2026-06-05 05:54:53.137952 | 
2026-06-05 05:54:53.138117 | LOOP [upload-logs : Upload compressed console log and json output]
2026-06-05 05:54:53.183536 | localhost | skipping: Conditional result was False
2026-06-05 05:54:53.183914 | 
2026-06-05 05:54:53.188067 | localhost | skipping: Conditional result was False
2026-06-05 05:54:53.204365 | 
2026-06-05 05:54:53.204564 | LOOP [upload-logs : Upload console log and json output]