2026-06-02 23:41:06.261298 | Job console starting 2026-06-02 23:41:06.293419 | Updating git repos 2026-06-02 23:41:09.111091 | Cloning repos into workspace 2026-06-02 23:41:22.854912 | Restoring repo states 2026-06-02 23:41:30.613820 | Merging changes 2026-06-02 23:41:31.953520 | Checking out repos 2026-06-02 23:41:36.716291 | Preparing playbooks 2026-06-02 23:41:42.157760 | Running Ansible setup 2026-06-02 23:41:46.086948 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-06-02 23:41:46.828097 | 2026-06-02 23:41:46.828897 | PLAY [localhost] 2026-06-02 23:41:46.837474 | 2026-06-02 23:41:46.837552 | TASK [Gathering Facts] 2026-06-02 23:41:47.802839 | localhost | ok 2026-06-02 23:41:47.817158 | 2026-06-02 23:41:47.817259 | TASK [Setup log path fact] 2026-06-02 23:41:47.839818 | localhost | ok 2026-06-02 23:41:47.857072 | 2026-06-02 23:41:47.857144 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-06-02 23:41:47.909403 | localhost | ok 2026-06-02 23:41:47.926758 | 2026-06-02 23:41:47.926862 | TASK [emit-job-header : Print job information] 2026-06-02 23:41:47.971898 | # Job Information 2026-06-02 23:41:47.972118 | Ansible Version: 2.16.18 2026-06-02 23:41:47.972166 | Job: atmosphere-molecule-aio-openvswitch 2026-06-02 23:41:47.972198 | Pipeline: check 2026-06-02 23:41:47.972228 | Executor: 2d72f0692154 2026-06-02 23:41:47.972263 | Triggered by: https://github.com/vexxhost/atmosphere/pull/3985 2026-06-02 23:41:47.972305 | Event ID: 670198d0-5edc-11f1-9184-c74e22c2ee0d 2026-06-02 23:41:47.980178 | 2026-06-02 23:41:47.989539 | LOOP [emit-job-header : Print node information] 2026-06-02 23:41:48.092665 | localhost | ok: 2026-06-02 23:41:48.093071 | localhost | # Node Information 2026-06-02 23:41:48.093127 | localhost | Inventory Hostname: instance 2026-06-02 23:41:48.093160 | localhost | Hostname: np0000178132 2026-06-02 23:41:48.093189 | localhost | Username: zuul 2026-06-02 23:41:48.093222 | localhost | Distro: Ubuntu 22.04 2026-06-02 23:41:48.093250 | localhost | Provider: yul1 2026-06-02 23:41:48.093278 | localhost | Region: ca-ymq-1 2026-06-02 23:41:48.093305 | localhost | Label: ubuntu-jammy-16 2026-06-02 23:41:48.093332 | localhost | Product Name: OpenStack Nova 2026-06-02 23:41:48.093360 | localhost | Interface IP: 199.204.45.113 2026-06-02 23:41:48.114509 | 2026-06-02 23:41:48.114687 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-06-02 23:41:48.614605 | localhost -> localhost | changed 2026-06-02 23:41:48.623782 | 2026-06-02 23:41:48.623882 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-06-02 23:41:49.568646 | localhost -> localhost | changed 2026-06-02 23:41:49.577967 | 2026-06-02 23:41:49.578119 | PLAY [all] 2026-06-02 23:41:49.591430 | 2026-06-02 23:41:49.591642 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-06-02 23:41:49.840024 | instance -> localhost | ok 2026-06-02 23:41:49.849091 | 2026-06-02 23:41:49.849163 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-06-02 23:41:49.885368 | instance | ok 2026-06-02 23:41:49.905096 | instance | included: /var/lib/zuul/builds/7d2e436f150745aea0a457fa3b574897/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-06-02 23:41:49.914956 | 2026-06-02 23:41:49.915046 | TASK [add-build-sshkey : Create Temp SSH key] 2026-06-02 23:41:50.949884 | instance -> localhost | Generating public/private rsa key pair. 2026-06-02 23:41:50.950081 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/7d2e436f150745aea0a457fa3b574897/work/7d2e436f150745aea0a457fa3b574897_id_rsa 2026-06-02 23:41:50.950113 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/7d2e436f150745aea0a457fa3b574897/work/7d2e436f150745aea0a457fa3b574897_id_rsa.pub 2026-06-02 23:41:50.950136 | instance -> localhost | The key fingerprint is: 2026-06-02 23:41:50.950159 | instance -> localhost | SHA256:sTEwQIiU8+f8tnONIw0AmizlvlNkvuwChgoMR4wcy70 zuul-build-sshkey 2026-06-02 23:41:50.950196 | instance -> localhost | The key's randomart image is: 2026-06-02 23:41:50.950219 | instance -> localhost | +---[RSA 3072]----+ 2026-06-02 23:41:50.950244 | instance -> localhost | |o*ooo.o | 2026-06-02 23:41:50.950267 | instance -> localhost | |++B. o | 2026-06-02 23:41:50.950288 | instance -> localhost | |.*=.. + | 2026-06-02 23:41:50.950309 | instance -> localhost | |o+o.+o = | 2026-06-02 23:41:50.950329 | instance -> localhost | |=o E+ . S | 2026-06-02 23:41:50.950350 | instance -> localhost | |+o. oo . | 2026-06-02 23:41:50.950370 | instance -> localhost | |+. + .. o o | 2026-06-02 23:41:50.950392 | instance -> localhost | |. + o = = . | 2026-06-02 23:41:50.950415 | instance -> localhost | | +. ..= . | 2026-06-02 23:41:50.950436 | instance -> localhost | +----[SHA256]-----+ 2026-06-02 23:41:50.950487 | instance -> localhost | ok: Runtime: 0:00:00.572145 2026-06-02 23:41:50.956536 | 2026-06-02 23:41:50.956601 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-06-02 23:41:50.989359 | instance | ok 2026-06-02 23:41:50.999616 | instance | included: /var/lib/zuul/builds/7d2e436f150745aea0a457fa3b574897/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-06-02 23:41:51.006978 | 2026-06-02 23:41:51.007039 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-06-02 23:41:51.021970 | instance | skipping: Conditional result was False 2026-06-02 23:41:51.032506 | 2026-06-02 23:41:51.032571 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-06-02 23:41:51.456525 | instance | changed 2026-06-02 23:41:51.462983 | 2026-06-02 23:41:51.463049 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-06-02 23:41:51.706117 | instance | ok 2026-06-02 23:41:51.711561 | 2026-06-02 23:41:51.711629 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-06-02 23:41:52.181306 | instance | changed 2026-06-02 23:41:52.189793 | 2026-06-02 23:41:52.189864 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-06-02 23:41:52.652371 | instance | changed 2026-06-02 23:41:52.685544 | 2026-06-02 23:41:52.685646 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-06-02 23:41:52.710768 | instance | skipping: Conditional result was False 2026-06-02 23:41:52.729518 | 2026-06-02 23:41:52.729621 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-06-02 23:41:53.089975 | instance -> localhost | changed 2026-06-02 23:41:53.119562 | 2026-06-02 23:41:53.119755 | TASK [add-build-sshkey : Add back temp key] 2026-06-02 23:41:53.417347 | instance -> localhost | Identity added: /var/lib/zuul/builds/7d2e436f150745aea0a457fa3b574897/work/7d2e436f150745aea0a457fa3b574897_id_rsa (zuul-build-sshkey) 2026-06-02 23:41:53.417544 | instance -> localhost | ok: Runtime: 0:00:00.017045 2026-06-02 23:41:53.427917 | 2026-06-02 23:41:53.427998 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-06-02 23:41:53.693289 | instance | ok 2026-06-02 23:41:53.700037 | 2026-06-02 23:41:53.700119 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-06-02 23:41:53.724785 | instance | skipping: Conditional result was False 2026-06-02 23:41:53.736320 | 2026-06-02 23:41:53.736409 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-06-02 23:41:54.019554 | instance | ok 2026-06-02 23:41:54.025399 | 2026-06-02 23:41:54.025468 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-06-02 23:41:57.845949 | instance | Output suppressed because no_log was given 2026-06-02 23:41:57.856632 | 2026-06-02 23:41:57.856717 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-06-02 23:41:58.053581 | instance | ok: "logs" 2026-06-02 23:41:58.303783 | instance | ok: All items complete 2026-06-02 23:41:58.303956 | 2026-06-02 23:41:58.312109 | instance | ok: "artifacts" 2026-06-02 23:41:58.372249 | instance | ok: "docs" 2026-06-02 23:41:58.395315 | 2026-06-02 23:41:58.395526 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-06-02 23:41:58.596354 | instance | changed: "logs" 2026-06-02 23:41:58.773883 | instance | changed: "artifacts" 2026-06-02 23:41:58.932225 | instance | changed: "docs" 2026-06-02 23:41:58.947542 | 2026-06-02 23:41:58.947612 | PLAY RECAP 2026-06-02 23:41:58.947670 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-06-02 23:41:58.947710 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-06-02 23:41:58.947742 | 2026-06-02 23:41:59.121311 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-06-02 23:41:59.204553 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-06-02 23:41:59.921653 | 2026-06-02 23:41:59.976309 | PLAY [all] 2026-06-02 23:42:00.000900 | 2026-06-02 23:42:00.001095 | TASK [setup-uv : Extract archive] 2026-06-02 23:42:02.427616 | instance | changed 2026-06-02 23:42:02.583313 | 2026-06-02 23:42:02.583563 | TASK [setup-uv : Print version] 2026-06-02 23:42:02.969481 | instance | uv 0.8.13 2026-06-02 23:42:03.152614 | instance | ok: Runtime: 0:00:00.012918 2026-06-02 23:42:03.164583 | 2026-06-02 23:42:03.164684 | TASK [Install Ansible collection siblings] 2026-06-02 23:42:04.201036 | instance | ERROR 2026-06-02 23:42:06.210570 | instance | { 2026-06-02 23:42:06.210684 | instance | "log": [ 2026-06-02 23:42:06.210709 | instance | "Sibling collection ansible.netcommon at /home/zuul/src/github.com/ansible-collections/ansible.netcommon", 2026-06-02 23:42:06.210739 | instance | "Sibling collection ansible.posix at /home/zuul/src/github.com/ansible-collections/ansible.posix", 2026-06-02 23:42:06.210759 | instance | "Sibling collection ansible.utils at /home/zuul/src/github.com/ansible-collections/ansible.utils", 2026-06-02 23:42:06.210783 | instance | "Sibling collection community.crypto at /home/zuul/src/github.com/ansible-collections/community.crypto", 2026-06-02 23:42:06.210801 | instance | "Sibling collection community.general at /home/zuul/src/github.com/ansible-collections/community.general", 2026-06-02 23:42:06.210822 | instance | "Sibling collection community.mysql at /home/zuul/src/github.com/ansible-collections/community.mysql", 2026-06-02 23:42:06.210841 | instance | "Sibling collection kubernetes.core at /home/zuul/src/github.com/ansible-collections/kubernetes.core", 2026-06-02 23:42:06.210859 | instance | "Sibling collection vexxhost.ceph at /home/zuul/src/github.com/vexxhost/ansible-collection-ceph", 2026-06-02 23:42:06.210878 | instance | "Sibling collection vexxhost.containers at /home/zuul/src/github.com/vexxhost/ansible-collection-containers", 2026-06-02 23:42:06.210899 | instance | "Sibling collection vexxhost.kubernetes at /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes", 2026-06-02 23:42:06.210917 | instance | "Sibling collection atmosphere.common at /home/zuul/src/github.com/vexxhost/atmosphere.common", 2026-06-02 23:42:06.210935 | instance | "Sibling collection openstack.cloud at /home/zuul/src/opendev.org/openstack/ansible-collections-openstack", 2026-06-02 23:42:06.210953 | instance | "Resolved ansible.netcommon to v8.5.2 for >=1.2.0", 2026-06-02 23:42:06.210972 | instance | "Resolved ansible.posix to 1.6.0 for 1.6.0", 2026-06-02 23:42:06.210990 | instance | "Resolved ansible.utils to v6.0.2 for >=2.9.0,>=3.0.0", 2026-06-02 23:42:06.211008 | instance | "Resolved community.crypto to 2.2.3 for 2.2.3", 2026-06-02 23:42:06.211027 | instance | "Resolved community.general to 7.3.0 for 7.3.0", 2026-06-02 23:42:06.211044 | instance | "Resolved community.mysql to 3.6.0 for 3.6.0", 2026-06-02 23:42:06.211063 | instance | "Resolved kubernetes.core to 2.4.0 for 2.4.0", 2026-06-02 23:42:06.211080 | instance | "Resolved openstack.cloud to 1.7.0 for 1.7.0", 2026-06-02 23:42:06.211099 | instance | "Resolved atmosphere.common to v0.7.0 for >=0.6.0", 2026-06-02 23:42:06.211124 | instance | "Resolved vexxhost.ceph to v4.0.0 for >=3.1.2", 2026-06-02 23:42:06.211142 | instance | "Resolved vexxhost.kubernetes to v3.1.0 for >=2.3.2,>=2.5.0", 2026-06-02 23:42:06.211161 | instance | "Resolved ansible.utils to v6.0.2 for >=2.9.0,>=3.0.0,>=6.0.0", 2026-06-02 23:42:06.211178 | instance | "Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2" 2026-06-02 23:42:06.211196 | instance | ], 2026-06-02 23:42:06.211214 | instance | "msg": "No local release tag for openstack.cloud satisfies 1.7.0,>=2.0.0" 2026-06-02 23:42:06.211232 | instance | } failure 2026-06-02 23:42:06.218504 | 2026-06-02 23:42:06.218562 | PLAY RECAP 2026-06-02 23:42:06.218611 | instance | ok: 2 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2026-06-02 23:42:06.218632 | 2026-06-02 23:42:06.425689 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-06-02 23:42:06.452202 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-06-02 23:42:07.140027 | 2026-06-02 23:42:07.140154 | PLAY [all] 2026-06-02 23:42:07.151936 | 2026-06-02 23:42:07.152049 | TASK [fetch-output : Set log path for multiple nodes] 2026-06-02 23:42:07.203249 | instance | skipping: Conditional result was False 2026-06-02 23:42:07.215242 | 2026-06-02 23:42:07.215447 | TASK [fetch-output : Set log path for single node] 2026-06-02 23:42:07.257508 | instance | ok 2026-06-02 23:42:07.264135 | 2026-06-02 23:42:07.264214 | LOOP [fetch-output : Ensure local output dirs] 2026-06-02 23:42:09.193128 | instance -> localhost | ok: "/var/lib/zuul/builds/7d2e436f150745aea0a457fa3b574897/work/logs" 2026-06-02 23:42:09.468509 | instance -> localhost | changed: "/var/lib/zuul/builds/7d2e436f150745aea0a457fa3b574897/work/artifacts" 2026-06-02 23:42:09.713516 | instance -> localhost | changed: "/var/lib/zuul/builds/7d2e436f150745aea0a457fa3b574897/work/docs" 2026-06-02 23:42:09.723965 | 2026-06-02 23:42:09.724118 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-06-02 23:42:10.408596 | instance | ok 2026-06-02 23:42:10.632917 | instance | changed: All items complete 2026-06-02 23:42:10.633136 | 2026-06-02 23:42:10.857616 | instance | changed: .d..t...... ./ 2026-06-02 23:42:11.370993 | instance | changed: .d..t...... ./ 2026-06-02 23:42:11.394090 | 2026-06-02 23:42:11.394235 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-06-02 23:42:11.828438 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.009668 2026-06-02 23:42:12.065512 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.008084 2026-06-02 23:42:12.081413 | 2026-06-02 23:42:12.081563 | PLAY [all] 2026-06-02 23:42:12.089724 | 2026-06-02 23:42:12.089813 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-06-02 23:42:12.510394 | instance | changed 2026-06-02 23:42:12.544505 | 2026-06-02 23:42:12.544574 | PLAY RECAP 2026-06-02 23:42:12.544636 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-06-02 23:42:12.544665 | 2026-06-02 23:42:12.677841 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-06-02 23:42:12.694229 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-06-02 23:42:13.365413 | 2026-06-02 23:42:13.393508 | PLAY [localhost] 2026-06-02 23:42:13.410196 | 2026-06-02 23:42:13.410314 | TASK [Generate Zuul manifest] 2026-06-02 23:42:13.434755 | localhost | ok 2026-06-02 23:42:13.452702 | 2026-06-02 23:42:13.452866 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-06-02 23:42:13.909216 | localhost | changed 2026-06-02 23:42:13.919739 | 2026-06-02 23:42:13.919875 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-06-02 23:42:13.971921 | localhost | ok 2026-06-02 23:42:13.981259 | 2026-06-02 23:42:13.981332 | TASK [Upload logs] 2026-06-02 23:42:14.002567 | localhost | ok 2026-06-02 23:42:14.132438 | 2026-06-02 23:42:14.132597 | TASK [Set zuul-log-path fact] 2026-06-02 23:42:14.155281 | localhost | ok 2026-06-02 23:42:14.170654 | 2026-06-02 23:42:14.170778 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-06-02 23:42:14.203230 | localhost | ok 2026-06-02 23:42:14.213385 | 2026-06-02 23:42:14.213474 | TASK [upload-logs : Create log directories] 2026-06-02 23:42:14.719356 | localhost | changed 2026-06-02 23:42:14.726132 | 2026-06-02 23:42:14.726244 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-06-02 23:42:15.176495 | localhost -> localhost | ok: Runtime: 0:00:00.005889 2026-06-02 23:42:15.181969 | 2026-06-02 23:42:15.182044 | TASK [upload-logs : Upload logs to log server] 2026-06-02 23:42:16.077981 | localhost | Output suppressed because no_log was given 2026-06-02 23:42:16.082280 | 2026-06-02 23:42:16.082356 | LOOP [upload-logs : Compress console log and json output] 2026-06-02 23:42:16.146496 | localhost | skipping: Conditional result was False 2026-06-02 23:42:16.159154 | localhost | skipping: Conditional result was False 2026-06-02 23:42:16.181491 | 2026-06-02 23:42:16.181847 | LOOP [upload-logs : Upload compressed console log and json output] 2026-06-02 23:42:16.228710 | localhost | skipping: Conditional result was False 2026-06-02 23:42:16.229013 | 2026-06-02 23:42:16.233113 | localhost | skipping: Conditional result was False 2026-06-02 23:42:16.246658 | 2026-06-02 23:42:16.246834 | LOOP [upload-logs : Upload console log and json output]