2026-06-13 01:01:31.858582 | Job console starting 2026-06-13 01:01:31.868613 | Updating git repos 2026-06-13 01:01:32.302737 | Cloning repos into workspace 2026-06-13 01:01:53.101813 | Restoring repo states 2026-06-13 01:02:04.254181 | Merging changes 2026-06-13 01:02:05.489306 | Checking out repos 2026-06-13 01:02:07.486651 | Preparing playbooks 2026-06-13 01:02:11.119820 | Running Ansible setup 2026-06-13 01:02:14.671530 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-06-13 01:02:15.278267 | 2026-06-13 01:02:15.278426 | PLAY [localhost] 2026-06-13 01:02:15.286808 | 2026-06-13 01:02:15.286886 | TASK [Gathering Facts] 2026-06-13 01:02:16.177379 | localhost | ok 2026-06-13 01:02:16.188795 | 2026-06-13 01:02:16.188909 | TASK [Setup log path fact] 2026-06-13 01:02:16.212422 | localhost | ok 2026-06-13 01:02:16.224858 | 2026-06-13 01:02:16.224950 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-06-13 01:02:16.255783 | localhost | ok 2026-06-13 01:02:16.264445 | 2026-06-13 01:02:16.264574 | TASK [emit-job-header : Print job information] 2026-06-13 01:02:16.308122 | # Job Information 2026-06-13 01:02:16.308360 | Ansible Version: 2.16.18 2026-06-13 01:02:16.308433 | Job: atmosphere-molecule-csi-local-path-provisioner 2026-06-13 01:02:16.308485 | Pipeline: check 2026-06-13 01:02:16.308532 | Executor: 2d72f0692154 2026-06-13 01:02:16.308578 | Triggered by: https://github.com/vexxhost/atmosphere/pull/3985 2026-06-13 01:02:16.308617 | Event ID: d5e8fa20-66c2-11f1-9142-8939870a2e2d 2026-06-13 01:02:16.312471 | 2026-06-13 01:02:16.312560 | LOOP [emit-job-header : Print node information] 2026-06-13 01:02:16.411088 | localhost | ok: 2026-06-13 01:02:16.411424 | localhost | # Node Information 2026-06-13 01:02:16.411498 | localhost | Inventory Hostname: instance 2026-06-13 01:02:16.411555 | localhost | Hostname: np0000179868 2026-06-13 01:02:16.411608 | localhost | Username: zuul 2026-06-13 01:02:16.411667 | localhost | Distro: Ubuntu 22.04 2026-06-13 01:02:16.411707 | localhost | Provider: yul1 2026-06-13 01:02:16.411731 | localhost | Region: ca-ymq-1 2026-06-13 01:02:16.411778 | localhost | Label: ubuntu-jammy 2026-06-13 01:02:16.411816 | localhost | Product Name: OpenStack Nova 2026-06-13 01:02:16.411842 | localhost | Interface IP: 199.204.45.248 2026-06-13 01:02:16.424106 | 2026-06-13 01:02:16.424239 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-06-13 01:02:16.835421 | localhost -> localhost | changed 2026-06-13 01:02:16.844639 | 2026-06-13 01:02:16.844732 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-06-13 01:02:17.673140 | localhost -> localhost | changed 2026-06-13 01:02:17.683756 | 2026-06-13 01:02:17.683838 | PLAY [all] 2026-06-13 01:02:17.694416 | 2026-06-13 01:02:17.694484 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-06-13 01:02:17.922076 | instance -> localhost | ok 2026-06-13 01:02:17.932915 | 2026-06-13 01:02:17.933006 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-06-13 01:02:17.967834 | instance | ok 2026-06-13 01:02:17.981165 | instance | included: /var/lib/zuul/builds/debc79887e9e408ebc561b0ffe7f513b/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-06-13 01:02:17.986824 | 2026-06-13 01:02:17.986897 | TASK [add-build-sshkey : Create Temp SSH key] 2026-06-13 01:02:18.955246 | instance -> localhost | Generating public/private rsa key pair. 2026-06-13 01:02:18.955543 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/debc79887e9e408ebc561b0ffe7f513b/work/debc79887e9e408ebc561b0ffe7f513b_id_rsa 2026-06-13 01:02:18.955607 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/debc79887e9e408ebc561b0ffe7f513b/work/debc79887e9e408ebc561b0ffe7f513b_id_rsa.pub 2026-06-13 01:02:18.955656 | instance -> localhost | The key fingerprint is: 2026-06-13 01:02:18.955704 | instance -> localhost | SHA256:QWTIU/e3g1GQrnjrFgnfFHdSDqBkGKx0bdHfWymstKk zuul-build-sshkey 2026-06-13 01:02:18.955746 | instance -> localhost | The key's randomart image is: 2026-06-13 01:02:18.955768 | instance -> localhost | +---[RSA 3072]----+ 2026-06-13 01:02:18.955798 | instance -> localhost | | . =*+=oo+o..| 2026-06-13 01:02:18.955821 | instance -> localhost | | =o++oo+.oo.| 2026-06-13 01:02:18.955841 | instance -> localhost | | . +....++.+o| 2026-06-13 01:02:18.955861 | instance -> localhost | | . .. .o*.oo| 2026-06-13 01:02:18.955881 | instance -> localhost | | S+.== + o| 2026-06-13 01:02:18.955901 | instance -> localhost | | . *+. o | 2026-06-13 01:02:18.955921 | instance -> localhost | | ..o | 2026-06-13 01:02:18.955940 | instance -> localhost | | Eo | 2026-06-13 01:02:18.955962 | instance -> localhost | | o. | 2026-06-13 01:02:18.955983 | instance -> localhost | +----[SHA256]-----+ 2026-06-13 01:02:18.956034 | instance -> localhost | ok: Runtime: 0:00:00.553178 2026-06-13 01:02:18.962329 | 2026-06-13 01:02:18.962395 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-06-13 01:02:18.996759 | instance | ok 2026-06-13 01:02:19.008382 | instance | included: /var/lib/zuul/builds/debc79887e9e408ebc561b0ffe7f513b/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-06-13 01:02:19.015938 | 2026-06-13 01:02:19.016007 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-06-13 01:02:19.041829 | instance | skipping: Conditional result was False 2026-06-13 01:02:19.051328 | 2026-06-13 01:02:19.051447 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-06-13 01:02:19.519880 | instance | changed 2026-06-13 01:02:19.528655 | 2026-06-13 01:02:19.528770 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-06-13 01:02:19.714679 | instance | ok 2026-06-13 01:02:19.721830 | 2026-06-13 01:02:19.721920 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-06-13 01:02:20.166870 | instance | changed 2026-06-13 01:02:20.173795 | 2026-06-13 01:02:20.173930 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-06-13 01:02:20.653739 | instance | changed 2026-06-13 01:02:20.664751 | 2026-06-13 01:02:20.664917 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-06-13 01:02:20.691598 | instance | skipping: Conditional result was False 2026-06-13 01:02:20.702948 | 2026-06-13 01:02:20.703045 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-06-13 01:02:21.073754 | instance -> localhost | changed 2026-06-13 01:02:21.089695 | 2026-06-13 01:02:21.089866 | TASK [add-build-sshkey : Add back temp key] 2026-06-13 01:02:21.365824 | instance -> localhost | Identity added: /var/lib/zuul/builds/debc79887e9e408ebc561b0ffe7f513b/work/debc79887e9e408ebc561b0ffe7f513b_id_rsa (zuul-build-sshkey) 2026-06-13 01:02:21.366092 | instance -> localhost | ok: Runtime: 0:00:00.014240 2026-06-13 01:02:21.373479 | 2026-06-13 01:02:21.373610 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-06-13 01:02:21.695334 | instance | ok 2026-06-13 01:02:21.700093 | 2026-06-13 01:02:21.700163 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-06-13 01:02:21.725989 | instance | skipping: Conditional result was False 2026-06-13 01:02:21.741309 | 2026-06-13 01:02:21.741377 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-06-13 01:02:22.069167 | instance | ok 2026-06-13 01:02:22.074863 | 2026-06-13 01:02:22.074929 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-06-13 01:02:25.485897 | instance | Output suppressed because no_log was given 2026-06-13 01:02:25.495606 | 2026-06-13 01:02:25.495672 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-06-13 01:02:25.697547 | instance | ok: "logs" 2026-06-13 01:02:25.697840 | instance | ok: All items complete 2026-06-13 01:02:25.697880 | 2026-06-13 01:02:25.841549 | instance | ok: "artifacts" 2026-06-13 01:02:25.997429 | instance | ok: "docs" 2026-06-13 01:02:26.016713 | 2026-06-13 01:02:26.016933 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-06-13 01:02:26.200240 | instance | changed: "logs" 2026-06-13 01:02:26.355236 | instance | changed: "artifacts" 2026-06-13 01:02:26.501658 | instance | changed: "docs" 2026-06-13 01:02:26.521921 | 2026-06-13 01:02:26.522007 | PLAY RECAP 2026-06-13 01:02:26.522055 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-06-13 01:02:26.522085 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-06-13 01:02:26.522107 | 2026-06-13 01:02:26.660648 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-06-13 01:02:26.664604 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-06-13 01:02:27.289849 | 2026-06-13 01:02:27.290013 | PLAY [all] 2026-06-13 01:02:27.301948 | 2026-06-13 01:02:27.302038 | TASK [setup-uv : Extract archive] 2026-06-13 01:02:29.514710 | instance | changed 2026-06-13 01:02:29.520889 | 2026-06-13 01:02:29.521011 | TASK [setup-uv : Print version] 2026-06-13 01:02:29.865643 | instance | uv 0.8.13 2026-06-13 01:02:30.057237 | instance | ok: Runtime: 0:00:00.012135 2026-06-13 01:02:30.068307 | 2026-06-13 01:02:30.068383 | TASK [Install Ansible collection siblings] 2026-06-13 01:02:30.683022 | instance | ERROR 2026-06-13 01:02:30.683387 | instance | { 2026-06-13 01:02:30.683434 | instance | "log": [ 2026-06-13 01:02:30.683464 | instance | "Sibling collection ansible.netcommon at /home/zuul/src/github.com/ansible-collections/ansible.netcommon", 2026-06-13 01:02:30.683493 | instance | "Sibling collection ansible.posix at /home/zuul/src/github.com/ansible-collections/ansible.posix", 2026-06-13 01:02:30.683519 | instance | "Sibling collection ansible.utils at /home/zuul/src/github.com/ansible-collections/ansible.utils", 2026-06-13 01:02:30.683549 | instance | "Sibling collection community.crypto at /home/zuul/src/github.com/ansible-collections/community.crypto", 2026-06-13 01:02:30.683576 | instance | "Sibling collection community.general at /home/zuul/src/github.com/ansible-collections/community.general", 2026-06-13 01:02:30.683604 | instance | "Sibling collection community.mysql at /home/zuul/src/github.com/ansible-collections/community.mysql", 2026-06-13 01:02:30.683630 | instance | "Sibling collection kubernetes.core at /home/zuul/src/github.com/ansible-collections/kubernetes.core", 2026-06-13 01:02:30.683655 | instance | "Sibling collection vexxhost.ceph at /home/zuul/src/github.com/vexxhost/ansible-collection-ceph", 2026-06-13 01:02:30.683680 | instance | "Sibling collection vexxhost.containers at /home/zuul/src/github.com/vexxhost/ansible-collection-containers", 2026-06-13 01:02:30.683704 | instance | "Sibling collection vexxhost.kubernetes at /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes", 2026-06-13 01:02:30.683729 | instance | "Sibling collection atmosphere.common at /home/zuul/src/github.com/vexxhost/atmosphere.common", 2026-06-13 01:02:30.683753 | instance | "Sibling collection openstack.cloud at /home/zuul/src/opendev.org/openstack/ansible-collections-openstack", 2026-06-13 01:02:30.683777 | instance | "Resolved ansible.netcommon to v8.5.2 for >=1.2.0", 2026-06-13 01:02:30.683802 | instance | "Resolved ansible.posix to 1.6.0 for 1.6.0", 2026-06-13 01:02:30.683827 | instance | "Resolved ansible.utils to v6.0.2 for >=2.9.0,>=3.0.0", 2026-06-13 01:02:30.683851 | instance | "Resolved community.crypto to 2.2.3 for 2.2.3", 2026-06-13 01:02:30.683876 | instance | "Resolved community.general to 7.3.0 for 7.3.0", 2026-06-13 01:02:30.683900 | instance | "Resolved community.mysql to 3.6.0 for 3.6.0", 2026-06-13 01:02:30.683924 | instance | "Resolved kubernetes.core to 2.4.0 for 2.4.0", 2026-06-13 01:02:30.683949 | instance | "Resolved openstack.cloud to 1.7.0 for 1.7.0", 2026-06-13 01:02:30.683992 | instance | "Resolved atmosphere.common to v0.7.0 for >=0.6.0", 2026-06-13 01:02:30.684019 | instance | "Resolved vexxhost.ceph to v4.0.0 for >=3.1.2", 2026-06-13 01:02:30.684043 | instance | "Resolved vexxhost.kubernetes to v3.2.0 for >=2.3.2,>=2.5.0", 2026-06-13 01:02:30.684068 | instance | "Resolved ansible.utils to v6.0.2 for >=2.9.0,>=3.0.0,>=6.0.0", 2026-06-13 01:02:30.684093 | instance | "Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2" 2026-06-13 01:02:30.684117 | instance | ], 2026-06-13 01:02:30.684141 | instance | "msg": "No local release tag for openstack.cloud satisfies 1.7.0,>=2.0.0" 2026-06-13 01:02:30.684165 | instance | } failure 2026-06-13 01:02:30.688333 | 2026-06-13 01:02:30.688398 | PLAY RECAP 2026-06-13 01:02:30.688461 | instance | ok: 2 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2026-06-13 01:02:30.688491 | 2026-06-13 01:02:30.822592 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-06-13 01:02:30.827847 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-06-13 01:02:31.444763 | 2026-06-13 01:02:31.444905 | PLAY [all] 2026-06-13 01:02:31.456773 | 2026-06-13 01:02:31.456853 | TASK [fetch-output : Set log path for multiple nodes] 2026-06-13 01:02:31.502814 | instance | skipping: Conditional result was False 2026-06-13 01:02:31.512455 | 2026-06-13 01:02:31.512566 | TASK [fetch-output : Set log path for single node] 2026-06-13 01:02:31.557600 | instance | ok 2026-06-13 01:02:31.562892 | 2026-06-13 01:02:31.562962 | LOOP [fetch-output : Ensure local output dirs] 2026-06-13 01:02:31.950663 | instance -> localhost | ok: "/var/lib/zuul/builds/debc79887e9e408ebc561b0ffe7f513b/work/logs" 2026-06-13 01:02:32.167326 | instance -> localhost | changed: "/var/lib/zuul/builds/debc79887e9e408ebc561b0ffe7f513b/work/artifacts" 2026-06-13 01:02:32.369347 | instance -> localhost | changed: "/var/lib/zuul/builds/debc79887e9e408ebc561b0ffe7f513b/work/docs" 2026-06-13 01:02:32.393239 | 2026-06-13 01:02:32.393399 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-06-13 01:02:33.028553 | instance | ok 2026-06-13 01:02:33.029334 | instance | changed: All items complete 2026-06-13 01:02:33.029383 | 2026-06-13 01:02:33.486642 | instance | changed: .d..t...... ./ 2026-06-13 01:02:34.008873 | instance | changed: .d..t...... ./ 2026-06-13 01:02:34.025373 | 2026-06-13 01:02:34.025626 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-06-13 01:02:34.445582 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.007370 2026-06-13 01:02:34.679027 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.008250 2026-06-13 01:02:34.695445 | 2026-06-13 01:02:34.695608 | PLAY [all] 2026-06-13 01:02:34.702250 | 2026-06-13 01:02:34.702329 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-06-13 01:02:35.116421 | instance | changed 2026-06-13 01:02:35.122806 | 2026-06-13 01:02:35.122863 | PLAY RECAP 2026-06-13 01:02:35.122912 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-06-13 01:02:35.122937 | 2026-06-13 01:02:35.267141 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-06-13 01:02:35.440724 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-06-13 01:02:36.053708 | 2026-06-13 01:02:36.053850 | PLAY [localhost] 2026-06-13 01:02:36.064148 | 2026-06-13 01:02:36.064221 | TASK [Generate Zuul manifest] 2026-06-13 01:02:36.086265 | localhost | ok 2026-06-13 01:02:36.102708 | 2026-06-13 01:02:36.102791 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-06-13 01:02:37.256132 | localhost | changed 2026-06-13 01:02:37.268132 | 2026-06-13 01:02:37.268213 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-06-13 01:02:37.300229 | localhost | ok 2026-06-13 01:02:37.306838 | 2026-06-13 01:02:37.306901 | TASK [Upload logs] 2026-06-13 01:02:37.329388 | localhost | ok 2026-06-13 01:02:37.388645 | 2026-06-13 01:02:37.388783 | TASK [Set zuul-log-path fact] 2026-06-13 01:02:37.410433 | localhost | ok 2026-06-13 01:02:37.423854 | 2026-06-13 01:02:37.423954 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-06-13 01:02:37.456029 | localhost | ok 2026-06-13 01:02:37.465822 | 2026-06-13 01:02:37.465921 | TASK [upload-logs : Create log directories] 2026-06-13 01:02:38.016116 | localhost | changed 2026-06-13 01:02:38.023421 | 2026-06-13 01:02:38.023491 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-06-13 01:02:38.425166 | localhost -> localhost | ok: Runtime: 0:00:00.005718 2026-06-13 01:02:38.432238 | 2026-06-13 01:02:38.432330 | TASK [upload-logs : Upload logs to log server] 2026-06-13 01:02:38.925114 | localhost | Output suppressed because no_log was given 2026-06-13 01:02:38.962660 | 2026-06-13 01:02:38.962798 | LOOP [upload-logs : Compress console log and json output] 2026-06-13 01:02:39.008851 | localhost | skipping: Conditional result was False 2026-06-13 01:02:39.016790 | localhost | skipping: Conditional result was False 2026-06-13 01:02:39.029260 | 2026-06-13 01:02:39.029453 | LOOP [upload-logs : Upload compressed console log and json output] 2026-06-13 01:02:39.067323 | localhost | skipping: Conditional result was False 2026-06-13 01:02:39.067678 | 2026-06-13 01:02:39.071165 | localhost | skipping: Conditional result was False 2026-06-13 01:02:39.081143 | 2026-06-13 01:02:39.081281 | LOOP [upload-logs : Upload console log and json output]