2026-05-30 05:11:29.090867 | Job console starting 2026-05-30 05:11:29.101287 | Updating git repos 2026-05-30 05:11:29.500181 | Cloning repos into workspace 2026-05-30 05:11:48.427552 | Restoring repo states 2026-05-30 05:11:53.823101 | Merging changes 2026-05-30 05:11:55.253042 | Checking out repos 2026-05-30 05:11:59.573733 | Preparing playbooks 2026-05-30 05:12:09.317113 | Running Ansible setup 2026-05-30 05:12:13.340240 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-05-30 05:12:13.967530 | 2026-05-30 05:12:13.967664 | PLAY [localhost] 2026-05-30 05:12:13.976952 | 2026-05-30 05:12:13.977106 | TASK [Gathering Facts] 2026-05-30 05:12:14.959659 | localhost | ok 2026-05-30 05:12:14.972792 | 2026-05-30 05:12:14.972879 | TASK [Setup log path fact] 2026-05-30 05:12:14.994680 | localhost | ok 2026-05-30 05:12:15.011904 | 2026-05-30 05:12:15.012015 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-30 05:12:15.054533 | localhost | ok 2026-05-30 05:12:15.063829 | 2026-05-30 05:12:15.063905 | TASK [emit-job-header : Print job information] 2026-05-30 05:12:15.108022 | # Job Information 2026-05-30 05:12:15.108273 | Ansible Version: 2.16.18 2026-05-30 05:12:15.108334 | Job: atmosphere-molecule-aio-openvswitch 2026-05-30 05:12:15.108366 | Pipeline: check 2026-05-30 05:12:15.108393 | Executor: 2d72f0692154 2026-05-30 05:12:15.108421 | Triggered by: https://github.com/vexxhost/atmosphere/pull/3991 2026-05-30 05:12:15.108451 | Event ID: 992abf70-5be5-11f1-9fa3-1ae483caeda5 2026-05-30 05:12:15.113545 | 2026-05-30 05:12:15.113637 | LOOP [emit-job-header : Print node information] 2026-05-30 05:12:15.209903 | localhost | ok: 2026-05-30 05:12:15.210181 | localhost | # Node Information 2026-05-30 05:12:15.210255 | localhost | Inventory Hostname: instance 2026-05-30 05:12:15.210298 | localhost | Hostname: np0000177838 2026-05-30 05:12:15.210334 | localhost | Username: zuul 2026-05-30 05:12:15.210371 | localhost | Distro: Ubuntu 22.04 2026-05-30 05:12:15.210407 | localhost | Provider: yul1 2026-05-30 05:12:15.210441 | localhost | Region: ca-ymq-1 2026-05-30 05:12:15.210473 | localhost | Label: ubuntu-jammy-16 2026-05-30 05:12:15.210505 | localhost | Product Name: OpenStack Nova 2026-05-30 05:12:15.210537 | localhost | Interface IP: 199.204.45.23 2026-05-30 05:12:15.220340 | 2026-05-30 05:12:15.220584 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-05-30 05:12:15.624355 | localhost -> localhost | changed 2026-05-30 05:12:15.632168 | 2026-05-30 05:12:15.632263 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-05-30 05:12:16.624356 | localhost -> localhost | changed 2026-05-30 05:12:16.632929 | 2026-05-30 05:12:16.633083 | PLAY [all] 2026-05-30 05:12:16.645780 | 2026-05-30 05:12:16.645942 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-05-30 05:12:16.994524 | instance -> localhost | ok 2026-05-30 05:12:17.006162 | 2026-05-30 05:12:17.006231 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-05-30 05:12:17.038212 | instance | ok 2026-05-30 05:12:17.053624 | instance | included: /var/lib/zuul/builds/8f2705aaf40a464286e1a5e974834ab5/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-05-30 05:12:17.059397 | 2026-05-30 05:12:17.059459 | TASK [add-build-sshkey : Create Temp SSH key] 2026-05-30 05:12:18.123385 | instance -> localhost | Generating public/private rsa key pair. 2026-05-30 05:12:18.123634 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/8f2705aaf40a464286e1a5e974834ab5/work/8f2705aaf40a464286e1a5e974834ab5_id_rsa 2026-05-30 05:12:18.123668 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/8f2705aaf40a464286e1a5e974834ab5/work/8f2705aaf40a464286e1a5e974834ab5_id_rsa.pub 2026-05-30 05:12:18.123693 | instance -> localhost | The key fingerprint is: 2026-05-30 05:12:18.123715 | instance -> localhost | SHA256:M1u8ArN5t752MpAitWL8IfrVRsFhHxRqp8bSOLFcZFI zuul-build-sshkey 2026-05-30 05:12:18.123747 | instance -> localhost | The key's randomart image is: 2026-05-30 05:12:18.123769 | instance -> localhost | +---[RSA 3072]----+ 2026-05-30 05:12:18.123797 | instance -> localhost | | ..E.+. | 2026-05-30 05:12:18.123819 | instance -> localhost | | * + . | 2026-05-30 05:12:18.123840 | instance -> localhost | | . * o | 2026-05-30 05:12:18.123861 | instance -> localhost | | ..O = | 2026-05-30 05:12:18.123881 | instance -> localhost | | . .O.S.o | 2026-05-30 05:12:18.123930 | instance -> localhost | | * +@o= . | 2026-05-30 05:12:18.123950 | instance -> localhost | | o =+o*.o | 2026-05-30 05:12:18.123970 | instance -> localhost | | . ..o o+.. | 2026-05-30 05:12:18.123993 | instance -> localhost | | .. o+= | 2026-05-30 05:12:18.124013 | instance -> localhost | +----[SHA256]-----+ 2026-05-30 05:12:18.124656 | instance -> localhost | ok: Runtime: 0:00:00.631763 2026-05-30 05:12:18.135261 | 2026-05-30 05:12:18.135461 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-05-30 05:12:18.173629 | instance | ok 2026-05-30 05:12:18.184243 | instance | included: /var/lib/zuul/builds/8f2705aaf40a464286e1a5e974834ab5/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-05-30 05:12:18.193171 | 2026-05-30 05:12:18.193247 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-05-30 05:12:18.220307 | instance | skipping: Conditional result was False 2026-05-30 05:12:18.231023 | 2026-05-30 05:12:18.231145 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-05-30 05:12:18.652272 | instance | changed 2026-05-30 05:12:18.665814 | 2026-05-30 05:12:18.665982 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-05-30 05:12:18.897776 | instance | ok 2026-05-30 05:12:18.903541 | 2026-05-30 05:12:18.903612 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-05-30 05:12:19.480925 | instance | changed 2026-05-30 05:12:19.487894 | 2026-05-30 05:12:19.487980 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-05-30 05:12:19.926882 | instance | changed 2026-05-30 05:12:19.933975 | 2026-05-30 05:12:19.934046 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-05-30 05:12:19.959196 | instance | skipping: Conditional result was False 2026-05-30 05:12:19.971132 | 2026-05-30 05:12:19.971224 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-05-30 05:12:20.295081 | instance -> localhost | changed 2026-05-30 05:12:20.310301 | 2026-05-30 05:12:20.310393 | TASK [add-build-sshkey : Add back temp key] 2026-05-30 05:12:20.695662 | instance -> localhost | Identity added: /var/lib/zuul/builds/8f2705aaf40a464286e1a5e974834ab5/work/8f2705aaf40a464286e1a5e974834ab5_id_rsa (zuul-build-sshkey) 2026-05-30 05:12:20.695887 | instance -> localhost | ok: Runtime: 0:00:00.013625 2026-05-30 05:12:20.703095 | 2026-05-30 05:12:20.703173 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-05-30 05:12:20.964285 | instance | ok 2026-05-30 05:12:20.972983 | 2026-05-30 05:12:20.973057 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-05-30 05:12:20.998400 | instance | skipping: Conditional result was False 2026-05-30 05:12:21.013301 | 2026-05-30 05:12:21.013381 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-05-30 05:12:21.310951 | instance | ok 2026-05-30 05:12:21.319939 | 2026-05-30 05:12:21.320002 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-05-30 05:12:27.186026 | instance | Output suppressed because no_log was given 2026-05-30 05:12:27.198971 | 2026-05-30 05:12:27.199089 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-05-30 05:12:27.393404 | instance | ok: "logs" 2026-05-30 05:12:27.393670 | instance | ok: All items complete 2026-05-30 05:12:27.393708 | 2026-05-30 05:12:27.553839 | instance | ok: "artifacts" 2026-05-30 05:12:27.710429 | instance | ok: "docs" 2026-05-30 05:12:27.727625 | 2026-05-30 05:12:27.727778 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-05-30 05:12:27.910327 | instance | changed: "logs" 2026-05-30 05:12:28.078985 | instance | changed: "artifacts" 2026-05-30 05:12:28.247093 | instance | changed: "docs" 2026-05-30 05:12:28.262925 | 2026-05-30 05:12:28.263061 | PLAY RECAP 2026-05-30 05:12:28.263113 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-05-30 05:12:28.263145 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-05-30 05:12:28.263168 | 2026-05-30 05:12:29.329176 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-05-30 05:12:29.339650 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-05-30 05:12:29.962507 | 2026-05-30 05:12:29.962639 | PLAY [all] 2026-05-30 05:12:29.973825 | 2026-05-30 05:12:29.973904 | TASK [setup-uv : Extract archive] 2026-05-30 05:12:32.116897 | instance | changed 2026-05-30 05:12:32.125365 | 2026-05-30 05:12:32.125463 | TASK [setup-uv : Print version] 2026-05-30 05:12:32.452312 | instance | uv 0.8.13 2026-05-30 05:12:32.661517 | instance | ok: Runtime: 0:00:00.011582 2026-05-30 05:12:32.672469 | 2026-05-30 05:12:32.672532 | TASK [Install Ansible collection siblings] 2026-05-30 05:12:33.303672 | instance | ERROR 2026-05-30 05:12:33.304037 | instance | { 2026-05-30 05:12:33.304069 | instance | "log": [ 2026-05-30 05:12:33.304091 | instance | "Sibling collection ansible.netcommon at /home/zuul/src/github.com/ansible-collections/ansible.netcommon", 2026-05-30 05:12:33.304117 | instance | "Sibling collection ansible.posix at /home/zuul/src/github.com/ansible-collections/ansible.posix", 2026-05-30 05:12:33.304137 | instance | "Sibling collection ansible.utils at /home/zuul/src/github.com/ansible-collections/ansible.utils", 2026-05-30 05:12:33.304160 | instance | "Sibling collection community.crypto at /home/zuul/src/github.com/ansible-collections/community.crypto", 2026-05-30 05:12:33.304178 | instance | "Sibling collection community.general at /home/zuul/src/github.com/ansible-collections/community.general", 2026-05-30 05:12:33.304200 | instance | "Sibling collection community.mysql at /home/zuul/src/github.com/ansible-collections/community.mysql", 2026-05-30 05:12:33.304225 | instance | "Sibling collection kubernetes.core at /home/zuul/src/github.com/ansible-collections/kubernetes.core", 2026-05-30 05:12:33.304250 | instance | "Sibling collection vexxhost.ceph at /home/zuul/src/github.com/vexxhost/ansible-collection-ceph", 2026-05-30 05:12:33.304269 | instance | "Sibling collection vexxhost.containers at /home/zuul/src/github.com/vexxhost/ansible-collection-containers", 2026-05-30 05:12:33.304287 | instance | "Sibling collection vexxhost.kubernetes at /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes", 2026-05-30 05:12:33.304304 | instance | "Sibling collection atmosphere.common at /home/zuul/src/github.com/vexxhost/atmosphere.common", 2026-05-30 05:12:33.304321 | instance | "Sibling collection openstack.cloud at /home/zuul/src/opendev.org/openstack/ansible-collections-openstack", 2026-05-30 05:12:33.304338 | instance | "Resolved ansible.netcommon to v8.5.2 for >=1.2.0", 2026-05-30 05:12:33.304356 | instance | "Resolved ansible.posix to 1.6.0 for 1.6.0", 2026-05-30 05:12:33.304374 | instance | "Resolved ansible.utils to v6.0.2 for >=2.9.0,>=3.0.0", 2026-05-30 05:12:33.304392 | instance | "Resolved community.crypto to 2.2.3 for 2.2.3", 2026-05-30 05:12:33.304410 | instance | "Resolved community.general to 7.3.0 for 7.3.0", 2026-05-30 05:12:33.304427 | instance | "Resolved community.mysql to 3.6.0 for 3.6.0", 2026-05-30 05:12:33.304444 | instance | "Resolved kubernetes.core to 2.4.0 for 2.4.0", 2026-05-30 05:12:33.304461 | instance | "Resolved openstack.cloud to 1.7.0 for 1.7.0", 2026-05-30 05:12:33.304479 | instance | "Resolved atmosphere.common to v0.7.0 for >=0.6.0", 2026-05-30 05:12:33.304517 | instance | "Resolved vexxhost.ceph to v4.0.0 for >=3.1.2", 2026-05-30 05:12:33.304535 | instance | "Resolved vexxhost.kubernetes to v3.1.0 for >=2.3.2,>=2.5.0", 2026-05-30 05:12:33.304553 | instance | "Resolved ansible.utils to v6.0.2 for >=2.9.0,>=3.0.0,>=6.0.0", 2026-05-30 05:12:33.304569 | instance | "Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2" 2026-05-30 05:12:33.304586 | instance | ], 2026-05-30 05:12:33.304603 | instance | "msg": "No local release tag for openstack.cloud satisfies 1.7.0,>=2.0.0" 2026-05-30 05:12:33.304621 | instance | } failure 2026-05-30 05:12:33.307706 | 2026-05-30 05:12:33.307753 | PLAY RECAP 2026-05-30 05:12:33.307799 | instance | ok: 2 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2026-05-30 05:12:33.307822 | 2026-05-30 05:12:33.431276 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-05-30 05:12:33.444665 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-05-30 05:12:34.080335 | 2026-05-30 05:12:34.080458 | PLAY [all] 2026-05-30 05:12:34.091284 | 2026-05-30 05:12:34.091362 | TASK [fetch-output : Set log path for multiple nodes] 2026-05-30 05:12:34.146400 | instance | skipping: Conditional result was False 2026-05-30 05:12:34.157619 | 2026-05-30 05:12:34.157696 | TASK [fetch-output : Set log path for single node] 2026-05-30 05:12:34.190987 | instance | ok 2026-05-30 05:12:34.198063 | 2026-05-30 05:12:34.198155 | LOOP [fetch-output : Ensure local output dirs] 2026-05-30 05:12:34.605590 | instance -> localhost | ok: "/var/lib/zuul/builds/8f2705aaf40a464286e1a5e974834ab5/work/logs" 2026-05-30 05:12:34.808706 | instance -> localhost | changed: "/var/lib/zuul/builds/8f2705aaf40a464286e1a5e974834ab5/work/artifacts" 2026-05-30 05:12:35.044082 | instance -> localhost | changed: "/var/lib/zuul/builds/8f2705aaf40a464286e1a5e974834ab5/work/docs" 2026-05-30 05:12:35.063446 | 2026-05-30 05:12:35.063550 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-05-30 05:12:35.698595 | instance | changed: .d..t...... ./ 2026-05-30 05:12:35.698867 | instance | changed: All items complete 2026-05-30 05:12:35.698907 | 2026-05-30 05:12:36.144761 | instance | changed: .d..t...... ./ 2026-05-30 05:12:36.589365 | instance | changed: .d..t...... ./ 2026-05-30 05:12:36.612779 | 2026-05-30 05:12:36.612939 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-05-30 05:12:37.055142 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.007361 2026-05-30 05:12:37.281775 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.009882 2026-05-30 05:12:37.293305 | 2026-05-30 05:12:37.293421 | PLAY [all] 2026-05-30 05:12:37.303528 | 2026-05-30 05:12:37.303599 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-05-30 05:12:37.751325 | instance | changed 2026-05-30 05:12:37.761030 | 2026-05-30 05:12:37.761082 | PLAY RECAP 2026-05-30 05:12:37.761127 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-05-30 05:12:37.761148 | 2026-05-30 05:12:37.883425 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-05-30 05:12:37.895907 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-05-30 05:12:38.534832 | 2026-05-30 05:12:38.534956 | PLAY [localhost] 2026-05-30 05:12:38.544913 | 2026-05-30 05:12:38.545140 | TASK [Generate Zuul manifest] 2026-05-30 05:12:38.562844 | localhost | ok 2026-05-30 05:12:38.579779 | 2026-05-30 05:12:38.579862 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-05-30 05:12:38.938579 | localhost | changed 2026-05-30 05:12:38.950782 | 2026-05-30 05:12:38.950855 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-05-30 05:12:38.981959 | localhost | ok 2026-05-30 05:12:38.990167 | 2026-05-30 05:12:38.990232 | TASK [Upload logs] 2026-05-30 05:12:39.011406 | localhost | ok 2026-05-30 05:12:39.111267 | 2026-05-30 05:12:39.111405 | TASK [Set zuul-log-path fact] 2026-05-30 05:12:39.138109 | localhost | ok 2026-05-30 05:12:39.150906 | 2026-05-30 05:12:39.150987 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-30 05:12:39.184783 | localhost | ok 2026-05-30 05:12:39.194209 | 2026-05-30 05:12:39.194292 | TASK [upload-logs : Create log directories] 2026-05-30 05:12:39.639003 | localhost | changed 2026-05-30 05:12:39.644097 | 2026-05-30 05:12:39.644173 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-05-30 05:12:40.037330 | localhost -> localhost | ok: Runtime: 0:00:00.007872 2026-05-30 05:12:40.043922 | 2026-05-30 05:12:40.043994 | TASK [upload-logs : Upload logs to log server] 2026-05-30 05:12:40.509145 | localhost | Output suppressed because no_log was given 2026-05-30 05:12:40.514668 | 2026-05-30 05:12:40.514762 | LOOP [upload-logs : Compress console log and json output] 2026-05-30 05:12:40.565603 | localhost | skipping: Conditional result was False 2026-05-30 05:12:40.572720 | localhost | skipping: Conditional result was False 2026-05-30 05:12:40.594231 | 2026-05-30 05:12:40.594525 | LOOP [upload-logs : Upload compressed console log and json output] 2026-05-30 05:12:40.637654 | localhost | skipping: Conditional result was False 2026-05-30 05:12:40.637914 | 2026-05-30 05:12:40.642394 | localhost | skipping: Conditional result was False 2026-05-30 05:12:40.660846 | 2026-05-30 05:12:40.661196 | LOOP [upload-logs : Upload console log and json output]