2026-06-03 07:19:27.911907 | Job console starting 2026-06-03 07:19:27.923812 | Updating git repos 2026-06-03 07:19:37.105210 | Cloning repos into workspace 2026-06-03 07:20:05.269209 | Restoring repo states 2026-06-03 07:20:11.925165 | Merging changes 2026-06-03 07:20:14.188881 | Checking out repos 2026-06-03 07:20:21.137442 | Preparing playbooks 2026-06-03 07:20:32.192842 | Running Ansible setup 2026-06-03 07:20:36.358992 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-06-03 07:20:37.018984 | 2026-06-03 07:20:37.019113 | PLAY [localhost] 2026-06-03 07:20:37.028662 | 2026-06-03 07:20:37.028794 | TASK [Gathering Facts] 2026-06-03 07:20:38.418525 | localhost | ok 2026-06-03 07:20:38.430751 | 2026-06-03 07:20:38.431086 | TASK [Setup log path fact] 2026-06-03 07:20:38.456568 | localhost | ok 2026-06-03 07:20:38.471841 | 2026-06-03 07:20:38.472005 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-06-03 07:20:38.501684 | localhost | ok 2026-06-03 07:20:38.510531 | 2026-06-03 07:20:38.510702 | TASK [emit-job-header : Print job information] 2026-06-03 07:20:38.561107 | # Job Information 2026-06-03 07:20:38.561338 | Ansible Version: 2.16.18 2026-06-03 07:20:38.561392 | Job: atmosphere-molecule-aio-openvswitch 2026-06-03 07:20:38.561441 | Pipeline: check 2026-06-03 07:20:38.561478 | Executor: 2d72f0692154 2026-06-03 07:20:38.561515 | Triggered by: https://github.com/vexxhost/atmosphere/pull/3985 2026-06-03 07:20:38.561855 | Event ID: 6c44b080-5f1c-11f1-9ba4-38657aac9846 2026-06-03 07:20:38.569621 | 2026-06-03 07:20:38.569793 | LOOP [emit-job-header : Print node information] 2026-06-03 07:20:38.671292 | localhost | ok: 2026-06-03 07:20:38.671690 | localhost | # Node Information 2026-06-03 07:20:38.671770 | localhost | Inventory Hostname: instance 2026-06-03 07:20:38.671820 | localhost | Hostname: np0000178403 2026-06-03 07:20:38.671865 | localhost | Username: zuul 2026-06-03 07:20:38.671915 | localhost | Distro: Ubuntu 22.04 2026-06-03 07:20:38.671981 | localhost | Provider: yul1 2026-06-03 07:20:38.672025 | localhost | Region: ca-ymq-1 2026-06-03 07:20:38.672084 | localhost | Label: ubuntu-jammy-16 2026-06-03 07:20:38.672133 | localhost | Product Name: OpenStack Nova 2026-06-03 07:20:38.672182 | localhost | Interface IP: 199.204.45.205 2026-06-03 07:20:38.697546 | 2026-06-03 07:20:38.697717 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-06-03 07:20:39.282815 | localhost -> localhost | changed 2026-06-03 07:20:39.347391 | 2026-06-03 07:20:39.347518 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-06-03 07:20:40.430394 | localhost -> localhost | changed 2026-06-03 07:20:40.439588 | 2026-06-03 07:20:40.439684 | PLAY [all] 2026-06-03 07:20:40.448900 | 2026-06-03 07:20:40.449069 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-06-03 07:20:40.800437 | instance -> localhost | ok 2026-06-03 07:20:40.811807 | 2026-06-03 07:20:40.811910 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-06-03 07:20:40.846776 | instance | ok 2026-06-03 07:20:40.865360 | instance | included: /var/lib/zuul/builds/ad713141ba6e42e5803968253bbad3de/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-06-03 07:20:40.873634 | 2026-06-03 07:20:40.873724 | TASK [add-build-sshkey : Create Temp SSH key] 2026-06-03 07:20:42.440291 | instance -> localhost | Generating public/private rsa key pair. 2026-06-03 07:20:42.440469 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/ad713141ba6e42e5803968253bbad3de/work/ad713141ba6e42e5803968253bbad3de_id_rsa 2026-06-03 07:20:42.440501 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/ad713141ba6e42e5803968253bbad3de/work/ad713141ba6e42e5803968253bbad3de_id_rsa.pub 2026-06-03 07:20:42.440525 | instance -> localhost | The key fingerprint is: 2026-06-03 07:20:42.440547 | instance -> localhost | SHA256:QIk+WC8wvjYj91TMzHl52F1iG5ee4uwZ9SQE6chYbj4 zuul-build-sshkey 2026-06-03 07:20:42.440584 | instance -> localhost | The key's randomart image is: 2026-06-03 07:20:42.440607 | instance -> localhost | +---[RSA 3072]----+ 2026-06-03 07:20:42.440632 | instance -> localhost | | ... .o . | 2026-06-03 07:20:42.440655 | instance -> localhost | | o o.. . = = | 2026-06-03 07:20:42.440676 | instance -> localhost | | . * *.. O = O . | 2026-06-03 07:20:42.440698 | instance -> localhost | | o + O.= B = = .| 2026-06-03 07:20:42.440718 | instance -> localhost | | . + .S+ o o + | 2026-06-03 07:20:42.440739 | instance -> localhost | |. * . E + .| 2026-06-03 07:20:42.440759 | instance -> localhost | | + = o o | 2026-06-03 07:20:42.440780 | instance -> localhost | | . o | 2026-06-03 07:20:42.440804 | instance -> localhost | | | 2026-06-03 07:20:42.440825 | instance -> localhost | +----[SHA256]-----+ 2026-06-03 07:20:42.440872 | instance -> localhost | ok: Runtime: 0:00:01.111547 2026-06-03 07:20:42.447018 | 2026-06-03 07:20:42.447091 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-06-03 07:20:42.476901 | instance | ok 2026-06-03 07:20:42.489279 | instance | included: /var/lib/zuul/builds/ad713141ba6e42e5803968253bbad3de/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-06-03 07:20:42.510142 | 2026-06-03 07:20:42.510311 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-06-03 07:20:42.535042 | instance | skipping: Conditional result was False 2026-06-03 07:20:44.591717 | 2026-06-03 07:20:44.591826 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-06-03 07:20:45.089629 | instance | changed 2026-06-03 07:20:45.098416 | 2026-06-03 07:20:45.098533 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-06-03 07:20:45.318956 | instance | ok 2026-06-03 07:20:45.324914 | 2026-06-03 07:20:45.325014 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-06-03 07:20:46.061469 | instance | changed 2026-06-03 07:20:46.068687 | 2026-06-03 07:20:46.078174 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-06-03 07:20:46.539403 | instance | changed 2026-06-03 07:20:46.752760 | 2026-06-03 07:20:46.770213 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-06-03 07:20:46.796445 | instance | skipping: Conditional result was False 2026-06-03 07:20:46.823422 | 2026-06-03 07:20:46.823626 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-06-03 07:20:47.214002 | instance -> localhost | changed 2026-06-03 07:20:47.224395 | 2026-06-03 07:20:47.224462 | TASK [add-build-sshkey : Add back temp key] 2026-06-03 07:20:49.479331 | instance -> localhost | Identity added: /var/lib/zuul/builds/ad713141ba6e42e5803968253bbad3de/work/ad713141ba6e42e5803968253bbad3de_id_rsa (zuul-build-sshkey) 2026-06-03 07:20:49.479599 | instance -> localhost | ok: Runtime: 0:00:00.013724 2026-06-03 07:20:49.486693 | 2026-06-03 07:20:49.486881 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-06-03 07:20:49.807339 | instance | ok 2026-06-03 07:20:49.878022 | 2026-06-03 07:20:49.878137 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-06-03 07:20:49.902733 | instance | skipping: Conditional result was False 2026-06-03 07:20:49.920240 | 2026-06-03 07:20:49.920391 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-06-03 07:20:50.220165 | instance | ok 2026-06-03 07:20:50.231288 | 2026-06-03 07:20:50.231476 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-06-03 07:20:54.307303 | instance | Output suppressed because no_log was given 2026-06-03 07:20:54.316252 | 2026-06-03 07:20:54.316321 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-06-03 07:20:54.515675 | instance | ok: "logs" 2026-06-03 07:20:54.515977 | instance | ok: All items complete 2026-06-03 07:20:54.516007 | 2026-06-03 07:20:54.677636 | instance | ok: "artifacts" 2026-06-03 07:20:54.860949 | instance | ok: "docs" 2026-06-03 07:20:54.881373 | 2026-06-03 07:20:54.881586 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-06-03 07:20:55.066855 | instance | changed: "logs" 2026-06-03 07:20:55.239072 | instance | changed: "artifacts" 2026-06-03 07:20:55.401689 | instance | changed: "docs" 2026-06-03 07:20:55.663021 | 2026-06-03 07:20:55.663189 | PLAY RECAP 2026-06-03 07:20:55.663274 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-06-03 07:20:55.663327 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-06-03 07:20:55.663370 | 2026-06-03 07:20:55.865881 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-06-03 07:20:55.877640 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-06-03 07:20:56.559515 | 2026-06-03 07:20:56.559653 | PLAY [all] 2026-06-03 07:20:56.571467 | 2026-06-03 07:20:56.571589 | TASK [setup-uv : Extract archive] 2026-06-03 07:20:58.916059 | instance | changed 2026-06-03 07:20:58.924235 | 2026-06-03 07:20:58.924371 | TASK [setup-uv : Print version] 2026-06-03 07:20:59.315409 | instance | uv 0.8.13 2026-06-03 07:20:59.469671 | instance | ok: Runtime: 0:00:00.011510 2026-06-03 07:20:59.480366 | 2026-06-03 07:20:59.480440 | TASK [Install Ansible collection siblings] 2026-06-03 07:21:00.223055 | instance | ERROR 2026-06-03 07:21:00.271085 | instance | { 2026-06-03 07:21:00.271209 | instance | "log": [ 2026-06-03 07:21:00.271264 | instance | "Sibling collection ansible.netcommon at /home/zuul/src/github.com/ansible-collections/ansible.netcommon", 2026-06-03 07:21:00.271442 | instance | "Sibling collection ansible.posix at /home/zuul/src/github.com/ansible-collections/ansible.posix", 2026-06-03 07:21:00.271489 | instance | "Sibling collection ansible.utils at /home/zuul/src/github.com/ansible-collections/ansible.utils", 2026-06-03 07:21:00.271540 | instance | "Sibling collection community.crypto at /home/zuul/src/github.com/ansible-collections/community.crypto", 2026-06-03 07:21:00.271583 | instance | "Sibling collection community.general at /home/zuul/src/github.com/ansible-collections/community.general", 2026-06-03 07:21:00.271628 | instance | "Sibling collection community.mysql at /home/zuul/src/github.com/ansible-collections/community.mysql", 2026-06-03 07:21:00.271669 | instance | "Sibling collection kubernetes.core at /home/zuul/src/github.com/ansible-collections/kubernetes.core", 2026-06-03 07:21:00.271708 | instance | "Sibling collection vexxhost.ceph at /home/zuul/src/github.com/vexxhost/ansible-collection-ceph", 2026-06-03 07:21:00.271747 | instance | "Sibling collection vexxhost.containers at /home/zuul/src/github.com/vexxhost/ansible-collection-containers", 2026-06-03 07:21:00.271786 | instance | "Sibling collection vexxhost.kubernetes at /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes", 2026-06-03 07:21:00.271826 | instance | "Sibling collection atmosphere.common at /home/zuul/src/github.com/vexxhost/atmosphere.common", 2026-06-03 07:21:00.271864 | instance | "Sibling collection openstack.cloud at /home/zuul/src/opendev.org/openstack/ansible-collections-openstack", 2026-06-03 07:21:00.271903 | instance | "Resolved ansible.netcommon to v8.5.2 for >=1.2.0", 2026-06-03 07:21:00.271943 | instance | "Resolved ansible.posix to 1.6.0 for 1.6.0", 2026-06-03 07:21:00.271982 | instance | "Resolved ansible.utils to v6.0.2 for >=2.9.0,>=3.0.0", 2026-06-03 07:21:00.272021 | instance | "Resolved community.crypto to 2.2.3 for 2.2.3", 2026-06-03 07:21:00.272059 | instance | "Resolved community.general to 7.3.0 for 7.3.0", 2026-06-03 07:21:00.272098 | instance | "Resolved community.mysql to 3.6.0 for 3.6.0", 2026-06-03 07:21:00.272135 | instance | "Resolved kubernetes.core to 2.4.0 for 2.4.0", 2026-06-03 07:21:00.272237 | instance | "Resolved openstack.cloud to 1.7.0 for 1.7.0", 2026-06-03 07:21:00.272284 | instance | "Resolved atmosphere.common to v0.7.0 for >=0.6.0", 2026-06-03 07:21:00.272341 | instance | "Resolved vexxhost.ceph to v4.0.0 for >=3.1.2", 2026-06-03 07:21:00.272456 | instance | "Resolved vexxhost.kubernetes to v3.1.0 for >=2.3.2,>=2.5.0", 2026-06-03 07:21:00.272518 | instance | "Resolved ansible.utils to v6.0.2 for >=2.9.0,>=3.0.0,>=6.0.0", 2026-06-03 07:21:00.272561 | instance | "Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2" 2026-06-03 07:21:00.272631 | instance | ], 2026-06-03 07:21:00.272715 | instance | "msg": "No local release tag for openstack.cloud satisfies 1.7.0,>=2.0.0" 2026-06-03 07:21:00.272774 | instance | } failure 2026-06-03 07:21:00.279958 | 2026-06-03 07:21:00.280072 | PLAY RECAP 2026-06-03 07:21:00.280270 | instance | ok: 2 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2026-06-03 07:21:00.280352 | 2026-06-03 07:21:00.519229 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-06-03 07:21:00.610434 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-06-03 07:21:01.366658 | 2026-06-03 07:21:01.366785 | PLAY [all] 2026-06-03 07:21:01.377941 | 2026-06-03 07:21:01.378019 | TASK [fetch-output : Set log path for multiple nodes] 2026-06-03 07:21:01.422869 | instance | skipping: Conditional result was False 2026-06-03 07:21:01.429863 | 2026-06-03 07:21:01.429947 | TASK [fetch-output : Set log path for single node] 2026-06-03 07:21:01.474613 | instance | ok 2026-06-03 07:21:01.480697 | 2026-06-03 07:21:01.480773 | LOOP [fetch-output : Ensure local output dirs] 2026-06-03 07:21:01.966116 | instance -> localhost | ok: "/var/lib/zuul/builds/ad713141ba6e42e5803968253bbad3de/work/logs" 2026-06-03 07:21:02.390046 | instance -> localhost | changed: "/var/lib/zuul/builds/ad713141ba6e42e5803968253bbad3de/work/artifacts" 2026-06-03 07:21:02.596508 | instance -> localhost | changed: "/var/lib/zuul/builds/ad713141ba6e42e5803968253bbad3de/work/docs" 2026-06-03 07:21:02.612380 | 2026-06-03 07:21:02.612523 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-06-03 07:21:03.286378 | instance | ok 2026-06-03 07:21:03.286746 | instance | changed: All items complete 2026-06-03 07:21:03.286793 | 2026-06-03 07:21:03.729224 | instance | changed: .d..t...... ./ 2026-06-03 07:21:04.383381 | instance | changed: .d..t...... ./ 2026-06-03 07:21:04.401753 | 2026-06-03 07:21:04.401947 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-06-03 07:21:04.959657 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.007986 2026-06-03 07:21:05.573586 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.260772 2026-06-03 07:21:05.590278 | 2026-06-03 07:21:05.590419 | PLAY [all] 2026-06-03 07:21:05.604800 | 2026-06-03 07:21:05.605003 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-06-03 07:21:06.045879 | instance | changed 2026-06-03 07:21:06.196161 | 2026-06-03 07:21:06.196257 | PLAY RECAP 2026-06-03 07:21:06.196313 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-06-03 07:21:06.196342 | 2026-06-03 07:21:06.338965 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-06-03 07:21:06.348930 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-06-03 07:21:07.057958 | 2026-06-03 07:21:07.058233 | PLAY [localhost] 2026-06-03 07:21:07.074099 | 2026-06-03 07:21:07.074263 | TASK [Generate Zuul manifest] 2026-06-03 07:21:07.098737 | localhost | ok 2026-06-03 07:21:07.118313 | 2026-06-03 07:21:07.118515 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-06-03 07:21:08.010779 | localhost | changed 2026-06-03 07:21:08.027318 | 2026-06-03 07:21:08.027509 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-06-03 07:21:08.074342 | localhost | ok 2026-06-03 07:21:08.084318 | 2026-06-03 07:21:08.084453 | TASK [Upload logs] 2026-06-03 07:21:08.108431 | localhost | ok 2026-06-03 07:21:08.262752 | 2026-06-03 07:21:08.262921 | TASK [Set zuul-log-path fact] 2026-06-03 07:21:08.281570 | localhost | ok 2026-06-03 07:21:08.292875 | 2026-06-03 07:21:08.293043 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-06-03 07:21:08.325623 | localhost | ok 2026-06-03 07:21:08.334481 | 2026-06-03 07:21:08.334599 | TASK [upload-logs : Create log directories] 2026-06-03 07:21:08.804578 | localhost | changed 2026-06-03 07:21:08.810120 | 2026-06-03 07:21:08.810192 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-06-03 07:21:09.513811 | localhost -> localhost | ok: Runtime: 0:00:00.142883 2026-06-03 07:21:09.522417 | 2026-06-03 07:21:09.522544 | TASK [upload-logs : Upload logs to log server] 2026-06-03 07:21:10.233615 | localhost | Output suppressed because no_log was given 2026-06-03 07:21:10.483920 | 2026-06-03 07:21:10.484107 | LOOP [upload-logs : Compress console log and json output] 2026-06-03 07:21:10.560568 | localhost | skipping: Conditional result was False 2026-06-03 07:21:10.575817 | localhost | skipping: Conditional result was False 2026-06-03 07:21:10.589669 | 2026-06-03 07:21:10.589842 | LOOP [upload-logs : Upload compressed console log and json output] 2026-06-03 07:21:10.631345 | localhost | skipping: Conditional result was False 2026-06-03 07:21:10.873554 | 2026-06-03 07:21:10.874996 | localhost | skipping: Conditional result was False 2026-06-03 07:21:10.884050 | 2026-06-03 07:21:10.884264 | LOOP [upload-logs : Upload console log and json output]