2026-06-12 01:16:05.320529 | Job console starting 2026-06-12 01:16:05.331242 | Updating git repos 2026-06-12 01:16:05.824593 | Cloning repos into workspace 2026-06-12 01:16:18.872839 | Restoring repo states 2026-06-12 01:16:29.561059 | Merging changes 2026-06-12 01:16:30.432255 | Checking out repos 2026-06-12 01:16:31.828800 | Preparing playbooks 2026-06-12 01:16:38.152557 | Running Ansible setup 2026-06-12 01:16:41.984026 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-06-12 01:16:42.595961 | 2026-06-12 01:16:42.699151 | PLAY [localhost] 2026-06-12 01:16:42.712291 | 2026-06-12 01:16:42.712393 | TASK [Gathering Facts] 2026-06-12 01:16:44.025375 | localhost | ok 2026-06-12 01:16:44.036566 | 2026-06-12 01:16:44.036647 | TASK [Setup log path fact] 2026-06-12 01:16:44.056175 | localhost | ok 2026-06-12 01:16:44.070071 | 2026-06-12 01:16:44.070144 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-06-12 01:16:44.098803 | localhost | ok 2026-06-12 01:16:44.106666 | 2026-06-12 01:16:44.106735 | TASK [emit-job-header : Print job information] 2026-06-12 01:16:44.148095 | # Job Information 2026-06-12 01:16:44.148379 | Ansible Version: 2.16.18 2026-06-12 01:16:44.148463 | Job: atmosphere-molecule-aio-ovn 2026-06-12 01:16:44.148519 | Pipeline: check 2026-06-12 01:16:44.148569 | Executor: 2d72f0692154 2026-06-12 01:16:44.148620 | Triggered by: https://github.com/vexxhost/atmosphere/pull/3985 2026-06-12 01:16:44.148676 | Event ID: 9ab5d300-65fb-11f1-875d-8f5e9ed90de6 2026-06-12 01:16:44.154264 | 2026-06-12 01:16:44.154339 | LOOP [emit-job-header : Print node information] 2026-06-12 01:16:44.247192 | localhost | ok: 2026-06-12 01:16:44.247422 | localhost | # Node Information 2026-06-12 01:16:44.247461 | localhost | Inventory Hostname: instance 2026-06-12 01:16:44.247491 | localhost | Hostname: np0000179753 2026-06-12 01:16:44.247520 | localhost | Username: zuul 2026-06-12 01:16:44.247552 | localhost | Distro: Ubuntu 22.04 2026-06-12 01:16:44.247580 | localhost | Provider: yul1 2026-06-12 01:16:44.247606 | localhost | Region: ca-ymq-1 2026-06-12 01:16:44.247631 | localhost | Label: ubuntu-jammy-16 2026-06-12 01:16:44.247656 | localhost | Product Name: OpenStack Nova 2026-06-12 01:16:44.247681 | localhost | Interface IP: 199.204.45.148 2026-06-12 01:16:44.262771 | 2026-06-12 01:16:44.262937 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-06-12 01:16:44.692390 | localhost -> localhost | changed 2026-06-12 01:16:44.701563 | 2026-06-12 01:16:44.701670 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-06-12 01:16:45.729071 | localhost -> localhost | changed 2026-06-12 01:16:45.739692 | 2026-06-12 01:16:45.739770 | PLAY [all] 2026-06-12 01:16:45.748553 | 2026-06-12 01:16:45.748625 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-06-12 01:16:45.985985 | instance -> localhost | ok 2026-06-12 01:16:45.998163 | 2026-06-12 01:16:45.998252 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-06-12 01:16:46.032944 | instance | ok 2026-06-12 01:16:46.048316 | instance | included: /var/lib/zuul/builds/7c7d8bc0724b41d6a715182a55fa3765/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-06-12 01:16:46.053811 | 2026-06-12 01:16:46.053871 | TASK [add-build-sshkey : Create Temp SSH key] 2026-06-12 01:16:46.759703 | instance -> localhost | Generating public/private rsa key pair. 2026-06-12 01:16:46.760036 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/7c7d8bc0724b41d6a715182a55fa3765/work/7c7d8bc0724b41d6a715182a55fa3765_id_rsa 2026-06-12 01:16:46.760104 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/7c7d8bc0724b41d6a715182a55fa3765/work/7c7d8bc0724b41d6a715182a55fa3765_id_rsa.pub 2026-06-12 01:16:46.760156 | instance -> localhost | The key fingerprint is: 2026-06-12 01:16:46.760202 | instance -> localhost | SHA256:cutxIq2KKop/YAxVUvBwHOIFp6aNyBxK6qBzQ+Lbn08 zuul-build-sshkey 2026-06-12 01:16:46.760271 | instance -> localhost | The key's randomart image is: 2026-06-12 01:16:46.760319 | instance -> localhost | +---[RSA 3072]----+ 2026-06-12 01:16:46.760374 | instance -> localhost | | **B. | 2026-06-12 01:16:46.760423 | instance -> localhost | | ..X. | 2026-06-12 01:16:46.760469 | instance -> localhost | | ++ . | 2026-06-12 01:16:46.760514 | instance -> localhost | |O=. | 2026-06-12 01:16:46.760559 | instance -> localhost | |O=o . S | 2026-06-12 01:16:46.760604 | instance -> localhost | |=o+ + . | 2026-06-12 01:16:46.760648 | instance -> localhost | |o+o. .E= . | 2026-06-12 01:16:46.760693 | instance -> localhost | |oooo. o+ + | 2026-06-12 01:16:46.760743 | instance -> localhost | |*+ooo+o.. | 2026-06-12 01:16:46.760789 | instance -> localhost | +----[SHA256]-----+ 2026-06-12 01:16:46.760884 | instance -> localhost | ok: Runtime: 0:00:00.305379 2026-06-12 01:16:46.766979 | 2026-06-12 01:16:46.767047 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-06-12 01:16:46.800803 | instance | ok 2026-06-12 01:16:46.811138 | instance | included: /var/lib/zuul/builds/7c7d8bc0724b41d6a715182a55fa3765/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-06-12 01:16:46.818310 | 2026-06-12 01:16:46.818369 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-06-12 01:16:46.842283 | instance | skipping: Conditional result was False 2026-06-12 01:16:46.851864 | 2026-06-12 01:16:46.851953 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-06-12 01:16:47.269805 | instance | changed 2026-06-12 01:16:47.278318 | 2026-06-12 01:16:47.278408 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-06-12 01:16:47.492946 | instance | ok 2026-06-12 01:16:47.497920 | 2026-06-12 01:16:47.497981 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-06-12 01:16:47.969732 | instance | changed 2026-06-12 01:16:47.974847 | 2026-06-12 01:16:47.974907 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-06-12 01:16:48.435003 | instance | changed 2026-06-12 01:16:48.440224 | 2026-06-12 01:16:48.440290 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-06-12 01:16:48.465108 | instance | skipping: Conditional result was False 2026-06-12 01:16:48.475893 | 2026-06-12 01:16:48.475989 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-06-12 01:16:48.831939 | instance -> localhost | changed 2026-06-12 01:16:48.850072 | 2026-06-12 01:16:48.850177 | TASK [add-build-sshkey : Add back temp key] 2026-06-12 01:16:49.126836 | instance -> localhost | Identity added: /var/lib/zuul/builds/7c7d8bc0724b41d6a715182a55fa3765/work/7c7d8bc0724b41d6a715182a55fa3765_id_rsa (zuul-build-sshkey) 2026-06-12 01:16:49.127168 | instance -> localhost | ok: Runtime: 0:00:00.014023 2026-06-12 01:16:49.134198 | 2026-06-12 01:16:49.134275 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-06-12 01:16:49.387844 | instance | ok 2026-06-12 01:16:49.396398 | 2026-06-12 01:16:49.396467 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-06-12 01:16:49.421283 | instance | skipping: Conditional result was False 2026-06-12 01:16:49.436130 | 2026-06-12 01:16:49.436201 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-06-12 01:16:49.713613 | instance | ok 2026-06-12 01:16:49.722029 | 2026-06-12 01:16:49.722147 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-06-12 01:16:53.075772 | instance | Output suppressed because no_log was given 2026-06-12 01:16:53.085392 | 2026-06-12 01:16:53.085456 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-06-12 01:16:53.295519 | instance | ok: "logs" 2026-06-12 01:16:53.295775 | instance | ok: All items complete 2026-06-12 01:16:53.295803 | 2026-06-12 01:16:53.443469 | instance | ok: "artifacts" 2026-06-12 01:16:53.597390 | instance | ok: "docs" 2026-06-12 01:16:53.615299 | 2026-06-12 01:16:53.615475 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-06-12 01:16:53.805732 | instance | changed: "logs" 2026-06-12 01:16:53.956429 | instance | changed: "artifacts" 2026-06-12 01:16:54.112052 | instance | changed: "docs" 2026-06-12 01:16:54.128092 | 2026-06-12 01:16:54.128194 | PLAY RECAP 2026-06-12 01:16:54.128242 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-06-12 01:16:54.128271 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-06-12 01:16:54.128295 | 2026-06-12 01:16:54.288156 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-06-12 01:16:54.299351 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-06-12 01:16:54.922031 | 2026-06-12 01:16:54.922336 | PLAY [all] 2026-06-12 01:16:54.933470 | 2026-06-12 01:16:54.933543 | TASK [setup-uv : Extract archive] 2026-06-12 01:16:57.450792 | instance | changed 2026-06-12 01:16:57.458320 | 2026-06-12 01:16:57.458404 | TASK [setup-uv : Print version] 2026-06-12 01:16:57.835442 | instance | uv 0.8.13 2026-06-12 01:16:58.569232 | instance | ok: Runtime: 0:00:00.013251 2026-06-12 01:16:58.581573 | 2026-06-12 01:16:58.581645 | TASK [Install Ansible collection siblings] 2026-06-12 01:16:59.172296 | instance | ERROR 2026-06-12 01:16:59.281563 | instance | { 2026-06-12 01:16:59.281661 | instance | "log": [ 2026-06-12 01:16:59.281715 | instance | "Sibling collection ansible.netcommon at /home/zuul/src/github.com/ansible-collections/ansible.netcommon", 2026-06-12 01:16:59.281771 | instance | "Sibling collection ansible.posix at /home/zuul/src/github.com/ansible-collections/ansible.posix", 2026-06-12 01:16:59.281816 | instance | "Sibling collection ansible.utils at /home/zuul/src/github.com/ansible-collections/ansible.utils", 2026-06-12 01:16:59.281866 | instance | "Sibling collection community.crypto at /home/zuul/src/github.com/ansible-collections/community.crypto", 2026-06-12 01:16:59.281910 | instance | "Sibling collection community.general at /home/zuul/src/github.com/ansible-collections/community.general", 2026-06-12 01:16:59.281956 | instance | "Sibling collection community.mysql at /home/zuul/src/github.com/ansible-collections/community.mysql", 2026-06-12 01:16:59.281999 | instance | "Sibling collection kubernetes.core at /home/zuul/src/github.com/ansible-collections/kubernetes.core", 2026-06-12 01:16:59.282041 | instance | "Sibling collection vexxhost.ceph at /home/zuul/src/github.com/vexxhost/ansible-collection-ceph", 2026-06-12 01:16:59.282081 | instance | "Sibling collection vexxhost.containers at /home/zuul/src/github.com/vexxhost/ansible-collection-containers", 2026-06-12 01:16:59.282122 | instance | "Sibling collection vexxhost.kubernetes at /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes", 2026-06-12 01:16:59.282162 | instance | "Sibling collection atmosphere.common at /home/zuul/src/github.com/vexxhost/atmosphere.common", 2026-06-12 01:16:59.282202 | instance | "Sibling collection openstack.cloud at /home/zuul/src/opendev.org/openstack/ansible-collections-openstack", 2026-06-12 01:16:59.282242 | instance | "Resolved ansible.netcommon to v8.5.2 for >=1.2.0", 2026-06-12 01:16:59.282283 | instance | "Resolved ansible.posix to 1.6.0 for 1.6.0", 2026-06-12 01:16:59.282324 | instance | "Resolved ansible.utils to v6.0.2 for >=2.9.0,>=3.0.0", 2026-06-12 01:16:59.282365 | instance | "Resolved community.crypto to 2.2.3 for 2.2.3", 2026-06-12 01:16:59.282406 | instance | "Resolved community.general to 7.3.0 for 7.3.0", 2026-06-12 01:16:59.282446 | instance | "Resolved community.mysql to 3.6.0 for 3.6.0", 2026-06-12 01:16:59.282486 | instance | "Resolved kubernetes.core to 2.4.0 for 2.4.0", 2026-06-12 01:16:59.282526 | instance | "Resolved openstack.cloud to 1.7.0 for 1.7.0", 2026-06-12 01:16:59.282569 | instance | "Resolved atmosphere.common to v0.7.0 for >=0.6.0", 2026-06-12 01:16:59.282628 | instance | "Resolved vexxhost.ceph to v4.0.0 for >=3.1.2", 2026-06-12 01:16:59.282671 | instance | "Resolved vexxhost.kubernetes to v3.2.0 for >=2.3.2,>=2.5.0", 2026-06-12 01:16:59.282712 | instance | "Resolved ansible.utils to v6.0.2 for >=2.9.0,>=3.0.0,>=6.0.0", 2026-06-12 01:16:59.282752 | instance | "Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2" 2026-06-12 01:16:59.282791 | instance | ], 2026-06-12 01:16:59.282832 | instance | "msg": "No local release tag for openstack.cloud satisfies 1.7.0,>=2.0.0" 2026-06-12 01:16:59.282873 | instance | } failure 2026-06-12 01:16:59.288951 | 2026-06-12 01:16:59.289078 | PLAY RECAP 2026-06-12 01:16:59.289171 | instance | ok: 2 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2026-06-12 01:16:59.289219 | 2026-06-12 01:16:59.421510 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-06-12 01:16:59.436463 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-06-12 01:17:00.123472 | 2026-06-12 01:17:00.125383 | PLAY [all] 2026-06-12 01:17:00.140120 | 2026-06-12 01:17:00.140200 | TASK [fetch-output : Set log path for multiple nodes] 2026-06-12 01:17:00.186844 | instance | skipping: Conditional result was False 2026-06-12 01:17:00.196476 | 2026-06-12 01:17:00.196589 | TASK [fetch-output : Set log path for single node] 2026-06-12 01:17:00.242513 | instance | ok 2026-06-12 01:17:00.248639 | 2026-06-12 01:17:00.248710 | LOOP [fetch-output : Ensure local output dirs] 2026-06-12 01:17:00.649656 | instance -> localhost | ok: "/var/lib/zuul/builds/7c7d8bc0724b41d6a715182a55fa3765/work/logs" 2026-06-12 01:17:01.045945 | instance -> localhost | changed: "/var/lib/zuul/builds/7c7d8bc0724b41d6a715182a55fa3765/work/artifacts" 2026-06-12 01:17:01.265371 | instance -> localhost | changed: "/var/lib/zuul/builds/7c7d8bc0724b41d6a715182a55fa3765/work/docs" 2026-06-12 01:17:01.287874 | 2026-06-12 01:17:01.288070 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-06-12 01:17:02.087678 | instance | changed: .d..t...... ./ 2026-06-12 01:17:02.087943 | instance | changed: All items complete 2026-06-12 01:17:02.087986 | 2026-06-12 01:17:02.542689 | instance | changed: .d..t...... ./ 2026-06-12 01:17:03.009684 | instance | changed: .d..t...... ./ 2026-06-12 01:17:03.034304 | 2026-06-12 01:17:03.034826 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-06-12 01:17:03.618691 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.169236 2026-06-12 01:17:03.900375 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.008394 2026-06-12 01:17:03.921864 | 2026-06-12 01:17:03.922010 | PLAY [all] 2026-06-12 01:17:03.928286 | 2026-06-12 01:17:03.928359 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-06-12 01:17:04.355011 | instance | changed 2026-06-12 01:17:04.361163 | 2026-06-12 01:17:04.361221 | PLAY RECAP 2026-06-12 01:17:04.361267 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-06-12 01:17:04.361289 | 2026-06-12 01:17:04.503190 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-06-12 01:17:04.516406 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-06-12 01:17:05.161478 | 2026-06-12 01:17:05.161605 | PLAY [localhost] 2026-06-12 01:17:05.171359 | 2026-06-12 01:17:05.171432 | TASK [Generate Zuul manifest] 2026-06-12 01:17:05.202460 | localhost | ok 2026-06-12 01:17:05.221899 | 2026-06-12 01:17:05.221988 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-06-12 01:17:05.573845 | localhost | changed 2026-06-12 01:17:05.584538 | 2026-06-12 01:17:05.584615 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-06-12 01:17:05.616038 | localhost | ok 2026-06-12 01:17:05.623366 | 2026-06-12 01:17:05.623432 | TASK [Upload logs] 2026-06-12 01:17:05.643805 | localhost | ok 2026-06-12 01:17:05.743684 | 2026-06-12 01:17:05.743842 | TASK [Set zuul-log-path fact] 2026-06-12 01:17:05.774748 | localhost | ok 2026-06-12 01:17:05.787546 | 2026-06-12 01:17:05.787613 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-06-12 01:17:05.818504 | localhost | ok 2026-06-12 01:17:05.826802 | 2026-06-12 01:17:05.826865 | TASK [upload-logs : Create log directories] 2026-06-12 01:17:06.181860 | localhost | changed 2026-06-12 01:17:06.188533 | 2026-06-12 01:17:06.188637 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-06-12 01:17:06.557172 | localhost -> localhost | ok: Runtime: 0:00:00.005956 2026-06-12 01:17:06.564163 | 2026-06-12 01:17:06.564263 | TASK [upload-logs : Upload logs to log server] 2026-06-12 01:17:07.024774 | localhost | Output suppressed because no_log was given 2026-06-12 01:17:07.030546 | 2026-06-12 01:17:07.030636 | LOOP [upload-logs : Compress console log and json output] 2026-06-12 01:17:07.078497 | localhost | skipping: Conditional result was False 2026-06-12 01:17:07.084982 | localhost | skipping: Conditional result was False 2026-06-12 01:17:07.097665 | 2026-06-12 01:17:07.097886 | LOOP [upload-logs : Upload compressed console log and json output] 2026-06-12 01:17:07.138222 | localhost | skipping: Conditional result was False 2026-06-12 01:17:07.138964 | 2026-06-12 01:17:07.142258 | localhost | skipping: Conditional result was False 2026-06-12 01:17:07.157923 | 2026-06-12 01:17:07.158085 | LOOP [upload-logs : Upload console log and json output]