2026-05-29 02:00:15.647749 | Job console starting 2026-05-29 02:00:15.655542 | Updating git repos 2026-05-29 02:00:16.991250 | Cloning repos into workspace 2026-05-29 02:00:34.029684 | Restoring repo states 2026-05-29 02:00:40.314775 | Merging changes 2026-05-29 02:00:42.515915 | Checking out repos 2026-05-29 02:00:46.906584 | Preparing playbooks 2026-05-29 02:00:52.544999 | Running Ansible setup 2026-05-29 02:00:56.459954 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-05-29 02:00:57.083353 | 2026-05-29 02:00:57.083491 | PLAY [localhost] 2026-05-29 02:00:57.091223 | 2026-05-29 02:00:57.091296 | TASK [Gathering Facts] 2026-05-29 02:00:58.002362 | localhost | ok 2026-05-29 02:00:58.014523 | 2026-05-29 02:00:58.014635 | TASK [Setup log path fact] 2026-05-29 02:00:58.037554 | localhost | ok 2026-05-29 02:00:58.050839 | 2026-05-29 02:00:58.050907 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-29 02:00:58.082264 | localhost | ok 2026-05-29 02:00:58.090097 | 2026-05-29 02:00:58.090186 | TASK [emit-job-header : Print job information] 2026-05-29 02:00:58.132909 | # Job Information 2026-05-29 02:00:58.133159 | Ansible Version: 2.16.18 2026-05-29 02:00:58.133232 | Job: atmosphere-molecule-csi-rbd 2026-05-29 02:00:58.133281 | Pipeline: check 2026-05-29 02:00:58.133326 | Executor: 2d72f0692154 2026-05-29 02:00:58.133373 | Triggered by: https://github.com/vexxhost/atmosphere/pull/3991 2026-05-29 02:00:58.133424 | Event ID: fa808e60-5b01-11f1-865d-24a5b51dd999 2026-05-29 02:00:58.139435 | 2026-05-29 02:00:58.139576 | LOOP [emit-job-header : Print node information] 2026-05-29 02:00:58.255479 | localhost | ok: 2026-05-29 02:00:58.255794 | localhost | # Node Information 2026-05-29 02:00:58.255840 | localhost | Inventory Hostname: instance 2026-05-29 02:00:58.255871 | localhost | Hostname: np0000177423 2026-05-29 02:00:58.255899 | localhost | Username: zuul 2026-05-29 02:00:58.255931 | localhost | Distro: Ubuntu 22.04 2026-05-29 02:00:58.255960 | localhost | Provider: yul1 2026-05-29 02:00:58.255987 | localhost | Region: ca-ymq-1 2026-05-29 02:00:58.256015 | localhost | Label: ubuntu-jammy 2026-05-29 02:00:58.256050 | localhost | Product Name: OpenStack Nova 2026-05-29 02:00:58.256089 | localhost | Interface IP: 199.204.45.250 2026-05-29 02:00:58.271977 | 2026-05-29 02:00:58.272173 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-05-29 02:00:58.895766 | localhost -> localhost | changed 2026-05-29 02:00:58.903193 | 2026-05-29 02:00:58.903287 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-05-29 02:00:59.909093 | localhost -> localhost | changed 2026-05-29 02:00:59.915928 | 2026-05-29 02:00:59.915985 | PLAY [all] 2026-05-29 02:00:59.925375 | 2026-05-29 02:00:59.925447 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-05-29 02:01:00.158245 | instance -> localhost | ok 2026-05-29 02:01:00.167420 | 2026-05-29 02:01:00.167487 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-05-29 02:01:00.198824 | instance | ok 2026-05-29 02:01:00.216736 | instance | included: /var/lib/zuul/builds/381b0d214d8941348c76940d71879918/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-05-29 02:01:00.223026 | 2026-05-29 02:01:00.223088 | TASK [add-build-sshkey : Create Temp SSH key] 2026-05-29 02:01:01.625268 | instance -> localhost | Generating public/private rsa key pair. 2026-05-29 02:01:01.625455 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/381b0d214d8941348c76940d71879918/work/381b0d214d8941348c76940d71879918_id_rsa 2026-05-29 02:01:01.625488 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/381b0d214d8941348c76940d71879918/work/381b0d214d8941348c76940d71879918_id_rsa.pub 2026-05-29 02:01:01.625511 | instance -> localhost | The key fingerprint is: 2026-05-29 02:01:01.625598 | instance -> localhost | SHA256:rgdfoMj+BZfIiw/xC3RjNX6fHba1WGg2TJmrTemdryA zuul-build-sshkey 2026-05-29 02:01:01.625636 | instance -> localhost | The key's randomart image is: 2026-05-29 02:01:01.625659 | instance -> localhost | +---[RSA 3072]----+ 2026-05-29 02:01:01.625687 | instance -> localhost | | | 2026-05-29 02:01:01.625709 | instance -> localhost | | o | 2026-05-29 02:01:01.625730 | instance -> localhost | | o + | 2026-05-29 02:01:01.625751 | instance -> localhost | | . +.o o + | 2026-05-29 02:01:01.625772 | instance -> localhost | | .o.B.S.. Xo..| 2026-05-29 02:01:01.625792 | instance -> localhost | | .o*o* ...B+=+o| 2026-05-29 02:01:01.625811 | instance -> localhost | | .+ ooo.E.++o+ | 2026-05-29 02:01:01.625831 | instance -> localhost | | .+ +o . . .| 2026-05-29 02:01:01.625855 | instance -> localhost | | .=. ...| 2026-05-29 02:01:01.625876 | instance -> localhost | +----[SHA256]-----+ 2026-05-29 02:01:01.625933 | instance -> localhost | ok: Runtime: 0:00:00.784009 2026-05-29 02:01:01.632488 | 2026-05-29 02:01:01.632555 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-05-29 02:01:01.666492 | instance | ok 2026-05-29 02:01:01.678012 | instance | included: /var/lib/zuul/builds/381b0d214d8941348c76940d71879918/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-05-29 02:01:01.685724 | 2026-05-29 02:01:01.685789 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-05-29 02:01:01.711670 | instance | skipping: Conditional result was False 2026-05-29 02:01:01.720500 | 2026-05-29 02:01:01.720608 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-05-29 02:01:02.225502 | instance | changed 2026-05-29 02:01:02.232290 | 2026-05-29 02:01:02.232360 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-05-29 02:01:02.414122 | instance | ok 2026-05-29 02:01:02.421207 | 2026-05-29 02:01:02.421315 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-05-29 02:01:02.878798 | instance | changed 2026-05-29 02:01:02.968864 | 2026-05-29 02:01:02.969003 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-05-29 02:01:03.468791 | instance | changed 2026-05-29 02:01:03.475184 | 2026-05-29 02:01:03.475265 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-05-29 02:01:03.500643 | instance | skipping: Conditional result was False 2026-05-29 02:01:03.512333 | 2026-05-29 02:01:03.512404 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-05-29 02:01:03.924690 | instance -> localhost | changed 2026-05-29 02:01:03.937170 | 2026-05-29 02:01:03.937244 | TASK [add-build-sshkey : Add back temp key] 2026-05-29 02:01:04.380783 | instance -> localhost | Identity added: /var/lib/zuul/builds/381b0d214d8941348c76940d71879918/work/381b0d214d8941348c76940d71879918_id_rsa (zuul-build-sshkey) 2026-05-29 02:01:04.381402 | instance -> localhost | ok: Runtime: 0:00:00.015413 2026-05-29 02:01:04.390821 | 2026-05-29 02:01:04.390886 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-05-29 02:01:04.703237 | instance | ok 2026-05-29 02:01:04.784337 | 2026-05-29 02:01:04.784443 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-05-29 02:01:04.812661 | instance | skipping: Conditional result was False 2026-05-29 02:01:04.829243 | 2026-05-29 02:01:04.829308 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-05-29 02:01:05.175735 | instance | ok 2026-05-29 02:01:05.185138 | 2026-05-29 02:01:05.185262 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-05-29 02:01:09.076578 | instance | Output suppressed because no_log was given 2026-05-29 02:01:09.086063 | 2026-05-29 02:01:09.086141 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-05-29 02:01:09.274235 | instance | ok: "logs" 2026-05-29 02:01:09.274518 | instance | ok: All items complete 2026-05-29 02:01:09.274549 | 2026-05-29 02:01:09.424630 | instance | ok: "artifacts" 2026-05-29 02:01:09.590738 | instance | ok: "docs" 2026-05-29 02:01:09.600207 | 2026-05-29 02:01:09.600335 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-05-29 02:01:09.799347 | instance | changed: "logs" 2026-05-29 02:01:09.963008 | instance | changed: "artifacts" 2026-05-29 02:01:10.107741 | instance | changed: "docs" 2026-05-29 02:01:10.126820 | 2026-05-29 02:01:10.126954 | PLAY RECAP 2026-05-29 02:01:10.127004 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-05-29 02:01:10.127033 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-05-29 02:01:10.127055 | 2026-05-29 02:01:10.283972 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-05-29 02:01:10.755128 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-05-29 02:01:11.533960 | 2026-05-29 02:01:11.534115 | PLAY [all] 2026-05-29 02:01:11.545610 | 2026-05-29 02:01:11.545716 | TASK [setup-uv : Extract archive] 2026-05-29 02:01:17.201790 | instance | changed 2026-05-29 02:01:17.209886 | 2026-05-29 02:01:17.209986 | TASK [setup-uv : Print version] 2026-05-29 02:01:17.558749 | instance | uv 0.8.13 2026-05-29 02:01:17.819603 | instance | ok: Runtime: 0:00:00.012297 2026-05-29 02:01:17.832945 | 2026-05-29 02:01:17.833053 | TASK [Install Ansible collection siblings] 2026-05-29 02:01:18.452187 | instance | ERROR 2026-05-29 02:01:18.452511 | instance | { 2026-05-29 02:01:18.452556 | instance | "log": [ 2026-05-29 02:01:18.452585 | instance | "Sibling collection ansible.netcommon at /home/zuul/src/github.com/ansible-collections/ansible.netcommon", 2026-05-29 02:01:18.452614 | instance | "Sibling collection ansible.posix at /home/zuul/src/github.com/ansible-collections/ansible.posix", 2026-05-29 02:01:18.452639 | instance | "Sibling collection ansible.utils at /home/zuul/src/github.com/ansible-collections/ansible.utils", 2026-05-29 02:01:18.452666 | instance | "Sibling collection community.crypto at /home/zuul/src/github.com/ansible-collections/community.crypto", 2026-05-29 02:01:18.452694 | instance | "Sibling collection community.general at /home/zuul/src/github.com/ansible-collections/community.general", 2026-05-29 02:01:18.452720 | instance | "Sibling collection community.mysql at /home/zuul/src/github.com/ansible-collections/community.mysql", 2026-05-29 02:01:18.452743 | instance | "Sibling collection kubernetes.core at /home/zuul/src/github.com/ansible-collections/kubernetes.core", 2026-05-29 02:01:18.452768 | instance | "Sibling collection vexxhost.ceph at /home/zuul/src/github.com/vexxhost/ansible-collection-ceph", 2026-05-29 02:01:18.452795 | instance | "Sibling collection vexxhost.containers at /home/zuul/src/github.com/vexxhost/ansible-collection-containers", 2026-05-29 02:01:18.452820 | instance | "Sibling collection vexxhost.kubernetes at /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes", 2026-05-29 02:01:18.452844 | instance | "Sibling collection atmosphere.common at /home/zuul/src/github.com/vexxhost/atmosphere.common", 2026-05-29 02:01:18.452868 | instance | "Sibling collection openstack.cloud at /home/zuul/src/opendev.org/openstack/ansible-collections-openstack", 2026-05-29 02:01:18.452891 | instance | "Resolved ansible.netcommon to v8.5.2 for >=1.2.0", 2026-05-29 02:01:18.452914 | instance | "Resolved ansible.posix to 1.6.0 for 1.6.0", 2026-05-29 02:01:18.452940 | instance | "Resolved ansible.utils to v6.0.2 for >=2.9.0,>=3.0.0", 2026-05-29 02:01:18.452969 | instance | "Resolved community.crypto to 2.2.3 for 2.2.3", 2026-05-29 02:01:18.453011 | instance | "Resolved community.general to 7.3.0 for 7.3.0", 2026-05-29 02:01:18.453036 | instance | "Resolved community.mysql to 3.6.0 for 3.6.0", 2026-05-29 02:01:18.453059 | instance | "Resolved kubernetes.core to 2.4.0 for 2.4.0", 2026-05-29 02:01:18.453080 | instance | "Resolved openstack.cloud to 1.7.0 for 1.7.0", 2026-05-29 02:01:18.453107 | instance | "Resolved atmosphere.common to v0.7.0 for >=0.6.0", 2026-05-29 02:01:18.453147 | instance | "Resolved vexxhost.ceph to v4.0.0 for >=3.1.2", 2026-05-29 02:01:18.453171 | instance | "Resolved vexxhost.kubernetes to v3.1.0 for >=2.3.2,>=2.5.0", 2026-05-29 02:01:18.453196 | instance | "Resolved ansible.utils to v6.0.2 for >=2.9.0,>=3.0.0,>=6.0.0", 2026-05-29 02:01:18.453222 | instance | "Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2" 2026-05-29 02:01:18.453245 | instance | ], 2026-05-29 02:01:18.453267 | instance | "msg": "No local release tag for openstack.cloud satisfies 1.7.0,>=2.0.0" 2026-05-29 02:01:18.453289 | instance | } failure 2026-05-29 02:01:18.456722 | 2026-05-29 02:01:18.456771 | PLAY RECAP 2026-05-29 02:01:18.456823 | instance | ok: 2 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2026-05-29 02:01:18.456845 | 2026-05-29 02:01:18.639490 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-05-29 02:01:18.642659 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-05-29 02:01:19.259857 | 2026-05-29 02:01:19.282662 | PLAY [all] 2026-05-29 02:01:19.297087 | 2026-05-29 02:01:19.297166 | TASK [fetch-output : Set log path for multiple nodes] 2026-05-29 02:01:19.341467 | instance | skipping: Conditional result was False 2026-05-29 02:01:19.351843 | 2026-05-29 02:01:19.351973 | TASK [fetch-output : Set log path for single node] 2026-05-29 02:01:19.394437 | instance | ok 2026-05-29 02:01:19.400644 | 2026-05-29 02:01:19.400822 | LOOP [fetch-output : Ensure local output dirs] 2026-05-29 02:01:19.809509 | instance -> localhost | ok: "/var/lib/zuul/builds/381b0d214d8941348c76940d71879918/work/logs" 2026-05-29 02:01:20.052468 | instance -> localhost | changed: "/var/lib/zuul/builds/381b0d214d8941348c76940d71879918/work/artifacts" 2026-05-29 02:01:20.257409 | instance -> localhost | changed: "/var/lib/zuul/builds/381b0d214d8941348c76940d71879918/work/docs" 2026-05-29 02:01:20.276054 | 2026-05-29 02:01:20.276274 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-05-29 02:01:20.925963 | instance | changed: .d..t...... ./ 2026-05-29 02:01:20.926171 | instance | changed: All items complete 2026-05-29 02:01:20.926198 | 2026-05-29 02:01:21.384740 | instance | changed: .d..t...... ./ 2026-05-29 02:01:21.830596 | instance | changed: .d..t...... ./ 2026-05-29 02:01:21.855798 | 2026-05-29 02:01:21.855936 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-05-29 02:01:22.292568 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.008836 2026-05-29 02:01:22.521363 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.008116 2026-05-29 02:01:22.539771 | 2026-05-29 02:01:22.540255 | PLAY [all] 2026-05-29 02:01:22.547942 | 2026-05-29 02:01:22.548006 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-05-29 02:01:22.949283 | instance | changed 2026-05-29 02:01:22.954938 | 2026-05-29 02:01:22.954992 | PLAY RECAP 2026-05-29 02:01:22.955042 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-05-29 02:01:22.955064 | 2026-05-29 02:01:23.106496 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-05-29 02:01:23.109518 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-05-29 02:01:23.744154 | 2026-05-29 02:01:23.744303 | PLAY [localhost] 2026-05-29 02:01:23.755394 | 2026-05-29 02:01:23.755527 | TASK [Generate Zuul manifest] 2026-05-29 02:01:23.778062 | localhost | ok 2026-05-29 02:01:23.795520 | 2026-05-29 02:01:23.795602 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-05-29 02:01:24.251619 | localhost | changed 2026-05-29 02:01:24.262787 | 2026-05-29 02:01:24.262882 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-05-29 02:01:24.313763 | localhost | ok 2026-05-29 02:01:24.322870 | 2026-05-29 02:01:24.322973 | TASK [Upload logs] 2026-05-29 02:01:24.348916 | localhost | ok 2026-05-29 02:01:24.406750 | 2026-05-29 02:01:24.406879 | TASK [Set zuul-log-path fact] 2026-05-29 02:01:24.429157 | localhost | ok 2026-05-29 02:01:24.443562 | 2026-05-29 02:01:24.443631 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-29 02:01:24.476262 | localhost | ok 2026-05-29 02:01:24.483909 | 2026-05-29 02:01:24.483974 | TASK [upload-logs : Create log directories] 2026-05-29 02:01:24.900812 | localhost | changed 2026-05-29 02:01:24.907812 | 2026-05-29 02:01:24.907887 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-05-29 02:01:25.325444 | localhost -> localhost | ok: Runtime: 0:00:00.005820 2026-05-29 02:01:25.332759 | 2026-05-29 02:01:25.332831 | TASK [upload-logs : Upload logs to log server] 2026-05-29 02:01:25.811353 | localhost | Output suppressed because no_log was given 2026-05-29 02:01:25.863177 | 2026-05-29 02:01:25.863342 | LOOP [upload-logs : Compress console log and json output] 2026-05-29 02:01:25.917001 | localhost | skipping: Conditional result was False 2026-05-29 02:01:25.924583 | localhost | skipping: Conditional result was False 2026-05-29 02:01:25.933725 | 2026-05-29 02:01:25.933892 | LOOP [upload-logs : Upload compressed console log and json output] 2026-05-29 02:01:25.979528 | localhost | skipping: Conditional result was False 2026-05-29 02:01:25.980031 | 2026-05-29 02:01:25.983880 | localhost | skipping: Conditional result was False 2026-05-29 02:01:25.999331 | 2026-05-29 02:01:25.999458 | LOOP [upload-logs : Upload console log and json output]