2026-05-29 02:03:25.691347 | Job console starting 2026-05-29 02:03:25.700621 | Updating git repos 2026-05-29 02:03:27.597932 | Cloning repos into workspace 2026-05-29 02:03:41.704084 | Restoring repo states 2026-05-29 02:03:46.771308 | Merging changes 2026-05-29 02:03:48.411066 | Checking out repos 2026-05-29 02:03:51.403347 | Preparing playbooks 2026-05-29 02:03:57.619200 | Running Ansible setup 2026-05-29 02:04:01.938379 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-05-29 02:04:02.574524 | 2026-05-29 02:04:02.574641 | PLAY [localhost] 2026-05-29 02:04:02.581832 | 2026-05-29 02:04:02.581901 | TASK [Gathering Facts] 2026-05-29 02:04:03.493648 | localhost | ok 2026-05-29 02:04:03.501647 | 2026-05-29 02:04:03.501721 | TASK [Setup log path fact] 2026-05-29 02:04:03.525712 | localhost | ok 2026-05-29 02:04:03.540488 | 2026-05-29 02:04:03.540557 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-29 02:04:03.572589 | localhost | ok 2026-05-29 02:04:03.580590 | 2026-05-29 02:04:03.580654 | TASK [emit-job-header : Print job information] 2026-05-29 02:04:03.625227 | # Job Information 2026-05-29 02:04:03.625388 | Ansible Version: 2.16.18 2026-05-29 02:04:03.625420 | Job: atmosphere-molecule-aio-ovn 2026-05-29 02:04:03.625442 | Pipeline: check 2026-05-29 02:04:03.625462 | Executor: 2d72f0692154 2026-05-29 02:04:03.625481 | Triggered by: https://github.com/vexxhost/atmosphere/pull/3991 2026-05-29 02:04:03.625508 | Event ID: fa808e60-5b01-11f1-865d-24a5b51dd999 2026-05-29 02:04:03.629601 | 2026-05-29 02:04:03.629674 | LOOP [emit-job-header : Print node information] 2026-05-29 02:04:03.723220 | localhost | ok: 2026-05-29 02:04:03.723439 | localhost | # Node Information 2026-05-29 02:04:03.723480 | localhost | Inventory Hostname: instance 2026-05-29 02:04:03.723513 | localhost | Hostname: np0000177431 2026-05-29 02:04:03.723544 | localhost | Username: zuul 2026-05-29 02:04:03.723578 | localhost | Distro: Ubuntu 22.04 2026-05-29 02:04:03.723608 | localhost | Provider: yul1 2026-05-29 02:04:03.723637 | localhost | Region: ca-ymq-1 2026-05-29 02:04:03.723665 | localhost | Label: ubuntu-jammy-16 2026-05-29 02:04:03.723693 | localhost | Product Name: OpenStack Nova 2026-05-29 02:04:03.723721 | localhost | Interface IP: 199.204.45.129 2026-05-29 02:04:03.737337 | 2026-05-29 02:04:03.737490 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-05-29 02:04:04.149490 | localhost -> localhost | changed 2026-05-29 02:04:04.175990 | 2026-05-29 02:04:04.176083 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-05-29 02:04:05.043608 | localhost -> localhost | changed 2026-05-29 02:04:05.051636 | 2026-05-29 02:04:05.051709 | PLAY [all] 2026-05-29 02:04:05.060592 | 2026-05-29 02:04:05.060662 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-05-29 02:04:05.510543 | instance -> localhost | ok 2026-05-29 02:04:05.538840 | 2026-05-29 02:04:05.538914 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-05-29 02:04:05.568266 | instance | ok 2026-05-29 02:04:05.581189 | instance | included: /var/lib/zuul/builds/ee32eaa62f684031a30e412b01977523/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-05-29 02:04:05.586823 | 2026-05-29 02:04:05.586885 | TASK [add-build-sshkey : Create Temp SSH key] 2026-05-29 02:04:06.327614 | instance -> localhost | Generating public/private rsa key pair. 2026-05-29 02:04:06.327814 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/ee32eaa62f684031a30e412b01977523/work/ee32eaa62f684031a30e412b01977523_id_rsa 2026-05-29 02:04:06.327855 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/ee32eaa62f684031a30e412b01977523/work/ee32eaa62f684031a30e412b01977523_id_rsa.pub 2026-05-29 02:04:06.327886 | instance -> localhost | The key fingerprint is: 2026-05-29 02:04:06.327915 | instance -> localhost | SHA256:Og6EFIPx6PgKMmWKyvZLi/whO2yeYOvsFJQZzh5qb2E zuul-build-sshkey 2026-05-29 02:04:06.327995 | instance -> localhost | The key's randomart image is: 2026-05-29 02:04:06.328035 | instance -> localhost | +---[RSA 3072]----+ 2026-05-29 02:04:06.328070 | instance -> localhost | |.+o | 2026-05-29 02:04:06.328102 | instance -> localhost | |+o+o | 2026-05-29 02:04:06.328130 | instance -> localhost | |.Bo | 2026-05-29 02:04:06.328157 | instance -> localhost | |*... | 2026-05-29 02:04:06.328184 | instance -> localhost | |o=+E. S | 2026-05-29 02:04:06.328211 | instance -> localhost | |o=+.. . | 2026-05-29 02:04:06.328237 | instance -> localhost | |O+.=. o | 2026-05-29 02:04:06.328264 | instance -> localhost | |@BO oo . | 2026-05-29 02:04:06.328294 | instance -> localhost | |O%+=. . | 2026-05-29 02:04:06.328321 | instance -> localhost | +----[SHA256]-----+ 2026-05-29 02:04:06.328392 | instance -> localhost | ok: Runtime: 0:00:00.327103 2026-05-29 02:04:06.337584 | 2026-05-29 02:04:06.337674 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-05-29 02:04:06.371524 | instance | ok 2026-05-29 02:04:06.383212 | instance | included: /var/lib/zuul/builds/ee32eaa62f684031a30e412b01977523/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-05-29 02:04:06.391101 | 2026-05-29 02:04:06.391187 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-05-29 02:04:06.416717 | instance | skipping: Conditional result was False 2026-05-29 02:04:06.428324 | 2026-05-29 02:04:06.428462 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-05-29 02:04:06.869208 | instance | changed 2026-05-29 02:04:06.876200 | 2026-05-29 02:04:06.876266 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-05-29 02:04:07.087430 | instance | ok 2026-05-29 02:04:07.092369 | 2026-05-29 02:04:07.092434 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-05-29 02:04:07.556157 | instance | changed 2026-05-29 02:04:07.563358 | 2026-05-29 02:04:07.563445 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-05-29 02:04:08.015977 | instance | changed 2026-05-29 02:04:08.023896 | 2026-05-29 02:04:08.023989 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-05-29 02:04:08.050134 | instance | skipping: Conditional result was False 2026-05-29 02:04:08.064280 | 2026-05-29 02:04:08.064447 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-05-29 02:04:08.373328 | instance -> localhost | changed 2026-05-29 02:04:08.390022 | 2026-05-29 02:04:08.390132 | TASK [add-build-sshkey : Add back temp key] 2026-05-29 02:04:08.679767 | instance -> localhost | Identity added: /var/lib/zuul/builds/ee32eaa62f684031a30e412b01977523/work/ee32eaa62f684031a30e412b01977523_id_rsa (zuul-build-sshkey) 2026-05-29 02:04:08.679978 | instance -> localhost | ok: Runtime: 0:00:00.013454 2026-05-29 02:04:08.701902 | 2026-05-29 02:04:08.701980 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-05-29 02:04:08.963596 | instance | ok 2026-05-29 02:04:08.969890 | 2026-05-29 02:04:08.969954 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-05-29 02:04:08.994362 | instance | skipping: Conditional result was False 2026-05-29 02:04:09.008305 | 2026-05-29 02:04:09.008368 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-05-29 02:04:09.290210 | instance | ok 2026-05-29 02:04:09.295939 | 2026-05-29 02:04:09.296002 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-05-29 02:04:12.447625 | instance | Output suppressed because no_log was given 2026-05-29 02:04:12.459434 | 2026-05-29 02:04:12.459503 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-05-29 02:04:12.646492 | instance | ok: "logs" 2026-05-29 02:04:12.646727 | instance | ok: All items complete 2026-05-29 02:04:12.646756 | 2026-05-29 02:04:12.797083 | instance | ok: "artifacts" 2026-05-29 02:04:12.956698 | instance | ok: "docs" 2026-05-29 02:04:12.978822 | 2026-05-29 02:04:12.978954 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-05-29 02:04:13.179786 | instance | changed: "logs" 2026-05-29 02:04:13.333010 | instance | changed: "artifacts" 2026-05-29 02:04:13.498981 | instance | changed: "docs" 2026-05-29 02:04:13.537175 | 2026-05-29 02:04:13.537325 | PLAY RECAP 2026-05-29 02:04:13.537384 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-05-29 02:04:13.537413 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-05-29 02:04:13.537436 | 2026-05-29 02:04:13.664407 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-05-29 02:04:13.675701 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-05-29 02:04:14.262561 | 2026-05-29 02:04:14.263089 | PLAY [all] 2026-05-29 02:04:14.273913 | 2026-05-29 02:04:14.273986 | TASK [setup-uv : Extract archive] 2026-05-29 02:04:16.800887 | instance | changed 2026-05-29 02:04:16.807209 | 2026-05-29 02:04:16.807309 | TASK [setup-uv : Print version] 2026-05-29 02:04:17.164363 | instance | uv 0.8.13 2026-05-29 02:04:17.347601 | instance | ok: Runtime: 0:00:00.008609 2026-05-29 02:04:17.358994 | 2026-05-29 02:04:17.359058 | TASK [Install Ansible collection siblings] 2026-05-29 02:04:18.002211 | instance | ERROR 2026-05-29 02:04:18.002578 | instance | { 2026-05-29 02:04:18.002634 | instance | "log": [ 2026-05-29 02:04:18.002666 | instance | "Sibling collection ansible.netcommon at /home/zuul/src/github.com/ansible-collections/ansible.netcommon", 2026-05-29 02:04:18.002700 | instance | "Sibling collection ansible.posix at /home/zuul/src/github.com/ansible-collections/ansible.posix", 2026-05-29 02:04:18.002729 | instance | "Sibling collection ansible.utils at /home/zuul/src/github.com/ansible-collections/ansible.utils", 2026-05-29 02:04:18.002759 | instance | "Sibling collection community.crypto at /home/zuul/src/github.com/ansible-collections/community.crypto", 2026-05-29 02:04:18.002785 | instance | "Sibling collection community.general at /home/zuul/src/github.com/ansible-collections/community.general", 2026-05-29 02:04:18.002813 | instance | "Sibling collection community.mysql at /home/zuul/src/github.com/ansible-collections/community.mysql", 2026-05-29 02:04:18.002840 | instance | "Sibling collection kubernetes.core at /home/zuul/src/github.com/ansible-collections/kubernetes.core", 2026-05-29 02:04:18.002865 | instance | "Sibling collection vexxhost.ceph at /home/zuul/src/github.com/vexxhost/ansible-collection-ceph", 2026-05-29 02:04:18.002891 | instance | "Sibling collection vexxhost.containers at /home/zuul/src/github.com/vexxhost/ansible-collection-containers", 2026-05-29 02:04:18.002915 | instance | "Sibling collection vexxhost.kubernetes at /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes", 2026-05-29 02:04:18.002940 | instance | "Sibling collection atmosphere.common at /home/zuul/src/github.com/vexxhost/atmosphere.common", 2026-05-29 02:04:18.002965 | instance | "Sibling collection openstack.cloud at /home/zuul/src/opendev.org/openstack/ansible-collections-openstack", 2026-05-29 02:04:18.002989 | instance | "Resolved ansible.netcommon to v8.5.2 for >=1.2.0", 2026-05-29 02:04:18.003015 | instance | "Resolved ansible.posix to 1.6.0 for 1.6.0", 2026-05-29 02:04:18.003040 | instance | "Resolved ansible.utils to v6.0.2 for >=2.9.0,>=3.0.0", 2026-05-29 02:04:18.003065 | instance | "Resolved community.crypto to 2.2.3 for 2.2.3", 2026-05-29 02:04:18.003090 | instance | "Resolved community.general to 7.3.0 for 7.3.0", 2026-05-29 02:04:18.003115 | instance | "Resolved community.mysql to 3.6.0 for 3.6.0", 2026-05-29 02:04:18.003139 | instance | "Resolved kubernetes.core to 2.4.0 for 2.4.0", 2026-05-29 02:04:18.003163 | instance | "Resolved openstack.cloud to 1.7.0 for 1.7.0", 2026-05-29 02:04:18.003189 | instance | "Resolved atmosphere.common to v0.7.0 for >=0.6.0", 2026-05-29 02:04:18.003229 | instance | "Resolved vexxhost.ceph to v4.0.0 for >=3.1.2", 2026-05-29 02:04:18.003256 | instance | "Resolved vexxhost.kubernetes to v3.1.0 for >=2.3.2,>=2.5.0", 2026-05-29 02:04:18.003281 | instance | "Resolved ansible.utils to v6.0.2 for >=2.9.0,>=3.0.0,>=6.0.0", 2026-05-29 02:04:18.003305 | instance | "Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2" 2026-05-29 02:04:18.003329 | instance | ], 2026-05-29 02:04:18.003353 | instance | "msg": "No local release tag for openstack.cloud satisfies 1.7.0,>=2.0.0" 2026-05-29 02:04:18.003378 | instance | } failure 2026-05-29 02:04:18.008052 | 2026-05-29 02:04:18.008101 | PLAY RECAP 2026-05-29 02:04:18.008148 | instance | ok: 2 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2026-05-29 02:04:18.008169 | 2026-05-29 02:04:18.150732 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-05-29 02:04:18.163830 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-05-29 02:04:18.878436 | 2026-05-29 02:04:18.878621 | PLAY [all] 2026-05-29 02:04:18.890870 | 2026-05-29 02:04:18.890953 | TASK [fetch-output : Set log path for multiple nodes] 2026-05-29 02:04:18.937090 | instance | skipping: Conditional result was False 2026-05-29 02:04:18.947901 | 2026-05-29 02:04:18.948014 | TASK [fetch-output : Set log path for single node] 2026-05-29 02:04:18.990104 | instance | ok 2026-05-29 02:04:18.997109 | 2026-05-29 02:04:18.997220 | LOOP [fetch-output : Ensure local output dirs] 2026-05-29 02:04:19.455890 | instance -> localhost | ok: "/var/lib/zuul/builds/ee32eaa62f684031a30e412b01977523/work/logs" 2026-05-29 02:04:19.688270 | instance -> localhost | changed: "/var/lib/zuul/builds/ee32eaa62f684031a30e412b01977523/work/artifacts" 2026-05-29 02:04:19.928382 | instance -> localhost | changed: "/var/lib/zuul/builds/ee32eaa62f684031a30e412b01977523/work/docs" 2026-05-29 02:04:19.946592 | 2026-05-29 02:04:19.946788 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-05-29 02:04:20.607238 | instance | ok 2026-05-29 02:04:20.607508 | instance | changed: All items complete 2026-05-29 02:04:20.607547 | 2026-05-29 02:04:21.071143 | instance | changed: .d..t...... ./ 2026-05-29 02:04:21.520167 | instance | changed: .d..t...... ./ 2026-05-29 02:04:21.540259 | 2026-05-29 02:04:21.540534 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-05-29 02:04:22.109230 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.008483 2026-05-29 02:04:22.362428 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.009209 2026-05-29 02:04:22.381340 | 2026-05-29 02:04:22.381490 | PLAY [all] 2026-05-29 02:04:22.396632 | 2026-05-29 02:04:22.396854 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-05-29 02:04:22.842529 | instance | changed 2026-05-29 02:04:22.853243 | 2026-05-29 02:04:22.853296 | PLAY RECAP 2026-05-29 02:04:22.853341 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-05-29 02:04:22.853363 | 2026-05-29 02:04:22.978745 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-05-29 02:04:22.991619 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-05-29 02:04:23.627574 | 2026-05-29 02:04:23.627718 | PLAY [localhost] 2026-05-29 02:04:23.638305 | 2026-05-29 02:04:23.638378 | TASK [Generate Zuul manifest] 2026-05-29 02:04:23.659475 | localhost | ok 2026-05-29 02:04:23.678374 | 2026-05-29 02:04:23.678464 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-05-29 02:04:24.044453 | localhost | changed 2026-05-29 02:04:24.057502 | 2026-05-29 02:04:24.057648 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-05-29 02:04:24.090086 | localhost | ok 2026-05-29 02:04:24.100282 | 2026-05-29 02:04:24.100428 | TASK [Upload logs] 2026-05-29 02:04:24.122819 | localhost | ok 2026-05-29 02:04:24.280379 | 2026-05-29 02:04:24.280556 | TASK [Set zuul-log-path fact] 2026-05-29 02:04:24.303175 | localhost | ok 2026-05-29 02:04:24.318802 | 2026-05-29 02:04:24.318869 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-29 02:04:24.351196 | localhost | ok 2026-05-29 02:04:24.360307 | 2026-05-29 02:04:24.360393 | TASK [upload-logs : Create log directories] 2026-05-29 02:04:24.724557 | localhost | changed 2026-05-29 02:04:24.729747 | 2026-05-29 02:04:24.729829 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-05-29 02:04:25.103882 | localhost -> localhost | ok: Runtime: 0:00:00.005353 2026-05-29 02:04:25.109330 | 2026-05-29 02:04:25.109399 | TASK [upload-logs : Upload logs to log server] 2026-05-29 02:04:25.538147 | localhost | Output suppressed because no_log was given 2026-05-29 02:04:25.543114 | 2026-05-29 02:04:25.543182 | LOOP [upload-logs : Compress console log and json output] 2026-05-29 02:04:25.592156 | localhost | skipping: Conditional result was False 2026-05-29 02:04:25.598586 | localhost | skipping: Conditional result was False 2026-05-29 02:04:25.612649 | 2026-05-29 02:04:25.612865 | LOOP [upload-logs : Upload compressed console log and json output] 2026-05-29 02:04:25.656025 | localhost | skipping: Conditional result was False 2026-05-29 02:04:25.656338 | 2026-05-29 02:04:25.659760 | localhost | skipping: Conditional result was False 2026-05-29 02:04:25.674354 | 2026-05-29 02:04:25.674542 | LOOP [upload-logs : Upload console log and json output]