2026-05-30 05:05:36.863226 | Job console starting
2026-05-30 05:05:36.873097 | Updating git repos
2026-05-30 05:05:43.480896 | Cloning repos into workspace
2026-05-30 05:06:04.099687 | Restoring repo states
2026-05-30 05:06:06.935389 | Merging changes
2026-05-30 05:06:08.690024 | Checking out repos
2026-05-30 05:06:12.927332 | Preparing playbooks
2026-05-30 05:06:27.665922 | Running Ansible setup
2026-05-30 05:06:31.295578 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-05-30 05:06:32.019854 | 
2026-05-30 05:06:32.019984 | PLAY [localhost]
2026-05-30 05:06:32.027634 | 
2026-05-30 05:06:32.027703 | TASK [Gathering Facts]
2026-05-30 05:06:33.156800 | localhost | ok
2026-05-30 05:06:33.177597 | 
2026-05-30 05:06:33.177731 | TASK [Setup log path fact]
2026-05-30 05:06:33.198949 | localhost | ok
2026-05-30 05:06:33.214357 | 
2026-05-30 05:06:33.214447 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-05-30 05:06:33.243377 | localhost | ok
2026-05-30 05:06:33.249989 | 
2026-05-30 05:06:33.250062 | TASK [emit-job-header : Print job information]
2026-05-30 05:06:33.294159 | # Job Information
2026-05-30 05:06:33.294357 | Ansible Version: 2.16.18
2026-05-30 05:06:33.294390 | Job: atmosphere-molecule-aio-openvswitch
2026-05-30 05:06:33.294412 | Pipeline: check
2026-05-30 05:06:33.294433 | Executor: 2d72f0692154
2026-05-30 05:06:33.294452 | Triggered by: https://github.com/vexxhost/atmosphere/pull/3985
2026-05-30 05:06:33.294476 | Event ID: 11bfd8e0-5be5-11f1-826c-d826e70d94d2
2026-05-30 05:06:33.297661 | 
2026-05-30 05:06:33.297732 | LOOP [emit-job-header : Print node information]
2026-05-30 05:06:33.392920 | localhost | ok:
2026-05-30 05:06:33.393584 | localhost | # Node Information
2026-05-30 05:06:33.393615 | localhost | Inventory Hostname: instance
2026-05-30 05:06:33.393637 | localhost | Hostname: np0000177790
2026-05-30 05:06:33.393657 | localhost | Username: zuul
2026-05-30 05:06:33.393680 | localhost | Distro: Ubuntu 22.04
2026-05-30 05:06:33.393701 | localhost | Provider: yul1
2026-05-30 05:06:33.393719 | localhost | Region: ca-ymq-1
2026-05-30 05:06:33.393738 | localhost | Label: ubuntu-jammy-16
2026-05-30 05:06:33.393756 | localhost | Product Name: OpenStack Nova
2026-05-30 05:06:33.393774 | localhost | Interface IP: 199.204.45.23
2026-05-30 05:06:33.406642 | 
2026-05-30 05:06:33.406827 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-05-30 05:06:33.994674 | localhost -> localhost | changed
2026-05-30 05:06:34.005493 | 
2026-05-30 05:06:34.005565 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-05-30 05:06:35.200026 | localhost -> localhost | changed
2026-05-30 05:06:35.210025 | 
2026-05-30 05:06:35.210112 | PLAY [all]
2026-05-30 05:06:35.220998 | 
2026-05-30 05:06:35.221069 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-05-30 05:06:35.523162 | instance -> localhost | ok
2026-05-30 05:06:35.531244 | 
2026-05-30 05:06:35.531320 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-05-30 05:06:35.564778 | instance | ok
2026-05-30 05:06:35.591736 | instance | included: /var/lib/zuul/builds/a77408349f5e463186ccea303e82d3dd/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-05-30 05:06:35.600162 | 
2026-05-30 05:06:35.600234 | TASK [add-build-sshkey : Create Temp SSH key]
2026-05-30 05:06:36.591924 | instance -> localhost | Generating public/private rsa key pair.
2026-05-30 05:06:36.592108 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/a77408349f5e463186ccea303e82d3dd/work/a77408349f5e463186ccea303e82d3dd_id_rsa
2026-05-30 05:06:36.592138 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/a77408349f5e463186ccea303e82d3dd/work/a77408349f5e463186ccea303e82d3dd_id_rsa.pub
2026-05-30 05:06:36.592161 | instance -> localhost | The key fingerprint is:
2026-05-30 05:06:36.592183 | instance -> localhost | SHA256:Dg+un4eyuyqWnANMqpqPA+EXcoZjTbKAHEgp1yxLVoo zuul-build-sshkey
2026-05-30 05:06:36.592221 | instance -> localhost | The key's randomart image is:
2026-05-30 05:06:36.592244 | instance -> localhost | +---[RSA 3072]----+
2026-05-30 05:06:36.592272 | instance -> localhost | |=oo+.            |
2026-05-30 05:06:36.592296 | instance -> localhost | |===oo            |
2026-05-30 05:06:36.592317 | instance -> localhost | |E+Bo             |
2026-05-30 05:06:36.592337 | instance -> localhost | |.B.=             |
2026-05-30 05:06:36.592357 | instance -> localhost | |*.= . o S        |
2026-05-30 05:06:36.592377 | instance -> localhost | |+o . . =         |
2026-05-30 05:06:36.592397 | instance -> localhost | |= +   ..o        |
2026-05-30 05:06:36.592417 | instance -> localhost | |+O  .....        |
2026-05-30 05:06:36.592439 | instance -> localhost | |*++.=*o.         |
2026-05-30 05:06:36.592459 | instance -> localhost | +----[SHA256]-----+
2026-05-30 05:06:36.592513 | instance -> localhost | ok: Runtime: 0:00:00.562789
2026-05-30 05:06:36.598426 | 
2026-05-30 05:06:36.598514 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-05-30 05:06:36.632407 | instance | ok
2026-05-30 05:06:36.644035 | instance | included: /var/lib/zuul/builds/a77408349f5e463186ccea303e82d3dd/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-05-30 05:06:36.651552 | 
2026-05-30 05:06:36.651610 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-05-30 05:06:36.675728 | instance | skipping: Conditional result was False
2026-05-30 05:06:36.685577 | 
2026-05-30 05:06:36.685661 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-05-30 05:06:37.144255 | instance | changed
2026-05-30 05:06:37.151520 | 
2026-05-30 05:06:37.151588 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-05-30 05:06:37.395595 | instance | ok
2026-05-30 05:06:37.400486 | 
2026-05-30 05:06:37.400551 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-05-30 05:06:37.963122 | instance | changed
2026-05-30 05:06:37.993347 | 
2026-05-30 05:06:37.993438 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-05-30 05:06:38.450180 | instance | changed
2026-05-30 05:06:38.455413 | 
2026-05-30 05:06:38.455479 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-05-30 05:06:38.482384 | instance | skipping: Conditional result was False
2026-05-30 05:06:38.490295 | 
2026-05-30 05:06:38.490367 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-05-30 05:06:38.811851 | instance -> localhost | changed
2026-05-30 05:06:38.830425 | 
2026-05-30 05:06:38.982737 | TASK [add-build-sshkey : Add back temp key]
2026-05-30 05:06:39.308316 | instance -> localhost | Identity added: /var/lib/zuul/builds/a77408349f5e463186ccea303e82d3dd/work/a77408349f5e463186ccea303e82d3dd_id_rsa (zuul-build-sshkey)
2026-05-30 05:06:39.308616 | instance -> localhost | ok: Runtime: 0:00:00.014474
2026-05-30 05:06:39.323520 | 
2026-05-30 05:06:39.323589 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-05-30 05:06:39.624793 | instance | ok
2026-05-30 05:06:39.660452 | 
2026-05-30 05:06:39.660635 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-05-30 05:06:39.697858 | instance | skipping: Conditional result was False
2026-05-30 05:06:39.715000 | 
2026-05-30 05:06:39.715146 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-05-30 05:06:39.996198 | instance | ok
2026-05-30 05:06:40.002667 | 
2026-05-30 05:06:40.002743 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-05-30 05:06:46.174920 | instance | Output suppressed because no_log was given
2026-05-30 05:06:46.185375 | 
2026-05-30 05:06:46.470080 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-05-30 05:06:46.701318 | instance | ok: "logs"
2026-05-30 05:06:46.711015 | instance | ok: All items complete
2026-05-30 05:06:46.711129 | 
2026-05-30 05:06:46.846004 | instance | ok: "artifacts"
2026-05-30 05:06:47.008559 | instance | ok: "docs"
2026-05-30 05:06:47.026497 | 
2026-05-30 05:06:47.026720 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-05-30 05:06:47.241504 | instance | changed: "logs"
2026-05-30 05:06:47.402323 | instance | changed: "artifacts"
2026-05-30 05:06:47.563415 | instance | changed: "docs"
2026-05-30 05:06:47.581276 | 
2026-05-30 05:06:47.581391 | PLAY RECAP
2026-05-30 05:06:47.581467 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-05-30 05:06:47.581512 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-05-30 05:06:47.581558 | 
2026-05-30 05:06:47.806428 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-05-30 05:06:47.817019 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-05-30 05:06:48.523613 | 
2026-05-30 05:06:48.523769 | PLAY [all]
2026-05-30 05:06:48.535891 | 
2026-05-30 05:06:48.535970 | TASK [setup-uv : Extract archive]
2026-05-30 05:06:50.830621 | instance | changed
2026-05-30 05:06:50.839393 | 
2026-05-30 05:06:50.839539 | TASK [setup-uv : Print version]
2026-05-30 05:06:51.184503 | instance | uv 0.8.13
2026-05-30 05:06:51.377939 | instance | ok: Runtime: 0:00:00.011109
2026-05-30 05:06:51.388827 | 
2026-05-30 05:06:51.388897 | TASK [Install Ansible collection siblings]
2026-05-30 05:06:51.987961 | instance | ERROR
2026-05-30 05:06:51.988317 | instance | {
2026-05-30 05:06:51.988363 | instance |   "log": [
2026-05-30 05:06:51.988393 | instance |     "Sibling collection ansible.netcommon at /home/zuul/src/github.com/ansible-collections/ansible.netcommon",
2026-05-30 05:06:51.988426 | instance |     "Sibling collection ansible.posix at /home/zuul/src/github.com/ansible-collections/ansible.posix",
2026-05-30 05:06:51.988453 | instance |     "Sibling collection ansible.utils at /home/zuul/src/github.com/ansible-collections/ansible.utils",
2026-05-30 05:06:51.988484 | instance |     "Sibling collection community.crypto at /home/zuul/src/github.com/ansible-collections/community.crypto",
2026-05-30 05:06:51.988511 | instance |     "Sibling collection community.general at /home/zuul/src/github.com/ansible-collections/community.general",
2026-05-30 05:06:51.988538 | instance |     "Sibling collection community.mysql at /home/zuul/src/github.com/ansible-collections/community.mysql",
2026-05-30 05:06:51.988563 | instance |     "Sibling collection kubernetes.core at /home/zuul/src/github.com/ansible-collections/kubernetes.core",
2026-05-30 05:06:51.988588 | instance |     "Sibling collection vexxhost.ceph at /home/zuul/src/github.com/vexxhost/ansible-collection-ceph",
2026-05-30 05:06:51.988613 | instance |     "Sibling collection vexxhost.containers at /home/zuul/src/github.com/vexxhost/ansible-collection-containers",
2026-05-30 05:06:51.988637 | instance |     "Sibling collection vexxhost.kubernetes at /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes",
2026-05-30 05:06:51.988661 | instance |     "Sibling collection atmosphere.common at /home/zuul/src/github.com/vexxhost/atmosphere.common",
2026-05-30 05:06:51.988686 | instance |     "Sibling collection openstack.cloud at /home/zuul/src/opendev.org/openstack/ansible-collections-openstack",
2026-05-30 05:06:51.988709 | instance |     "Resolved ansible.netcommon to v8.5.2 for >=1.2.0",
2026-05-30 05:06:51.988735 | instance |     "Resolved ansible.posix to 1.6.0 for 1.6.0",
2026-05-30 05:06:51.988760 | instance |     "Resolved ansible.utils to v6.0.2 for >=2.9.0,>=3.0.0",
2026-05-30 05:06:51.988785 | instance |     "Resolved community.crypto to 2.2.3 for 2.2.3",
2026-05-30 05:06:51.988828 | instance |     "Resolved community.general to 7.3.0 for 7.3.0",
2026-05-30 05:06:51.988853 | instance |     "Resolved community.mysql to 3.6.0 for 3.6.0",
2026-05-30 05:06:51.988878 | instance |     "Resolved kubernetes.core to 2.4.0 for 2.4.0",
2026-05-30 05:06:51.988901 | instance |     "Resolved openstack.cloud to 1.7.0 for 1.7.0",
2026-05-30 05:06:51.988927 | instance |     "Resolved atmosphere.common to v0.7.0 for >=0.6.0",
2026-05-30 05:06:51.988993 | instance |     "Resolved vexxhost.ceph to v4.0.0 for >=3.1.2",
2026-05-30 05:06:51.989023 | instance |     "Resolved vexxhost.kubernetes to v3.1.0 for >=2.3.2,>=2.5.0",
2026-05-30 05:06:51.989048 | instance |     "Resolved ansible.utils to v6.0.2 for >=2.9.0,>=3.0.0,>=6.0.0",
2026-05-30 05:06:51.989072 | instance |     "Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2"
2026-05-30 05:06:51.989096 | instance |   ],
2026-05-30 05:06:51.989119 | instance |   "msg": "No local release tag for openstack.cloud satisfies 1.7.0,>=2.0.0"
2026-05-30 05:06:51.989143 | instance | }
failure
2026-05-30 05:06:51.993336 | 
2026-05-30 05:06:51.993439 | PLAY RECAP
2026-05-30 05:06:51.993511 | instance | ok: 2 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-05-30 05:06:51.993543 | 
2026-05-30 05:06:52.291447 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-05-30 05:06:52.304394 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-05-30 05:06:53.031133 | 
2026-05-30 05:06:53.031478 | PLAY [all]
2026-05-30 05:06:53.043386 | 
2026-05-30 05:06:53.043466 | TASK [fetch-output : Set log path for multiple nodes]
2026-05-30 05:06:53.089834 | instance | skipping: Conditional result was False
2026-05-30 05:06:53.099462 | 
2026-05-30 05:06:53.099567 | TASK [fetch-output : Set log path for single node]
2026-05-30 05:06:53.165021 | instance | ok
2026-05-30 05:06:53.172129 | 
2026-05-30 05:06:53.172209 | LOOP [fetch-output : Ensure local output dirs]
2026-05-30 05:06:53.656754 | instance -> localhost | ok: "/var/lib/zuul/builds/a77408349f5e463186ccea303e82d3dd/work/logs"
2026-05-30 05:06:53.909729 | instance -> localhost | changed: "/var/lib/zuul/builds/a77408349f5e463186ccea303e82d3dd/work/artifacts"
2026-05-30 05:06:54.135799 | instance -> localhost | changed: "/var/lib/zuul/builds/a77408349f5e463186ccea303e82d3dd/work/docs"
2026-05-30 05:06:54.150207 | 
2026-05-30 05:06:54.150283 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-05-30 05:06:54.774158 | instance | ok
2026-05-30 05:06:54.975671 | instance | changed: All items complete
2026-05-30 05:06:54.975820 | 
2026-05-30 05:06:55.260903 | instance | changed: .d..t...... ./
2026-05-30 05:06:55.813558 | instance | changed: .d..t...... ./
2026-05-30 05:06:55.842767 | 
2026-05-30 05:06:55.842963 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-05-30 05:06:56.379900 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.111950
2026-05-30 05:06:56.602768 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.006725
2026-05-30 05:06:56.621069 | 
2026-05-30 05:06:56.621432 | PLAY [all]
2026-05-30 05:06:56.628107 | 
2026-05-30 05:06:56.628178 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-05-30 05:06:57.099616 | instance | changed
2026-05-30 05:06:57.106908 | 
2026-05-30 05:06:57.106962 | PLAY RECAP
2026-05-30 05:06:57.107010 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-05-30 05:06:57.107199 | 
2026-05-30 05:06:57.328064 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-05-30 05:06:57.339595 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-05-30 05:06:58.000563 | 
2026-05-30 05:06:58.001004 | PLAY [localhost]
2026-05-30 05:06:58.014908 | 
2026-05-30 05:06:58.015024 | TASK [Generate Zuul manifest]
2026-05-30 05:06:58.040275 | localhost | ok
2026-05-30 05:06:58.060154 | 
2026-05-30 05:06:58.060448 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-05-30 05:06:58.474167 | localhost | changed
2026-05-30 05:06:58.486341 | 
2026-05-30 05:06:58.486528 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-05-30 05:06:58.520550 | localhost | ok
2026-05-30 05:06:58.529358 | 
2026-05-30 05:06:58.529443 | TASK [Upload logs]
2026-05-30 05:06:58.552620 | localhost | ok
2026-05-30 05:06:58.664750 | 
2026-05-30 05:06:58.664920 | TASK [Set zuul-log-path fact]
2026-05-30 05:06:58.685411 | localhost | ok
2026-05-30 05:06:58.700633 | 
2026-05-30 05:06:58.700756 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-05-30 05:06:58.735391 | localhost | ok
2026-05-30 05:06:58.742836 | 
2026-05-30 05:06:58.742907 | TASK [upload-logs : Create log directories]
2026-05-30 05:06:59.176093 | localhost | changed
2026-05-30 05:06:59.181244 | 
2026-05-30 05:06:59.181323 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-05-30 05:06:59.690278 | localhost -> localhost | ok: Runtime: 0:00:00.005829
2026-05-30 05:06:59.695782 | 
2026-05-30 05:06:59.695852 | TASK [upload-logs : Upload logs to log server]
2026-05-30 05:07:00.407624 | localhost | Output suppressed because no_log was given
2026-05-30 05:07:00.441351 | 
2026-05-30 05:07:00.442301 | LOOP [upload-logs : Compress console log and json output]
2026-05-30 05:07:00.491187 | localhost | skipping: Conditional result was False
2026-05-30 05:07:00.497660 | localhost | skipping: Conditional result was False
2026-05-30 05:07:00.585633 | 
2026-05-30 05:07:00.585869 | LOOP [upload-logs : Upload compressed console log and json output]
2026-05-30 05:07:00.639978 | localhost | skipping: Conditional result was False
2026-05-30 05:07:00.640278 | 
2026-05-30 05:07:00.646737 | localhost | skipping: Conditional result was False
2026-05-30 05:07:00.657599 | 
2026-05-30 05:07:00.719676 | LOOP [upload-logs : Upload console log and json output]