2026-06-13 00:58:22.832364 | Job console starting
2026-06-13 00:58:22.842640 | Updating git repos
2026-06-13 00:58:25.222245 | Cloning repos into workspace
2026-06-13 00:58:39.513308 | Restoring repo states
2026-06-13 00:58:48.156266 | Merging changes
2026-06-13 00:58:49.303480 | Checking out repos
2026-06-13 00:58:51.496631 | Preparing playbooks
2026-06-13 00:58:54.664256 | Running Ansible setup
2026-06-13 00:58:58.098570 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-06-13 00:58:58.707469 | 
2026-06-13 00:58:58.707590 | PLAY [localhost]
2026-06-13 00:58:58.715114 | 
2026-06-13 00:58:58.715188 | TASK [Gathering Facts]
2026-06-13 00:58:59.639883 | localhost | ok
2026-06-13 00:58:59.652414 | 
2026-06-13 00:58:59.652529 | TASK [Setup log path fact]
2026-06-13 00:58:59.673424 | localhost | ok
2026-06-13 00:58:59.685885 | 
2026-06-13 00:58:59.685959 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-06-13 00:58:59.715698 | localhost | ok
2026-06-13 00:58:59.724786 | 
2026-06-13 00:58:59.724903 | TASK [emit-job-header : Print job information]
2026-06-13 00:58:59.768234 | # Job Information
2026-06-13 00:58:59.768500 | Ansible Version: 2.16.18
2026-06-13 00:58:59.768573 | Job: atmosphere-molecule-csi-rbd
2026-06-13 00:58:59.768622 | Pipeline: check
2026-06-13 00:58:59.768666 | Executor: 2d72f0692154
2026-06-13 00:58:59.768709 | Triggered by: https://github.com/vexxhost/atmosphere/pull/3985
2026-06-13 00:58:59.768759 | Event ID: d5e8fa20-66c2-11f1-9142-8939870a2e2d
2026-06-13 00:58:59.772390 | 
2026-06-13 00:58:59.772461 | LOOP [emit-job-header : Print node information]
2026-06-13 00:58:59.891676 | localhost | ok:
2026-06-13 00:58:59.891874 | localhost | # Node Information
2026-06-13 00:58:59.891904 | localhost | Inventory Hostname: instance
2026-06-13 00:58:59.891927 | localhost | Hostname: np0000179853
2026-06-13 00:58:59.891948 | localhost | Username: zuul
2026-06-13 00:58:59.891973 | localhost | Distro: Ubuntu 22.04
2026-06-13 00:58:59.891994 | localhost | Provider: yul1
2026-06-13 00:58:59.892014 | localhost | Region: ca-ymq-1
2026-06-13 00:58:59.892033 | localhost | Label: ubuntu-jammy
2026-06-13 00:58:59.892052 | localhost | Product Name: OpenStack Nova
2026-06-13 00:58:59.892071 | localhost | Interface IP: 199.204.45.25
2026-06-13 00:58:59.907050 | 
2026-06-13 00:58:59.907225 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-06-13 00:59:00.896521 | localhost -> localhost | changed
2026-06-13 00:59:00.905324 | 
2026-06-13 00:59:00.905483 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-06-13 00:59:01.762582 | localhost -> localhost | changed
2026-06-13 00:59:01.773901 | 
2026-06-13 00:59:01.773962 | PLAY [all]
2026-06-13 00:59:01.783067 | 
2026-06-13 00:59:01.783205 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-06-13 00:59:02.080682 | instance -> localhost | ok
2026-06-13 00:59:02.091468 | 
2026-06-13 00:59:02.091536 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-06-13 00:59:02.122374 | instance | ok
2026-06-13 00:59:02.137101 | instance | included: /var/lib/zuul/builds/cb163c5e885441cda5d68f8078dc977a/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-06-13 00:59:02.142727 | 
2026-06-13 00:59:02.142789 | TASK [add-build-sshkey : Create Temp SSH key]
2026-06-13 00:59:02.904025 | instance -> localhost | Generating public/private rsa key pair.
2026-06-13 00:59:02.904194 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/cb163c5e885441cda5d68f8078dc977a/work/cb163c5e885441cda5d68f8078dc977a_id_rsa
2026-06-13 00:59:02.904223 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/cb163c5e885441cda5d68f8078dc977a/work/cb163c5e885441cda5d68f8078dc977a_id_rsa.pub
2026-06-13 00:59:02.904245 | instance -> localhost | The key fingerprint is:
2026-06-13 00:59:02.904267 | instance -> localhost | SHA256:u5DYiMXivtxYf8Hc/w+2JXluPcUzPYBTi8OKESvbTiA zuul-build-sshkey
2026-06-13 00:59:02.904300 | instance -> localhost | The key's randomart image is:
2026-06-13 00:59:02.904322 | instance -> localhost | +---[RSA 3072]----+
2026-06-13 00:59:02.904352 | instance -> localhost | |                 |
2026-06-13 00:59:02.904374 | instance -> localhost | |       .     .   |
2026-06-13 00:59:02.904394 | instance -> localhost | |        o . + .  |
2026-06-13 00:59:02.904414 | instance -> localhost | |   E o o   * o   |
2026-06-13 00:59:02.904435 | instance -> localhost | |  . + =oS.. o ...|
2026-06-13 00:59:02.904454 | instance -> localhost | | . + = =+o.    =+|
2026-06-13 00:59:02.904473 | instance -> localhost | |  o + * .. .  = O|
2026-06-13 00:59:02.904493 | instance -> localhost | | o + . o..  .. Oo|
2026-06-13 00:59:02.904515 | instance -> localhost | |  =.. ...    .oo+|
2026-06-13 00:59:02.904536 | instance -> localhost | +----[SHA256]-----+
2026-06-13 00:59:02.904585 | instance -> localhost | ok: Runtime: 0:00:00.292338
2026-06-13 00:59:02.912205 | 
2026-06-13 00:59:02.912266 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-06-13 00:59:02.941921 | instance | ok
2026-06-13 00:59:02.950582 | instance | included: /var/lib/zuul/builds/cb163c5e885441cda5d68f8078dc977a/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-06-13 00:59:02.959678 | 
2026-06-13 00:59:02.959763 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-06-13 00:59:02.985173 | instance | skipping: Conditional result was False
2026-06-13 00:59:02.995573 | 
2026-06-13 00:59:02.995717 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-06-13 00:59:03.440420 | instance | changed
2026-06-13 00:59:03.446522 | 
2026-06-13 00:59:03.446589 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-06-13 00:59:03.632819 | instance | ok
2026-06-13 00:59:03.637845 | 
2026-06-13 00:59:03.637909 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-06-13 00:59:04.088904 | instance | changed
2026-06-13 00:59:04.094435 | 
2026-06-13 00:59:04.094502 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-06-13 00:59:04.561832 | instance | changed
2026-06-13 00:59:04.571459 | 
2026-06-13 00:59:04.571566 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-06-13 00:59:04.597675 | instance | skipping: Conditional result was False
2026-06-13 00:59:04.606431 | 
2026-06-13 00:59:04.606544 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-06-13 00:59:04.965298 | instance -> localhost | changed
2026-06-13 00:59:04.979208 | 
2026-06-13 00:59:04.979274 | TASK [add-build-sshkey : Add back temp key]
2026-06-13 00:59:05.261274 | instance -> localhost | Identity added: /var/lib/zuul/builds/cb163c5e885441cda5d68f8078dc977a/work/cb163c5e885441cda5d68f8078dc977a_id_rsa (zuul-build-sshkey)
2026-06-13 00:59:05.261466 | instance -> localhost | ok: Runtime: 0:00:00.016538
2026-06-13 00:59:05.267377 | 
2026-06-13 00:59:05.267460 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-06-13 00:59:05.601050 | instance | ok
2026-06-13 00:59:05.606297 | 
2026-06-13 00:59:05.606362 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-06-13 00:59:05.631541 | instance | skipping: Conditional result was False
2026-06-13 00:59:05.645294 | 
2026-06-13 00:59:05.645362 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-06-13 00:59:05.992062 | instance | ok
2026-06-13 00:59:05.999849 | 
2026-06-13 00:59:05.999925 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-06-13 00:59:09.321184 | instance | Output suppressed because no_log was given
2026-06-13 00:59:09.329702 | 
2026-06-13 00:59:09.329762 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-06-13 00:59:09.546023 | instance | ok: "logs"
2026-06-13 00:59:09.546271 | instance | ok: All items complete
2026-06-13 00:59:09.546298 | 
2026-06-13 00:59:09.704057 | instance | ok: "artifacts"
2026-06-13 00:59:09.866973 | instance | ok: "docs"
2026-06-13 00:59:09.884605 | 
2026-06-13 00:59:09.884703 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-06-13 00:59:10.074559 | instance | changed: "logs"
2026-06-13 00:59:10.240518 | instance | changed: "artifacts"
2026-06-13 00:59:10.408014 | instance | changed: "docs"
2026-06-13 00:59:10.431623 | 
2026-06-13 00:59:10.431720 | PLAY RECAP
2026-06-13 00:59:10.431766 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-06-13 00:59:10.431794 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-06-13 00:59:10.431816 | 
2026-06-13 00:59:10.605168 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-06-13 00:59:10.664455 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-06-13 00:59:11.244192 | 
2026-06-13 00:59:11.244335 | PLAY [all]
2026-06-13 00:59:11.255407 | 
2026-06-13 00:59:11.255540 | TASK [setup-uv : Extract archive]
2026-06-13 00:59:13.641250 | instance | changed
2026-06-13 00:59:13.647490 | 
2026-06-13 00:59:13.647580 | TASK [setup-uv : Print version]
2026-06-13 00:59:14.016238 | instance | uv 0.8.13
2026-06-13 00:59:14.183739 | instance | ok: Runtime: 0:00:00.011646
2026-06-13 00:59:14.193310 | 
2026-06-13 00:59:14.193398 | TASK [Install Ansible collection siblings]
2026-06-13 00:59:14.832587 | instance | ERROR
2026-06-13 00:59:14.832871 | instance | {
2026-06-13 00:59:14.832910 | instance |   "log": [
2026-06-13 00:59:14.832934 | instance |     "Sibling collection ansible.netcommon at /home/zuul/src/github.com/ansible-collections/ansible.netcommon",
2026-06-13 00:59:14.832955 | instance |     "Sibling collection ansible.posix at /home/zuul/src/github.com/ansible-collections/ansible.posix",
2026-06-13 00:59:14.832995 | instance |     "Sibling collection ansible.utils at /home/zuul/src/github.com/ansible-collections/ansible.utils",
2026-06-13 00:59:14.833020 | instance |     "Sibling collection community.crypto at /home/zuul/src/github.com/ansible-collections/community.crypto",
2026-06-13 00:59:14.833041 | instance |     "Sibling collection community.general at /home/zuul/src/github.com/ansible-collections/community.general",
2026-06-13 00:59:14.833062 | instance |     "Sibling collection community.mysql at /home/zuul/src/github.com/ansible-collections/community.mysql",
2026-06-13 00:59:14.833081 | instance |     "Sibling collection kubernetes.core at /home/zuul/src/github.com/ansible-collections/kubernetes.core",
2026-06-13 00:59:14.833100 | instance |     "Sibling collection vexxhost.ceph at /home/zuul/src/github.com/vexxhost/ansible-collection-ceph",
2026-06-13 00:59:14.833118 | instance |     "Sibling collection vexxhost.containers at /home/zuul/src/github.com/vexxhost/ansible-collection-containers",
2026-06-13 00:59:14.833136 | instance |     "Sibling collection vexxhost.kubernetes at /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes",
2026-06-13 00:59:14.833155 | instance |     "Sibling collection atmosphere.common at /home/zuul/src/github.com/vexxhost/atmosphere.common",
2026-06-13 00:59:14.833173 | instance |     "Sibling collection openstack.cloud at /home/zuul/src/opendev.org/openstack/ansible-collections-openstack",
2026-06-13 00:59:14.833190 | instance |     "Resolved ansible.netcommon to v8.5.2 for >=1.2.0",
2026-06-13 00:59:14.833209 | instance |     "Resolved ansible.posix to 1.6.0 for 1.6.0",
2026-06-13 00:59:14.833227 | instance |     "Resolved ansible.utils to v6.0.2 for >=2.9.0,>=3.0.0",
2026-06-13 00:59:14.833245 | instance |     "Resolved community.crypto to 2.2.3 for 2.2.3",
2026-06-13 00:59:14.833262 | instance |     "Resolved community.general to 7.3.0 for 7.3.0",
2026-06-13 00:59:14.833280 | instance |     "Resolved community.mysql to 3.6.0 for 3.6.0",
2026-06-13 00:59:14.833297 | instance |     "Resolved kubernetes.core to 2.4.0 for 2.4.0",
2026-06-13 00:59:14.833315 | instance |     "Resolved openstack.cloud to 1.7.0 for 1.7.0",
2026-06-13 00:59:14.833334 | instance |     "Resolved atmosphere.common to v0.7.0 for >=0.6.0",
2026-06-13 00:59:14.833369 | instance |     "Resolved vexxhost.ceph to v4.0.0 for >=3.1.2",
2026-06-13 00:59:14.833389 | instance |     "Resolved vexxhost.kubernetes to v3.2.0 for >=2.3.2,>=2.5.0",
2026-06-13 00:59:14.833407 | instance |     "Resolved ansible.utils to v6.0.2 for >=2.9.0,>=3.0.0,>=6.0.0",
2026-06-13 00:59:14.833425 | instance |     "Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2"
2026-06-13 00:59:14.833442 | instance |   ],
2026-06-13 00:59:14.833461 | instance |   "msg": "No local release tag for openstack.cloud satisfies 1.7.0,>=2.0.0"
2026-06-13 00:59:14.833479 | instance | }
failure
2026-06-13 00:59:14.836495 | 
2026-06-13 00:59:14.836542 | PLAY RECAP
2026-06-13 00:59:14.836586 | instance | ok: 2 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-06-13 00:59:14.836609 | 
2026-06-13 00:59:14.945567 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-06-13 00:59:14.951151 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-06-13 00:59:15.649577 | 
2026-06-13 00:59:15.649772 | PLAY [all]
2026-06-13 00:59:15.663679 | 
2026-06-13 00:59:15.663755 | TASK [fetch-output : Set log path for multiple nodes]
2026-06-13 00:59:15.709470 | instance | skipping: Conditional result was False
2026-06-13 00:59:15.715872 | 
2026-06-13 00:59:15.715960 | TASK [fetch-output : Set log path for single node]
2026-06-13 00:59:15.757172 | instance | ok
2026-06-13 00:59:15.769204 | 
2026-06-13 00:59:15.769401 | LOOP [fetch-output : Ensure local output dirs]
2026-06-13 00:59:16.198145 | instance -> localhost | ok: "/var/lib/zuul/builds/cb163c5e885441cda5d68f8078dc977a/work/logs"
2026-06-13 00:59:16.423530 | instance -> localhost | changed: "/var/lib/zuul/builds/cb163c5e885441cda5d68f8078dc977a/work/artifacts"
2026-06-13 00:59:16.635439 | instance -> localhost | changed: "/var/lib/zuul/builds/cb163c5e885441cda5d68f8078dc977a/work/docs"
2026-06-13 00:59:16.659452 | 
2026-06-13 00:59:16.659627 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-06-13 00:59:17.287237 | instance | ok
2026-06-13 00:59:17.287416 | instance | changed: All items complete
2026-06-13 00:59:17.287444 | 
2026-06-13 00:59:17.741252 | instance | changed: .d..t...... ./
2026-06-13 00:59:18.176614 | instance | changed: .d..t...... ./
2026-06-13 00:59:18.202396 | 
2026-06-13 00:59:18.202562 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-06-13 00:59:18.638825 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.007925
2026-06-13 00:59:18.871138 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.017206
2026-06-13 00:59:18.884256 | 
2026-06-13 00:59:18.884383 | PLAY [all]
2026-06-13 00:59:18.890660 | 
2026-06-13 00:59:18.890726 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-06-13 00:59:19.290207 | instance | changed
2026-06-13 00:59:19.296703 | 
2026-06-13 00:59:19.296754 | PLAY RECAP
2026-06-13 00:59:19.296800 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-06-13 00:59:19.296836 | 
2026-06-13 00:59:19.457942 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-06-13 00:59:19.460322 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-06-13 00:59:20.108929 | 
2026-06-13 00:59:20.109416 | PLAY [localhost]
2026-06-13 00:59:20.123076 | 
2026-06-13 00:59:20.123178 | TASK [Generate Zuul manifest]
2026-06-13 00:59:20.146662 | localhost | ok
2026-06-13 00:59:20.164306 | 
2026-06-13 00:59:20.164603 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-06-13 00:59:20.533592 | localhost | changed
2026-06-13 00:59:20.543574 | 
2026-06-13 00:59:20.543647 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-06-13 00:59:20.575171 | localhost | ok
2026-06-13 00:59:20.583760 | 
2026-06-13 00:59:20.583820 | TASK [Upload logs]
2026-06-13 00:59:20.605510 | localhost | ok
2026-06-13 00:59:20.661273 | 
2026-06-13 00:59:20.661368 | TASK [Set zuul-log-path fact]
2026-06-13 00:59:20.682119 | localhost | ok
2026-06-13 00:59:20.694967 | 
2026-06-13 00:59:20.695032 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-06-13 00:59:20.724702 | localhost | ok
2026-06-13 00:59:20.732904 | 
2026-06-13 00:59:20.732965 | TASK [upload-logs : Create log directories]
2026-06-13 00:59:21.111871 | localhost | changed
2026-06-13 00:59:21.118844 | 
2026-06-13 00:59:21.118976 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-06-13 00:59:21.626742 | localhost -> localhost | ok: Runtime: 0:00:00.005085
2026-06-13 00:59:21.631955 | 
2026-06-13 00:59:21.632019 | TASK [upload-logs : Upload logs to log server]
2026-06-13 00:59:22.093846 | localhost | Output suppressed because no_log was given
2026-06-13 00:59:22.127895 | 
2026-06-13 00:59:22.128022 | LOOP [upload-logs : Compress console log and json output]
2026-06-13 00:59:22.169873 | localhost | skipping: Conditional result was False
2026-06-13 00:59:22.176222 | localhost | skipping: Conditional result was False
2026-06-13 00:59:22.187482 | 
2026-06-13 00:59:22.187587 | LOOP [upload-logs : Upload compressed console log and json output]
2026-06-13 00:59:22.224422 | localhost | skipping: Conditional result was False
2026-06-13 00:59:22.224984 | 
2026-06-13 00:59:22.228173 | localhost | skipping: Conditional result was False
2026-06-13 00:59:22.237262 | 
2026-06-13 00:59:22.237395 | LOOP [upload-logs : Upload console log and json output]