2026-05-26 05:41:17.894492 | Job console starting 2026-05-26 05:41:18.027510 | Updating git repos 2026-05-26 05:41:23.630764 | Cloning repos into workspace 2026-05-26 05:41:41.945770 | Restoring repo states 2026-05-26 05:41:46.989002 | Merging changes 2026-05-26 05:41:49.295419 | Checking out repos 2026-05-26 05:41:52.903394 | Preparing playbooks 2026-05-26 05:42:03.285082 | Running Ansible setup 2026-05-26 05:42:07.148077 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-05-26 05:42:07.812350 | 2026-05-26 05:42:08.191946 | PLAY [localhost] 2026-05-26 05:42:08.203880 | 2026-05-26 05:42:08.203992 | TASK [Gathering Facts] 2026-05-26 05:42:09.162987 | localhost | ok 2026-05-26 05:42:09.173035 | 2026-05-26 05:42:09.173132 | TASK [Setup log path fact] 2026-05-26 05:42:09.191943 | localhost | ok 2026-05-26 05:42:09.204903 | 2026-05-26 05:42:09.204986 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-26 05:42:09.234054 | localhost | ok 2026-05-26 05:42:09.241680 | 2026-05-26 05:42:09.241848 | TASK [emit-job-header : Print job information] 2026-05-26 05:42:09.285177 | # Job Information 2026-05-26 05:42:09.285356 | Ansible Version: 2.16.18 2026-05-26 05:42:09.285404 | Job: atmosphere-molecule-aio-openvswitch 2026-05-26 05:42:09.285437 | Pipeline: check 2026-05-26 05:42:09.285468 | Executor: 2d72f0692154 2026-05-26 05:42:09.285497 | Triggered by: https://github.com/vexxhost/atmosphere/pull/3991 2026-05-26 05:42:09.285531 | Event ID: 4af667a0-58c5-11f1-90f3-935b3405b672 2026-05-26 05:42:09.289611 | 2026-05-26 05:42:09.289702 | LOOP [emit-job-header : Print node information] 2026-05-26 05:42:09.387653 | localhost | ok: 2026-05-26 05:42:09.387854 | localhost | # Node Information 2026-05-26 05:42:09.387897 | localhost | Inventory Hostname: instance 2026-05-26 05:42:09.387929 | localhost | Hostname: np0000176433 2026-05-26 05:42:09.387959 | localhost | Username: zuul 2026-05-26 05:42:09.387993 | localhost | Distro: Ubuntu 22.04 2026-05-26 05:42:09.388024 | localhost | Provider: yul1 2026-05-26 05:42:09.388053 | localhost | Region: ca-ymq-1 2026-05-26 05:42:09.388082 | localhost | Label: ubuntu-jammy-16 2026-05-26 05:42:09.388109 | localhost | Product Name: OpenStack Nova 2026-05-26 05:42:09.388137 | localhost | Interface IP: 199.19.213.27 2026-05-26 05:42:09.397845 | 2026-05-26 05:42:09.398383 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-05-26 05:42:09.818250 | localhost -> localhost | changed 2026-05-26 05:42:09.824066 | 2026-05-26 05:42:09.824156 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-05-26 05:42:10.849890 | localhost -> localhost | changed 2026-05-26 05:42:10.860509 | 2026-05-26 05:42:10.860570 | PLAY [all] 2026-05-26 05:42:10.869721 | 2026-05-26 05:42:10.869794 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-05-26 05:42:11.101958 | instance -> localhost | ok 2026-05-26 05:42:11.113786 | 2026-05-26 05:42:11.113861 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-05-26 05:42:11.149023 | instance | ok 2026-05-26 05:42:11.165037 | instance | included: /var/lib/zuul/builds/b07ea374cf6241ea951b9349b5e40847/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-05-26 05:42:11.172186 | 2026-05-26 05:42:11.172263 | TASK [add-build-sshkey : Create Temp SSH key] 2026-05-26 05:42:13.110989 | instance -> localhost | Generating public/private rsa key pair. 2026-05-26 05:42:13.111168 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/b07ea374cf6241ea951b9349b5e40847/work/b07ea374cf6241ea951b9349b5e40847_id_rsa 2026-05-26 05:42:13.111201 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/b07ea374cf6241ea951b9349b5e40847/work/b07ea374cf6241ea951b9349b5e40847_id_rsa.pub 2026-05-26 05:42:13.111225 | instance -> localhost | The key fingerprint is: 2026-05-26 05:42:13.111247 | instance -> localhost | SHA256:S0fmL/Ee56MI+wQR+WXO7R/6ZPbI9C+90aow7rUMxpY zuul-build-sshkey 2026-05-26 05:42:13.111278 | instance -> localhost | The key's randomart image is: 2026-05-26 05:42:13.111299 | instance -> localhost | +---[RSA 3072]----+ 2026-05-26 05:42:13.111324 | instance -> localhost | | .. | 2026-05-26 05:42:13.111345 | instance -> localhost | | .. o | 2026-05-26 05:42:13.111366 | instance -> localhost | | ..o= . | 2026-05-26 05:42:13.111386 | instance -> localhost | | =. o . | 2026-05-26 05:42:13.111406 | instance -> localhost | | S + . | 2026-05-26 05:42:13.111426 | instance -> localhost | | . = = ...| 2026-05-26 05:42:13.111446 | instance -> localhost | | o E = +Bo| 2026-05-26 05:42:13.111465 | instance -> localhost | | B X O*+=| 2026-05-26 05:42:13.111488 | instance -> localhost | | .o= *o*=*| 2026-05-26 05:42:13.111508 | instance -> localhost | +----[SHA256]-----+ 2026-05-26 05:42:13.111558 | instance -> localhost | ok: Runtime: 0:00:01.452411 2026-05-26 05:42:13.117697 | 2026-05-26 05:42:13.117942 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-05-26 05:42:13.143161 | instance | ok 2026-05-26 05:42:13.151617 | instance | included: /var/lib/zuul/builds/b07ea374cf6241ea951b9349b5e40847/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-05-26 05:42:13.158946 | 2026-05-26 05:42:13.159011 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-05-26 05:42:13.184285 | instance | skipping: Conditional result was False 2026-05-26 05:42:13.193700 | 2026-05-26 05:42:13.193766 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-05-26 05:42:13.640563 | instance | changed 2026-05-26 05:42:13.648711 | 2026-05-26 05:42:13.648783 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-05-26 05:42:13.865225 | instance | ok 2026-05-26 05:42:13.870566 | 2026-05-26 05:42:13.870640 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-05-26 05:42:14.344455 | instance | changed 2026-05-26 05:42:14.350013 | 2026-05-26 05:42:14.350086 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-05-26 05:42:14.804783 | instance | changed 2026-05-26 05:42:14.880288 | 2026-05-26 05:42:14.880484 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-05-26 05:42:14.907365 | instance | skipping: Conditional result was False 2026-05-26 05:42:14.914964 | 2026-05-26 05:42:14.915080 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-05-26 05:42:15.277574 | instance -> localhost | changed 2026-05-26 05:42:15.293657 | 2026-05-26 05:42:15.293829 | TASK [add-build-sshkey : Add back temp key] 2026-05-26 05:42:18.124326 | instance -> localhost | Identity added: /var/lib/zuul/builds/b07ea374cf6241ea951b9349b5e40847/work/b07ea374cf6241ea951b9349b5e40847_id_rsa (zuul-build-sshkey) 2026-05-26 05:42:18.124599 | instance -> localhost | ok: Runtime: 0:00:00.424410 2026-05-26 05:42:18.130364 | 2026-05-26 05:42:18.130437 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-05-26 05:42:19.509504 | instance | ok 2026-05-26 05:42:19.773769 | 2026-05-26 05:42:19.773918 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-05-26 05:42:19.800795 | instance | skipping: Conditional result was False 2026-05-26 05:42:19.816502 | 2026-05-26 05:42:19.816627 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-05-26 05:42:20.121991 | instance | ok 2026-05-26 05:42:20.128536 | 2026-05-26 05:42:20.128605 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-05-26 05:42:24.347044 | instance | Output suppressed because no_log was given 2026-05-26 05:42:24.388156 | 2026-05-26 05:42:24.388241 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-05-26 05:42:24.622955 | instance | ok: "logs" 2026-05-26 05:42:24.623231 | instance | ok: All items complete 2026-05-26 05:42:24.623262 | 2026-05-26 05:42:24.779390 | instance | ok: "artifacts" 2026-05-26 05:42:24.935069 | instance | ok: "docs" 2026-05-26 05:42:24.999671 | 2026-05-26 05:42:24.999826 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-05-26 05:42:25.198693 | instance | changed: "logs" 2026-05-26 05:42:25.344175 | instance | changed: "artifacts" 2026-05-26 05:42:25.514125 | instance | changed: "docs" 2026-05-26 05:42:25.528877 | 2026-05-26 05:42:25.529033 | PLAY RECAP 2026-05-26 05:42:25.529093 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-05-26 05:42:25.529125 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-05-26 05:42:25.529148 | 2026-05-26 05:42:25.765428 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-05-26 05:42:25.784202 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-05-26 05:42:26.536014 | 2026-05-26 05:42:26.564748 | PLAY [all] 2026-05-26 05:42:26.580583 | 2026-05-26 05:42:26.580684 | TASK [setup-uv : Extract archive] 2026-05-26 05:42:28.865796 | instance | changed 2026-05-26 05:42:28.880698 | 2026-05-26 05:42:28.880879 | TASK [setup-uv : Print version] 2026-05-26 05:42:29.225835 | instance | uv 0.8.13 2026-05-26 05:42:29.429306 | instance | ok: Runtime: 0:00:00.012740 2026-05-26 05:42:29.453110 | 2026-05-26 05:42:29.453385 | TASK [Install Ansible collection siblings] 2026-05-26 05:42:30.121373 | instance | ERROR 2026-05-26 05:42:30.121704 | instance | { 2026-05-26 05:42:30.121752 | instance | "log": [ 2026-05-26 05:42:30.121785 | instance | "Sibling collection ansible.netcommon at /home/zuul/src/github.com/ansible-collections/ansible.netcommon", 2026-05-26 05:42:30.121820 | instance | "Sibling collection ansible.posix at /home/zuul/src/github.com/ansible-collections/ansible.posix", 2026-05-26 05:42:30.121848 | instance | "Sibling collection ansible.utils at /home/zuul/src/github.com/ansible-collections/ansible.utils", 2026-05-26 05:42:30.121879 | instance | "Sibling collection community.crypto at /home/zuul/src/github.com/ansible-collections/community.crypto", 2026-05-26 05:42:30.121906 | instance | "Sibling collection community.general at /home/zuul/src/github.com/ansible-collections/community.general", 2026-05-26 05:42:30.121935 | instance | "Sibling collection community.mysql at /home/zuul/src/github.com/ansible-collections/community.mysql", 2026-05-26 05:42:30.121961 | instance | "Sibling collection kubernetes.core at /home/zuul/src/github.com/ansible-collections/kubernetes.core", 2026-05-26 05:42:30.121987 | instance | "Sibling collection vexxhost.ceph at /home/zuul/src/github.com/vexxhost/ansible-collection-ceph", 2026-05-26 05:42:30.122012 | instance | "Sibling collection vexxhost.containers at /home/zuul/src/github.com/vexxhost/ansible-collection-containers", 2026-05-26 05:42:30.122038 | instance | "Sibling collection vexxhost.kubernetes at /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes", 2026-05-26 05:42:30.122062 | instance | "Sibling collection atmosphere.common at /home/zuul/src/github.com/vexxhost/atmosphere.common", 2026-05-26 05:42:30.122096 | instance | "Sibling collection openstack.cloud at /home/zuul/src/opendev.org/openstack/ansible-collections-openstack", 2026-05-26 05:42:30.122128 | instance | "Resolved ansible.netcommon to v8.5.2 for >=1.2.0", 2026-05-26 05:42:30.122156 | instance | "Resolved ansible.posix to 1.6.0 for 1.6.0", 2026-05-26 05:42:30.122181 | instance | "Resolved ansible.utils to v6.0.2 for >=2.9.0,>=3.0.0", 2026-05-26 05:42:30.122206 | instance | "Resolved community.crypto to 2.2.3 for 2.2.3", 2026-05-26 05:42:30.122231 | instance | "Resolved community.general to 7.3.0 for 7.3.0", 2026-05-26 05:42:30.122255 | instance | "Resolved community.mysql to 3.6.0 for 3.6.0", 2026-05-26 05:42:30.122280 | instance | "Resolved kubernetes.core to 2.4.0 for 2.4.0", 2026-05-26 05:42:30.122305 | instance | "Resolved openstack.cloud to 1.7.0 for 1.7.0", 2026-05-26 05:42:30.122332 | instance | "Resolved atmosphere.common to v0.7.0 for >=0.6.0", 2026-05-26 05:42:30.122366 | instance | "Resolved vexxhost.ceph to v4.0.0 for >=3.1.2", 2026-05-26 05:42:30.122394 | instance | "Resolved vexxhost.kubernetes to v3.1.0 for >=2.3.2,>=2.5.0", 2026-05-26 05:42:30.122420 | instance | "Resolved ansible.utils to v6.0.2 for >=2.9.0,>=3.0.0,>=6.0.0", 2026-05-26 05:42:30.122444 | instance | "Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2" 2026-05-26 05:42:30.122472 | instance | ], 2026-05-26 05:42:30.122508 | instance | "msg": "No local release tag for openstack.cloud satisfies 1.7.0,>=2.0.0" 2026-05-26 05:42:30.122534 | instance | } failure 2026-05-26 05:42:30.136429 | 2026-05-26 05:42:30.136515 | PLAY RECAP 2026-05-26 05:42:30.136593 | instance | ok: 2 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2026-05-26 05:42:30.136620 | 2026-05-26 05:42:30.285314 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-05-26 05:42:30.298903 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-05-26 05:42:31.068150 | 2026-05-26 05:42:31.068517 | PLAY [all] 2026-05-26 05:42:31.085326 | 2026-05-26 05:42:31.085424 | TASK [fetch-output : Set log path for multiple nodes] 2026-05-26 05:42:31.133128 | instance | skipping: Conditional result was False 2026-05-26 05:42:31.145095 | 2026-05-26 05:42:31.145207 | TASK [fetch-output : Set log path for single node] 2026-05-26 05:42:31.188750 | instance | ok 2026-05-26 05:42:31.195784 | 2026-05-26 05:42:31.195858 | LOOP [fetch-output : Ensure local output dirs] 2026-05-26 05:42:31.689649 | instance -> localhost | ok: "/var/lib/zuul/builds/b07ea374cf6241ea951b9349b5e40847/work/logs" 2026-05-26 05:42:31.919901 | instance -> localhost | changed: "/var/lib/zuul/builds/b07ea374cf6241ea951b9349b5e40847/work/artifacts" 2026-05-26 05:42:32.136399 | instance -> localhost | changed: "/var/lib/zuul/builds/b07ea374cf6241ea951b9349b5e40847/work/docs" 2026-05-26 05:42:32.150384 | 2026-05-26 05:42:32.150975 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-05-26 05:42:32.809397 | instance | ok 2026-05-26 05:42:32.938176 | instance | changed: All items complete 2026-05-26 05:42:32.938335 | 2026-05-26 05:42:33.262903 | instance | changed: .d..t...... ./ 2026-05-26 05:42:33.723146 | instance | changed: .d..t...... ./ 2026-05-26 05:42:33.752836 | 2026-05-26 05:42:33.753159 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-05-26 05:42:34.172251 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.007416 2026-05-26 05:42:34.497835 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.010024 2026-05-26 05:42:34.519992 | 2026-05-26 05:42:34.520105 | PLAY [all] 2026-05-26 05:42:34.527465 | 2026-05-26 05:42:34.527559 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-05-26 05:42:35.003767 | instance | changed 2026-05-26 05:42:35.011313 | 2026-05-26 05:42:35.011391 | PLAY RECAP 2026-05-26 05:42:35.011449 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-05-26 05:42:35.011480 | 2026-05-26 05:42:35.169459 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-05-26 05:42:35.192417 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-05-26 05:42:35.830001 | 2026-05-26 05:42:35.830121 | PLAY [localhost] 2026-05-26 05:42:35.871604 | 2026-05-26 05:42:35.871790 | TASK [Generate Zuul manifest] 2026-05-26 05:42:35.893400 | localhost | ok 2026-05-26 05:42:35.910056 | 2026-05-26 05:42:35.910150 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-05-26 05:42:36.304894 | localhost | changed 2026-05-26 05:42:36.316375 | 2026-05-26 05:42:36.316448 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-05-26 05:42:36.528183 | localhost | ok 2026-05-26 05:42:36.539034 | 2026-05-26 05:42:36.539189 | TASK [Upload logs] 2026-05-26 05:42:36.570826 | localhost | ok 2026-05-26 05:42:36.702912 | 2026-05-26 05:42:36.703043 | TASK [Set zuul-log-path fact] 2026-05-26 05:42:36.725457 | localhost | ok 2026-05-26 05:42:36.736810 | 2026-05-26 05:42:36.736906 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-26 05:42:36.766265 | localhost | ok 2026-05-26 05:42:36.775237 | 2026-05-26 05:42:36.775311 | TASK [upload-logs : Create log directories] 2026-05-26 05:42:37.158868 | localhost | changed 2026-05-26 05:42:37.164162 | 2026-05-26 05:42:37.164275 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-05-26 05:42:37.532477 | localhost -> localhost | ok: Runtime: 0:00:00.005894 2026-05-26 05:42:37.537507 | 2026-05-26 05:42:37.537606 | TASK [upload-logs : Upload logs to log server] 2026-05-26 05:42:38.132891 | localhost | Output suppressed because no_log was given 2026-05-26 05:42:38.144518 | 2026-05-26 05:42:38.145145 | LOOP [upload-logs : Compress console log and json output] 2026-05-26 05:42:38.202783 | localhost | skipping: Conditional result was False 2026-05-26 05:42:38.216931 | localhost | skipping: Conditional result was False 2026-05-26 05:42:38.233733 | 2026-05-26 05:42:38.233905 | LOOP [upload-logs : Upload compressed console log and json output] 2026-05-26 05:42:38.278141 | localhost | skipping: Conditional result was False 2026-05-26 05:42:38.278551 | 2026-05-26 05:42:38.281925 | localhost | skipping: Conditional result was False 2026-05-26 05:42:38.299085 | 2026-05-26 05:42:38.299284 | LOOP [upload-logs : Upload console log and json output]