2026-05-26 05:41:35.571359 | Job console starting
2026-05-26 05:41:35.585483 | Updating git repos
2026-05-26 05:41:37.205942 | Cloning repos into workspace
2026-05-26 05:41:55.062178 | Restoring repo states
2026-05-26 05:42:03.625206 | Merging changes
2026-05-26 05:42:04.641389 | Checking out repos
2026-05-26 05:42:07.596665 | Preparing playbooks
2026-05-26 05:42:16.041496 | Running Ansible setup
2026-05-26 05:42:24.062703 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-05-26 05:42:24.828296 | 
2026-05-26 05:42:24.828427 | PLAY [localhost]
2026-05-26 05:42:24.836534 | 
2026-05-26 05:42:24.836611 | TASK [Gathering Facts]
2026-05-26 05:42:25.874292 | localhost | ok
2026-05-26 05:42:25.888165 | 
2026-05-26 05:42:25.888288 | TASK [Setup log path fact]
2026-05-26 05:42:25.923554 | localhost | ok
2026-05-26 05:42:25.937637 | 
2026-05-26 05:42:25.937722 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-05-26 05:42:25.970378 | localhost | ok
2026-05-26 05:42:25.976587 | 
2026-05-26 05:42:25.976664 | TASK [emit-job-header : Print job information]
2026-05-26 05:42:26.019626 | # Job Information
2026-05-26 05:42:26.130879 | Ansible Version: 2.16.18
2026-05-26 05:42:26.131138 | Job: atmosphere-molecule-csi-rbd
2026-05-26 05:42:26.131240 | Pipeline: check
2026-05-26 05:42:26.131306 | Executor: 2d72f0692154
2026-05-26 05:42:26.131354 | Triggered by: https://github.com/vexxhost/atmosphere/pull/3991
2026-05-26 05:42:26.131415 | Event ID: 4af667a0-58c5-11f1-90f3-935b3405b672
2026-05-26 05:42:26.137669 | 
2026-05-26 05:42:26.137782 | LOOP [emit-job-header : Print node information]
2026-05-26 05:42:26.232573 | localhost | ok:
2026-05-26 05:42:26.232736 | localhost | # Node Information
2026-05-26 05:42:26.232765 | localhost | Inventory Hostname: instance
2026-05-26 05:42:26.232787 | localhost | Hostname: np0000176436
2026-05-26 05:42:26.232807 | localhost | Username: zuul
2026-05-26 05:42:26.232831 | localhost | Distro: Ubuntu 22.04
2026-05-26 05:42:26.232851 | localhost | Provider: yul1
2026-05-26 05:42:26.232870 | localhost | Region: ca-ymq-1
2026-05-26 05:42:26.232888 | localhost | Label: ubuntu-jammy
2026-05-26 05:42:26.232907 | localhost | Product Name: OpenStack Nova
2026-05-26 05:42:26.232925 | localhost | Interface IP: 199.19.213.152
2026-05-26 05:42:26.250819 | 
2026-05-26 05:42:26.251012 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-05-26 05:42:26.916006 | localhost -> localhost | changed
2026-05-26 05:42:26.925854 | 
2026-05-26 05:42:26.925952 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-05-26 05:42:28.122588 | localhost -> localhost | changed
2026-05-26 05:42:28.134585 | 
2026-05-26 05:42:28.134647 | PLAY [all]
2026-05-26 05:42:28.143092 | 
2026-05-26 05:42:28.143161 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-05-26 05:42:28.497750 | instance -> localhost | ok
2026-05-26 05:42:28.512148 | 
2026-05-26 05:42:28.512294 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-05-26 05:42:28.547269 | instance | ok
2026-05-26 05:42:28.562918 | instance | included: /var/lib/zuul/builds/1da2b3dbe63c4a4c980d7f231a0098f8/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-05-26 05:42:28.570136 | 
2026-05-26 05:42:28.570201 | TASK [add-build-sshkey : Create Temp SSH key]
2026-05-26 05:42:29.422582 | instance -> localhost | Generating public/private rsa key pair.
2026-05-26 05:42:29.422802 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/1da2b3dbe63c4a4c980d7f231a0098f8/work/1da2b3dbe63c4a4c980d7f231a0098f8_id_rsa
2026-05-26 05:42:29.422846 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/1da2b3dbe63c4a4c980d7f231a0098f8/work/1da2b3dbe63c4a4c980d7f231a0098f8_id_rsa.pub
2026-05-26 05:42:29.422879 | instance -> localhost | The key fingerprint is:
2026-05-26 05:42:29.422910 | instance -> localhost | SHA256:6GOsXQgE/Q8eM2XPyj4TxDyT0noUvBNluBuASnKMDYY zuul-build-sshkey
2026-05-26 05:42:29.422956 | instance -> localhost | The key's randomart image is:
2026-05-26 05:42:29.422987 | instance -> localhost | +---[RSA 3072]----+
2026-05-26 05:42:29.423024 | instance -> localhost | |o*.. . . oo      |
2026-05-26 05:42:29.423062 | instance -> localhost | |E =.o . B.       |
2026-05-26 05:42:29.423097 | instance -> localhost | | + ... B O       |
2026-05-26 05:42:29.423132 | instance -> localhost | |  ..  B.# o      |
2026-05-26 05:42:29.423158 | instance -> localhost | |    ...%SB       |
2026-05-26 05:42:29.423183 | instance -> localhost | |     +o.B        |
2026-05-26 05:42:29.423203 | instance -> localhost | |      *o..       |
2026-05-26 05:42:29.423224 | instance -> localhost | |     + o+        |
2026-05-26 05:42:29.423247 | instance -> localhost | |    . .  o       |
2026-05-26 05:42:29.423267 | instance -> localhost | +----[SHA256]-----+
2026-05-26 05:42:29.423319 | instance -> localhost | ok: Runtime: 0:00:00.414013
2026-05-26 05:42:29.430437 | 
2026-05-26 05:42:29.430510 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-05-26 05:42:29.466200 | instance | ok
2026-05-26 05:42:29.576562 | instance | included: /var/lib/zuul/builds/1da2b3dbe63c4a4c980d7f231a0098f8/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-05-26 05:42:29.584509 | 
2026-05-26 05:42:29.584599 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-05-26 05:42:29.615723 | instance | skipping: Conditional result was False
2026-05-26 05:42:29.624597 | 
2026-05-26 05:42:29.624692 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-05-26 05:42:30.115512 | instance | changed
2026-05-26 05:42:30.123033 | 
2026-05-26 05:42:30.123115 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-05-26 05:42:30.309680 | instance | ok
2026-05-26 05:42:30.317917 | 
2026-05-26 05:42:30.318086 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-05-26 05:42:30.901115 | instance | changed
2026-05-26 05:42:30.908799 | 
2026-05-26 05:42:30.908932 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-05-26 05:42:31.420250 | instance | changed
2026-05-26 05:42:31.427500 | 
2026-05-26 05:42:31.427649 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-05-26 05:42:31.452836 | instance | skipping: Conditional result was False
2026-05-26 05:42:31.464111 | 
2026-05-26 05:42:31.464215 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-05-26 05:42:31.924796 | instance -> localhost | changed
2026-05-26 05:42:31.940519 | 
2026-05-26 05:42:31.940675 | TASK [add-build-sshkey : Add back temp key]
2026-05-26 05:42:32.245631 | instance -> localhost | Identity added: /var/lib/zuul/builds/1da2b3dbe63c4a4c980d7f231a0098f8/work/1da2b3dbe63c4a4c980d7f231a0098f8_id_rsa (zuul-build-sshkey)
2026-05-26 05:42:32.245822 | instance -> localhost | ok: Runtime: 0:00:00.015779
2026-05-26 05:42:32.251266 | 
2026-05-26 05:42:32.251342 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-05-26 05:42:32.561566 | instance | ok
2026-05-26 05:42:32.943237 | 
2026-05-26 05:42:32.943364 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-05-26 05:42:32.967984 | instance | skipping: Conditional result was False
2026-05-26 05:42:32.981480 | 
2026-05-26 05:42:32.981557 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-05-26 05:42:33.325000 | instance | ok
2026-05-26 05:42:33.330828 | 
2026-05-26 05:42:33.330893 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-05-26 05:42:39.837967 | instance | Output suppressed because no_log was given
2026-05-26 05:42:39.851417 | 
2026-05-26 05:42:39.851573 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-05-26 05:42:40.039241 | instance | ok: "logs"
2026-05-26 05:42:40.039493 | instance | ok: All items complete
2026-05-26 05:42:40.039523 | 
2026-05-26 05:42:40.178418 | instance | ok: "artifacts"
2026-05-26 05:42:40.334862 | instance | ok: "docs"
2026-05-26 05:42:40.350822 | 
2026-05-26 05:42:40.350980 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-05-26 05:42:40.538649 | instance | changed: "logs"
2026-05-26 05:42:40.709698 | instance | changed: "artifacts"
2026-05-26 05:42:40.866837 | instance | changed: "docs"
2026-05-26 05:42:40.875723 | 
2026-05-26 05:42:40.875828 | PLAY RECAP
2026-05-26 05:42:40.875878 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-05-26 05:42:40.875909 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-05-26 05:42:40.875933 | 
2026-05-26 05:42:41.010052 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-05-26 05:42:41.283069 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-05-26 05:42:41.993236 | 
2026-05-26 05:42:47.025243 | PLAY [all]
2026-05-26 05:42:47.063812 | 
2026-05-26 05:42:47.064045 | TASK [setup-uv : Extract archive]
2026-05-26 05:42:49.460853 | instance | changed
2026-05-26 05:42:49.467872 | 
2026-05-26 05:42:49.467954 | TASK [setup-uv : Print version]
2026-05-26 05:42:49.840864 | instance | uv 0.8.13
2026-05-26 05:42:51.360621 | instance | ok: Runtime: 0:00:00.010522
2026-05-26 05:42:51.373292 | 
2026-05-26 05:42:51.373427 | TASK [Install Ansible collection siblings]
2026-05-26 05:42:52.120643 | instance | ERROR
2026-05-26 05:42:52.137349 | instance | {
2026-05-26 05:42:52.137512 | instance |   "log": [
2026-05-26 05:42:52.137557 | instance |     "Sibling collection ansible.netcommon at /home/zuul/src/github.com/ansible-collections/ansible.netcommon",
2026-05-26 05:42:52.137597 | instance |     "Sibling collection ansible.posix at /home/zuul/src/github.com/ansible-collections/ansible.posix",
2026-05-26 05:42:52.137636 | instance |     "Sibling collection ansible.utils at /home/zuul/src/github.com/ansible-collections/ansible.utils",
2026-05-26 05:42:52.137677 | instance |     "Sibling collection community.crypto at /home/zuul/src/github.com/ansible-collections/community.crypto",
2026-05-26 05:42:52.137713 | instance |     "Sibling collection community.general at /home/zuul/src/github.com/ansible-collections/community.general",
2026-05-26 05:42:52.137755 | instance |     "Sibling collection community.mysql at /home/zuul/src/github.com/ansible-collections/community.mysql",
2026-05-26 05:42:52.137795 | instance |     "Sibling collection kubernetes.core at /home/zuul/src/github.com/ansible-collections/kubernetes.core",
2026-05-26 05:42:52.137829 | instance |     "Sibling collection vexxhost.ceph at /home/zuul/src/github.com/vexxhost/ansible-collection-ceph",
2026-05-26 05:42:52.137864 | instance |     "Sibling collection vexxhost.containers at /home/zuul/src/github.com/vexxhost/ansible-collection-containers",
2026-05-26 05:42:52.137900 | instance |     "Sibling collection vexxhost.kubernetes at /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes",
2026-05-26 05:42:52.137935 | instance |     "Sibling collection atmosphere.common at /home/zuul/src/github.com/vexxhost/atmosphere.common",
2026-05-26 05:42:52.137969 | instance |     "Sibling collection openstack.cloud at /home/zuul/src/opendev.org/openstack/ansible-collections-openstack",
2026-05-26 05:42:52.138010 | instance |     "Resolved ansible.netcommon to v8.5.2 for >=1.2.0",
2026-05-26 05:42:52.138050 | instance |     "Resolved ansible.posix to 1.6.0 for 1.6.0",
2026-05-26 05:42:52.138085 | instance |     "Resolved ansible.utils to v6.0.2 for >=2.9.0,>=3.0.0",
2026-05-26 05:42:52.138121 | instance |     "Resolved community.crypto to 2.2.3 for 2.2.3",
2026-05-26 05:42:52.138155 | instance |     "Resolved community.general to 7.3.0 for 7.3.0",
2026-05-26 05:42:52.138189 | instance |     "Resolved community.mysql to 3.6.0 for 3.6.0",
2026-05-26 05:42:52.138263 | instance |     "Resolved kubernetes.core to 2.4.0 for 2.4.0",
2026-05-26 05:42:52.138301 | instance |     "Resolved openstack.cloud to 1.7.0 for 1.7.0",
2026-05-26 05:42:52.138332 | instance |     "Resolved atmosphere.common to v0.7.0 for >=0.6.0",
2026-05-26 05:42:52.138375 | instance |     "Resolved vexxhost.ceph to v4.0.0 for >=3.1.2",
2026-05-26 05:42:52.138404 | instance |     "Resolved vexxhost.kubernetes to v3.1.0 for >=2.3.2,>=2.5.0",
2026-05-26 05:42:52.138440 | instance |     "Resolved ansible.utils to v6.0.2 for >=2.9.0,>=3.0.0,>=6.0.0",
2026-05-26 05:42:52.138469 | instance |     "Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2"
2026-05-26 05:42:52.138498 | instance |   ],
2026-05-26 05:42:52.138537 | instance |   "msg": "No local release tag for openstack.cloud satisfies 1.7.0,>=2.0.0"
2026-05-26 05:42:52.138577 | instance | }
failure
2026-05-26 05:42:52.144455 | 
2026-05-26 05:42:52.144547 | PLAY RECAP
2026-05-26 05:42:52.144680 | instance | ok: 2 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-05-26 05:42:52.144754 | 
2026-05-26 05:42:52.281645 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-05-26 05:42:52.287870 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-05-26 05:42:53.043227 | 
2026-05-26 05:42:53.043378 | PLAY [all]
2026-05-26 05:42:53.058264 | 
2026-05-26 05:42:53.058412 | TASK [fetch-output : Set log path for multiple nodes]
2026-05-26 05:42:53.103431 | instance | skipping: Conditional result was False
2026-05-26 05:42:53.111170 | 
2026-05-26 05:42:53.111293 | TASK [fetch-output : Set log path for single node]
2026-05-26 05:42:53.151312 | instance | ok
2026-05-26 05:42:53.186739 | 
2026-05-26 05:42:53.186912 | LOOP [fetch-output : Ensure local output dirs]
2026-05-26 05:42:53.822568 | instance -> localhost | ok: "/var/lib/zuul/builds/1da2b3dbe63c4a4c980d7f231a0098f8/work/logs"
2026-05-26 05:42:54.089470 | instance -> localhost | changed: "/var/lib/zuul/builds/1da2b3dbe63c4a4c980d7f231a0098f8/work/artifacts"
2026-05-26 05:42:54.305016 | instance -> localhost | changed: "/var/lib/zuul/builds/1da2b3dbe63c4a4c980d7f231a0098f8/work/docs"
2026-05-26 05:42:54.327203 | 
2026-05-26 05:42:54.765649 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-05-26 05:42:55.405740 | instance | ok
2026-05-26 05:42:55.405936 | instance | changed: All items complete
2026-05-26 05:42:55.405964 | 
2026-05-26 05:42:55.872660 | instance | changed: .d..t...... ./
2026-05-26 05:42:56.330239 | instance | changed: .d..t...... ./
2026-05-26 05:42:56.352328 | 
2026-05-26 05:42:56.352482 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-05-26 05:42:56.785382 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.006933
2026-05-26 05:42:57.063428 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.007042
2026-05-26 05:42:57.082207 | 
2026-05-26 05:42:57.082328 | PLAY [all]
2026-05-26 05:42:57.088624 | 
2026-05-26 05:42:57.088688 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-05-26 05:42:57.551889 | instance | changed
2026-05-26 05:42:57.561678 | 
2026-05-26 05:42:57.561746 | PLAY RECAP
2026-05-26 05:42:57.561797 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-05-26 05:42:57.561820 | 
2026-05-26 05:42:57.704266 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-05-26 05:42:57.709445 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-05-26 05:42:58.384617 | 
2026-05-26 05:42:58.384816 | PLAY [localhost]
2026-05-26 05:42:58.422350 | 
2026-05-26 05:42:58.422572 | TASK [Generate Zuul manifest]
2026-05-26 05:42:58.446514 | localhost | ok
2026-05-26 05:42:58.465800 | 
2026-05-26 05:42:58.466023 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-05-26 05:42:58.894573 | localhost | changed
2026-05-26 05:42:58.907824 | 
2026-05-26 05:42:58.907992 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-05-26 05:42:58.939606 | localhost | ok
2026-05-26 05:42:58.948349 | 
2026-05-26 05:42:58.948504 | TASK [Upload logs]
2026-05-26 05:42:58.972707 | localhost | ok
2026-05-26 05:42:59.037076 | 
2026-05-26 05:42:59.037234 | TASK [Set zuul-log-path fact]
2026-05-26 05:42:59.059443 | localhost | ok
2026-05-26 05:42:59.072717 | 
2026-05-26 05:42:59.072794 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-05-26 05:42:59.116609 | localhost | ok
2026-05-26 05:42:59.128800 | 
2026-05-26 05:42:59.129022 | TASK [upload-logs : Create log directories]
2026-05-26 05:42:59.561097 | localhost | changed
2026-05-26 05:42:59.568163 | 
2026-05-26 05:42:59.568368 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-05-26 05:43:00.121581 | localhost -> localhost | ok: Runtime: 0:00:00.003492
2026-05-26 05:43:00.127444 | 
2026-05-26 05:43:00.127514 | TASK [upload-logs : Upload logs to log server]
2026-05-26 05:43:01.442160 | localhost | Output suppressed because no_log was given
2026-05-26 05:43:01.505333 | 
2026-05-26 05:43:01.505484 | LOOP [upload-logs : Compress console log and json output]
2026-05-26 05:43:01.550114 | localhost | skipping: Conditional result was False
2026-05-26 05:43:01.557671 | localhost | skipping: Conditional result was False
2026-05-26 05:43:01.568880 | 
2026-05-26 05:43:01.569328 | LOOP [upload-logs : Upload compressed console log and json output]
2026-05-26 05:43:01.608717 | localhost | skipping: Conditional result was False
2026-05-26 05:43:01.609764 | 
2026-05-26 05:43:01.612553 | localhost | skipping: Conditional result was False
2026-05-26 05:43:01.623323 | 
2026-05-26 05:43:01.623571 | LOOP [upload-logs : Upload console log and json output]