2026-06-09 22:13:14.520851 | Job console starting 2026-06-09 22:13:14.546130 | Updating git repos 2026-06-09 22:13:18.264331 | Cloning repos into workspace 2026-06-09 22:13:43.755738 | Restoring repo states 2026-06-09 22:13:51.206803 | Merging changes 2026-06-09 22:13:53.533197 | Checking out repos 2026-06-09 22:13:58.017529 | Preparing playbooks 2026-06-09 22:14:03.148855 | Running Ansible setup 2026-06-09 22:14:08.231693 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-06-09 22:14:09.059254 | 2026-06-09 22:14:09.245336 | PLAY [localhost] 2026-06-09 22:14:09.260838 | 2026-06-09 22:14:09.261060 | TASK [Gathering Facts] 2026-06-09 22:14:10.698757 | localhost | ok 2026-06-09 22:14:10.714554 | 2026-06-09 22:14:10.714717 | TASK [Setup log path fact] 2026-06-09 22:14:10.747420 | localhost | ok 2026-06-09 22:14:10.761073 | 2026-06-09 22:14:10.761161 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-06-09 22:14:10.795278 | localhost | ok 2026-06-09 22:14:10.802862 | 2026-06-09 22:14:10.802947 | TASK [emit-job-header : Print job information] 2026-06-09 22:14:10.848626 | # Job Information 2026-06-09 22:14:10.848873 | Ansible Version: 2.16.18 2026-06-09 22:14:10.848932 | Job: atmosphere-molecule-aio-ovn 2026-06-09 22:14:10.849064 | Pipeline: check 2026-06-09 22:14:10.849118 | Executor: 2d72f0692154 2026-06-09 22:14:10.849182 | Triggered by: https://github.com/vexxhost/atmosphere/pull/3985 2026-06-09 22:14:10.849230 | Event ID: 05bd7660-6450-11f1-861b-2fbea32f4542 2026-06-09 22:14:10.855336 | 2026-06-09 22:14:10.855449 | LOOP [emit-job-header : Print node information] 2026-06-09 22:14:10.974178 | localhost | ok: 2026-06-09 22:14:10.974692 | localhost | # Node Information 2026-06-09 22:14:10.974739 | localhost | Inventory Hostname: instance 2026-06-09 22:14:10.974764 | localhost | Hostname: np0000179328 2026-06-09 22:14:10.974808 | localhost | Username: zuul 2026-06-09 22:14:10.974833 | localhost | Distro: Ubuntu 22.04 2026-06-09 22:14:10.974853 | localhost | Provider: yul1 2026-06-09 22:14:10.974873 | localhost | Region: ca-ymq-1 2026-06-09 22:14:10.974891 | localhost | Label: ubuntu-jammy-16 2026-06-09 22:14:10.974909 | localhost | Product Name: OpenStack Nova 2026-06-09 22:14:10.974927 | localhost | Interface IP: 199.204.45.242 2026-06-09 22:14:10.990143 | 2026-06-09 22:14:10.990475 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-06-09 22:14:11.519418 | localhost -> localhost | changed 2026-06-09 22:14:11.531780 | 2026-06-09 22:14:11.532026 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-06-09 22:14:12.758377 | localhost -> localhost | changed 2026-06-09 22:14:12.767062 | 2026-06-09 22:14:12.767163 | PLAY [all] 2026-06-09 22:14:12.775455 | 2026-06-09 22:14:12.775546 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-06-09 22:14:13.048764 | instance -> localhost | ok 2026-06-09 22:14:13.058291 | 2026-06-09 22:14:13.058427 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-06-09 22:14:13.094462 | instance | ok 2026-06-09 22:14:13.111102 | instance | included: /var/lib/zuul/builds/8ee10951b3b343c8a7c6fc8e8b6d1eac/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-06-09 22:14:13.117595 | 2026-06-09 22:14:13.117707 | TASK [add-build-sshkey : Create Temp SSH key] 2026-06-09 22:14:15.236873 | instance -> localhost | Generating public/private rsa key pair. 2026-06-09 22:14:15.237171 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/8ee10951b3b343c8a7c6fc8e8b6d1eac/work/8ee10951b3b343c8a7c6fc8e8b6d1eac_id_rsa 2026-06-09 22:14:15.237228 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/8ee10951b3b343c8a7c6fc8e8b6d1eac/work/8ee10951b3b343c8a7c6fc8e8b6d1eac_id_rsa.pub 2026-06-09 22:14:15.237267 | instance -> localhost | The key fingerprint is: 2026-06-09 22:14:15.237291 | instance -> localhost | SHA256:aO8aX2Zu7DiMeIwf1aMNH3hEb/54blPTghAkkBPdZwU zuul-build-sshkey 2026-06-09 22:14:15.237333 | instance -> localhost | The key's randomart image is: 2026-06-09 22:14:15.237355 | instance -> localhost | +---[RSA 3072]----+ 2026-06-09 22:14:15.237386 | instance -> localhost | | o=.oo Eo. | 2026-06-09 22:14:15.237409 | instance -> localhost | | o .oo.o | 2026-06-09 22:14:15.237431 | instance -> localhost | | . .+o | 2026-06-09 22:14:15.237452 | instance -> localhost | | . +.o | 2026-06-09 22:14:15.237473 | instance -> localhost | | o S =... .| 2026-06-09 22:14:15.237493 | instance -> localhost | | . o * o.o..o| 2026-06-09 22:14:15.237513 | instance -> localhost | | +ooo.* . oo.| 2026-06-09 22:14:15.237534 | instance -> localhost | | o +=o*o oo | 2026-06-09 22:14:15.237558 | instance -> localhost | | oo.++o ... | 2026-06-09 22:14:15.237579 | instance -> localhost | +----[SHA256]-----+ 2026-06-09 22:14:15.237643 | instance -> localhost | ok: Runtime: 0:00:01.586768 2026-06-09 22:14:15.245249 | 2026-06-09 22:14:15.245394 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-06-09 22:14:15.277513 | instance | ok 2026-06-09 22:14:15.287553 | instance | included: /var/lib/zuul/builds/8ee10951b3b343c8a7c6fc8e8b6d1eac/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-06-09 22:14:15.296618 | 2026-06-09 22:14:15.296696 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-06-09 22:14:15.321459 | instance | skipping: Conditional result was False 2026-06-09 22:14:15.332777 | 2026-06-09 22:14:15.332944 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-06-09 22:14:15.862188 | instance | changed 2026-06-09 22:14:15.871829 | 2026-06-09 22:14:15.871970 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-06-09 22:14:16.123742 | instance | ok 2026-06-09 22:14:16.343397 | 2026-06-09 22:14:16.343562 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-06-09 22:14:16.840987 | instance | changed 2026-06-09 22:14:17.069071 | 2026-06-09 22:14:17.069263 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-06-09 22:14:17.557340 | instance | changed 2026-06-09 22:14:17.563907 | 2026-06-09 22:14:17.564078 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-06-09 22:14:17.592060 | instance | skipping: Conditional result was False 2026-06-09 22:14:17.604099 | 2026-06-09 22:14:17.604276 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-06-09 22:14:18.007405 | instance -> localhost | changed 2026-06-09 22:14:18.019214 | 2026-06-09 22:14:18.019385 | TASK [add-build-sshkey : Add back temp key] 2026-06-09 22:14:18.357598 | instance -> localhost | Identity added: /var/lib/zuul/builds/8ee10951b3b343c8a7c6fc8e8b6d1eac/work/8ee10951b3b343c8a7c6fc8e8b6d1eac_id_rsa (zuul-build-sshkey) 2026-06-09 22:14:18.635952 | instance -> localhost | ok: Runtime: 0:00:00.015950 2026-06-09 22:14:18.646061 | 2026-06-09 22:14:18.646176 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-06-09 22:14:18.926307 | instance | ok 2026-06-09 22:14:18.933548 | 2026-06-09 22:14:18.933640 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-06-09 22:14:18.959194 | instance | skipping: Conditional result was False 2026-06-09 22:14:18.974037 | 2026-06-09 22:14:18.974197 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-06-09 22:14:19.271852 | instance | ok 2026-06-09 22:14:19.281177 | 2026-06-09 22:14:19.281306 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-06-09 22:14:23.186268 | instance | Output suppressed because no_log was given 2026-06-09 22:14:23.198811 | 2026-06-09 22:14:23.198888 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-06-09 22:14:23.404115 | instance | ok: "logs" 2026-06-09 22:14:23.404445 | instance | ok: All items complete 2026-06-09 22:14:23.404492 | 2026-06-09 22:14:23.532171 | instance | ok: "artifacts" 2026-06-09 22:14:23.703949 | instance | ok: "docs" 2026-06-09 22:14:23.729064 | 2026-06-09 22:14:23.729319 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-06-09 22:14:23.918539 | instance | changed: "logs" 2026-06-09 22:14:24.077427 | instance | changed: "artifacts" 2026-06-09 22:14:24.236460 | instance | changed: "docs" 2026-06-09 22:14:24.411940 | 2026-06-09 22:14:24.412058 | PLAY RECAP 2026-06-09 22:14:24.412108 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-06-09 22:14:24.412139 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-06-09 22:14:24.412163 | 2026-06-09 22:14:24.613696 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-06-09 22:14:24.668300 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-06-09 22:14:25.445564 | 2026-06-09 22:14:25.476630 | PLAY [all] 2026-06-09 22:14:25.498169 | 2026-06-09 22:14:25.498336 | TASK [setup-uv : Extract archive] 2026-06-09 22:14:27.987472 | instance | changed 2026-06-09 22:14:28.034026 | 2026-06-09 22:14:28.035839 | TASK [setup-uv : Print version] 2026-06-09 22:14:28.414642 | instance | uv 0.8.13 2026-06-09 22:14:28.578243 | instance | ok: Runtime: 0:00:00.011440 2026-06-09 22:14:28.590902 | 2026-06-09 22:14:28.591018 | TASK [Install Ansible collection siblings] 2026-06-09 22:14:29.210028 | instance | ERROR 2026-06-09 22:14:29.210423 | instance | { 2026-06-09 22:14:29.210463 | instance | "log": [ 2026-06-09 22:14:29.210488 | instance | "Sibling collection ansible.netcommon at /home/zuul/src/github.com/ansible-collections/ansible.netcommon", 2026-06-09 22:14:29.210515 | instance | "Sibling collection ansible.posix at /home/zuul/src/github.com/ansible-collections/ansible.posix", 2026-06-09 22:14:29.210536 | instance | "Sibling collection ansible.utils at /home/zuul/src/github.com/ansible-collections/ansible.utils", 2026-06-09 22:14:29.210561 | instance | "Sibling collection community.crypto at /home/zuul/src/github.com/ansible-collections/community.crypto", 2026-06-09 22:14:29.210582 | instance | "Sibling collection community.general at /home/zuul/src/github.com/ansible-collections/community.general", 2026-06-09 22:14:29.210605 | instance | "Sibling collection community.mysql at /home/zuul/src/github.com/ansible-collections/community.mysql", 2026-06-09 22:14:29.210626 | instance | "Sibling collection kubernetes.core at /home/zuul/src/github.com/ansible-collections/kubernetes.core", 2026-06-09 22:14:29.210644 | instance | "Sibling collection vexxhost.ceph at /home/zuul/src/github.com/vexxhost/ansible-collection-ceph", 2026-06-09 22:14:29.210665 | instance | "Sibling collection vexxhost.containers at /home/zuul/src/github.com/vexxhost/ansible-collection-containers", 2026-06-09 22:14:29.210685 | instance | "Sibling collection vexxhost.kubernetes at /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes", 2026-06-09 22:14:29.210705 | instance | "Sibling collection atmosphere.common at /home/zuul/src/github.com/vexxhost/atmosphere.common", 2026-06-09 22:14:29.210724 | instance | "Sibling collection openstack.cloud at /home/zuul/src/opendev.org/openstack/ansible-collections-openstack", 2026-06-09 22:14:29.210743 | instance | "Resolved ansible.netcommon to v8.5.2 for >=1.2.0", 2026-06-09 22:14:29.210763 | instance | "Resolved ansible.posix to 1.6.0 for 1.6.0", 2026-06-09 22:14:29.210782 | instance | "Resolved ansible.utils to v6.0.2 for >=2.9.0,>=3.0.0", 2026-06-09 22:14:29.210801 | instance | "Resolved community.crypto to 2.2.3 for 2.2.3", 2026-06-09 22:14:29.210820 | instance | "Resolved community.general to 7.3.0 for 7.3.0", 2026-06-09 22:14:29.210840 | instance | "Resolved community.mysql to 3.6.0 for 3.6.0", 2026-06-09 22:14:29.210858 | instance | "Resolved kubernetes.core to 2.4.0 for 2.4.0", 2026-06-09 22:14:29.210877 | instance | "Resolved openstack.cloud to 1.7.0 for 1.7.0", 2026-06-09 22:14:29.210898 | instance | "Resolved atmosphere.common to v0.7.0 for >=0.6.0", 2026-06-09 22:14:29.210933 | instance | "Resolved vexxhost.ceph to v4.0.0 for >=3.1.2", 2026-06-09 22:14:29.210954 | instance | "Resolved vexxhost.kubernetes to v3.2.0 for >=2.3.2,>=2.5.0", 2026-06-09 22:14:29.210973 | instance | "Resolved ansible.utils to v6.0.2 for >=2.9.0,>=3.0.0,>=6.0.0", 2026-06-09 22:14:29.210992 | instance | "Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2" 2026-06-09 22:14:29.211010 | instance | ], 2026-06-09 22:14:29.211030 | instance | "msg": "No local release tag for openstack.cloud satisfies 1.7.0,>=2.0.0" 2026-06-09 22:14:29.211049 | instance | } failure 2026-06-09 22:14:29.214660 | 2026-06-09 22:14:29.214716 | PLAY RECAP 2026-06-09 22:14:29.214769 | instance | ok: 2 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2026-06-09 22:14:29.214793 | 2026-06-09 22:14:29.400584 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-06-09 22:14:29.413056 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-06-09 22:14:30.234934 | 2026-06-09 22:14:30.235098 | PLAY [all] 2026-06-09 22:14:30.251365 | 2026-06-09 22:14:30.251545 | TASK [fetch-output : Set log path for multiple nodes] 2026-06-09 22:14:30.298190 | instance | skipping: Conditional result was False 2026-06-09 22:14:30.309355 | 2026-06-09 22:14:30.309553 | TASK [fetch-output : Set log path for single node] 2026-06-09 22:14:30.354402 | instance | ok 2026-06-09 22:14:30.359241 | 2026-06-09 22:14:30.359313 | LOOP [fetch-output : Ensure local output dirs] 2026-06-09 22:14:30.926330 | instance -> localhost | ok: "/var/lib/zuul/builds/8ee10951b3b343c8a7c6fc8e8b6d1eac/work/logs" 2026-06-09 22:14:31.175375 | instance -> localhost | changed: "/var/lib/zuul/builds/8ee10951b3b343c8a7c6fc8e8b6d1eac/work/artifacts" 2026-06-09 22:14:31.426396 | instance -> localhost | changed: "/var/lib/zuul/builds/8ee10951b3b343c8a7c6fc8e8b6d1eac/work/docs" 2026-06-09 22:14:31.442568 | 2026-06-09 22:14:31.442767 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-06-09 22:14:32.116698 | instance | changed: .d..t...... ./ 2026-06-09 22:14:32.117027 | instance | changed: All items complete 2026-06-09 22:14:32.117073 | 2026-06-09 22:14:32.616081 | instance | changed: .d..t...... ./ 2026-06-09 22:14:33.289463 | instance | changed: .d..t...... ./ 2026-06-09 22:14:33.308478 | 2026-06-09 22:14:33.308622 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-06-09 22:14:33.871663 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.011142 2026-06-09 22:14:34.132599 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.008450 2026-06-09 22:14:34.153243 | 2026-06-09 22:14:34.153388 | PLAY [all] 2026-06-09 22:14:34.160583 | 2026-06-09 22:14:34.160688 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-06-09 22:14:34.642977 | instance | changed 2026-06-09 22:14:34.659498 | 2026-06-09 22:14:34.659673 | PLAY RECAP 2026-06-09 22:14:34.659746 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-06-09 22:14:34.659782 | 2026-06-09 22:14:34.920120 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-06-09 22:14:34.936838 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-06-09 22:14:35.717539 | 2026-06-09 22:14:35.717715 | PLAY [localhost] 2026-06-09 22:14:35.728694 | 2026-06-09 22:14:35.728782 | TASK [Generate Zuul manifest] 2026-06-09 22:14:35.748302 | localhost | ok 2026-06-09 22:14:35.763895 | 2026-06-09 22:14:35.763980 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-06-09 22:14:36.282156 | localhost | changed 2026-06-09 22:14:36.296793 | 2026-06-09 22:14:36.297016 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-06-09 22:14:36.491432 | localhost | ok 2026-06-09 22:14:36.747289 | 2026-06-09 22:14:36.747491 | TASK [Upload logs] 2026-06-09 22:14:36.769338 | localhost | ok 2026-06-09 22:14:36.923440 | 2026-06-09 22:14:36.923726 | TASK [Set zuul-log-path fact] 2026-06-09 22:14:36.947170 | localhost | ok 2026-06-09 22:14:36.970279 | 2026-06-09 22:14:36.970516 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-06-09 22:14:37.010279 | localhost | ok 2026-06-09 22:14:37.019583 | 2026-06-09 22:14:37.019663 | TASK [upload-logs : Create log directories] 2026-06-09 22:14:38.015408 | localhost | changed 2026-06-09 22:14:38.022130 | 2026-06-09 22:14:38.022237 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-06-09 22:14:38.722877 | localhost -> localhost | ok: Runtime: 0:00:00.006461 2026-06-09 22:14:38.730306 | 2026-06-09 22:14:38.730398 | TASK [upload-logs : Upload logs to log server] 2026-06-09 22:14:39.287665 | localhost | Output suppressed because no_log was given 2026-06-09 22:14:39.292838 | 2026-06-09 22:14:39.292924 | LOOP [upload-logs : Compress console log and json output] 2026-06-09 22:14:39.364585 | localhost | skipping: Conditional result was False 2026-06-09 22:14:39.380540 | localhost | skipping: Conditional result was False 2026-06-09 22:14:39.405265 | 2026-06-09 22:14:39.405446 | LOOP [upload-logs : Upload compressed console log and json output] 2026-06-09 22:14:39.453830 | localhost | skipping: Conditional result was False 2026-06-09 22:14:39.454308 | 2026-06-09 22:14:39.458205 | localhost | skipping: Conditional result was False 2026-06-09 22:14:39.472203 | 2026-06-09 22:14:39.472404 | LOOP [upload-logs : Upload console log and json output]