2026-05-27 00:30:23.786172 | Job console starting 2026-05-27 00:30:23.797680 | Updating git repos 2026-05-27 00:30:28.287590 | Cloning repos into workspace 2026-05-27 00:31:29.447915 | Restoring repo states 2026-05-27 00:31:33.251223 | Merging changes 2026-05-27 00:31:36.361656 | Checking out repos 2026-05-27 00:31:54.314541 | Preparing playbooks 2026-05-27 00:32:17.327642 | Running Ansible setup 2026-05-27 00:32:22.060116 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-05-27 00:32:22.762428 | 2026-05-27 00:32:22.763035 | PLAY [localhost] 2026-05-27 00:32:22.812161 | 2026-05-27 00:32:22.812468 | TASK [Gathering Facts] 2026-05-27 00:32:23.784002 | localhost | ok 2026-05-27 00:32:23.794650 | 2026-05-27 00:32:23.794852 | TASK [Setup log path fact] 2026-05-27 00:32:23.815673 | localhost | ok 2026-05-27 00:32:23.830829 | 2026-05-27 00:32:23.830930 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-27 00:32:23.862616 | localhost | ok 2026-05-27 00:32:23.870110 | 2026-05-27 00:32:23.870180 | TASK [emit-job-header : Print job information] 2026-05-27 00:32:23.933345 | # Job Information 2026-05-27 00:32:23.933595 | Ansible Version: 2.16.18 2026-05-27 00:32:23.933641 | Job: atmosphere-molecule-csi-local-path-provisioner 2026-05-27 00:32:23.933673 | Pipeline: check 2026-05-27 00:32:23.933702 | Executor: 2d72f0692154 2026-05-27 00:32:23.933730 | Triggered by: https://github.com/vexxhost/atmosphere/pull/3985 2026-05-27 00:32:23.933761 | Event ID: eb0e5c50-5962-11f1-9b04-200fb3f0fd60 2026-05-27 00:32:23.938299 | 2026-05-27 00:32:23.938456 | LOOP [emit-job-header : Print node information] 2026-05-27 00:32:24.044569 | localhost | ok: 2026-05-27 00:32:24.044778 | localhost | # Node Information 2026-05-27 00:32:24.044808 | localhost | Inventory Hostname: instance 2026-05-27 00:32:24.044830 | localhost | Hostname: np0000176744 2026-05-27 00:32:24.044850 | localhost | Username: zuul 2026-05-27 00:32:24.044879 | localhost | Distro: Ubuntu 22.04 2026-05-27 00:32:24.044898 | localhost | Provider: yul1 2026-05-27 00:32:24.044918 | localhost | Region: ca-ymq-1 2026-05-27 00:32:24.044942 | localhost | Label: ubuntu-jammy 2026-05-27 00:32:24.044996 | localhost | Product Name: OpenStack Nova 2026-05-27 00:32:24.045024 | localhost | Interface IP: 199.204.45.132 2026-05-27 00:32:24.054248 | 2026-05-27 00:32:24.054441 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-05-27 00:32:24.954062 | localhost -> localhost | changed 2026-05-27 00:32:24.960954 | 2026-05-27 00:32:24.961083 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-05-27 00:32:26.197582 | localhost -> localhost | changed 2026-05-27 00:32:26.212889 | 2026-05-27 00:32:26.212988 | PLAY [all] 2026-05-27 00:32:26.228202 | 2026-05-27 00:32:26.228284 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-05-27 00:32:26.501344 | instance -> localhost | ok 2026-05-27 00:32:26.510732 | 2026-05-27 00:32:26.510811 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-05-27 00:32:26.542853 | instance | ok 2026-05-27 00:32:26.560011 | instance | included: /var/lib/zuul/builds/8b5d86d0c6d54ba1b7dfe79f6c891b34/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-05-27 00:32:26.566616 | 2026-05-27 00:32:26.566686 | TASK [add-build-sshkey : Create Temp SSH key] 2026-05-27 00:32:27.663652 | instance -> localhost | Generating public/private rsa key pair. 2026-05-27 00:32:27.663890 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/8b5d86d0c6d54ba1b7dfe79f6c891b34/work/8b5d86d0c6d54ba1b7dfe79f6c891b34_id_rsa 2026-05-27 00:32:27.663931 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/8b5d86d0c6d54ba1b7dfe79f6c891b34/work/8b5d86d0c6d54ba1b7dfe79f6c891b34_id_rsa.pub 2026-05-27 00:32:27.663963 | instance -> localhost | The key fingerprint is: 2026-05-27 00:32:27.663993 | instance -> localhost | SHA256:iIlBeX/Or9HnjhVcmtB+z/Tm5YRyyZaj0683U6F8pho zuul-build-sshkey 2026-05-27 00:32:27.664043 | instance -> localhost | The key's randomart image is: 2026-05-27 00:32:27.664073 | instance -> localhost | +---[RSA 3072]----+ 2026-05-27 00:32:27.664114 | instance -> localhost | | .. | 2026-05-27 00:32:27.664145 | instance -> localhost | | .. . . | 2026-05-27 00:32:27.664173 | instance -> localhost | | .. . . . . | 2026-05-27 00:32:27.664200 | instance -> localhost | | o o... + + . | 2026-05-27 00:32:27.664227 | instance -> localhost | | . o .+S B o o| 2026-05-27 00:32:27.664254 | instance -> localhost | | o. * Xo| 2026-05-27 00:32:27.664283 | instance -> localhost | | ...E+.% O| 2026-05-27 00:32:27.664312 | instance -> localhost | | ..=o*.Oo| 2026-05-27 00:32:27.664342 | instance -> localhost | | ...o=..+*| 2026-05-27 00:32:27.664370 | instance -> localhost | +----[SHA256]-----+ 2026-05-27 00:32:27.664438 | instance -> localhost | ok: Runtime: 0:00:00.616622 2026-05-27 00:32:27.672179 | 2026-05-27 00:32:27.672260 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-05-27 00:32:27.711281 | instance | ok 2026-05-27 00:32:27.722002 | instance | included: /var/lib/zuul/builds/8b5d86d0c6d54ba1b7dfe79f6c891b34/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-05-27 00:32:27.731397 | 2026-05-27 00:32:27.731479 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-05-27 00:32:27.758234 | instance | skipping: Conditional result was False 2026-05-27 00:32:27.771593 | 2026-05-27 00:32:27.771698 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-05-27 00:32:28.235404 | instance | changed 2026-05-27 00:32:28.246612 | 2026-05-27 00:32:28.246733 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-05-27 00:32:28.440783 | instance | ok 2026-05-27 00:32:28.446360 | 2026-05-27 00:32:28.446432 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-05-27 00:32:28.941769 | instance | changed 2026-05-27 00:32:28.948484 | 2026-05-27 00:32:28.948568 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-05-27 00:32:29.436819 | instance | changed 2026-05-27 00:32:29.444181 | 2026-05-27 00:32:29.444265 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-05-27 00:32:29.472892 | instance | skipping: Conditional result was False 2026-05-27 00:32:29.480700 | 2026-05-27 00:32:29.480814 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-05-27 00:32:29.943593 | instance -> localhost | changed 2026-05-27 00:32:29.969766 | 2026-05-27 00:32:29.970054 | TASK [add-build-sshkey : Add back temp key] 2026-05-27 00:32:30.600467 | instance -> localhost | Identity added: /var/lib/zuul/builds/8b5d86d0c6d54ba1b7dfe79f6c891b34/work/8b5d86d0c6d54ba1b7dfe79f6c891b34_id_rsa (zuul-build-sshkey) 2026-05-27 00:32:30.600862 | instance -> localhost | ok: Runtime: 0:00:00.014409 2026-05-27 00:32:30.607447 | 2026-05-27 00:32:30.607536 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-05-27 00:32:30.957965 | instance | ok 2026-05-27 00:32:30.972797 | 2026-05-27 00:32:30.972944 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-05-27 00:32:30.999040 | instance | skipping: Conditional result was False 2026-05-27 00:32:31.012675 | 2026-05-27 00:32:31.012780 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-05-27 00:32:31.420949 | instance | ok 2026-05-27 00:32:31.509429 | 2026-05-27 00:32:31.509536 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-05-27 00:32:37.265255 | instance | Output suppressed because no_log was given 2026-05-27 00:32:37.275034 | 2026-05-27 00:32:37.275106 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-05-27 00:32:37.466809 | instance | ok: "logs" 2026-05-27 00:32:37.467045 | instance | ok: All items complete 2026-05-27 00:32:37.467074 | 2026-05-27 00:32:37.640882 | instance | ok: "artifacts" 2026-05-27 00:32:37.799683 | instance | ok: "docs" 2026-05-27 00:32:37.812498 | 2026-05-27 00:32:37.812663 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-05-27 00:32:38.009739 | instance | changed: "logs" 2026-05-27 00:32:38.158393 | instance | changed: "artifacts" 2026-05-27 00:32:38.331308 | instance | changed: "docs" 2026-05-27 00:32:38.375408 | 2026-05-27 00:32:38.375512 | PLAY RECAP 2026-05-27 00:32:38.375561 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-05-27 00:32:38.375590 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-05-27 00:32:38.375612 | 2026-05-27 00:32:38.543291 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-05-27 00:32:38.546918 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-05-27 00:32:39.306424 | 2026-05-27 00:32:39.306625 | PLAY [all] 2026-05-27 00:32:39.321951 | 2026-05-27 00:32:39.322086 | TASK [setup-uv : Extract archive] 2026-05-27 00:32:41.637391 | instance | changed 2026-05-27 00:32:41.645079 | 2026-05-27 00:32:41.645242 | TASK [setup-uv : Print version] 2026-05-27 00:32:42.070433 | instance | uv 0.8.13 2026-05-27 00:32:42.181574 | instance | ok: Runtime: 0:00:00.016691 2026-05-27 00:32:42.191683 | 2026-05-27 00:32:42.191745 | TASK [Install Ansible collection siblings] 2026-05-27 00:32:42.852087 | instance | ERROR 2026-05-27 00:32:42.856335 | instance | { 2026-05-27 00:32:42.856405 | instance | "log": [ 2026-05-27 00:32:42.856442 | instance | "Sibling collection ansible.netcommon at /home/zuul/src/github.com/ansible-collections/ansible.netcommon", 2026-05-27 00:32:42.856472 | instance | "Sibling collection ansible.posix at /home/zuul/src/github.com/ansible-collections/ansible.posix", 2026-05-27 00:32:42.856499 | instance | "Sibling collection ansible.utils at /home/zuul/src/github.com/ansible-collections/ansible.utils", 2026-05-27 00:32:42.856531 | instance | "Sibling collection community.crypto at /home/zuul/src/github.com/ansible-collections/community.crypto", 2026-05-27 00:32:42.856557 | instance | "Sibling collection community.general at /home/zuul/src/github.com/ansible-collections/community.general", 2026-05-27 00:32:42.856586 | instance | "Sibling collection community.mysql at /home/zuul/src/github.com/ansible-collections/community.mysql", 2026-05-27 00:32:42.856613 | instance | "Sibling collection kubernetes.core at /home/zuul/src/github.com/ansible-collections/kubernetes.core", 2026-05-27 00:32:42.856638 | instance | "Sibling collection vexxhost.ceph at /home/zuul/src/github.com/vexxhost/ansible-collection-ceph", 2026-05-27 00:32:42.856935 | instance | "Sibling collection vexxhost.containers at /home/zuul/src/github.com/vexxhost/ansible-collection-containers", 2026-05-27 00:32:42.857012 | instance | "Sibling collection vexxhost.kubernetes at /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes", 2026-05-27 00:32:42.857043 | instance | "Sibling collection atmosphere.common at /home/zuul/src/github.com/vexxhost/atmosphere.common", 2026-05-27 00:32:42.857070 | instance | "Sibling collection openstack.cloud at /home/zuul/src/opendev.org/openstack/ansible-collections-openstack", 2026-05-27 00:32:42.857094 | instance | "Resolved ansible.netcommon to v8.5.2 for >=1.2.0", 2026-05-27 00:32:42.857121 | instance | "Resolved ansible.posix to 1.6.0 for 1.6.0", 2026-05-27 00:32:42.857147 | instance | "Resolved ansible.utils to v6.0.2 for >=2.9.0,>=3.0.0", 2026-05-27 00:32:42.857172 | instance | "Resolved community.crypto to 2.2.3 for 2.2.3", 2026-05-27 00:32:42.857197 | instance | "Resolved community.general to 7.3.0 for 7.3.0", 2026-05-27 00:32:42.857222 | instance | "Resolved community.mysql to 3.6.0 for 3.6.0", 2026-05-27 00:32:42.857246 | instance | "Resolved kubernetes.core to 2.4.0 for 2.4.0", 2026-05-27 00:32:42.857269 | instance | "Resolved openstack.cloud to 1.7.0 for 1.7.0", 2026-05-27 00:32:42.857321 | instance | "Resolved atmosphere.common to v0.7.0 for >=0.6.0", 2026-05-27 00:32:42.858217 | instance | "Resolved vexxhost.ceph to v4.0.0 for >=3.1.2", 2026-05-27 00:32:42.858274 | instance | "Resolved vexxhost.kubernetes to v3.1.0 for >=2.3.2,>=2.5.0", 2026-05-27 00:32:42.858306 | instance | "Resolved ansible.utils to v6.0.2 for >=2.9.0,>=3.0.0,>=6.0.0", 2026-05-27 00:32:42.858332 | instance | "Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2" 2026-05-27 00:32:42.858357 | instance | ], 2026-05-27 00:32:42.858382 | instance | "msg": "No local release tag for openstack.cloud satisfies 1.7.0,>=2.0.0" 2026-05-27 00:32:42.858407 | instance | } failure 2026-05-27 00:32:42.869683 | 2026-05-27 00:32:42.869808 | PLAY RECAP 2026-05-27 00:32:42.869860 | instance | ok: 2 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2026-05-27 00:32:42.869882 | 2026-05-27 00:32:43.070416 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-05-27 00:32:43.074909 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-05-27 00:32:43.738039 | 2026-05-27 00:32:43.773239 | PLAY [all] 2026-05-27 00:32:43.789894 | 2026-05-27 00:32:43.790041 | TASK [fetch-output : Set log path for multiple nodes] 2026-05-27 00:32:43.835728 | instance | skipping: Conditional result was False 2026-05-27 00:32:43.844190 | 2026-05-27 00:32:43.906184 | TASK [fetch-output : Set log path for single node] 2026-05-27 00:32:43.950174 | instance | ok 2026-05-27 00:32:43.955854 | 2026-05-27 00:32:43.955924 | LOOP [fetch-output : Ensure local output dirs] 2026-05-27 00:32:44.468688 | instance -> localhost | ok: "/var/lib/zuul/builds/8b5d86d0c6d54ba1b7dfe79f6c891b34/work/logs" 2026-05-27 00:32:44.753997 | instance -> localhost | changed: "/var/lib/zuul/builds/8b5d86d0c6d54ba1b7dfe79f6c891b34/work/artifacts" 2026-05-27 00:32:44.966026 | instance -> localhost | changed: "/var/lib/zuul/builds/8b5d86d0c6d54ba1b7dfe79f6c891b34/work/docs" 2026-05-27 00:32:44.989126 | 2026-05-27 00:32:44.989318 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-05-27 00:32:45.620621 | instance | changed: .d..t...... ./ 2026-05-27 00:32:45.620872 | instance | changed: All items complete 2026-05-27 00:32:45.620901 | 2026-05-27 00:32:46.118010 | instance | changed: .d..t...... ./ 2026-05-27 00:32:46.879563 | instance | changed: .d..t...... ./ 2026-05-27 00:32:46.904893 | 2026-05-27 00:32:46.905087 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-05-27 00:32:47.398438 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.006524 2026-05-27 00:32:47.701030 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.008635 2026-05-27 00:32:47.725613 | 2026-05-27 00:32:47.732508 | PLAY [all] 2026-05-27 00:32:47.743452 | 2026-05-27 00:32:47.743601 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-05-27 00:32:48.247787 | instance | changed 2026-05-27 00:32:48.265201 | 2026-05-27 00:32:48.265324 | PLAY RECAP 2026-05-27 00:32:48.265379 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-05-27 00:32:48.265403 | 2026-05-27 00:32:48.411996 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-05-27 00:32:48.415567 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-05-27 00:32:49.082557 | 2026-05-27 00:32:49.082724 | PLAY [localhost] 2026-05-27 00:32:49.096847 | 2026-05-27 00:32:49.097058 | TASK [Generate Zuul manifest] 2026-05-27 00:32:49.120147 | localhost | ok 2026-05-27 00:32:49.207660 | 2026-05-27 00:32:49.207782 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-05-27 00:32:49.642702 | localhost | changed 2026-05-27 00:32:49.727125 | 2026-05-27 00:32:49.752552 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-05-27 00:32:49.792435 | localhost | ok 2026-05-27 00:32:49.800143 | 2026-05-27 00:32:49.800257 | TASK [Upload logs] 2026-05-27 00:32:49.823179 | localhost | ok 2026-05-27 00:32:49.961000 | 2026-05-27 00:32:50.001873 | TASK [Set zuul-log-path fact] 2026-05-27 00:32:50.025646 | localhost | ok 2026-05-27 00:32:50.110010 | 2026-05-27 00:32:50.110133 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-27 00:32:50.142026 | localhost | ok 2026-05-27 00:32:50.200437 | 2026-05-27 00:32:50.200546 | TASK [upload-logs : Create log directories] 2026-05-27 00:32:50.646035 | localhost | changed 2026-05-27 00:32:50.650640 | 2026-05-27 00:32:50.650706 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-05-27 00:32:51.264918 | localhost -> localhost | ok: Runtime: 0:00:00.125952 2026-05-27 00:32:51.271975 | 2026-05-27 00:32:51.272085 | TASK [upload-logs : Upload logs to log server] 2026-05-27 00:32:51.846573 | localhost | Output suppressed because no_log was given 2026-05-27 00:32:51.925424 | 2026-05-27 00:32:51.925581 | LOOP [upload-logs : Compress console log and json output] 2026-05-27 00:32:51.971219 | localhost | skipping: Conditional result was False 2026-05-27 00:32:52.081784 | localhost | skipping: Conditional result was False 2026-05-27 00:32:52.097089 | 2026-05-27 00:32:52.097347 | LOOP [upload-logs : Upload compressed console log and json output] 2026-05-27 00:32:52.136825 | localhost | skipping: Conditional result was False 2026-05-27 00:32:52.137401 | 2026-05-27 00:32:52.140734 | localhost | skipping: Conditional result was False 2026-05-27 00:32:52.154625 | 2026-05-27 00:32:52.154852 | LOOP [upload-logs : Upload console log and json output]