2026-05-29 05:48:05.146723 | Job console starting 2026-05-29 05:48:05.157844 | Updating git repos 2026-05-29 05:48:08.182734 | Cloning repos into workspace 2026-05-29 05:48:28.338647 | Restoring repo states 2026-05-29 05:48:44.495361 | Merging changes 2026-05-29 05:48:45.381013 | Checking out repos 2026-05-29 05:49:49.982626 | Preparing playbooks 2026-05-29 05:50:13.307396 | Running Ansible setup 2026-05-29 05:50:16.652759 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-05-29 05:50:17.283448 | 2026-05-29 05:50:18.060417 | PLAY [localhost] 2026-05-29 05:50:18.084314 | 2026-05-29 05:50:18.084497 | TASK [Gathering Facts] 2026-05-29 05:50:18.924505 | localhost | ok 2026-05-29 05:50:18.935226 | 2026-05-29 05:50:18.935345 | TASK [Setup log path fact] 2026-05-29 05:50:18.957370 | localhost | ok 2026-05-29 05:50:18.969629 | 2026-05-29 05:50:18.969725 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-29 05:50:19.003036 | localhost | ok 2026-05-29 05:50:19.009836 | 2026-05-29 05:50:19.009944 | TASK [emit-job-header : Print job information] 2026-05-29 05:50:19.051487 | # Job Information 2026-05-29 05:50:19.051710 | Ansible Version: 2.16.18 2026-05-29 05:50:19.051761 | Job: atmosphere-molecule-csi-local-path-provisioner 2026-05-29 05:50:19.051796 | Pipeline: check 2026-05-29 05:50:19.051829 | Executor: 2d72f0692154 2026-05-29 05:50:19.051862 | Triggered by: https://github.com/vexxhost/atmosphere/pull/3991 2026-05-29 05:50:19.051895 | Event ID: f710a3e0-5b20-11f1-8f51-b83a56aada0f 2026-05-29 05:50:19.057140 | 2026-05-29 05:50:19.057239 | LOOP [emit-job-header : Print node information] 2026-05-29 05:50:19.153363 | localhost | ok: 2026-05-29 05:50:19.153767 | localhost | # Node Information 2026-05-29 05:50:19.153831 | localhost | Inventory Hostname: instance 2026-05-29 05:50:19.153856 | localhost | Hostname: np0000177559 2026-05-29 05:50:19.153875 | localhost | Username: zuul 2026-05-29 05:50:19.153899 | localhost | Distro: Ubuntu 22.04 2026-05-29 05:50:19.153920 | localhost | Provider: yul1 2026-05-29 05:50:19.153940 | localhost | Region: ca-ymq-1 2026-05-29 05:50:19.153959 | localhost | Label: ubuntu-jammy 2026-05-29 05:50:19.153977 | localhost | Product Name: OpenStack Nova 2026-05-29 05:50:19.153995 | localhost | Interface IP: 199.204.45.70 2026-05-29 05:50:19.175245 | 2026-05-29 05:50:19.175407 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-05-29 05:50:19.554837 | localhost -> localhost | changed 2026-05-29 05:50:19.563273 | 2026-05-29 05:50:19.563363 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-05-29 05:50:20.390857 | localhost -> localhost | changed 2026-05-29 05:50:20.403287 | 2026-05-29 05:50:20.403353 | PLAY [all] 2026-05-29 05:50:20.412966 | 2026-05-29 05:50:20.413051 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-05-29 05:50:20.640234 | instance -> localhost | ok 2026-05-29 05:50:20.652314 | 2026-05-29 05:50:20.652389 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-05-29 05:50:20.689104 | instance | ok 2026-05-29 05:50:20.706306 | instance | included: /var/lib/zuul/builds/b722dcb606a24ee7bd5bd99b05454114/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-05-29 05:50:20.712745 | 2026-05-29 05:50:20.712832 | TASK [add-build-sshkey : Create Temp SSH key] 2026-05-29 05:50:22.429108 | instance -> localhost | Generating public/private rsa key pair. 2026-05-29 05:50:22.429346 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/b722dcb606a24ee7bd5bd99b05454114/work/b722dcb606a24ee7bd5bd99b05454114_id_rsa 2026-05-29 05:50:22.429390 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/b722dcb606a24ee7bd5bd99b05454114/work/b722dcb606a24ee7bd5bd99b05454114_id_rsa.pub 2026-05-29 05:50:22.429421 | instance -> localhost | The key fingerprint is: 2026-05-29 05:50:22.429452 | instance -> localhost | SHA256:rPmQETOJqhiqPqFE8LwJtUisv2ptxzFjVdRKA8OuF8s zuul-build-sshkey 2026-05-29 05:50:22.429498 | instance -> localhost | The key's randomart image is: 2026-05-29 05:50:22.429529 | instance -> localhost | +---[RSA 3072]----+ 2026-05-29 05:50:22.429562 | instance -> localhost | |. .oo.. | 2026-05-29 05:50:22.429592 | instance -> localhost | |.o. . o.+ . | 2026-05-29 05:50:22.429620 | instance -> localhost | |+= .. * o o | 2026-05-29 05:50:22.429649 | instance -> localhost | |+.+. O . | 2026-05-29 05:50:22.429677 | instance -> localhost | |oo.o = S | 2026-05-29 05:50:22.429705 | instance -> localhost | |o=+ * E | 2026-05-29 05:50:22.429733 | instance -> localhost | |* o.o X | 2026-05-29 05:50:22.429774 | instance -> localhost | |oo.o o o | 2026-05-29 05:50:22.429826 | instance -> localhost | |=oo . . | 2026-05-29 05:50:22.429871 | instance -> localhost | +----[SHA256]-----+ 2026-05-29 05:50:22.429950 | instance -> localhost | ok: Runtime: 0:00:00.598148 2026-05-29 05:50:22.438585 | 2026-05-29 05:50:22.438657 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-05-29 05:50:22.470810 | instance | ok 2026-05-29 05:50:22.481196 | instance | included: /var/lib/zuul/builds/b722dcb606a24ee7bd5bd99b05454114/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-05-29 05:50:22.488359 | 2026-05-29 05:50:22.488420 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-05-29 05:50:22.512669 | instance | skipping: Conditional result was False 2026-05-29 05:50:22.523843 | 2026-05-29 05:50:22.523938 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-05-29 05:50:22.974445 | instance | changed 2026-05-29 05:50:22.983293 | 2026-05-29 05:50:22.983384 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-05-29 05:50:23.168819 | instance | ok 2026-05-29 05:50:23.174060 | 2026-05-29 05:50:23.174128 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-05-29 05:50:23.639459 | instance | changed 2026-05-29 05:50:23.646629 | 2026-05-29 05:50:23.646716 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-05-29 05:50:24.127332 | instance | changed 2026-05-29 05:50:24.137593 | 2026-05-29 05:50:24.137716 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-05-29 05:50:24.162943 | instance | skipping: Conditional result was False 2026-05-29 05:50:24.175587 | 2026-05-29 05:50:24.175734 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-05-29 05:50:24.545529 | instance -> localhost | changed 2026-05-29 05:50:24.563802 | 2026-05-29 05:50:24.563876 | TASK [add-build-sshkey : Add back temp key] 2026-05-29 05:50:24.832140 | instance -> localhost | Identity added: /var/lib/zuul/builds/b722dcb606a24ee7bd5bd99b05454114/work/b722dcb606a24ee7bd5bd99b05454114_id_rsa (zuul-build-sshkey) 2026-05-29 05:50:24.832391 | instance -> localhost | ok: Runtime: 0:00:00.014499 2026-05-29 05:50:24.839257 | 2026-05-29 05:50:24.839342 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-05-29 05:50:25.157658 | instance | ok 2026-05-29 05:50:25.162499 | 2026-05-29 05:50:25.162595 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-05-29 05:50:25.187085 | instance | skipping: Conditional result was False 2026-05-29 05:50:25.203462 | 2026-05-29 05:50:25.203535 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-05-29 05:50:25.541600 | instance | ok 2026-05-29 05:50:25.550009 | 2026-05-29 05:50:25.550130 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-05-29 05:50:30.007417 | instance | Output suppressed because no_log was given 2026-05-29 05:50:34.715069 | 2026-05-29 05:50:35.067631 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-05-29 05:50:35.266736 | instance | ok: "logs" 2026-05-29 05:50:35.266985 | instance | ok: All items complete 2026-05-29 05:50:35.267013 | 2026-05-29 05:50:35.413156 | instance | ok: "artifacts" 2026-05-29 05:50:35.580150 | instance | ok: "docs" 2026-05-29 05:50:35.592074 | 2026-05-29 05:50:35.592545 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-05-29 05:50:35.787786 | instance | changed: "logs" 2026-05-29 05:50:35.952053 | instance | changed: "artifacts" 2026-05-29 05:50:36.109580 | instance | changed: "docs" 2026-05-29 05:50:36.128750 | 2026-05-29 05:50:36.128895 | PLAY RECAP 2026-05-29 05:50:36.128982 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-05-29 05:50:36.129028 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-05-29 05:50:36.129060 | 2026-05-29 05:50:36.282055 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-05-29 05:50:36.284573 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-05-29 05:50:36.935733 | 2026-05-29 05:50:36.935861 | PLAY [all] 2026-05-29 05:50:36.946676 | 2026-05-29 05:50:36.946752 | TASK [setup-uv : Extract archive] 2026-05-29 05:50:39.625934 | instance | changed 2026-05-29 05:50:39.634356 | 2026-05-29 05:50:39.634472 | TASK [setup-uv : Print version] 2026-05-29 05:50:39.999494 | instance | uv 0.8.13 2026-05-29 05:50:41.254003 | instance | ok: Runtime: 0:00:00.012167 2026-05-29 05:50:41.264229 | 2026-05-29 05:50:41.264324 | TASK [Install Ansible collection siblings] 2026-05-29 05:50:41.941050 | instance | ERROR 2026-05-29 05:50:41.941382 | instance | { 2026-05-29 05:50:41.941426 | instance | "log": [ 2026-05-29 05:50:41.941457 | instance | "Sibling collection ansible.netcommon at /home/zuul/src/github.com/ansible-collections/ansible.netcommon", 2026-05-29 05:50:41.941485 | instance | "Sibling collection ansible.posix at /home/zuul/src/github.com/ansible-collections/ansible.posix", 2026-05-29 05:50:41.941512 | instance | "Sibling collection ansible.utils at /home/zuul/src/github.com/ansible-collections/ansible.utils", 2026-05-29 05:50:41.941544 | instance | "Sibling collection community.crypto at /home/zuul/src/github.com/ansible-collections/community.crypto", 2026-05-29 05:50:41.941571 | instance | "Sibling collection community.general at /home/zuul/src/github.com/ansible-collections/community.general", 2026-05-29 05:50:41.941603 | instance | "Sibling collection community.mysql at /home/zuul/src/github.com/ansible-collections/community.mysql", 2026-05-29 05:50:41.941631 | instance | "Sibling collection kubernetes.core at /home/zuul/src/github.com/ansible-collections/kubernetes.core", 2026-05-29 05:50:41.941656 | instance | "Sibling collection vexxhost.ceph at /home/zuul/src/github.com/vexxhost/ansible-collection-ceph", 2026-05-29 05:50:41.941681 | instance | "Sibling collection vexxhost.containers at /home/zuul/src/github.com/vexxhost/ansible-collection-containers", 2026-05-29 05:50:41.941706 | instance | "Sibling collection vexxhost.kubernetes at /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes", 2026-05-29 05:50:41.941730 | instance | "Sibling collection atmosphere.common at /home/zuul/src/github.com/vexxhost/atmosphere.common", 2026-05-29 05:50:41.941754 | instance | "Sibling collection openstack.cloud at /home/zuul/src/opendev.org/openstack/ansible-collections-openstack", 2026-05-29 05:50:41.941779 | instance | "Resolved ansible.netcommon to v8.5.2 for >=1.2.0", 2026-05-29 05:50:41.941806 | instance | "Resolved ansible.posix to 1.6.0 for 1.6.0", 2026-05-29 05:50:41.941831 | instance | "Resolved ansible.utils to v6.0.2 for >=2.9.0,>=3.0.0", 2026-05-29 05:50:41.941856 | instance | "Resolved community.crypto to 2.2.3 for 2.2.3", 2026-05-29 05:50:41.941880 | instance | "Resolved community.general to 7.3.0 for 7.3.0", 2026-05-29 05:50:41.941904 | instance | "Resolved community.mysql to 3.6.0 for 3.6.0", 2026-05-29 05:50:41.941928 | instance | "Resolved kubernetes.core to 2.4.0 for 2.4.0", 2026-05-29 05:50:41.941952 | instance | "Resolved openstack.cloud to 1.7.0 for 1.7.0", 2026-05-29 05:50:41.941992 | instance | "Resolved atmosphere.common to v0.7.0 for >=0.6.0", 2026-05-29 05:50:41.942018 | instance | "Resolved vexxhost.ceph to v4.0.0 for >=3.1.2", 2026-05-29 05:50:41.942042 | instance | "Resolved vexxhost.kubernetes to v3.1.0 for >=2.3.2,>=2.5.0", 2026-05-29 05:50:41.942066 | instance | "Resolved ansible.utils to v6.0.2 for >=2.9.0,>=3.0.0,>=6.0.0", 2026-05-29 05:50:41.942090 | instance | "Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2" 2026-05-29 05:50:41.942113 | instance | ], 2026-05-29 05:50:41.942137 | instance | "msg": "No local release tag for openstack.cloud satisfies 1.7.0,>=2.0.0" 2026-05-29 05:50:41.942161 | instance | } failure 2026-05-29 05:50:41.946574 | 2026-05-29 05:50:41.946621 | PLAY RECAP 2026-05-29 05:50:41.946670 | instance | ok: 2 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2026-05-29 05:50:41.946691 | 2026-05-29 05:50:42.094725 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-05-29 05:50:42.098360 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-05-29 05:50:42.759051 | 2026-05-29 05:50:42.759208 | PLAY [all] 2026-05-29 05:50:42.772257 | 2026-05-29 05:50:42.772347 | TASK [fetch-output : Set log path for multiple nodes] 2026-05-29 05:50:42.817580 | instance | skipping: Conditional result was False 2026-05-29 05:50:42.829824 | 2026-05-29 05:50:42.830013 | TASK [fetch-output : Set log path for single node] 2026-05-29 05:50:42.874411 | instance | ok 2026-05-29 05:50:42.879579 | 2026-05-29 05:50:42.879673 | LOOP [fetch-output : Ensure local output dirs] 2026-05-29 05:50:43.298239 | instance -> localhost | ok: "/var/lib/zuul/builds/b722dcb606a24ee7bd5bd99b05454114/work/logs" 2026-05-29 05:50:43.523468 | instance -> localhost | changed: "/var/lib/zuul/builds/b722dcb606a24ee7bd5bd99b05454114/work/artifacts" 2026-05-29 05:50:43.735547 | instance -> localhost | changed: "/var/lib/zuul/builds/b722dcb606a24ee7bd5bd99b05454114/work/docs" 2026-05-29 05:50:43.751432 | 2026-05-29 05:50:43.751588 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-05-29 05:50:44.427287 | instance | changed: .d..t...... ./ 2026-05-29 05:50:44.427600 | instance | changed: All items complete 2026-05-29 05:50:44.427659 | 2026-05-29 05:50:45.361144 | instance | changed: .d..t...... ./ 2026-05-29 05:50:45.816510 | instance | changed: .d..t...... ./ 2026-05-29 05:50:45.837753 | 2026-05-29 05:50:45.837941 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-05-29 05:50:46.269222 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.007701 2026-05-29 05:50:46.510477 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.007840 2026-05-29 05:50:46.533482 | 2026-05-29 05:50:46.533602 | PLAY [all] 2026-05-29 05:50:46.540157 | 2026-05-29 05:50:46.540229 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-05-29 05:50:46.959634 | instance | changed 2026-05-29 05:50:46.968722 | 2026-05-29 05:50:46.968805 | PLAY RECAP 2026-05-29 05:50:46.968866 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-05-29 05:50:46.968899 | 2026-05-29 05:50:47.148793 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-05-29 05:50:47.154563 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-05-29 05:50:47.792326 | 2026-05-29 05:50:47.792944 | PLAY [localhost] 2026-05-29 05:50:47.802804 | 2026-05-29 05:50:47.802874 | TASK [Generate Zuul manifest] 2026-05-29 05:50:47.820608 | localhost | ok 2026-05-29 05:50:47.835863 | 2026-05-29 05:50:47.835943 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-05-29 05:50:48.244407 | localhost | changed 2026-05-29 05:50:48.258310 | 2026-05-29 05:50:48.258449 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-05-29 05:50:48.301152 | localhost | ok 2026-05-29 05:50:48.312087 | 2026-05-29 05:50:48.312228 | TASK [Upload logs] 2026-05-29 05:50:48.336540 | localhost | ok 2026-05-29 05:50:48.393716 | 2026-05-29 05:50:48.393873 | TASK [Set zuul-log-path fact] 2026-05-29 05:50:48.415528 | localhost | ok 2026-05-29 05:50:48.429631 | 2026-05-29 05:50:48.429725 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-29 05:50:48.461647 | localhost | ok 2026-05-29 05:50:48.471082 | 2026-05-29 05:50:48.471173 | TASK [upload-logs : Create log directories] 2026-05-29 05:50:48.910959 | localhost | changed 2026-05-29 05:50:48.917874 | 2026-05-29 05:50:48.917991 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-05-29 05:50:49.860858 | localhost -> localhost | ok: Runtime: 0:00:00.488743 2026-05-29 05:50:49.867239 | 2026-05-29 05:50:49.867318 | TASK [upload-logs : Upload logs to log server] 2026-05-29 05:50:51.557049 | localhost | Output suppressed because no_log was given 2026-05-29 05:50:51.606463 | 2026-05-29 05:50:51.606606 | LOOP [upload-logs : Compress console log and json output] 2026-05-29 05:50:51.655751 | localhost | skipping: Conditional result was False 2026-05-29 05:50:51.663823 | localhost | skipping: Conditional result was False 2026-05-29 05:50:51.683733 | 2026-05-29 05:50:51.683926 | LOOP [upload-logs : Upload compressed console log and json output] 2026-05-29 05:50:51.730977 | localhost | skipping: Conditional result was False 2026-05-29 05:50:51.731361 | 2026-05-29 05:50:51.735459 | localhost | skipping: Conditional result was False 2026-05-29 05:50:51.753900 | 2026-05-29 05:50:51.754082 | LOOP [upload-logs : Upload console log and json output]