2026-05-26 05:42:18.934904 | Job console starting
2026-05-26 05:42:18.952480 | Updating git repos
2026-05-26 05:42:24.039153 | Cloning repos into workspace
2026-05-26 05:42:40.224461 | Restoring repo states
2026-05-26 05:42:51.374719 | Merging changes
2026-05-26 05:42:53.209003 | Checking out repos
2026-05-26 05:42:56.649904 | Preparing playbooks
2026-05-26 05:43:23.084451 | Running Ansible setup
2026-05-26 05:43:27.696138 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-05-26 05:43:28.401739 | 
2026-05-26 05:43:28.401895 | PLAY [localhost]
2026-05-26 05:43:28.411387 | 
2026-05-26 05:43:28.411519 | TASK [Gathering Facts]
2026-05-26 05:43:29.615817 | localhost | ok
2026-05-26 05:43:29.627584 | 
2026-05-26 05:43:29.627717 | TASK [Setup log path fact]
2026-05-26 05:43:29.648872 | localhost | ok
2026-05-26 05:43:29.660301 | 
2026-05-26 05:43:29.660385 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-05-26 05:43:29.695725 | localhost | ok
2026-05-26 05:43:29.704232 | 
2026-05-26 05:43:29.704325 | TASK [emit-job-header : Print job information]
2026-05-26 05:43:29.760060 | # Job Information
2026-05-26 05:43:29.760311 | Ansible Version: 2.16.18
2026-05-26 05:43:29.760354 | Job: atmosphere-molecule-aio-openvswitch
2026-05-26 05:43:29.760378 | Pipeline: check
2026-05-26 05:43:29.760401 | Executor: 2d72f0692154
2026-05-26 05:43:29.760422 | Triggered by: https://github.com/vexxhost/atmosphere/pull/3985
2026-05-26 05:43:29.760446 | Event ID: c15d7d80-58c4-11f1-9680-468cf01be9f3
2026-05-26 05:43:29.764377 | 
2026-05-26 05:43:29.764462 | LOOP [emit-job-header : Print node information]
2026-05-26 05:43:29.862721 | localhost | ok:
2026-05-26 05:43:29.900098 | localhost | # Node Information
2026-05-26 05:43:29.900213 | localhost | Inventory Hostname: instance
2026-05-26 05:43:29.900274 | localhost | Hostname: np0000176439
2026-05-26 05:43:29.900323 | localhost | Username: zuul
2026-05-26 05:43:29.900379 | localhost | Distro: Ubuntu 22.04
2026-05-26 05:43:29.900426 | localhost | Provider: yul1
2026-05-26 05:43:29.900473 | localhost | Region: ca-ymq-1
2026-05-26 05:43:29.900519 | localhost | Label: ubuntu-jammy-16
2026-05-26 05:43:29.900563 | localhost | Product Name: OpenStack Nova
2026-05-26 05:43:29.900608 | localhost | Interface IP: 199.19.213.140
2026-05-26 05:43:29.916365 | 
2026-05-26 05:43:29.916600 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-05-26 05:43:30.505221 | localhost -> localhost | changed
2026-05-26 05:43:30.513868 | 
2026-05-26 05:43:30.514023 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-05-26 05:43:31.960241 | localhost -> localhost | changed
2026-05-26 05:43:31.969770 | 
2026-05-26 05:43:31.969875 | PLAY [all]
2026-05-26 05:43:31.981475 | 
2026-05-26 05:43:31.981569 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-05-26 05:43:32.323556 | instance -> localhost | ok
2026-05-26 05:43:32.336415 | 
2026-05-26 05:43:32.336555 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-05-26 05:43:32.369076 | instance | ok
2026-05-26 05:43:32.387517 | instance | included: /var/lib/zuul/builds/13f4e2fd273147f99b75a8372e94f575/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-05-26 05:43:32.396209 | 
2026-05-26 05:43:32.396318 | TASK [add-build-sshkey : Create Temp SSH key]
2026-05-26 05:43:33.911272 | instance -> localhost | Generating public/private rsa key pair.
2026-05-26 05:43:33.911492 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/13f4e2fd273147f99b75a8372e94f575/work/13f4e2fd273147f99b75a8372e94f575_id_rsa
2026-05-26 05:43:33.911533 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/13f4e2fd273147f99b75a8372e94f575/work/13f4e2fd273147f99b75a8372e94f575_id_rsa.pub
2026-05-26 05:43:33.911569 | instance -> localhost | The key fingerprint is:
2026-05-26 05:43:33.911607 | instance -> localhost | SHA256:ZFBIFM8ztSbK68KAXqeKOkDpTDAUV+8PykguQ3NnYHk zuul-build-sshkey
2026-05-26 05:43:33.911664 | instance -> localhost | The key's randomart image is:
2026-05-26 05:43:33.911713 | instance -> localhost | +---[RSA 3072]----+
2026-05-26 05:43:33.911765 | instance -> localhost | |.o...+=+. .      |
2026-05-26 05:43:33.911800 | instance -> localhost | |o . . o+ . .     |
2026-05-26 05:43:33.911833 | instance -> localhost | |...+ E .O o      |
2026-05-26 05:43:33.911864 | instance -> localhost | | +. o..+ =       |
2026-05-26 05:43:33.911895 | instance -> localhost | |=+ o oooS        |
2026-05-26 05:43:33.911926 | instance -> localhost | |+o*.=...o        |
2026-05-26 05:43:33.911956 | instance -> localhost | |oo.=oo.  .       |
2026-05-26 05:43:33.911985 | instance -> localhost | |o.o.o.           |
2026-05-26 05:43:33.912017 | instance -> localhost | |+o.  ..          |
2026-05-26 05:43:33.912048 | instance -> localhost | +----[SHA256]-----+
2026-05-26 05:43:33.912132 | instance -> localhost | ok: Runtime: 0:00:01.025785
2026-05-26 05:43:33.921687 | 
2026-05-26 05:43:33.921838 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-05-26 05:43:33.957402 | instance | ok
2026-05-26 05:43:33.972932 | instance | included: /var/lib/zuul/builds/13f4e2fd273147f99b75a8372e94f575/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-05-26 05:43:33.982689 | 
2026-05-26 05:43:33.982792 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-05-26 05:43:34.008769 | instance | skipping: Conditional result was False
2026-05-26 05:43:34.018775 | 
2026-05-26 05:43:34.018881 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-05-26 05:43:34.469369 | instance | changed
2026-05-26 05:43:34.477937 | 
2026-05-26 05:43:34.478031 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-05-26 05:43:34.701896 | instance | ok
2026-05-26 05:43:34.724745 | 
2026-05-26 05:43:34.724843 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-05-26 05:43:35.206854 | instance | changed
2026-05-26 05:43:35.216968 | 
2026-05-26 05:43:35.217044 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-05-26 05:43:35.727187 | instance | changed
2026-05-26 05:43:35.735048 | 
2026-05-26 05:43:35.735528 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-05-26 05:43:35.761017 | instance | skipping: Conditional result was False
2026-05-26 05:43:35.773160 | 
2026-05-26 05:43:35.773296 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-05-26 05:43:36.122935 | instance -> localhost | changed
2026-05-26 05:43:36.143794 | 
2026-05-26 05:43:36.143984 | TASK [add-build-sshkey : Add back temp key]
2026-05-26 05:43:36.428810 | instance -> localhost | Identity added: /var/lib/zuul/builds/13f4e2fd273147f99b75a8372e94f575/work/13f4e2fd273147f99b75a8372e94f575_id_rsa (zuul-build-sshkey)
2026-05-26 05:43:36.429167 | instance -> localhost | ok: Runtime: 0:00:00.014029
2026-05-26 05:43:36.437315 | 
2026-05-26 05:43:36.437405 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-05-26 05:43:36.705043 | instance | ok
2026-05-26 05:43:36.714544 | 
2026-05-26 05:43:36.714699 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-05-26 05:43:36.740058 | instance | skipping: Conditional result was False
2026-05-26 05:43:36.756893 | 
2026-05-26 05:43:36.757092 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-05-26 05:43:37.054353 | instance | ok
2026-05-26 05:43:37.062783 | 
2026-05-26 05:43:37.062849 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-05-26 05:43:41.358095 | instance | Output suppressed because no_log was given
2026-05-26 05:43:41.371244 | 
2026-05-26 05:43:41.371366 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-05-26 05:43:41.572695 | instance | ok: "logs"
2026-05-26 05:43:41.572945 | instance | ok: All items complete
2026-05-26 05:43:41.573032 | 
2026-05-26 05:43:41.722827 | instance | ok: "artifacts"
2026-05-26 05:43:41.875512 | instance | ok: "docs"
2026-05-26 05:43:41.884133 | 
2026-05-26 05:43:41.884316 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-05-26 05:43:42.070829 | instance | changed: "logs"
2026-05-26 05:43:42.231342 | instance | changed: "artifacts"
2026-05-26 05:43:42.383426 | instance | changed: "docs"
2026-05-26 05:43:42.396469 | 
2026-05-26 05:43:42.396622 | PLAY RECAP
2026-05-26 05:43:42.396720 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-05-26 05:43:42.396766 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-05-26 05:43:42.396798 | 
2026-05-26 05:43:42.586405 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-05-26 05:43:42.599684 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-05-26 05:43:43.272566 | 
2026-05-26 05:43:43.272753 | PLAY [all]
2026-05-26 05:43:43.284227 | 
2026-05-26 05:43:43.284309 | TASK [setup-uv : Extract archive]
2026-05-26 05:43:45.591984 | instance | changed
2026-05-26 05:43:46.462381 | 
2026-05-26 05:43:46.462542 | TASK [setup-uv : Print version]
2026-05-26 05:43:46.844027 | instance | uv 0.8.13
2026-05-26 05:43:47.002845 | instance | ok: Runtime: 0:00:00.011123
2026-05-26 05:43:47.013033 | 
2026-05-26 05:43:47.013125 | TASK [Install Ansible collection siblings]
2026-05-26 05:43:47.671634 | instance | ERROR
2026-05-26 05:43:47.671980 | instance | {
2026-05-26 05:43:47.672025 | instance |   "log": [
2026-05-26 05:43:47.672057 | instance |     "Sibling collection ansible.netcommon at /home/zuul/src/github.com/ansible-collections/ansible.netcommon",
2026-05-26 05:43:47.672093 | instance |     "Sibling collection ansible.posix at /home/zuul/src/github.com/ansible-collections/ansible.posix",
2026-05-26 05:43:47.672122 | instance |     "Sibling collection ansible.utils at /home/zuul/src/github.com/ansible-collections/ansible.utils",
2026-05-26 05:43:47.672291 | instance |     "Sibling collection community.crypto at /home/zuul/src/github.com/ansible-collections/community.crypto",
2026-05-26 05:43:47.672573 | instance |     "Sibling collection community.general at /home/zuul/src/github.com/ansible-collections/community.general",
2026-05-26 05:43:47.672731 | instance |     "Sibling collection community.mysql at /home/zuul/src/github.com/ansible-collections/community.mysql",
2026-05-26 05:43:47.672781 | instance |     "Sibling collection kubernetes.core at /home/zuul/src/github.com/ansible-collections/kubernetes.core",
2026-05-26 05:43:47.672819 | instance |     "Sibling collection vexxhost.ceph at /home/zuul/src/github.com/vexxhost/ansible-collection-ceph",
2026-05-26 05:43:47.672855 | instance |     "Sibling collection vexxhost.containers at /home/zuul/src/github.com/vexxhost/ansible-collection-containers",
2026-05-26 05:43:47.672894 | instance |     "Sibling collection vexxhost.kubernetes at /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes",
2026-05-26 05:43:47.672930 | instance |     "Sibling collection atmosphere.common at /home/zuul/src/github.com/vexxhost/atmosphere.common",
2026-05-26 05:43:47.672985 | instance |     "Sibling collection openstack.cloud at /home/zuul/src/opendev.org/openstack/ansible-collections-openstack",
2026-05-26 05:43:47.673024 | instance |     "Resolved ansible.netcommon to v8.5.2 for >=1.2.0",
2026-05-26 05:43:47.673059 | instance |     "Resolved ansible.posix to 1.6.0 for 1.6.0",
2026-05-26 05:43:47.673087 | instance |     "Resolved ansible.utils to v6.0.2 for >=2.9.0,>=3.0.0",
2026-05-26 05:43:47.673114 | instance |     "Resolved community.crypto to 2.2.3 for 2.2.3",
2026-05-26 05:43:47.673141 | instance |     "Resolved community.general to 7.3.0 for 7.3.0",
2026-05-26 05:43:47.673167 | instance |     "Resolved community.mysql to 3.6.0 for 3.6.0",
2026-05-26 05:43:47.673192 | instance |     "Resolved kubernetes.core to 2.4.0 for 2.4.0",
2026-05-26 05:43:47.673218 | instance |     "Resolved openstack.cloud to 1.7.0 for 1.7.0",
2026-05-26 05:43:47.673246 | instance |     "Resolved atmosphere.common to v0.7.0 for >=0.6.0",
2026-05-26 05:43:47.673284 | instance |     "Resolved vexxhost.ceph to v4.0.0 for >=3.1.2",
2026-05-26 05:43:47.673313 | instance |     "Resolved vexxhost.kubernetes to v3.1.0 for >=2.3.2,>=2.5.0",
2026-05-26 05:43:47.673339 | instance |     "Resolved ansible.utils to v6.0.2 for >=2.9.0,>=3.0.0,>=6.0.0",
2026-05-26 05:43:47.673365 | instance |     "Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2"
2026-05-26 05:43:47.673391 | instance |   ],
2026-05-26 05:43:47.673417 | instance |   "msg": "No local release tag for openstack.cloud satisfies 1.7.0,>=2.0.0"
2026-05-26 05:43:47.673444 | instance | }
failure
2026-05-26 05:43:47.677529 | 
2026-05-26 05:43:47.677593 | PLAY RECAP
2026-05-26 05:43:47.677643 | instance | ok: 2 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-05-26 05:43:47.677666 | 
2026-05-26 05:43:47.827274 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-05-26 05:43:47.890862 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-05-26 05:43:48.601007 | 
2026-05-26 05:43:48.601177 | PLAY [all]
2026-05-26 05:43:48.612624 | 
2026-05-26 05:43:48.612703 | TASK [fetch-output : Set log path for multiple nodes]
2026-05-26 05:43:48.658174 | instance | skipping: Conditional result was False
2026-05-26 05:43:48.668787 | 
2026-05-26 05:43:48.668938 | TASK [fetch-output : Set log path for single node]
2026-05-26 05:43:48.714706 | instance | ok
2026-05-26 05:43:48.720000 | 
2026-05-26 05:43:48.720071 | LOOP [fetch-output : Ensure local output dirs]
2026-05-26 05:43:49.143455 | instance -> localhost | ok: "/var/lib/zuul/builds/13f4e2fd273147f99b75a8372e94f575/work/logs"
2026-05-26 05:43:49.852144 | instance -> localhost | changed: "/var/lib/zuul/builds/13f4e2fd273147f99b75a8372e94f575/work/artifacts"
2026-05-26 05:43:50.051994 | instance -> localhost | changed: "/var/lib/zuul/builds/13f4e2fd273147f99b75a8372e94f575/work/docs"
2026-05-26 05:43:50.069606 | 
2026-05-26 05:43:50.069824 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-05-26 05:43:50.737153 | instance | ok
2026-05-26 05:43:51.834131 | instance | changed: All items complete
2026-05-26 05:43:51.834292 | 
2026-05-26 05:43:51.922365 | instance | changed: .d..t...... ./
2026-05-26 05:43:52.377807 | instance | changed: .d..t...... ./
2026-05-26 05:43:52.400605 | 
2026-05-26 05:43:52.621496 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-05-26 05:43:53.370108 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.262879
2026-05-26 05:43:53.604626 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.009570
2026-05-26 05:43:53.623702 | 
2026-05-26 05:43:53.624125 | PLAY [all]
2026-05-26 05:43:53.631500 | 
2026-05-26 05:43:53.631638 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-05-26 05:43:54.098249 | instance | changed
2026-05-26 05:43:54.105582 | 
2026-05-26 05:43:54.105634 | PLAY RECAP
2026-05-26 05:43:54.105681 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-05-26 05:43:54.105704 | 
2026-05-26 05:43:54.249145 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-05-26 05:43:54.262551 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-05-26 05:43:54.951144 | 
2026-05-26 05:43:54.951263 | PLAY [localhost]
2026-05-26 05:43:54.961418 | 
2026-05-26 05:43:54.961488 | TASK [Generate Zuul manifest]
2026-05-26 05:43:54.982762 | localhost | ok
2026-05-26 05:43:55.000357 | 
2026-05-26 05:43:55.000496 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-05-26 05:43:55.511508 | localhost | changed
2026-05-26 05:43:55.522453 | 
2026-05-26 05:43:55.522547 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-05-26 05:43:55.755855 | localhost | ok
2026-05-26 05:43:55.763604 | 
2026-05-26 05:43:55.763688 | TASK [Upload logs]
2026-05-26 05:43:55.797137 | localhost | ok
2026-05-26 05:43:55.912479 | 
2026-05-26 05:43:55.912636 | TASK [Set zuul-log-path fact]
2026-05-26 05:43:55.933509 | localhost | ok
2026-05-26 05:43:55.950949 | 
2026-05-26 05:43:55.951034 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-05-26 05:43:55.985683 | localhost | ok
2026-05-26 05:43:56.000646 | 
2026-05-26 05:43:56.000830 | TASK [upload-logs : Create log directories]
2026-05-26 05:43:56.411836 | localhost | changed
2026-05-26 05:43:56.419152 | 
2026-05-26 05:43:56.419305 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-05-26 05:43:56.808222 | localhost -> localhost | ok: Runtime: 0:00:00.004404
2026-05-26 05:43:56.813885 | 
2026-05-26 05:43:56.813992 | TASK [upload-logs : Upload logs to log server]
2026-05-26 05:43:57.265512 | localhost | Output suppressed because no_log was given
2026-05-26 05:43:57.270680 | 
2026-05-26 05:43:57.270771 | LOOP [upload-logs : Compress console log and json output]
2026-05-26 05:43:57.316304 | localhost | skipping: Conditional result was False
2026-05-26 05:43:57.323904 | localhost | skipping: Conditional result was False
2026-05-26 05:43:57.338206 | 
2026-05-26 05:43:57.338368 | LOOP [upload-logs : Upload compressed console log and json output]
2026-05-26 05:43:57.379100 | localhost | skipping: Conditional result was False
2026-05-26 05:43:57.379353 | 
2026-05-26 05:43:57.383698 | localhost | skipping: Conditional result was False
2026-05-26 05:43:57.394056 | 
2026-05-26 05:43:57.394235 | LOOP [upload-logs : Upload console log and json output]