2026-05-25 00:33:19.317989 | Job console starting 2026-05-25 00:33:19.376719 | Updating git repos 2026-05-25 00:33:24.126883 | Cloning repos into workspace 2026-05-25 00:33:36.215718 | Restoring repo states 2026-05-25 00:33:40.661634 | Merging changes 2026-05-25 00:33:41.408604 | Checking out repos 2026-05-25 00:33:43.088570 | Preparing playbooks 2026-05-25 00:33:47.032502 | Running Ansible setup 2026-05-25 00:33:50.508819 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-05-25 00:33:51.113474 | 2026-05-25 00:33:51.113618 | PLAY [localhost] 2026-05-25 00:33:51.121263 | 2026-05-25 00:33:51.121332 | TASK [Gathering Facts] 2026-05-25 00:33:52.009308 | localhost | ok 2026-05-25 00:33:52.021196 | 2026-05-25 00:33:52.021307 | TASK [Setup log path fact] 2026-05-25 00:33:52.040175 | localhost | ok 2026-05-25 00:33:52.050727 | 2026-05-25 00:33:52.050794 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-25 00:33:52.081544 | localhost | ok 2026-05-25 00:33:52.091293 | 2026-05-25 00:33:52.091362 | TASK [emit-job-header : Print job information] 2026-05-25 00:33:52.131803 | # Job Information 2026-05-25 00:33:52.132028 | Ansible Version: 2.16.18 2026-05-25 00:33:52.132097 | Job: atmosphere-molecule-csi-rbd 2026-05-25 00:33:52.132146 | Pipeline: check 2026-05-25 00:33:52.132189 | Executor: 2d72f0692154 2026-05-25 00:33:52.132233 | Triggered by: https://github.com/vexxhost/atmosphere/pull/3985 2026-05-25 00:33:52.132283 | Event ID: 32078110-57d1-11f1-9f7b-b92460b429e0 2026-05-25 00:33:52.135701 | 2026-05-25 00:33:52.135769 | LOOP [emit-job-header : Print node information] 2026-05-25 00:33:52.227006 | localhost | ok: 2026-05-25 00:33:52.227275 | localhost | # Node Information 2026-05-25 00:33:52.227304 | localhost | Inventory Hostname: instance 2026-05-25 00:33:52.227326 | localhost | Hostname: np0000176247 2026-05-25 00:33:52.227346 | localhost | Username: zuul 2026-05-25 00:33:52.227368 | localhost | Distro: Ubuntu 22.04 2026-05-25 00:33:52.227389 | localhost | Provider: yul1 2026-05-25 00:33:52.227411 | localhost | Region: ca-ymq-1 2026-05-25 00:33:52.227431 | localhost | Label: ubuntu-jammy 2026-05-25 00:33:52.227449 | localhost | Product Name: OpenStack Nova 2026-05-25 00:33:52.227468 | localhost | Interface IP: 199.204.45.223 2026-05-25 00:33:52.239871 | 2026-05-25 00:33:52.240000 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-05-25 00:33:52.639593 | localhost -> localhost | changed 2026-05-25 00:33:52.647162 | 2026-05-25 00:33:52.647265 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-05-25 00:33:53.491867 | localhost -> localhost | changed 2026-05-25 00:33:53.503110 | 2026-05-25 00:33:53.503170 | PLAY [all] 2026-05-25 00:33:53.517469 | 2026-05-25 00:33:53.530722 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-05-25 00:33:53.780258 | instance -> localhost | ok 2026-05-25 00:33:53.790858 | 2026-05-25 00:33:53.791002 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-05-25 00:33:53.824823 | instance | ok 2026-05-25 00:33:53.845691 | instance | included: /var/lib/zuul/builds/22a2345c8c91405a8be6bc2d204ccec7/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-05-25 00:33:53.855833 | 2026-05-25 00:33:53.855898 | TASK [add-build-sshkey : Create Temp SSH key] 2026-05-25 00:33:55.318332 | instance -> localhost | Generating public/private rsa key pair. 2026-05-25 00:33:55.318547 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/22a2345c8c91405a8be6bc2d204ccec7/work/22a2345c8c91405a8be6bc2d204ccec7_id_rsa 2026-05-25 00:33:55.318588 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/22a2345c8c91405a8be6bc2d204ccec7/work/22a2345c8c91405a8be6bc2d204ccec7_id_rsa.pub 2026-05-25 00:33:55.318619 | instance -> localhost | The key fingerprint is: 2026-05-25 00:33:55.318649 | instance -> localhost | SHA256:5UUqqAR890YE/1KhLqYblMrXqv1sDzP6QHNUKNqyF+U zuul-build-sshkey 2026-05-25 00:33:55.318691 | instance -> localhost | The key's randomart image is: 2026-05-25 00:33:55.318720 | instance -> localhost | +---[RSA 3072]----+ 2026-05-25 00:33:55.318758 | instance -> localhost | | .. .+o . . | 2026-05-25 00:33:55.318804 | instance -> localhost | | ..o ++.. + | 2026-05-25 00:33:55.318834 | instance -> localhost | | +.=oo+ + . | 2026-05-25 00:33:55.318876 | instance -> localhost | | o.o+E.o* . | 2026-05-25 00:33:55.318907 | instance -> localhost | | o*.+.S o | 2026-05-25 00:33:55.318934 | instance -> localhost | | ..+.* . . | 2026-05-25 00:33:55.318961 | instance -> localhost | | o.= = | 2026-05-25 00:33:55.318988 | instance -> localhost | | o B.+ | 2026-05-25 00:33:55.319019 | instance -> localhost | | ..=++.. | 2026-05-25 00:33:55.319047 | instance -> localhost | +----[SHA256]-----+ 2026-05-25 00:33:55.319108 | instance -> localhost | ok: Runtime: 0:00:01.049495 2026-05-25 00:33:55.326693 | 2026-05-25 00:33:55.326761 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-05-25 00:33:55.355669 | instance | ok 2026-05-25 00:33:55.364147 | instance | included: /var/lib/zuul/builds/22a2345c8c91405a8be6bc2d204ccec7/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-05-25 00:33:55.371482 | 2026-05-25 00:33:55.371544 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-05-25 00:33:55.395683 | instance | skipping: Conditional result was False 2026-05-25 00:33:55.400974 | 2026-05-25 00:33:55.401042 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-05-25 00:33:55.848796 | instance | changed 2026-05-25 00:33:55.855266 | 2026-05-25 00:33:55.855333 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-05-25 00:33:56.037546 | instance | ok 2026-05-25 00:33:56.044296 | 2026-05-25 00:33:56.044365 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-05-25 00:33:56.513866 | instance | changed 2026-05-25 00:33:56.521524 | 2026-05-25 00:33:56.521608 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-05-25 00:33:56.970971 | instance | changed 2026-05-25 00:33:56.978429 | 2026-05-25 00:33:56.978491 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-05-25 00:33:57.002562 | instance | skipping: Conditional result was False 2026-05-25 00:33:57.015414 | 2026-05-25 00:33:57.015503 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-05-25 00:33:57.424991 | instance -> localhost | changed 2026-05-25 00:33:57.439292 | 2026-05-25 00:33:57.444508 | TASK [add-build-sshkey : Add back temp key] 2026-05-25 00:33:57.750108 | instance -> localhost | Identity added: /var/lib/zuul/builds/22a2345c8c91405a8be6bc2d204ccec7/work/22a2345c8c91405a8be6bc2d204ccec7_id_rsa (zuul-build-sshkey) 2026-05-25 00:33:57.763560 | instance -> localhost | ok: Runtime: 0:00:00.014868 2026-05-25 00:33:57.773305 | 2026-05-25 00:33:57.773369 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-05-25 00:33:58.106591 | instance | ok 2026-05-25 00:33:58.111236 | 2026-05-25 00:33:58.111305 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-05-25 00:33:58.137356 | instance | skipping: Conditional result was False 2026-05-25 00:33:58.151867 | 2026-05-25 00:33:58.151962 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-05-25 00:33:58.492056 | instance | ok 2026-05-25 00:33:58.501477 | 2026-05-25 00:33:58.501593 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-05-25 00:34:01.942501 | instance | Output suppressed because no_log was given 2026-05-25 00:34:01.951644 | 2026-05-25 00:34:01.951712 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-05-25 00:34:02.150745 | instance | ok: "logs" 2026-05-25 00:34:02.151044 | instance | ok: All items complete 2026-05-25 00:34:02.151073 | 2026-05-25 00:34:02.317166 | instance | ok: "artifacts" 2026-05-25 00:34:02.489009 | instance | ok: "docs" 2026-05-25 00:34:02.509549 | 2026-05-25 00:34:02.509857 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-05-25 00:34:02.708815 | instance | changed: "logs" 2026-05-25 00:34:02.880290 | instance | changed: "artifacts" 2026-05-25 00:34:03.032974 | instance | changed: "docs" 2026-05-25 00:34:03.081991 | 2026-05-25 00:34:03.082084 | PLAY RECAP 2026-05-25 00:34:03.082129 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-05-25 00:34:03.082157 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-05-25 00:34:03.082177 | 2026-05-25 00:34:03.234443 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-05-25 00:34:03.237736 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-05-25 00:34:03.866377 | 2026-05-25 00:34:03.866971 | PLAY [all] 2026-05-25 00:34:03.878987 | 2026-05-25 00:34:03.879072 | TASK [setup-uv : Extract archive] 2026-05-25 00:34:06.099853 | instance | changed 2026-05-25 00:34:06.107740 | 2026-05-25 00:34:06.107825 | TASK [setup-uv : Print version] 2026-05-25 00:34:06.411105 | instance | uv 0.8.13 2026-05-25 00:34:06.650082 | instance | ok: Runtime: 0:00:00.012990 2026-05-25 00:34:06.659506 | 2026-05-25 00:34:06.659579 | TASK [Install Ansible collection siblings] 2026-05-25 00:34:07.362562 | instance | ERROR 2026-05-25 00:34:07.362875 | instance | { 2026-05-25 00:34:07.362918 | instance | "log": [ 2026-05-25 00:34:07.362948 | instance | "Sibling collection ansible.netcommon at /home/zuul/src/github.com/ansible-collections/ansible.netcommon", 2026-05-25 00:34:07.362976 | instance | "Sibling collection ansible.posix at /home/zuul/src/github.com/ansible-collections/ansible.posix", 2026-05-25 00:34:07.363002 | instance | "Sibling collection ansible.utils at /home/zuul/src/github.com/ansible-collections/ansible.utils", 2026-05-25 00:34:07.363033 | instance | "Sibling collection community.crypto at /home/zuul/src/github.com/ansible-collections/community.crypto", 2026-05-25 00:34:07.363059 | instance | "Sibling collection community.general at /home/zuul/src/github.com/ansible-collections/community.general", 2026-05-25 00:34:07.363086 | instance | "Sibling collection community.mysql at /home/zuul/src/github.com/ansible-collections/community.mysql", 2026-05-25 00:34:07.363111 | instance | "Sibling collection kubernetes.core at /home/zuul/src/github.com/ansible-collections/kubernetes.core", 2026-05-25 00:34:07.363136 | instance | "Sibling collection vexxhost.ceph at /home/zuul/src/github.com/vexxhost/ansible-collection-ceph", 2026-05-25 00:34:07.363160 | instance | "Sibling collection vexxhost.containers at /home/zuul/src/github.com/vexxhost/ansible-collection-containers", 2026-05-25 00:34:07.363184 | instance | "Sibling collection vexxhost.kubernetes at /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes", 2026-05-25 00:34:07.363208 | instance | "Sibling collection atmosphere.common at /home/zuul/src/github.com/vexxhost/atmosphere.common", 2026-05-25 00:34:07.363231 | instance | "Sibling collection openstack.cloud at /home/zuul/src/opendev.org/openstack/ansible-collections-openstack", 2026-05-25 00:34:07.363255 | instance | "Resolved ansible.netcommon to v8.5.2 for >=1.2.0", 2026-05-25 00:34:07.363280 | instance | "Resolved ansible.posix to 1.6.0 for 1.6.0", 2026-05-25 00:34:07.363305 | instance | "Resolved ansible.utils to v6.0.2 for >=2.9.0,>=3.0.0", 2026-05-25 00:34:07.363328 | instance | "Resolved community.crypto to 2.2.3 for 2.2.3", 2026-05-25 00:34:07.363352 | instance | "Resolved community.general to 7.3.0 for 7.3.0", 2026-05-25 00:34:07.363376 | instance | "Resolved community.mysql to 3.6.0 for 3.6.0", 2026-05-25 00:34:07.363400 | instance | "Resolved kubernetes.core to 2.4.0 for 2.4.0", 2026-05-25 00:34:07.363423 | instance | "Resolved openstack.cloud to 1.7.0 for 1.7.0", 2026-05-25 00:34:07.363448 | instance | "Resolved atmosphere.common to v0.7.0 for >=0.6.0", 2026-05-25 00:34:07.363489 | instance | "Resolved vexxhost.ceph to v4.0.0 for >=3.1.2", 2026-05-25 00:34:07.363514 | instance | "Resolved vexxhost.kubernetes to v3.1.0 for >=2.3.2,>=2.5.0", 2026-05-25 00:34:07.363538 | instance | "Resolved ansible.utils to v6.0.2 for >=2.9.0,>=3.0.0,>=6.0.0", 2026-05-25 00:34:07.363562 | instance | "Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2" 2026-05-25 00:34:07.363585 | instance | ], 2026-05-25 00:34:07.363609 | instance | "msg": "No local release tag for openstack.cloud satisfies 1.7.0,>=2.0.0" 2026-05-25 00:34:07.363633 | instance | } failure 2026-05-25 00:34:07.370366 | 2026-05-25 00:34:07.370411 | PLAY RECAP 2026-05-25 00:34:07.370459 | instance | ok: 2 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2026-05-25 00:34:07.370480 | 2026-05-25 00:34:07.588791 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-05-25 00:34:07.593477 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-05-25 00:34:08.230308 | 2026-05-25 00:34:08.230490 | PLAY [all] 2026-05-25 00:34:08.245325 | 2026-05-25 00:34:08.245489 | TASK [fetch-output : Set log path for multiple nodes] 2026-05-25 00:34:08.292727 | instance | skipping: Conditional result was False 2026-05-25 00:34:08.303803 | 2026-05-25 00:34:08.304228 | TASK [fetch-output : Set log path for single node] 2026-05-25 00:34:08.349015 | instance | ok 2026-05-25 00:34:08.355441 | 2026-05-25 00:34:08.355576 | LOOP [fetch-output : Ensure local output dirs] 2026-05-25 00:34:08.750175 | instance -> localhost | ok: "/var/lib/zuul/builds/22a2345c8c91405a8be6bc2d204ccec7/work/logs" 2026-05-25 00:34:08.971793 | instance -> localhost | changed: "/var/lib/zuul/builds/22a2345c8c91405a8be6bc2d204ccec7/work/artifacts" 2026-05-25 00:34:09.205988 | instance -> localhost | changed: "/var/lib/zuul/builds/22a2345c8c91405a8be6bc2d204ccec7/work/docs" 2026-05-25 00:34:09.218268 | 2026-05-25 00:34:09.218403 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-05-25 00:34:09.873024 | instance | changed: .d..t...... ./ 2026-05-25 00:34:09.873271 | instance | changed: All items complete 2026-05-25 00:34:09.873311 | 2026-05-25 00:34:10.318041 | instance | changed: .d..t...... ./ 2026-05-25 00:34:10.783949 | instance | changed: .d..t...... ./ 2026-05-25 00:34:10.805260 | 2026-05-25 00:34:10.805459 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-05-25 00:34:11.246579 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.005630 2026-05-25 00:34:11.484563 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.007082 2026-05-25 00:34:11.499206 | 2026-05-25 00:34:11.499337 | PLAY [all] 2026-05-25 00:34:11.507980 | 2026-05-25 00:34:11.508050 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-05-25 00:34:11.922705 | instance | changed 2026-05-25 00:34:11.941121 | 2026-05-25 00:34:11.941214 | PLAY RECAP 2026-05-25 00:34:11.941270 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-05-25 00:34:11.941297 | 2026-05-25 00:34:12.121313 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-05-25 00:34:12.126017 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-05-25 00:34:12.796850 | 2026-05-25 00:34:12.798727 | PLAY [localhost] 2026-05-25 00:34:12.809144 | 2026-05-25 00:34:12.809216 | TASK [Generate Zuul manifest] 2026-05-25 00:34:12.850759 | localhost | ok 2026-05-25 00:34:12.870203 | 2026-05-25 00:34:12.870306 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-05-25 00:34:13.257603 | localhost | changed 2026-05-25 00:34:13.281547 | 2026-05-25 00:34:13.281673 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-05-25 00:34:13.310995 | localhost | ok 2026-05-25 00:34:13.317700 | 2026-05-25 00:34:13.317764 | TASK [Upload logs] 2026-05-25 00:34:13.337250 | localhost | ok 2026-05-25 00:34:13.398223 | 2026-05-25 00:34:13.398350 | TASK [Set zuul-log-path fact] 2026-05-25 00:34:13.418576 | localhost | ok 2026-05-25 00:34:13.433958 | 2026-05-25 00:34:13.434054 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-25 00:34:13.467684 | localhost | ok 2026-05-25 00:34:13.479178 | 2026-05-25 00:34:13.479252 | TASK [upload-logs : Create log directories] 2026-05-25 00:34:13.910258 | localhost | changed 2026-05-25 00:34:13.916016 | 2026-05-25 00:34:13.916093 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-05-25 00:34:14.389153 | localhost -> localhost | ok: Runtime: 0:00:00.004881 2026-05-25 00:34:14.399369 | 2026-05-25 00:34:14.399448 | TASK [upload-logs : Upload logs to log server] 2026-05-25 00:34:14.894096 | localhost | Output suppressed because no_log was given 2026-05-25 00:34:14.942038 | 2026-05-25 00:34:14.942206 | LOOP [upload-logs : Compress console log and json output] 2026-05-25 00:34:14.996525 | localhost | skipping: Conditional result was False 2026-05-25 00:34:15.003892 | localhost | skipping: Conditional result was False 2026-05-25 00:34:15.018326 | 2026-05-25 00:34:15.018492 | LOOP [upload-logs : Upload compressed console log and json output] 2026-05-25 00:34:15.058386 | localhost | skipping: Conditional result was False 2026-05-25 00:34:15.058848 | 2026-05-25 00:34:15.062176 | localhost | skipping: Conditional result was False 2026-05-25 00:34:15.072691 | 2026-05-25 00:34:15.072925 | LOOP [upload-logs : Upload console log and json output]