2026-05-28 21:39:17.975578 | Job console starting 2026-05-28 21:39:17.991349 | Updating git repos 2026-05-28 21:39:19.886670 | Cloning repos into workspace 2026-05-28 21:39:54.189297 | Restoring repo states 2026-05-28 21:40:02.689426 | Merging changes 2026-05-28 21:40:05.301799 | Checking out repos 2026-05-28 21:40:11.493413 | Preparing playbooks 2026-05-28 21:40:45.483598 | Running Ansible setup 2026-05-28 21:40:56.672942 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-05-28 21:40:57.360329 | 2026-05-28 21:40:57.360494 | PLAY [localhost] 2026-05-28 21:40:57.369794 | 2026-05-28 21:40:57.369949 | TASK [Gathering Facts] 2026-05-28 21:40:59.853230 | localhost | ok 2026-05-28 21:40:59.865918 | 2026-05-28 21:40:59.866075 | TASK [Setup log path fact] 2026-05-28 21:40:59.885863 | localhost | ok 2026-05-28 21:40:59.899395 | 2026-05-28 21:40:59.899503 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-28 21:40:59.941272 | localhost | ok 2026-05-28 21:40:59.949562 | 2026-05-28 21:40:59.949671 | TASK [emit-job-header : Print job information] 2026-05-28 21:40:59.990296 | # Job Information 2026-05-28 21:40:59.990533 | Ansible Version: 2.16.18 2026-05-28 21:40:59.990582 | Job: atmosphere-molecule-csi-local-path-provisioner 2026-05-28 21:40:59.990615 | Pipeline: check 2026-05-28 21:40:59.990645 | Executor: 2d72f0692154 2026-05-28 21:40:59.990749 | Triggered by: https://github.com/vexxhost/atmosphere/pull/3991 2026-05-28 21:40:59.990792 | Event ID: 12984f80-5add-11f1-9c6f-45eac0f5c474 2026-05-28 21:40:59.995180 | 2026-05-28 21:40:59.995287 | LOOP [emit-job-header : Print node information] 2026-05-28 21:41:00.088884 | localhost | ok: 2026-05-28 21:41:00.089352 | localhost | # Node Information 2026-05-28 21:41:00.089384 | localhost | Inventory Hostname: instance 2026-05-28 21:41:00.089409 | localhost | Hostname: np0000177323 2026-05-28 21:41:00.089431 | localhost | Username: zuul 2026-05-28 21:41:00.089455 | localhost | Distro: Ubuntu 22.04 2026-05-28 21:41:00.089477 | localhost | Provider: yul1 2026-05-28 21:41:00.089498 | localhost | Region: ca-ymq-1 2026-05-28 21:41:00.089519 | localhost | Label: ubuntu-jammy 2026-05-28 21:41:00.089545 | localhost | Product Name: OpenStack Nova 2026-05-28 21:41:00.089578 | localhost | Interface IP: 199.204.45.23 2026-05-28 21:41:00.104804 | 2026-05-28 21:41:00.105061 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-05-28 21:41:00.860977 | localhost -> localhost | changed 2026-05-28 21:41:00.872537 | 2026-05-28 21:41:00.872667 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-05-28 21:41:02.602707 | localhost -> localhost | changed 2026-05-28 21:41:02.609855 | 2026-05-28 21:41:02.610086 | PLAY [all] 2026-05-28 21:41:02.621359 | 2026-05-28 21:41:02.621451 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-05-28 21:41:02.890317 | instance -> localhost | ok 2026-05-28 21:41:02.904055 | 2026-05-28 21:41:02.904139 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-05-28 21:41:02.943694 | instance | ok 2026-05-28 21:41:03.427761 | instance | included: /var/lib/zuul/builds/b132fea471264ebf9de4cb61c30d22af/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-05-28 21:41:03.433641 | 2026-05-28 21:41:03.433709 | TASK [add-build-sshkey : Create Temp SSH key] 2026-05-28 21:41:05.562564 | instance -> localhost | Generating public/private rsa key pair. 2026-05-28 21:41:05.562757 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/b132fea471264ebf9de4cb61c30d22af/work/b132fea471264ebf9de4cb61c30d22af_id_rsa 2026-05-28 21:41:05.562788 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/b132fea471264ebf9de4cb61c30d22af/work/b132fea471264ebf9de4cb61c30d22af_id_rsa.pub 2026-05-28 21:41:05.562812 | instance -> localhost | The key fingerprint is: 2026-05-28 21:41:05.562833 | instance -> localhost | SHA256:jtQKSsao0iEAgZny7PN2HLpBhT8tpLir47UK9QCtZBM zuul-build-sshkey 2026-05-28 21:41:05.562865 | instance -> localhost | The key's randomart image is: 2026-05-28 21:41:05.562886 | instance -> localhost | +---[RSA 3072]----+ 2026-05-28 21:41:05.562910 | instance -> localhost | |+E | 2026-05-28 21:41:05.562932 | instance -> localhost | |*.. . | 2026-05-28 21:41:05.562952 | instance -> localhost | |+*. . o | 2026-05-28 21:41:05.562972 | instance -> localhost | |+=+. = o | 2026-05-28 21:41:05.563084 | instance -> localhost | |+.O + = S | 2026-05-28 21:41:05.563114 | instance -> localhost | |.*oB o.* | 2026-05-28 21:41:05.563135 | instance -> localhost | |+ ++ooo.. | 2026-05-28 21:41:05.563156 | instance -> localhost | |+ ..=.o | 2026-05-28 21:41:05.563178 | instance -> localhost | |o=oo.o | 2026-05-28 21:41:05.563198 | instance -> localhost | +----[SHA256]-----+ 2026-05-28 21:41:05.563250 | instance -> localhost | ok: Runtime: 0:00:01.609835 2026-05-28 21:41:05.570082 | 2026-05-28 21:41:05.570168 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-05-28 21:41:05.607181 | instance | ok 2026-05-28 21:41:05.624769 | instance | included: /var/lib/zuul/builds/b132fea471264ebf9de4cb61c30d22af/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-05-28 21:41:05.637921 | 2026-05-28 21:41:05.637997 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-05-28 21:41:05.663353 | instance | skipping: Conditional result was False 2026-05-28 21:41:05.674511 | 2026-05-28 21:41:05.674631 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-05-28 21:41:06.160622 | instance | changed 2026-05-28 21:41:06.167822 | 2026-05-28 21:41:06.167907 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-05-28 21:41:06.343272 | instance | ok 2026-05-28 21:41:06.350737 | 2026-05-28 21:41:06.350859 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-05-28 21:41:06.831024 | instance | changed 2026-05-28 21:41:06.838316 | 2026-05-28 21:41:06.838413 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-05-28 21:41:07.289042 | instance | changed 2026-05-28 21:41:07.460782 | 2026-05-28 21:41:07.460889 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-05-28 21:41:07.485607 | instance | skipping: Conditional result was False 2026-05-28 21:41:07.496382 | 2026-05-28 21:41:07.496469 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-05-28 21:41:08.078544 | instance -> localhost | changed 2026-05-28 21:41:08.097127 | 2026-05-28 21:41:08.097279 | TASK [add-build-sshkey : Add back temp key] 2026-05-28 21:41:08.415656 | instance -> localhost | Identity added: /var/lib/zuul/builds/b132fea471264ebf9de4cb61c30d22af/work/b132fea471264ebf9de4cb61c30d22af_id_rsa (zuul-build-sshkey) 2026-05-28 21:41:08.415919 | instance -> localhost | ok: Runtime: 0:00:00.016108 2026-05-28 21:41:08.423121 | 2026-05-28 21:41:08.423209 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-05-28 21:41:08.766334 | instance | ok 2026-05-28 21:41:08.771003 | 2026-05-28 21:41:08.771078 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-05-28 21:41:08.796527 | instance | skipping: Conditional result was False 2026-05-28 21:41:08.814128 | 2026-05-28 21:41:08.814279 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-05-28 21:41:09.139344 | instance | ok 2026-05-28 21:41:09.198629 | 2026-05-28 21:41:09.198761 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-05-28 21:41:13.097827 | instance | Output suppressed because no_log was given 2026-05-28 21:41:13.330920 | 2026-05-28 21:41:13.331098 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-05-28 21:41:13.518769 | instance | ok: "logs" 2026-05-28 21:41:13.673656 | instance | ok: All items complete 2026-05-28 21:41:13.673843 | 2026-05-28 21:41:13.682344 | instance | ok: "artifacts" 2026-05-28 21:41:13.856306 | instance | ok: "docs" 2026-05-28 21:41:13.874919 | 2026-05-28 21:41:13.875043 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-05-28 21:41:14.057347 | instance | changed: "logs" 2026-05-28 21:41:14.208675 | instance | changed: "artifacts" 2026-05-28 21:41:14.362539 | instance | changed: "docs" 2026-05-28 21:41:14.383677 | 2026-05-28 21:41:14.383812 | PLAY RECAP 2026-05-28 21:41:14.383880 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-05-28 21:41:14.383922 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-05-28 21:41:14.383955 | 2026-05-28 21:41:14.582518 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-05-28 21:41:14.587417 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-05-28 21:41:15.296142 | 2026-05-28 21:41:15.296363 | PLAY [all] 2026-05-28 21:41:15.311321 | 2026-05-28 21:41:15.311474 | TASK [setup-uv : Extract archive] 2026-05-28 21:41:17.579423 | instance | changed 2026-05-28 21:41:17.586831 | 2026-05-28 21:41:17.586959 | TASK [setup-uv : Print version] 2026-05-28 21:41:17.956441 | instance | uv 0.8.13 2026-05-28 21:41:18.119791 | instance | ok: Runtime: 0:00:00.012138 2026-05-28 21:41:18.129779 | 2026-05-28 21:41:18.129853 | TASK [Install Ansible collection siblings] 2026-05-28 21:41:18.734583 | instance | ERROR 2026-05-28 21:41:18.734863 | instance | { 2026-05-28 21:41:18.734894 | instance | "log": [ 2026-05-28 21:41:18.734916 | instance | "Sibling collection ansible.netcommon at /home/zuul/src/github.com/ansible-collections/ansible.netcommon", 2026-05-28 21:41:18.734935 | instance | "Sibling collection ansible.posix at /home/zuul/src/github.com/ansible-collections/ansible.posix", 2026-05-28 21:41:18.734954 | instance | "Sibling collection ansible.utils at /home/zuul/src/github.com/ansible-collections/ansible.utils", 2026-05-28 21:41:18.734975 | instance | "Sibling collection community.crypto at /home/zuul/src/github.com/ansible-collections/community.crypto", 2026-05-28 21:41:18.734994 | instance | "Sibling collection community.general at /home/zuul/src/github.com/ansible-collections/community.general", 2026-05-28 21:41:18.735014 | instance | "Sibling collection community.mysql at /home/zuul/src/github.com/ansible-collections/community.mysql", 2026-05-28 21:41:18.735033 | instance | "Sibling collection kubernetes.core at /home/zuul/src/github.com/ansible-collections/kubernetes.core", 2026-05-28 21:41:18.735050 | instance | "Sibling collection vexxhost.ceph at /home/zuul/src/github.com/vexxhost/ansible-collection-ceph", 2026-05-28 21:41:18.735069 | instance | "Sibling collection vexxhost.containers at /home/zuul/src/github.com/vexxhost/ansible-collection-containers", 2026-05-28 21:41:18.735089 | instance | "Sibling collection vexxhost.kubernetes at /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes", 2026-05-28 21:41:18.735106 | instance | "Sibling collection atmosphere.common at /home/zuul/src/github.com/vexxhost/atmosphere.common", 2026-05-28 21:41:18.735123 | instance | "Sibling collection openstack.cloud at /home/zuul/src/opendev.org/openstack/ansible-collections-openstack", 2026-05-28 21:41:18.735140 | instance | "Resolved ansible.netcommon to v8.5.2 for >=1.2.0", 2026-05-28 21:41:18.735157 | instance | "Resolved ansible.posix to 1.6.0 for 1.6.0", 2026-05-28 21:41:18.735175 | instance | "Resolved ansible.utils to v6.0.2 for >=2.9.0,>=3.0.0", 2026-05-28 21:41:18.735192 | instance | "Resolved community.crypto to 2.2.3 for 2.2.3", 2026-05-28 21:41:18.735209 | instance | "Resolved community.general to 7.3.0 for 7.3.0", 2026-05-28 21:41:18.735226 | instance | "Resolved community.mysql to 3.6.0 for 3.6.0", 2026-05-28 21:41:18.735243 | instance | "Resolved kubernetes.core to 2.4.0 for 2.4.0", 2026-05-28 21:41:18.735260 | instance | "Resolved openstack.cloud to 1.7.0 for 1.7.0", 2026-05-28 21:41:18.735295 | instance | "Resolved atmosphere.common to v0.7.0 for >=0.6.0", 2026-05-28 21:41:18.735314 | instance | "Resolved vexxhost.ceph to v4.0.0 for >=3.1.2", 2026-05-28 21:41:18.735331 | instance | "Resolved vexxhost.kubernetes to v3.1.0 for >=2.3.2,>=2.5.0", 2026-05-28 21:41:18.735348 | instance | "Resolved ansible.utils to v6.0.2 for >=2.9.0,>=3.0.0,>=6.0.0", 2026-05-28 21:41:18.735365 | instance | "Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2" 2026-05-28 21:41:18.735382 | instance | ], 2026-05-28 21:41:18.735398 | instance | "msg": "No local release tag for openstack.cloud satisfies 1.7.0,>=2.0.0" 2026-05-28 21:41:18.735415 | instance | } failure 2026-05-28 21:41:18.738389 | 2026-05-28 21:41:18.738434 | PLAY RECAP 2026-05-28 21:41:18.738476 | instance | ok: 2 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2026-05-28 21:41:18.738498 | 2026-05-28 21:41:18.903068 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-05-28 21:41:18.908302 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-05-28 21:41:19.596479 | 2026-05-28 21:41:19.596628 | PLAY [all] 2026-05-28 21:41:19.610261 | 2026-05-28 21:41:19.610357 | TASK [fetch-output : Set log path for multiple nodes] 2026-05-28 21:41:19.656850 | instance | skipping: Conditional result was False 2026-05-28 21:41:19.668585 | 2026-05-28 21:41:19.668759 | TASK [fetch-output : Set log path for single node] 2026-05-28 21:41:19.712469 | instance | ok 2026-05-28 21:41:19.719214 | 2026-05-28 21:41:19.719308 | LOOP [fetch-output : Ensure local output dirs] 2026-05-28 21:41:21.371656 | instance -> localhost | ok: "/var/lib/zuul/builds/b132fea471264ebf9de4cb61c30d22af/work/logs" 2026-05-28 21:41:21.629743 | instance -> localhost | changed: "/var/lib/zuul/builds/b132fea471264ebf9de4cb61c30d22af/work/artifacts" 2026-05-28 21:41:21.917745 | instance -> localhost | changed: "/var/lib/zuul/builds/b132fea471264ebf9de4cb61c30d22af/work/docs" 2026-05-28 21:41:21.937126 | 2026-05-28 21:41:21.937318 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-05-28 21:41:22.572929 | instance | ok 2026-05-28 21:41:22.573538 | instance | changed: All items complete 2026-05-28 21:41:22.573594 | 2026-05-28 21:41:25.198104 | instance | changed: .d..t...... ./ 2026-05-28 21:41:25.660153 | instance | changed: .d..t...... ./ 2026-05-28 21:41:25.675588 | 2026-05-28 21:41:25.675749 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-05-28 21:41:26.201496 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.009189 2026-05-28 21:41:26.476386 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.008370 2026-05-28 21:41:26.487059 | 2026-05-28 21:41:26.487187 | PLAY [all] 2026-05-28 21:41:26.494436 | 2026-05-28 21:41:26.494946 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-05-28 21:41:26.921580 | instance | changed 2026-05-28 21:41:26.931353 | 2026-05-28 21:41:26.931415 | PLAY RECAP 2026-05-28 21:41:26.931461 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-05-28 21:41:26.931483 | 2026-05-28 21:41:27.093489 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-05-28 21:41:27.095922 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-05-28 21:41:27.782212 | 2026-05-28 21:41:27.782371 | PLAY [localhost] 2026-05-28 21:41:27.796094 | 2026-05-28 21:41:27.796210 | TASK [Generate Zuul manifest] 2026-05-28 21:41:27.822117 | localhost | ok 2026-05-28 21:41:27.843344 | 2026-05-28 21:41:27.843504 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-05-28 21:41:28.313115 | localhost | changed 2026-05-28 21:41:28.324892 | 2026-05-28 21:41:28.325089 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-05-28 21:41:28.682164 | localhost | ok 2026-05-28 21:41:28.692068 | 2026-05-28 21:41:28.692162 | TASK [Upload logs] 2026-05-28 21:41:28.713920 | localhost | ok 2026-05-28 21:41:28.783808 | 2026-05-28 21:41:28.783964 | TASK [Set zuul-log-path fact] 2026-05-28 21:41:28.806639 | localhost | ok 2026-05-28 21:41:28.818636 | 2026-05-28 21:41:28.818710 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-28 21:41:28.851631 | localhost | ok 2026-05-28 21:41:28.861505 | 2026-05-28 21:41:28.861578 | TASK [upload-logs : Create log directories] 2026-05-28 21:41:29.283229 | localhost | changed 2026-05-28 21:41:29.289218 | 2026-05-28 21:41:29.289296 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-05-28 21:41:29.752599 | localhost -> localhost | ok: Runtime: 0:00:00.006276 2026-05-28 21:41:29.758513 | 2026-05-28 21:41:29.758586 | TASK [upload-logs : Upload logs to log server] 2026-05-28 21:41:33.061938 | localhost | Output suppressed because no_log was given 2026-05-28 21:41:33.126277 | 2026-05-28 21:41:33.126459 | LOOP [upload-logs : Compress console log and json output] 2026-05-28 21:41:33.174953 | localhost | skipping: Conditional result was False 2026-05-28 21:41:33.182344 | localhost | skipping: Conditional result was False 2026-05-28 21:41:33.203837 | 2026-05-28 21:41:33.204020 | LOOP [upload-logs : Upload compressed console log and json output] 2026-05-28 21:41:33.249031 | localhost | skipping: Conditional result was False 2026-05-28 21:41:33.249459 | 2026-05-28 21:41:33.252737 | localhost | skipping: Conditional result was False 2026-05-28 21:41:33.269993 | 2026-05-28 21:41:33.270165 | LOOP [upload-logs : Upload console log and json output]