2026-05-30 05:07:33.037695 | Job console starting
2026-05-30 05:07:33.116790 | Updating git repos
2026-05-30 05:07:38.181889 | Cloning repos into workspace
2026-05-30 05:07:51.599297 | Restoring repo states
2026-05-30 05:07:53.602759 | Merging changes
2026-05-30 05:07:55.798242 | Checking out repos
2026-05-30 05:07:59.475845 | Preparing playbooks
2026-05-30 05:08:06.652725 | Running Ansible setup
2026-05-30 05:08:10.727153 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-05-30 05:08:11.504345 | 
2026-05-30 05:08:11.504583 | PLAY [localhost]
2026-05-30 05:08:11.521751 | 
2026-05-30 05:08:11.522418 | TASK [Gathering Facts]
2026-05-30 05:08:12.558905 | localhost | ok
2026-05-30 05:08:12.567123 | 
2026-05-30 05:08:12.576273 | TASK [Setup log path fact]
2026-05-30 05:08:12.601261 | localhost | ok
2026-05-30 05:08:12.649037 | 
2026-05-30 05:08:12.649164 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-05-30 05:08:12.683176 | localhost | ok
2026-05-30 05:08:12.691187 | 
2026-05-30 05:08:12.696901 | TASK [emit-job-header : Print job information]
2026-05-30 05:08:12.741680 | # Job Information
2026-05-30 05:08:12.741932 | Ansible Version: 2.16.18
2026-05-30 05:08:12.742005 | Job: atmosphere-molecule-aio-ovn
2026-05-30 05:08:12.742064 | Pipeline: check
2026-05-30 05:08:12.742372 | Executor: 2d72f0692154
2026-05-30 05:08:12.742414 | Triggered by: https://github.com/vexxhost/atmosphere/pull/3985
2026-05-30 05:08:12.742443 | Event ID: 11bfd8e0-5be5-11f1-826c-d826e70d94d2
2026-05-30 05:08:12.745983 | 
2026-05-30 05:08:12.751044 | LOOP [emit-job-header : Print node information]
2026-05-30 05:08:12.862284 | localhost | ok:
2026-05-30 05:08:12.869936 | localhost | # Node Information
2026-05-30 05:08:12.869995 | localhost | Inventory Hostname: instance
2026-05-30 05:08:12.870030 | localhost | Hostname: np0000177812
2026-05-30 05:08:12.870058 | localhost | Username: zuul
2026-05-30 05:08:12.870091 | localhost | Distro: Ubuntu 22.04
2026-05-30 05:08:12.870121 | localhost | Provider: yul1
2026-05-30 05:08:12.870148 | localhost | Region: ca-ymq-1
2026-05-30 05:08:12.870174 | localhost | Label: ubuntu-jammy-16
2026-05-30 05:08:12.870200 | localhost | Product Name: OpenStack Nova
2026-05-30 05:08:12.870249 | localhost | Interface IP: 199.204.45.95
2026-05-30 05:08:12.886124 | 
2026-05-30 05:08:12.886382 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-05-30 05:08:13.455935 | localhost -> localhost | changed
2026-05-30 05:08:13.461359 | 
2026-05-30 05:08:13.461430 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-05-30 05:08:14.653584 | localhost -> localhost | changed
2026-05-30 05:08:14.663233 | 
2026-05-30 05:08:14.663318 | PLAY [all]
2026-05-30 05:08:14.672357 | 
2026-05-30 05:08:14.672428 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-05-30 05:08:14.916290 | instance -> localhost | ok
2026-05-30 05:08:14.925511 | 
2026-05-30 05:08:14.925627 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-05-30 05:08:14.963558 | instance | ok
2026-05-30 05:08:15.105467 | instance | included: /var/lib/zuul/builds/a40f422b8daf469a872b2f54d8e961dc/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-05-30 05:08:15.113265 | 
2026-05-30 05:08:15.113354 | TASK [add-build-sshkey : Create Temp SSH key]
2026-05-30 05:08:16.752262 | instance -> localhost | Generating public/private rsa key pair.
2026-05-30 05:08:16.752791 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/a40f422b8daf469a872b2f54d8e961dc/work/a40f422b8daf469a872b2f54d8e961dc_id_rsa
2026-05-30 05:08:16.752856 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/a40f422b8daf469a872b2f54d8e961dc/work/a40f422b8daf469a872b2f54d8e961dc_id_rsa.pub
2026-05-30 05:08:16.752896 | instance -> localhost | The key fingerprint is:
2026-05-30 05:08:16.752930 | instance -> localhost | SHA256:f4ICxIJxt5ZODy4HPmHM3kpjFqjuAkfuBYTnQvqXJOE zuul-build-sshkey
2026-05-30 05:08:16.753273 | instance -> localhost | The key's randomart image is:
2026-05-30 05:08:16.753324 | instance -> localhost | +---[RSA 3072]----+
2026-05-30 05:08:16.754785 | instance -> localhost | |... .            |
2026-05-30 05:08:16.754823 | instance -> localhost | |.O+o o           |
2026-05-30 05:08:16.754847 | instance -> localhost | |==O.O            |
2026-05-30 05:08:16.754869 | instance -> localhost | |++E%.o           |
2026-05-30 05:08:16.754890 | instance -> localhost | |.=X+*.. S        |
2026-05-30 05:08:16.754911 | instance -> localhost | |o++*+.   o       |
2026-05-30 05:08:16.754932 | instance -> localhost | |.+.o  . . o .    |
2026-05-30 05:08:16.754953 | instance -> localhost | |o .    .   o     |
2026-05-30 05:08:16.754975 | instance -> localhost | |..               |
2026-05-30 05:08:16.754997 | instance -> localhost | +----[SHA256]-----+
2026-05-30 05:08:16.755050 | instance -> localhost | ok: Runtime: 0:00:01.118457
2026-05-30 05:08:16.761925 | 
2026-05-30 05:08:16.762002 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-05-30 05:08:16.798979 | instance | ok
2026-05-30 05:08:16.853615 | instance | included: /var/lib/zuul/builds/a40f422b8daf469a872b2f54d8e961dc/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-05-30 05:08:16.867597 | 
2026-05-30 05:08:16.867681 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-05-30 05:08:16.893473 | instance | skipping: Conditional result was False
2026-05-30 05:08:16.973512 | 
2026-05-30 05:08:16.973631 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-05-30 05:08:17.451646 | instance | changed
2026-05-30 05:08:17.460757 | 
2026-05-30 05:08:17.460856 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-05-30 05:08:17.717735 | instance | ok
2026-05-30 05:08:17.723342 | 
2026-05-30 05:08:17.723428 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-05-30 05:08:18.216722 | instance | changed
2026-05-30 05:08:18.235276 | 
2026-05-30 05:08:18.235401 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-05-30 05:08:18.691217 | instance | changed
2026-05-30 05:08:18.746256 | 
2026-05-30 05:08:18.746821 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-05-30 05:08:18.771739 | instance | skipping: Conditional result was False
2026-05-30 05:08:18.781610 | 
2026-05-30 05:08:18.781703 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-05-30 05:08:19.112222 | instance -> localhost | changed
2026-05-30 05:08:19.146431 | 
2026-05-30 05:08:19.146597 | TASK [add-build-sshkey : Add back temp key]
2026-05-30 05:08:19.459797 | instance -> localhost | Identity added: /var/lib/zuul/builds/a40f422b8daf469a872b2f54d8e961dc/work/a40f422b8daf469a872b2f54d8e961dc_id_rsa (zuul-build-sshkey)
2026-05-30 05:08:19.460011 | instance -> localhost | ok: Runtime: 0:00:00.013256
2026-05-30 05:08:19.466087 | 
2026-05-30 05:08:19.466153 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-05-30 05:08:19.745626 | instance | ok
2026-05-30 05:08:19.754175 | 
2026-05-30 05:08:19.754273 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-05-30 05:08:19.779973 | instance | skipping: Conditional result was False
2026-05-30 05:08:19.799036 | 
2026-05-30 05:08:19.799643 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-05-30 05:08:20.100667 | instance | ok
2026-05-30 05:08:20.107863 | 
2026-05-30 05:08:20.107925 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-05-30 05:08:23.382193 | instance | Output suppressed because no_log was given
2026-05-30 05:08:23.391719 | 
2026-05-30 05:08:23.391794 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-05-30 05:08:23.587437 | instance | ok: "logs"
2026-05-30 05:08:23.606317 | instance | ok: All items complete
2026-05-30 05:08:23.606382 | 
2026-05-30 05:08:23.734806 | instance | ok: "artifacts"
2026-05-30 05:08:23.896509 | instance | ok: "docs"
2026-05-30 05:08:23.912567 | 
2026-05-30 05:08:23.912759 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-05-30 05:08:24.110197 | instance | changed: "logs"
2026-05-30 05:08:24.273606 | instance | changed: "artifacts"
2026-05-30 05:08:24.434262 | instance | changed: "docs"
2026-05-30 05:08:24.450736 | 
2026-05-30 05:08:24.450958 | PLAY RECAP
2026-05-30 05:08:24.451131 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-05-30 05:08:24.451186 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-05-30 05:08:24.451215 | 
2026-05-30 05:08:24.692264 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-05-30 05:08:24.702977 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-05-30 05:08:25.352774 | 
2026-05-30 05:08:25.353170 | PLAY [all]
2026-05-30 05:08:25.365497 | 
2026-05-30 05:08:25.365584 | TASK [setup-uv : Extract archive]
2026-05-30 05:08:27.657246 | instance | changed
2026-05-30 05:08:27.688221 | 
2026-05-30 05:08:27.688620 | TASK [setup-uv : Print version]
2026-05-30 05:08:28.097193 | instance | uv 0.8.13
2026-05-30 05:08:28.240072 | instance | ok: Runtime: 0:00:00.015252
2026-05-30 05:08:28.254071 | 
2026-05-30 05:08:28.254190 | TASK [Install Ansible collection siblings]
2026-05-30 05:08:28.984478 | instance | ERROR
2026-05-30 05:08:28.984804 | instance | {
2026-05-30 05:08:28.984874 | instance |   "log": [
2026-05-30 05:08:28.984916 | instance |     "Sibling collection ansible.netcommon at /home/zuul/src/github.com/ansible-collections/ansible.netcommon",
2026-05-30 05:08:28.985002 | instance |     "Sibling collection ansible.posix at /home/zuul/src/github.com/ansible-collections/ansible.posix",
2026-05-30 05:08:28.985048 | instance |     "Sibling collection ansible.utils at /home/zuul/src/github.com/ansible-collections/ansible.utils",
2026-05-30 05:08:28.985089 | instance |     "Sibling collection community.crypto at /home/zuul/src/github.com/ansible-collections/community.crypto",
2026-05-30 05:08:28.985119 | instance |     "Sibling collection community.general at /home/zuul/src/github.com/ansible-collections/community.general",
2026-05-30 05:08:28.985155 | instance |     "Sibling collection community.mysql at /home/zuul/src/github.com/ansible-collections/community.mysql",
2026-05-30 05:08:28.985187 | instance |     "Sibling collection kubernetes.core at /home/zuul/src/github.com/ansible-collections/kubernetes.core",
2026-05-30 05:08:28.985218 | instance |     "Sibling collection vexxhost.ceph at /home/zuul/src/github.com/vexxhost/ansible-collection-ceph",
2026-05-30 05:08:28.985248 | instance |     "Sibling collection vexxhost.containers at /home/zuul/src/github.com/vexxhost/ansible-collection-containers",
2026-05-30 05:08:28.985329 | instance |     "Sibling collection vexxhost.kubernetes at /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes",
2026-05-30 05:08:28.985362 | instance |     "Sibling collection atmosphere.common at /home/zuul/src/github.com/vexxhost/atmosphere.common",
2026-05-30 05:08:28.985394 | instance |     "Sibling collection openstack.cloud at /home/zuul/src/opendev.org/openstack/ansible-collections-openstack",
2026-05-30 05:08:28.985424 | instance |     "Resolved ansible.netcommon to v8.5.2 for >=1.2.0",
2026-05-30 05:08:28.985455 | instance |     "Resolved ansible.posix to 1.6.0 for 1.6.0",
2026-05-30 05:08:28.985486 | instance |     "Resolved ansible.utils to v6.0.2 for >=2.9.0,>=3.0.0",
2026-05-30 05:08:28.985516 | instance |     "Resolved community.crypto to 2.2.3 for 2.2.3",
2026-05-30 05:08:28.985547 | instance |     "Resolved community.general to 7.3.0 for 7.3.0",
2026-05-30 05:08:28.985578 | instance |     "Resolved community.mysql to 3.6.0 for 3.6.0",
2026-05-30 05:08:28.985608 | instance |     "Resolved kubernetes.core to 2.4.0 for 2.4.0",
2026-05-30 05:08:28.985637 | instance |     "Resolved openstack.cloud to 1.7.0 for 1.7.0",
2026-05-30 05:08:28.985671 | instance |     "Resolved atmosphere.common to v0.7.0 for >=0.6.0",
2026-05-30 05:08:28.985724 | instance |     "Resolved vexxhost.ceph to v4.0.0 for >=3.1.2",
2026-05-30 05:08:28.985762 | instance |     "Resolved vexxhost.kubernetes to v3.1.0 for >=2.3.2,>=2.5.0",
2026-05-30 05:08:28.985797 | instance |     "Resolved ansible.utils to v6.0.2 for >=2.9.0,>=3.0.0,>=6.0.0",
2026-05-30 05:08:28.985828 | instance |     "Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2"
2026-05-30 05:08:28.985857 | instance |   ],
2026-05-30 05:08:28.985888 | instance |   "msg": "No local release tag for openstack.cloud satisfies 1.7.0,>=2.0.0"
2026-05-30 05:08:28.985918 | instance | }
failure
2026-05-30 05:08:28.991266 | 
2026-05-30 05:08:28.991330 | PLAY RECAP
2026-05-30 05:08:28.991390 | instance | ok: 2 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-05-30 05:08:28.991419 | 
2026-05-30 05:08:29.190936 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-05-30 05:08:29.204160 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-05-30 05:08:29.909361 | 
2026-05-30 05:08:29.909500 | PLAY [all]
2026-05-30 05:08:29.921588 | 
2026-05-30 05:08:29.921670 | TASK [fetch-output : Set log path for multiple nodes]
2026-05-30 05:08:29.969538 | instance | skipping: Conditional result was False
2026-05-30 05:08:29.982896 | 
2026-05-30 05:08:29.983014 | TASK [fetch-output : Set log path for single node]
2026-05-30 05:08:30.028142 | instance | ok
2026-05-30 05:08:30.034708 | 
2026-05-30 05:08:30.034802 | LOOP [fetch-output : Ensure local output dirs]
2026-05-30 05:08:30.477756 | instance -> localhost | ok: "/var/lib/zuul/builds/a40f422b8daf469a872b2f54d8e961dc/work/logs"
2026-05-30 05:08:30.723476 | instance -> localhost | changed: "/var/lib/zuul/builds/a40f422b8daf469a872b2f54d8e961dc/work/artifacts"
2026-05-30 05:08:31.019693 | instance -> localhost | changed: "/var/lib/zuul/builds/a40f422b8daf469a872b2f54d8e961dc/work/docs"
2026-05-30 05:08:31.040595 | 
2026-05-30 05:08:31.040739 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-05-30 05:08:31.683015 | instance | ok
2026-05-30 05:08:31.683284 | instance | changed: All items complete
2026-05-30 05:08:31.683317 | 
2026-05-30 05:08:32.195885 | instance | changed: .d..t...... ./
2026-05-30 05:08:32.703968 | instance | changed: .d..t...... ./
2026-05-30 05:08:32.722538 | 
2026-05-30 05:08:32.722690 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-05-30 05:08:33.230243 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.008449
2026-05-30 05:08:33.568352 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.008770
2026-05-30 05:08:33.584756 | 
2026-05-30 05:08:33.584894 | PLAY [all]
2026-05-30 05:08:33.591503 | 
2026-05-30 05:08:33.591572 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-05-30 05:08:34.043669 | instance | changed
2026-05-30 05:08:34.053526 | 
2026-05-30 05:08:34.053599 | PLAY RECAP
2026-05-30 05:08:34.053645 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-05-30 05:08:34.053667 | 
2026-05-30 05:08:34.244465 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-05-30 05:08:34.370905 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-05-30 05:08:35.105053 | 
2026-05-30 05:08:35.105187 | PLAY [localhost]
2026-05-30 05:08:35.115396 | 
2026-05-30 05:08:35.116732 | TASK [Generate Zuul manifest]
2026-05-30 05:08:35.141974 | localhost | ok
2026-05-30 05:08:35.158661 | 
2026-05-30 05:08:35.158764 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-05-30 05:08:35.611941 | localhost | changed
2026-05-30 05:08:35.631750 | 
2026-05-30 05:08:35.634789 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-05-30 05:08:35.670559 | localhost | ok
2026-05-30 05:08:35.681871 | 
2026-05-30 05:08:35.682030 | TASK [Upload logs]
2026-05-30 05:08:35.713668 | localhost | ok
2026-05-30 05:08:35.833835 | 
2026-05-30 05:08:35.833990 | TASK [Set zuul-log-path fact]
2026-05-30 05:08:35.856317 | localhost | ok
2026-05-30 05:08:35.870061 | 
2026-05-30 05:08:35.870132 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-05-30 05:08:35.903344 | localhost | ok
2026-05-30 05:08:35.913804 | 
2026-05-30 05:08:35.913897 | TASK [upload-logs : Create log directories]
2026-05-30 05:08:36.333473 | localhost | changed
2026-05-30 05:08:36.339169 | 
2026-05-30 05:08:36.339279 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-05-30 05:08:36.929924 | localhost -> localhost | ok: Runtime: 0:00:00.005774
2026-05-30 05:08:36.937778 | 
2026-05-30 05:08:36.937969 | TASK [upload-logs : Upload logs to log server]
2026-05-30 05:08:37.397038 | localhost | Output suppressed because no_log was given
2026-05-30 05:08:37.405393 | 
2026-05-30 05:08:37.405631 | LOOP [upload-logs : Compress console log and json output]
2026-05-30 05:08:37.458864 | localhost | skipping: Conditional result was False
2026-05-30 05:08:37.466322 | localhost | skipping: Conditional result was False
2026-05-30 05:08:37.477635 | 
2026-05-30 05:08:37.477789 | LOOP [upload-logs : Upload compressed console log and json output]
2026-05-30 05:08:37.522528 | localhost | skipping: Conditional result was False
2026-05-30 05:08:37.522927 | 
2026-05-30 05:08:37.526934 | localhost | skipping: Conditional result was False
2026-05-30 05:08:37.538795 | 
2026-05-30 05:08:37.538952 | LOOP [upload-logs : Upload console log and json output]