2026-06-13 22:45:13.329384 | Job console starting 2026-06-13 22:45:13.344261 | Updating git repos 2026-06-13 22:45:14.453205 | Cloning repos into workspace 2026-06-13 22:45:26.447008 | Restoring repo states 2026-06-13 22:45:32.511154 | Merging changes 2026-06-13 22:45:33.467990 | Checking out repos 2026-06-13 22:45:35.275020 | Preparing playbooks 2026-06-13 22:45:37.858035 | Running Ansible setup 2026-06-13 22:45:41.403396 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-06-13 22:45:42.078685 | 2026-06-13 22:45:42.078833 | PLAY [localhost] 2026-06-13 22:45:42.086321 | 2026-06-13 22:45:42.086393 | TASK [Gathering Facts] 2026-06-13 22:45:43.118843 | localhost | ok 2026-06-13 22:45:43.131656 | 2026-06-13 22:45:43.131772 | TASK [Setup log path fact] 2026-06-13 22:45:43.156851 | localhost | ok 2026-06-13 22:45:43.170291 | 2026-06-13 22:45:43.258271 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-06-13 22:45:43.291773 | localhost | ok 2026-06-13 22:45:43.312140 | 2026-06-13 22:45:43.312274 | TASK [emit-job-header : Print job information] 2026-06-13 22:45:43.355419 | # Job Information 2026-06-13 22:45:43.355634 | Ansible Version: 2.16.18 2026-06-13 22:45:43.355681 | Job: atmosphere-molecule-aio-openvswitch 2026-06-13 22:45:43.355713 | Pipeline: check 2026-06-13 22:45:43.355742 | Executor: 2d72f0692154 2026-06-13 22:45:43.355771 | Triggered by: https://github.com/vexxhost/atmosphere/pull/3991 2026-06-13 22:45:43.355804 | Event ID: db68a200-6778-11f1-8371-8dddab958de6 2026-06-13 22:45:43.361059 | 2026-06-13 22:45:43.361163 | LOOP [emit-job-header : Print node information] 2026-06-13 22:45:43.459446 | localhost | ok: 2026-06-13 22:45:43.459707 | localhost | # Node Information 2026-06-13 22:45:43.459748 | localhost | Inventory Hostname: instance 2026-06-13 22:45:43.459780 | localhost | Hostname: np0000179953 2026-06-13 22:45:43.459809 | localhost | Username: zuul 2026-06-13 22:45:43.459842 | localhost | Distro: Ubuntu 22.04 2026-06-13 22:45:43.459871 | localhost | Provider: yul1 2026-06-13 22:45:43.459899 | localhost | Region: ca-ymq-1 2026-06-13 22:45:43.459926 | localhost | Label: ubuntu-jammy-16 2026-06-13 22:45:43.459953 | localhost | Product Name: OpenStack Nova 2026-06-13 22:45:43.459980 | localhost | Interface IP: 199.204.45.240 2026-06-13 22:45:43.496743 | 2026-06-13 22:45:43.496915 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-06-13 22:45:43.921595 | localhost -> localhost | changed 2026-06-13 22:45:43.934009 | 2026-06-13 22:45:43.934116 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-06-13 22:45:44.782876 | localhost -> localhost | changed 2026-06-13 22:45:44.794324 | 2026-06-13 22:45:44.794397 | PLAY [all] 2026-06-13 22:45:44.802401 | 2026-06-13 22:45:44.802472 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-06-13 22:45:45.020093 | instance -> localhost | ok 2026-06-13 22:45:45.033332 | 2026-06-13 22:45:45.033434 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-06-13 22:45:45.066418 | instance | ok 2026-06-13 22:45:45.082428 | instance | included: /var/lib/zuul/builds/3ccc7364c2a94fdcb7a675cb6a1f18ee/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-06-13 22:45:45.088392 | 2026-06-13 22:45:45.088451 | TASK [add-build-sshkey : Create Temp SSH key] 2026-06-13 22:45:46.004830 | instance -> localhost | Generating public/private rsa key pair. 2026-06-13 22:45:46.005041 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/3ccc7364c2a94fdcb7a675cb6a1f18ee/work/3ccc7364c2a94fdcb7a675cb6a1f18ee_id_rsa 2026-06-13 22:45:46.005075 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/3ccc7364c2a94fdcb7a675cb6a1f18ee/work/3ccc7364c2a94fdcb7a675cb6a1f18ee_id_rsa.pub 2026-06-13 22:45:46.005098 | instance -> localhost | The key fingerprint is: 2026-06-13 22:45:46.005127 | instance -> localhost | SHA256:wP/89yJJlMrZ/K/KV6Cb3o42NmqZ6iMIFlZfSznGtOg zuul-build-sshkey 2026-06-13 22:45:46.005161 | instance -> localhost | The key's randomart image is: 2026-06-13 22:45:46.005183 | instance -> localhost | +---[RSA 3072]----+ 2026-06-13 22:45:46.005212 | instance -> localhost | | o.. | 2026-06-13 22:45:46.005235 | instance -> localhost | | .. .B. | 2026-06-13 22:45:46.005262 | instance -> localhost | | . .++.o . | 2026-06-13 22:45:46.005283 | instance -> localhost | | o ..o. o . | 2026-06-13 22:45:46.005303 | instance -> localhost | | . . E S. * . . | 2026-06-13 22:45:46.005323 | instance -> localhost | | o o+ = .| 2026-06-13 22:45:46.005343 | instance -> localhost | | . . . o+ = . | 2026-06-13 22:45:46.005363 | instance -> localhost | | . . . +oXo= | 2026-06-13 22:45:46.005386 | instance -> localhost | | oo+.=*O+=o| 2026-06-13 22:45:46.005406 | instance -> localhost | +----[SHA256]-----+ 2026-06-13 22:45:46.005456 | instance -> localhost | ok: Runtime: 0:00:00.486555 2026-06-13 22:45:46.011752 | 2026-06-13 22:45:46.011856 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-06-13 22:45:46.044667 | instance | ok 2026-06-13 22:45:46.056099 | instance | included: /var/lib/zuul/builds/3ccc7364c2a94fdcb7a675cb6a1f18ee/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-06-13 22:45:46.064013 | 2026-06-13 22:45:46.064075 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-06-13 22:45:46.088832 | instance | skipping: Conditional result was False 2026-06-13 22:45:46.098531 | 2026-06-13 22:45:46.098623 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-06-13 22:45:46.565573 | instance | changed 2026-06-13 22:45:46.571622 | 2026-06-13 22:45:46.571684 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-06-13 22:45:46.803598 | instance | ok 2026-06-13 22:45:46.808889 | 2026-06-13 22:45:46.808967 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-06-13 22:45:47.298153 | instance | changed 2026-06-13 22:45:47.303176 | 2026-06-13 22:45:47.303241 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-06-13 22:45:47.791928 | instance | changed 2026-06-13 22:45:47.797411 | 2026-06-13 22:45:47.797482 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-06-13 22:45:47.821657 | instance | skipping: Conditional result was False 2026-06-13 22:45:47.832680 | 2026-06-13 22:45:47.832783 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-06-13 22:45:48.162268 | instance -> localhost | changed 2026-06-13 22:45:48.176500 | 2026-06-13 22:45:48.176568 | TASK [add-build-sshkey : Add back temp key] 2026-06-13 22:45:48.463539 | instance -> localhost | Identity added: /var/lib/zuul/builds/3ccc7364c2a94fdcb7a675cb6a1f18ee/work/3ccc7364c2a94fdcb7a675cb6a1f18ee_id_rsa (zuul-build-sshkey) 2026-06-13 22:45:48.463871 | instance -> localhost | ok: Runtime: 0:00:00.015104 2026-06-13 22:45:48.470126 | 2026-06-13 22:45:48.470190 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-06-13 22:45:48.746315 | instance | ok 2026-06-13 22:45:48.752742 | 2026-06-13 22:45:48.752910 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-06-13 22:45:48.777159 | instance | skipping: Conditional result was False 2026-06-13 22:45:48.791044 | 2026-06-13 22:45:48.791112 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-06-13 22:45:49.073532 | instance | ok 2026-06-13 22:45:49.079519 | 2026-06-13 22:45:49.079591 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-06-13 22:45:52.200111 | instance | Output suppressed because no_log was given 2026-06-13 22:45:52.209483 | 2026-06-13 22:45:52.209548 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-06-13 22:45:52.387382 | instance | ok: "logs" 2026-06-13 22:45:52.387644 | instance | ok: All items complete 2026-06-13 22:45:52.387673 | 2026-06-13 22:45:52.533071 | instance | ok: "artifacts" 2026-06-13 22:45:52.686062 | instance | ok: "docs" 2026-06-13 22:45:52.701679 | 2026-06-13 22:45:52.701749 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-06-13 22:45:52.884767 | instance | changed: "logs" 2026-06-13 22:45:53.034217 | instance | changed: "artifacts" 2026-06-13 22:45:53.179052 | instance | changed: "docs" 2026-06-13 22:45:53.198832 | 2026-06-13 22:45:53.199171 | PLAY RECAP 2026-06-13 22:45:53.199225 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-06-13 22:45:53.199256 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-06-13 22:45:53.199279 | 2026-06-13 22:45:53.343246 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-06-13 22:45:53.355046 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-06-13 22:45:53.997169 | 2026-06-13 22:45:53.997316 | PLAY [all] 2026-06-13 22:45:54.009305 | 2026-06-13 22:45:54.009383 | TASK [setup-uv : Extract archive] 2026-06-13 22:45:56.334631 | instance | changed 2026-06-13 22:45:56.399757 | 2026-06-13 22:45:56.399864 | TASK [setup-uv : Print version] 2026-06-13 22:45:56.756930 | instance | uv 0.8.13 2026-06-13 22:45:56.943390 | instance | ok: Runtime: 0:00:00.014090 2026-06-13 22:45:56.954249 | 2026-06-13 22:45:56.954320 | TASK [Install Ansible collection siblings] 2026-06-13 22:45:57.629854 | instance | ERROR 2026-06-13 22:45:57.630193 | instance | { 2026-06-13 22:45:57.630227 | instance | "log": [ 2026-06-13 22:45:57.630252 | instance | "Sibling collection ansible.netcommon at /home/zuul/src/github.com/ansible-collections/ansible.netcommon", 2026-06-13 22:45:57.630278 | instance | "Sibling collection ansible.posix at /home/zuul/src/github.com/ansible-collections/ansible.posix", 2026-06-13 22:45:57.630297 | instance | "Sibling collection ansible.utils at /home/zuul/src/github.com/ansible-collections/ansible.utils", 2026-06-13 22:45:57.630319 | instance | "Sibling collection community.crypto at /home/zuul/src/github.com/ansible-collections/community.crypto", 2026-06-13 22:45:57.630338 | instance | "Sibling collection community.general at /home/zuul/src/github.com/ansible-collections/community.general", 2026-06-13 22:45:57.630359 | instance | "Sibling collection community.mysql at /home/zuul/src/github.com/ansible-collections/community.mysql", 2026-06-13 22:45:57.630377 | instance | "Sibling collection kubernetes.core at /home/zuul/src/github.com/ansible-collections/kubernetes.core", 2026-06-13 22:45:57.630395 | instance | "Sibling collection vexxhost.ceph at /home/zuul/src/github.com/vexxhost/ansible-collection-ceph", 2026-06-13 22:45:57.630418 | instance | "Sibling collection vexxhost.containers at /home/zuul/src/github.com/vexxhost/ansible-collection-containers", 2026-06-13 22:45:57.630441 | instance | "Sibling collection vexxhost.kubernetes at /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes", 2026-06-13 22:45:57.630463 | instance | "Sibling collection atmosphere.common at /home/zuul/src/github.com/vexxhost/atmosphere.common", 2026-06-13 22:45:57.630486 | instance | "Sibling collection openstack.cloud at /home/zuul/src/opendev.org/openstack/ansible-collections-openstack", 2026-06-13 22:45:57.630508 | instance | "Resolved ansible.netcommon to v8.5.2 for >=1.2.0", 2026-06-13 22:45:57.630528 | instance | "Resolved ansible.posix to 1.6.0 for 1.6.0", 2026-06-13 22:45:57.630546 | instance | "Resolved ansible.utils to v6.0.2 for >=2.9.0,>=3.0.0", 2026-06-13 22:45:57.630564 | instance | "Resolved community.crypto to 2.2.3 for 2.2.3", 2026-06-13 22:45:57.630581 | instance | "Resolved community.general to 7.3.0 for 7.3.0", 2026-06-13 22:45:57.630599 | instance | "Resolved community.mysql to 3.6.0 for 3.6.0", 2026-06-13 22:45:57.630617 | instance | "Resolved kubernetes.core to 2.4.0 for 2.4.0", 2026-06-13 22:45:57.630634 | instance | "Resolved openstack.cloud to 1.7.0 for 1.7.0", 2026-06-13 22:45:57.630654 | instance | "Resolved atmosphere.common to v0.7.0 for >=0.6.0", 2026-06-13 22:45:57.630689 | instance | "Resolved vexxhost.ceph to v4.0.0 for >=3.1.2", 2026-06-13 22:45:57.630707 | instance | "Resolved vexxhost.kubernetes to v3.2.0 for >=2.3.2,>=2.5.0", 2026-06-13 22:45:57.630724 | instance | "Resolved ansible.utils to v6.0.2 for >=2.9.0,>=3.0.0,>=6.0.0", 2026-06-13 22:45:57.630742 | instance | "Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2" 2026-06-13 22:45:57.630759 | instance | ], 2026-06-13 22:45:57.630776 | instance | "msg": "No local release tag for openstack.cloud satisfies 1.7.0,>=2.0.0" 2026-06-13 22:45:57.630794 | instance | } failure 2026-06-13 22:45:57.634314 | 2026-06-13 22:45:57.634365 | PLAY RECAP 2026-06-13 22:45:57.634410 | instance | ok: 2 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2026-06-13 22:45:57.634432 | 2026-06-13 22:45:57.812947 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-06-13 22:45:57.822733 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-06-13 22:45:58.480042 | 2026-06-13 22:45:58.480195 | PLAY [all] 2026-06-13 22:45:58.491488 | 2026-06-13 22:45:58.491565 | TASK [fetch-output : Set log path for multiple nodes] 2026-06-13 22:45:58.537555 | instance | skipping: Conditional result was False 2026-06-13 22:45:58.548701 | 2026-06-13 22:45:58.548815 | TASK [fetch-output : Set log path for single node] 2026-06-13 22:45:58.594485 | instance | ok 2026-06-13 22:45:58.601762 | 2026-06-13 22:45:58.601833 | LOOP [fetch-output : Ensure local output dirs] 2026-06-13 22:45:59.014991 | instance -> localhost | ok: "/var/lib/zuul/builds/3ccc7364c2a94fdcb7a675cb6a1f18ee/work/logs" 2026-06-13 22:45:59.241280 | instance -> localhost | changed: "/var/lib/zuul/builds/3ccc7364c2a94fdcb7a675cb6a1f18ee/work/artifacts" 2026-06-13 22:45:59.633675 | instance -> localhost | changed: "/var/lib/zuul/builds/3ccc7364c2a94fdcb7a675cb6a1f18ee/work/docs" 2026-06-13 22:45:59.651731 | 2026-06-13 22:45:59.651891 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-06-13 22:46:00.288577 | instance | changed: .d..t...... ./ 2026-06-13 22:46:00.288827 | instance | changed: All items complete 2026-06-13 22:46:00.288874 | 2026-06-13 22:46:00.752078 | instance | changed: .d..t...... ./ 2026-06-13 22:46:01.216243 | instance | changed: .d..t...... ./ 2026-06-13 22:46:01.240534 | 2026-06-13 22:46:01.240674 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-06-13 22:46:01.663112 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.007622 2026-06-13 22:46:01.902863 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.007618 2026-06-13 22:46:01.915968 | 2026-06-13 22:46:01.916107 | PLAY [all] 2026-06-13 22:46:01.922450 | 2026-06-13 22:46:01.922789 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-06-13 22:46:02.340242 | instance | changed 2026-06-13 22:46:02.346493 | 2026-06-13 22:46:02.346551 | PLAY RECAP 2026-06-13 22:46:02.346600 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-06-13 22:46:02.346621 | 2026-06-13 22:46:02.504162 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-06-13 22:46:02.517784 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-06-13 22:46:03.167236 | 2026-06-13 22:46:03.167385 | PLAY [localhost] 2026-06-13 22:46:03.177799 | 2026-06-13 22:46:03.177889 | TASK [Generate Zuul manifest] 2026-06-13 22:46:03.198349 | localhost | ok 2026-06-13 22:46:03.215299 | 2026-06-13 22:46:03.215437 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-06-13 22:46:03.632173 | localhost | changed 2026-06-13 22:46:03.645179 | 2026-06-13 22:46:03.645320 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-06-13 22:46:03.675987 | localhost | ok 2026-06-13 22:46:03.684273 | 2026-06-13 22:46:03.684342 | TASK [Upload logs] 2026-06-13 22:46:03.705842 | localhost | ok 2026-06-13 22:46:03.807298 | 2026-06-13 22:46:03.807444 | TASK [Set zuul-log-path fact] 2026-06-13 22:46:03.829618 | localhost | ok 2026-06-13 22:46:03.843843 | 2026-06-13 22:46:03.843909 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-06-13 22:46:03.877041 | localhost | ok 2026-06-13 22:46:03.889919 | 2026-06-13 22:46:03.889995 | TASK [upload-logs : Create log directories] 2026-06-13 22:46:04.285134 | localhost | changed 2026-06-13 22:46:04.291932 | 2026-06-13 22:46:04.292066 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-06-13 22:46:04.962631 | localhost -> localhost | ok: Runtime: 0:00:00.283304 2026-06-13 22:46:04.970693 | 2026-06-13 22:46:04.970783 | TASK [upload-logs : Upload logs to log server] 2026-06-13 22:46:05.394700 | localhost | Output suppressed because no_log was given 2026-06-13 22:46:05.399872 | 2026-06-13 22:46:05.399963 | LOOP [upload-logs : Compress console log and json output] 2026-06-13 22:46:05.448842 | localhost | skipping: Conditional result was False 2026-06-13 22:46:05.456094 | localhost | skipping: Conditional result was False 2026-06-13 22:46:05.468476 | 2026-06-13 22:46:05.468628 | LOOP [upload-logs : Upload compressed console log and json output] 2026-06-13 22:46:05.512749 | localhost | skipping: Conditional result was False 2026-06-13 22:46:05.513291 | 2026-06-13 22:46:05.516486 | localhost | skipping: Conditional result was False 2026-06-13 22:46:05.531771 | 2026-06-13 22:46:05.531935 | LOOP [upload-logs : Upload console log and json output]