2026-05-26 05:45:18.276502 | Job console starting
2026-05-26 05:45:18.293404 | Updating git repos
2026-05-26 05:45:20.042349 | Cloning repos into workspace
2026-05-26 05:45:34.566058 | Restoring repo states
2026-05-26 05:45:43.132385 | Merging changes
2026-05-26 05:45:44.164861 | Checking out repos
2026-05-26 05:45:45.841979 | Preparing playbooks
2026-05-26 05:45:52.363327 | Running Ansible setup
2026-05-26 05:45:56.376298 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-05-26 05:45:57.003312 | 
2026-05-26 05:45:57.003434 | PLAY [localhost]
2026-05-26 05:45:57.011065 | 
2026-05-26 05:45:57.011139 | TASK [Gathering Facts]
2026-05-26 05:45:57.846860 | localhost | ok
2026-05-26 05:45:57.857525 | 
2026-05-26 05:45:57.857627 | TASK [Setup log path fact]
2026-05-26 05:45:57.876571 | localhost | ok
2026-05-26 05:45:57.889671 | 
2026-05-26 05:45:57.889740 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-05-26 05:45:57.918110 | localhost | ok
2026-05-26 05:45:57.926015 | 
2026-05-26 05:45:57.926085 | TASK [emit-job-header : Print job information]
2026-05-26 05:45:57.965423 | # Job Information
2026-05-26 05:45:57.965640 | Ansible Version: 2.16.18
2026-05-26 05:45:57.965715 | Job: atmosphere-molecule-csi-local-path-provisioner
2026-05-26 05:45:57.965767 | Pipeline: check
2026-05-26 05:45:57.965814 | Executor: 2d72f0692154
2026-05-26 05:45:57.965859 | Triggered by: https://github.com/vexxhost/atmosphere/pull/3991
2026-05-26 05:45:57.965914 | Event ID: 4af667a0-58c5-11f1-90f3-935b3405b672
2026-05-26 05:45:57.970938 | 
2026-05-26 05:45:57.971004 | LOOP [emit-job-header : Print node information]
2026-05-26 05:45:58.061447 | localhost | ok:
2026-05-26 05:45:58.061658 | localhost | # Node Information
2026-05-26 05:45:58.061703 | localhost | Inventory Hostname: instance
2026-05-26 05:45:58.061736 | localhost | Hostname: np0000176448
2026-05-26 05:45:58.061768 | localhost | Username: zuul
2026-05-26 05:45:58.061803 | localhost | Distro: Ubuntu 22.04
2026-05-26 05:45:58.061834 | localhost | Provider: yul1
2026-05-26 05:45:58.061863 | localhost | Region: ca-ymq-1
2026-05-26 05:45:58.061892 | localhost | Label: ubuntu-jammy
2026-05-26 05:45:58.061919 | localhost | Product Name: OpenStack Nova
2026-05-26 05:45:58.061950 | localhost | Interface IP: 199.204.45.65
2026-05-26 05:45:58.080733 | 
2026-05-26 05:45:58.080956 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-05-26 05:45:58.470711 | localhost -> localhost | changed
2026-05-26 05:45:58.478240 | 
2026-05-26 05:45:58.478336 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-05-26 05:45:59.328314 | localhost -> localhost | changed
2026-05-26 05:45:59.337551 | 
2026-05-26 05:45:59.337629 | PLAY [all]
2026-05-26 05:45:59.346561 | 
2026-05-26 05:45:59.346626 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-05-26 05:45:59.567855 | instance -> localhost | ok
2026-05-26 05:45:59.582490 | 
2026-05-26 05:45:59.582714 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-05-26 05:45:59.623278 | instance | ok
2026-05-26 05:45:59.637820 | instance | included: /var/lib/zuul/builds/6243e11827624de6b8115f6b1fd124f4/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-05-26 05:45:59.646680 | 
2026-05-26 05:45:59.646898 | TASK [add-build-sshkey : Create Temp SSH key]
2026-05-26 05:46:00.791436 | instance -> localhost | Generating public/private rsa key pair.
2026-05-26 05:46:00.791632 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/6243e11827624de6b8115f6b1fd124f4/work/6243e11827624de6b8115f6b1fd124f4_id_rsa
2026-05-26 05:46:00.791677 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/6243e11827624de6b8115f6b1fd124f4/work/6243e11827624de6b8115f6b1fd124f4_id_rsa.pub
2026-05-26 05:46:00.791711 | instance -> localhost | The key fingerprint is:
2026-05-26 05:46:00.791752 | instance -> localhost | SHA256:imklIzKQSdv5k/6iffFtftL52Ga6gK+TBv5Ab0ONWBQ zuul-build-sshkey
2026-05-26 05:46:00.791801 | instance -> localhost | The key's randomart image is:
2026-05-26 05:46:00.791833 | instance -> localhost | +---[RSA 3072]----+
2026-05-26 05:46:00.791871 | instance -> localhost | | .       E.      |
2026-05-26 05:46:00.791903 | instance -> localhost | |.oo .   .        |
2026-05-26 05:46:00.791934 | instance -> localhost | |+. o     .       |
2026-05-26 05:46:00.791965 | instance -> localhost | |.   . . o o      |
2026-05-26 05:46:00.791996 | instance -> localhost | |o . o+.oSo .     |
2026-05-26 05:46:00.792028 | instance -> localhost | | o ..*o+o .      |
2026-05-26 05:46:00.792061 | instance -> localhost | |    +.o.+=o.. .  |
2026-05-26 05:46:00.792092 | instance -> localhost | |   ....oo=o+.+oo |
2026-05-26 05:46:00.792126 | instance -> localhost | |   ...o.oo=oo+*o |
2026-05-26 05:46:00.792157 | instance -> localhost | +----[SHA256]-----+
2026-05-26 05:46:00.792217 | instance -> localhost | ok: Runtime: 0:00:00.674701
2026-05-26 05:46:00.800111 | 
2026-05-26 05:46:00.800177 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-05-26 05:46:00.832462 | instance | ok
2026-05-26 05:46:00.906342 | instance | included: /var/lib/zuul/builds/6243e11827624de6b8115f6b1fd124f4/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-05-26 05:46:00.915212 | 
2026-05-26 05:46:00.915275 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-05-26 05:46:00.938702 | instance | skipping: Conditional result was False
2026-05-26 05:46:00.950114 | 
2026-05-26 05:46:00.950187 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-05-26 05:46:01.396670 | instance | changed
2026-05-26 05:46:01.429356 | 
2026-05-26 05:46:01.471966 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-05-26 05:46:01.657543 | instance | ok
2026-05-26 05:46:01.663861 | 
2026-05-26 05:46:01.663923 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-05-26 05:46:02.115337 | instance | changed
2026-05-26 05:46:02.120474 | 
2026-05-26 05:46:02.120535 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-05-26 05:46:02.580918 | instance | changed
2026-05-26 05:46:02.589635 | 
2026-05-26 05:46:02.589721 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-05-26 05:46:02.613757 | instance | skipping: Conditional result was False
2026-05-26 05:46:02.622537 | 
2026-05-26 05:46:02.622634 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-05-26 05:46:03.023818 | instance -> localhost | changed
2026-05-26 05:46:03.037525 | 
2026-05-26 05:46:03.037594 | TASK [add-build-sshkey : Add back temp key]
2026-05-26 05:46:03.302662 | instance -> localhost | Identity added: /var/lib/zuul/builds/6243e11827624de6b8115f6b1fd124f4/work/6243e11827624de6b8115f6b1fd124f4_id_rsa (zuul-build-sshkey)
2026-05-26 05:46:03.302863 | instance -> localhost | ok: Runtime: 0:00:00.014579
2026-05-26 05:46:03.309491 | 
2026-05-26 05:46:03.309555 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-05-26 05:46:03.604949 | instance | ok
2026-05-26 05:46:03.609594 | 
2026-05-26 05:46:03.609657 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-05-26 05:46:03.633190 | instance | skipping: Conditional result was False
2026-05-26 05:46:03.647645 | 
2026-05-26 05:46:03.647709 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-05-26 05:46:03.977332 | instance | ok
2026-05-26 05:46:03.986383 | 
2026-05-26 05:46:03.986447 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-05-26 05:46:06.976018 | instance | Output suppressed because no_log was given
2026-05-26 05:46:06.985907 | 
2026-05-26 05:46:06.985980 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-05-26 05:46:09.794325 | instance | ok: "logs"
2026-05-26 05:46:09.852240 | instance | ok: All items complete
2026-05-26 05:46:09.852395 | 
2026-05-26 05:46:09.940430 | instance | ok: "artifacts"
2026-05-26 05:46:10.090789 | instance | ok: "docs"
2026-05-26 05:46:10.107534 | 
2026-05-26 05:46:10.107684 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-05-26 05:46:10.280962 | instance | changed: "logs"
2026-05-26 05:46:10.428367 | instance | changed: "artifacts"
2026-05-26 05:46:10.573063 | instance | changed: "docs"
2026-05-26 05:46:10.593842 | 
2026-05-26 05:46:10.594017 | PLAY RECAP
2026-05-26 05:46:10.594081 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-05-26 05:46:10.594111 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-05-26 05:46:10.594134 | 
2026-05-26 05:46:10.804080 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-05-26 05:46:10.808146 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-05-26 05:46:11.396016 | 
2026-05-26 05:46:12.887511 | PLAY [all]
2026-05-26 05:46:12.908347 | 
2026-05-26 05:46:12.908471 | TASK [setup-uv : Extract archive]
2026-05-26 05:46:15.250343 | instance | changed
2026-05-26 05:46:18.642601 | 
2026-05-26 05:46:18.642781 | TASK [setup-uv : Print version]
2026-05-26 05:46:19.035517 | instance | uv 0.8.13
2026-05-26 05:46:20.623491 | instance | ok: Runtime: 0:00:00.011054
2026-05-26 05:46:20.635653 | 
2026-05-26 05:46:20.635829 | TASK [Install Ansible collection siblings]
2026-05-26 05:46:21.353826 | instance | ERROR
2026-05-26 05:46:23.097247 | instance | {
2026-05-26 05:46:23.097486 | instance |   "log": [
2026-05-26 05:46:23.097545 | instance |     "Sibling collection ansible.netcommon at /home/zuul/src/github.com/ansible-collections/ansible.netcommon",
2026-05-26 05:46:23.097593 | instance |     "Sibling collection ansible.posix at /home/zuul/src/github.com/ansible-collections/ansible.posix",
2026-05-26 05:46:23.097637 | instance |     "Sibling collection ansible.utils at /home/zuul/src/github.com/ansible-collections/ansible.utils",
2026-05-26 05:46:23.097689 | instance |     "Sibling collection community.crypto at /home/zuul/src/github.com/ansible-collections/community.crypto",
2026-05-26 05:46:23.097732 | instance |     "Sibling collection community.general at /home/zuul/src/github.com/ansible-collections/community.general",
2026-05-26 05:46:23.097778 | instance |     "Sibling collection community.mysql at /home/zuul/src/github.com/ansible-collections/community.mysql",
2026-05-26 05:46:23.097820 | instance |     "Sibling collection kubernetes.core at /home/zuul/src/github.com/ansible-collections/kubernetes.core",
2026-05-26 05:46:23.097858 | instance |     "Sibling collection vexxhost.ceph at /home/zuul/src/github.com/vexxhost/ansible-collection-ceph",
2026-05-26 05:46:23.097898 | instance |     "Sibling collection vexxhost.containers at /home/zuul/src/github.com/vexxhost/ansible-collection-containers",
2026-05-26 05:46:23.097937 | instance |     "Sibling collection vexxhost.kubernetes at /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes",
2026-05-26 05:46:23.097976 | instance |     "Sibling collection atmosphere.common at /home/zuul/src/github.com/vexxhost/atmosphere.common",
2026-05-26 05:46:23.098014 | instance |     "Sibling collection openstack.cloud at /home/zuul/src/opendev.org/openstack/ansible-collections-openstack",
2026-05-26 05:46:23.098054 | instance |     "Resolved ansible.netcommon to v8.5.2 for >=1.2.0",
2026-05-26 05:46:23.098095 | instance |     "Resolved ansible.posix to 1.6.0 for 1.6.0",
2026-05-26 05:46:23.098138 | instance |     "Resolved ansible.utils to v6.0.2 for >=2.9.0,>=3.0.0",
2026-05-26 05:46:23.098179 | instance |     "Resolved community.crypto to 2.2.3 for 2.2.3",
2026-05-26 05:46:23.098218 | instance |     "Resolved community.general to 7.3.0 for 7.3.0",
2026-05-26 05:46:23.098257 | instance |     "Resolved community.mysql to 3.6.0 for 3.6.0",
2026-05-26 05:46:23.098296 | instance |     "Resolved kubernetes.core to 2.4.0 for 2.4.0",
2026-05-26 05:46:23.098334 | instance |     "Resolved openstack.cloud to 1.7.0 for 1.7.0",
2026-05-26 05:46:23.098414 | instance |     "Resolved atmosphere.common to v0.7.0 for >=0.6.0",
2026-05-26 05:46:23.098456 | instance |     "Resolved vexxhost.ceph to v4.0.0 for >=3.1.2",
2026-05-26 05:46:23.098496 | instance |     "Resolved vexxhost.kubernetes to v3.1.0 for >=2.3.2,>=2.5.0",
2026-05-26 05:46:23.098535 | instance |     "Resolved ansible.utils to v6.0.2 for >=2.9.0,>=3.0.0,>=6.0.0",
2026-05-26 05:46:23.098573 | instance |     "Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2"
2026-05-26 05:46:23.098612 | instance |   ],
2026-05-26 05:46:23.098652 | instance |   "msg": "No local release tag for openstack.cloud satisfies 1.7.0,>=2.0.0"
2026-05-26 05:46:23.098691 | instance | }
failure
2026-05-26 05:46:23.105586 | 
2026-05-26 05:46:23.105685 | PLAY RECAP
2026-05-26 05:46:23.105751 | instance | ok: 2 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-05-26 05:46:23.105783 | 
2026-05-26 05:46:23.328304 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-05-26 05:46:23.331761 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-05-26 05:46:23.996400 | 
2026-05-26 05:46:23.996515 | PLAY [all]
2026-05-26 05:46:24.008879 | 
2026-05-26 05:46:24.009014 | TASK [fetch-output : Set log path for multiple nodes]
2026-05-26 05:46:24.053504 | instance | skipping: Conditional result was False
2026-05-26 05:46:24.059432 | 
2026-05-26 05:46:24.059524 | TASK [fetch-output : Set log path for single node]
2026-05-26 05:46:24.103066 | instance | ok
2026-05-26 05:46:24.109176 | 
2026-05-26 05:46:24.109285 | LOOP [fetch-output : Ensure local output dirs]
2026-05-26 05:46:24.483106 | instance -> localhost | ok: "/var/lib/zuul/builds/6243e11827624de6b8115f6b1fd124f4/work/logs"
2026-05-26 05:46:24.698171 | instance -> localhost | changed: "/var/lib/zuul/builds/6243e11827624de6b8115f6b1fd124f4/work/artifacts"
2026-05-26 05:46:24.904016 | instance -> localhost | changed: "/var/lib/zuul/builds/6243e11827624de6b8115f6b1fd124f4/work/docs"
2026-05-26 05:46:24.922411 | 
2026-05-26 05:46:24.922548 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-05-26 05:46:25.555979 | instance | ok
2026-05-26 05:46:26.891498 | instance | changed: All items complete
2026-05-26 05:46:26.891657 | 
2026-05-26 05:46:26.954117 | instance | changed: .d..t...... ./
2026-05-26 05:46:27.417317 | instance | changed: .d..t...... ./
2026-05-26 05:46:27.434305 | 
2026-05-26 05:46:27.434473 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-05-26 05:46:27.848421 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.006923
2026-05-26 05:46:28.094273 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.009832
2026-05-26 05:46:28.108291 | 
2026-05-26 05:46:28.108401 | PLAY [all]
2026-05-26 05:46:28.118386 | 
2026-05-26 05:46:28.118499 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-05-26 05:46:28.537226 | instance | changed
2026-05-26 05:46:28.543762 | 
2026-05-26 05:46:28.543835 | PLAY RECAP
2026-05-26 05:46:28.543883 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-05-26 05:46:28.543907 | 
2026-05-26 05:46:28.688676 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-05-26 05:46:28.693663 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-05-26 05:46:29.312940 | 
2026-05-26 05:46:29.313103 | PLAY [localhost]
2026-05-26 05:46:29.323482 | 
2026-05-26 05:46:29.323557 | TASK [Generate Zuul manifest]
2026-05-26 05:46:29.347621 | localhost | ok
2026-05-26 05:46:29.365197 | 
2026-05-26 05:46:29.365342 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-05-26 05:46:29.732655 | localhost | changed
2026-05-26 05:46:29.744943 | 
2026-05-26 05:46:29.745042 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-05-26 05:46:29.828225 | localhost | ok
2026-05-26 05:46:29.836842 | 
2026-05-26 05:46:29.836913 | TASK [Upload logs]
2026-05-26 05:46:29.860650 | localhost | ok
2026-05-26 05:46:29.918920 | 
2026-05-26 05:46:29.919031 | TASK [Set zuul-log-path fact]
2026-05-26 05:46:29.941639 | localhost | ok
2026-05-26 05:46:29.954438 | 
2026-05-26 05:46:29.954506 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-05-26 05:46:29.986909 | localhost | ok
2026-05-26 05:46:29.996402 | 
2026-05-26 05:46:29.996471 | TASK [upload-logs : Create log directories]
2026-05-26 05:46:30.406270 | localhost | changed
2026-05-26 05:46:30.411333 | 
2026-05-26 05:46:30.411409 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-05-26 05:46:30.863090 | localhost -> localhost | ok: Runtime: 0:00:00.004996
2026-05-26 05:46:30.868517 | 
2026-05-26 05:46:30.868622 | TASK [upload-logs : Upload logs to log server]
2026-05-26 05:46:31.339575 | localhost | Output suppressed because no_log was given
2026-05-26 05:46:31.379928 | 
2026-05-26 05:46:31.380062 | LOOP [upload-logs : Compress console log and json output]
2026-05-26 05:46:31.423250 | localhost | skipping: Conditional result was False
2026-05-26 05:46:31.430711 | localhost | skipping: Conditional result was False
2026-05-26 05:46:31.444478 | 
2026-05-26 05:46:31.444630 | LOOP [upload-logs : Upload compressed console log and json output]
2026-05-26 05:46:31.502936 | localhost | skipping: Conditional result was False
2026-05-26 05:46:31.503354 | 
2026-05-26 05:46:31.507306 | localhost | skipping: Conditional result was False
2026-05-26 05:46:31.526366 | 
2026-05-26 05:46:31.526524 | LOOP [upload-logs : Upload console log and json output]