2026-05-27 05:59:15.732183 | Job console starting
2026-05-27 05:59:15.744714 | Updating git repos
2026-05-27 05:59:16.343014 | Cloning repos into workspace
2026-05-27 05:59:53.754335 | Restoring repo states
2026-05-27 05:59:56.728991 | Merging changes
2026-05-27 05:59:58.786600 | Checking out repos
2026-05-27 06:00:02.659101 | Preparing playbooks
2026-05-27 06:00:06.916663 | Running Ansible setup
2026-05-27 06:00:11.575598 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-05-27 06:00:12.239294 | 
2026-05-27 06:00:12.239491 | PLAY [localhost]
2026-05-27 06:00:12.249127 | 
2026-05-27 06:00:12.249278 | TASK [Gathering Facts]
2026-05-27 06:00:13.456932 | localhost | ok
2026-05-27 06:00:13.464708 | 
2026-05-27 06:00:13.464831 | TASK [Setup log path fact]
2026-05-27 06:00:13.486876 | localhost | ok
2026-05-27 06:00:13.503337 | 
2026-05-27 06:00:13.503472 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-05-27 06:00:13.535997 | localhost | ok
2026-05-27 06:00:13.542562 | 
2026-05-27 06:00:13.542656 | TASK [emit-job-header : Print job information]
2026-05-27 06:00:13.595571 | # Job Information
2026-05-27 06:00:13.595794 | Ansible Version: 2.16.18
2026-05-27 06:00:13.595864 | Job: atmosphere-molecule-csi-rbd
2026-05-27 06:00:13.595901 | Pipeline: check
2026-05-27 06:00:13.595932 | Executor: 2d72f0692154
2026-05-27 06:00:13.595961 | Triggered by: https://github.com/vexxhost/atmosphere/pull/3985
2026-05-27 06:00:13.595997 | Event ID: 673ad3e0-598f-11f1-84f2-318afe1532f9
2026-05-27 06:00:13.600946 | 
2026-05-27 06:00:13.601077 | LOOP [emit-job-header : Print node information]
2026-05-27 06:00:13.714195 | localhost | ok:
2026-05-27 06:00:13.714425 | localhost | # Node Information
2026-05-27 06:00:13.714454 | localhost | Inventory Hostname: instance
2026-05-27 06:00:13.714476 | localhost | Hostname: np0000176889
2026-05-27 06:00:13.714498 | localhost | Username: zuul
2026-05-27 06:00:13.714521 | localhost | Distro: Ubuntu 22.04
2026-05-27 06:00:13.714540 | localhost | Provider: yul1
2026-05-27 06:00:13.714561 | localhost | Region: ca-ymq-1
2026-05-27 06:00:13.714582 | localhost | Label: ubuntu-jammy
2026-05-27 06:00:13.714600 | localhost | Product Name: OpenStack Nova
2026-05-27 06:00:13.714617 | localhost | Interface IP: 199.19.213.14
2026-05-27 06:00:13.728037 | 
2026-05-27 06:00:13.728233 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-05-27 06:00:14.170883 | localhost -> localhost | changed
2026-05-27 06:00:14.177762 | 
2026-05-27 06:00:14.177851 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-05-27 06:00:15.110647 | localhost -> localhost | changed
2026-05-27 06:00:15.122445 | 
2026-05-27 06:00:15.122531 | PLAY [all]
2026-05-27 06:00:15.136012 | 
2026-05-27 06:00:15.136099 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-05-27 06:00:15.400082 | instance -> localhost | ok
2026-05-27 06:00:15.408951 | 
2026-05-27 06:00:15.409240 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-05-27 06:00:15.454492 | instance | ok
2026-05-27 06:00:15.472935 | instance | included: /var/lib/zuul/builds/82ee11e9af3b40049aec9c0b1edba0b3/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-05-27 06:00:15.483417 | 
2026-05-27 06:00:15.483498 | TASK [add-build-sshkey : Create Temp SSH key]
2026-05-27 06:00:16.363688 | instance -> localhost | Generating public/private rsa key pair.
2026-05-27 06:00:16.363887 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/82ee11e9af3b40049aec9c0b1edba0b3/work/82ee11e9af3b40049aec9c0b1edba0b3_id_rsa
2026-05-27 06:00:16.363918 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/82ee11e9af3b40049aec9c0b1edba0b3/work/82ee11e9af3b40049aec9c0b1edba0b3_id_rsa.pub
2026-05-27 06:00:16.363942 | instance -> localhost | The key fingerprint is:
2026-05-27 06:00:16.363963 | instance -> localhost | SHA256:9cUIel96nErkcHCHf7IZ7kE3CA4fE5QJS4+UOZuUsX8 zuul-build-sshkey
2026-05-27 06:00:16.363995 | instance -> localhost | The key's randomart image is:
2026-05-27 06:00:16.364018 | instance -> localhost | +---[RSA 3072]----+
2026-05-27 06:00:16.364046 | instance -> localhost | |         =Xo=..  |
2026-05-27 06:00:16.364068 | instance -> localhost | |        oO=X.+   |
2026-05-27 06:00:16.364089 | instance -> localhost | |        o+@.B.=  |
2026-05-27 06:00:16.364109 | instance -> localhost | |         =.X *=oo|
2026-05-27 06:00:16.364128 | instance -> localhost | |        S  .*E+B.|
2026-05-27 06:00:16.364148 | instance -> localhost | |           ..o=  |
2026-05-27 06:00:16.364167 | instance -> localhost | |            .. . |
2026-05-27 06:00:16.364186 | instance -> localhost | |              .  |
2026-05-27 06:00:16.364208 | instance -> localhost | |                 |
2026-05-27 06:00:16.364228 | instance -> localhost | +----[SHA256]-----+
2026-05-27 06:00:16.364291 | instance -> localhost | ok: Runtime: 0:00:00.260128
2026-05-27 06:00:16.372603 | 
2026-05-27 06:00:16.372685 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-05-27 06:00:16.405984 | instance | ok
2026-05-27 06:00:16.420908 | instance | included: /var/lib/zuul/builds/82ee11e9af3b40049aec9c0b1edba0b3/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-05-27 06:00:16.429533 | 
2026-05-27 06:00:16.429627 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-05-27 06:00:16.456393 | instance | skipping: Conditional result was False
2026-05-27 06:00:16.465047 | 
2026-05-27 06:00:16.465129 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-05-27 06:00:16.898871 | instance | changed
2026-05-27 06:00:16.905478 | 
2026-05-27 06:00:16.905548 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-05-27 06:00:17.090041 | instance | ok
2026-05-27 06:00:17.097401 | 
2026-05-27 06:00:17.097498 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-05-27 06:00:17.580138 | instance | changed
2026-05-27 06:00:17.586109 | 
2026-05-27 06:00:17.586183 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-05-27 06:00:18.048050 | instance | changed
2026-05-27 06:00:18.054896 | 
2026-05-27 06:00:18.054965 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-05-27 06:00:18.080081 | instance | skipping: Conditional result was False
2026-05-27 06:00:18.092093 | 
2026-05-27 06:00:18.092194 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-05-27 06:00:18.646285 | instance -> localhost | changed
2026-05-27 06:00:18.662222 | 
2026-05-27 06:00:18.662361 | TASK [add-build-sshkey : Add back temp key]
2026-05-27 06:00:18.948147 | instance -> localhost | Identity added: /var/lib/zuul/builds/82ee11e9af3b40049aec9c0b1edba0b3/work/82ee11e9af3b40049aec9c0b1edba0b3_id_rsa (zuul-build-sshkey)
2026-05-27 06:00:18.948452 | instance -> localhost | ok: Runtime: 0:00:00.015075
2026-05-27 06:00:18.956045 | 
2026-05-27 06:00:18.956331 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-05-27 06:00:19.294846 | instance | ok
2026-05-27 06:00:19.336772 | 
2026-05-27 06:00:19.425817 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-05-27 06:00:19.462366 | instance | skipping: Conditional result was False
2026-05-27 06:00:19.472606 | 
2026-05-27 06:00:19.472683 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-05-27 06:00:19.828325 | instance | ok
2026-05-27 06:00:19.836562 | 
2026-05-27 06:00:19.836643 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-05-27 06:00:23.566105 | instance | Output suppressed because no_log was given
2026-05-27 06:00:23.584308 | 
2026-05-27 06:00:23.584451 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-05-27 06:00:23.785805 | instance | ok: "logs"
2026-05-27 06:00:23.786051 | instance | ok: All items complete
2026-05-27 06:00:23.786080 | 
2026-05-27 06:00:23.930522 | instance | ok: "artifacts"
2026-05-27 06:00:24.095972 | instance | ok: "docs"
2026-05-27 06:00:24.109026 | 
2026-05-27 06:00:24.109099 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-05-27 06:00:24.308362 | instance | changed: "logs"
2026-05-27 06:00:24.460319 | instance | changed: "artifacts"
2026-05-27 06:00:24.623964 | instance | changed: "docs"
2026-05-27 06:00:24.644701 | 
2026-05-27 06:00:24.644823 | PLAY RECAP
2026-05-27 06:00:24.644889 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-05-27 06:00:24.644950 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-05-27 06:00:24.645010 | 
2026-05-27 06:00:24.843123 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-05-27 06:00:24.847841 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-05-27 06:00:25.496764 | 
2026-05-27 06:00:25.496910 | PLAY [all]
2026-05-27 06:00:25.507499 | 
2026-05-27 06:00:25.507574 | TASK [setup-uv : Extract archive]
2026-05-27 06:00:27.832936 | instance | changed
2026-05-27 06:00:27.972037 | 
2026-05-27 06:00:27.972289 | TASK [setup-uv : Print version]
2026-05-27 06:00:28.391481 | instance | uv 0.8.13
2026-05-27 06:00:28.515516 | instance | ok: Runtime: 0:00:00.013652
2026-05-27 06:00:28.525228 | 
2026-05-27 06:00:28.525327 | TASK [Install Ansible collection siblings]
2026-05-27 06:00:29.150535 | instance | ERROR
2026-05-27 06:00:29.150880 | instance | {
2026-05-27 06:00:29.150936 | instance |   "log": [
2026-05-27 06:00:29.150970 | instance |     "Sibling collection ansible.netcommon at /home/zuul/src/github.com/ansible-collections/ansible.netcommon",
2026-05-27 06:00:29.151001 | instance |     "Sibling collection ansible.posix at /home/zuul/src/github.com/ansible-collections/ansible.posix",
2026-05-27 06:00:29.151029 | instance |     "Sibling collection ansible.utils at /home/zuul/src/github.com/ansible-collections/ansible.utils",
2026-05-27 06:00:29.151062 | instance |     "Sibling collection community.crypto at /home/zuul/src/github.com/ansible-collections/community.crypto",
2026-05-27 06:00:29.151090 | instance |     "Sibling collection community.general at /home/zuul/src/github.com/ansible-collections/community.general",
2026-05-27 06:00:29.151120 | instance |     "Sibling collection community.mysql at /home/zuul/src/github.com/ansible-collections/community.mysql",
2026-05-27 06:00:29.151147 | instance |     "Sibling collection kubernetes.core at /home/zuul/src/github.com/ansible-collections/kubernetes.core",
2026-05-27 06:00:29.151174 | instance |     "Sibling collection vexxhost.ceph at /home/zuul/src/github.com/vexxhost/ansible-collection-ceph",
2026-05-27 06:00:29.151200 | instance |     "Sibling collection vexxhost.containers at /home/zuul/src/github.com/vexxhost/ansible-collection-containers",
2026-05-27 06:00:29.151227 | instance |     "Sibling collection vexxhost.kubernetes at /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes",
2026-05-27 06:00:29.151253 | instance |     "Sibling collection atmosphere.common at /home/zuul/src/github.com/vexxhost/atmosphere.common",
2026-05-27 06:00:29.151279 | instance |     "Sibling collection openstack.cloud at /home/zuul/src/opendev.org/openstack/ansible-collections-openstack",
2026-05-27 06:00:29.151305 | instance |     "Resolved ansible.netcommon to v8.5.2 for >=1.2.0",
2026-05-27 06:00:29.151332 | instance |     "Resolved ansible.posix to 1.6.0 for 1.6.0",
2026-05-27 06:00:29.151359 | instance |     "Resolved ansible.utils to v6.0.2 for >=2.9.0,>=3.0.0",
2026-05-27 06:00:29.151385 | instance |     "Resolved community.crypto to 2.2.3 for 2.2.3",
2026-05-27 06:00:29.151411 | instance |     "Resolved community.general to 7.3.0 for 7.3.0",
2026-05-27 06:00:29.151437 | instance |     "Resolved community.mysql to 3.6.0 for 3.6.0",
2026-05-27 06:00:29.151469 | instance |     "Resolved kubernetes.core to 2.4.0 for 2.4.0",
2026-05-27 06:00:29.151502 | instance |     "Resolved openstack.cloud to 1.7.0 for 1.7.0",
2026-05-27 06:00:29.151537 | instance |     "Resolved atmosphere.common to v0.7.0 for >=0.6.0",
2026-05-27 06:00:29.151580 | instance |     "Resolved vexxhost.ceph to v4.0.0 for >=3.1.2",
2026-05-27 06:00:29.151607 | instance |     "Resolved vexxhost.kubernetes to v3.1.0 for >=2.3.2,>=2.5.0",
2026-05-27 06:00:29.151634 | instance |     "Resolved ansible.utils to v6.0.2 for >=2.9.0,>=3.0.0,>=6.0.0",
2026-05-27 06:00:29.151660 | instance |     "Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2"
2026-05-27 06:00:29.151685 | instance |   ],
2026-05-27 06:00:29.151711 | instance |   "msg": "No local release tag for openstack.cloud satisfies 1.7.0,>=2.0.0"
2026-05-27 06:00:29.151737 | instance | }
failure
2026-05-27 06:00:29.164438 | 
2026-05-27 06:00:29.165097 | PLAY RECAP
2026-05-27 06:00:29.165172 | instance | ok: 2 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-05-27 06:00:29.165206 | 
2026-05-27 06:00:29.354338 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-05-27 06:00:29.365205 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-05-27 06:00:29.994140 | 
2026-05-27 06:00:29.994273 | PLAY [all]
2026-05-27 06:00:30.006343 | 
2026-05-27 06:00:30.006422 | TASK [fetch-output : Set log path for multiple nodes]
2026-05-27 06:00:30.051665 | instance | skipping: Conditional result was False
2026-05-27 06:00:30.060641 | 
2026-05-27 06:00:30.060830 | TASK [fetch-output : Set log path for single node]
2026-05-27 06:00:30.104159 | instance | ok
2026-05-27 06:00:30.113293 | 
2026-05-27 06:00:30.140429 | LOOP [fetch-output : Ensure local output dirs]
2026-05-27 06:00:30.581740 | instance -> localhost | ok: "/var/lib/zuul/builds/82ee11e9af3b40049aec9c0b1edba0b3/work/logs"
2026-05-27 06:00:30.826222 | instance -> localhost | changed: "/var/lib/zuul/builds/82ee11e9af3b40049aec9c0b1edba0b3/work/artifacts"
2026-05-27 06:00:31.056190 | instance -> localhost | changed: "/var/lib/zuul/builds/82ee11e9af3b40049aec9c0b1edba0b3/work/docs"
2026-05-27 06:00:31.072511 | 
2026-05-27 06:00:31.072865 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-05-27 06:00:31.776542 | instance | ok
2026-05-27 06:00:32.002853 | instance | changed: All items complete
2026-05-27 06:00:32.003035 | 
2026-05-27 06:00:32.255665 | instance | changed: .d..t...... ./
2026-05-27 06:00:32.805637 | instance | changed: .d..t...... ./
2026-05-27 06:00:32.825282 | 
2026-05-27 06:00:32.825416 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-05-27 06:00:33.285561 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.006759
2026-05-27 06:00:33.536933 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.008638
2026-05-27 06:00:33.557156 | 
2026-05-27 06:00:33.557367 | PLAY [all]
2026-05-27 06:00:33.580175 | 
2026-05-27 06:00:33.580314 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-05-27 06:00:34.061594 | instance | changed
2026-05-27 06:00:34.315846 | 
2026-05-27 06:00:34.317101 | PLAY RECAP
2026-05-27 06:00:34.317177 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-05-27 06:00:34.317210 | 
2026-05-27 06:00:34.452238 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-05-27 06:00:34.571096 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-05-27 06:00:35.296498 | 
2026-05-27 06:00:35.296654 | PLAY [localhost]
2026-05-27 06:00:35.307248 | 
2026-05-27 06:00:35.324175 | TASK [Generate Zuul manifest]
2026-05-27 06:00:35.349999 | localhost | ok
2026-05-27 06:00:35.373355 | 
2026-05-27 06:00:35.373576 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-05-27 06:00:35.769687 | localhost | changed
2026-05-27 06:00:35.781680 | 
2026-05-27 06:00:35.781790 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-05-27 06:00:35.835613 | localhost | ok
2026-05-27 06:00:35.842930 | 
2026-05-27 06:00:35.843087 | TASK [Upload logs]
2026-05-27 06:00:35.869266 | localhost | ok
2026-05-27 06:00:35.932110 | 
2026-05-27 06:00:35.932309 | TASK [Set zuul-log-path fact]
2026-05-27 06:00:35.957363 | localhost | ok
2026-05-27 06:00:35.976199 | 
2026-05-27 06:00:35.976420 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-05-27 06:00:36.009230 | localhost | ok
2026-05-27 06:00:36.019404 | 
2026-05-27 06:00:36.019473 | TASK [upload-logs : Create log directories]
2026-05-27 06:00:36.476173 | localhost | changed
2026-05-27 06:00:36.484601 | 
2026-05-27 06:00:36.484744 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-05-27 06:00:36.942000 | localhost -> localhost | ok: Runtime: 0:00:00.008229
2026-05-27 06:00:36.947440 | 
2026-05-27 06:00:36.947537 | TASK [upload-logs : Upload logs to log server]
2026-05-27 06:00:37.565126 | localhost | Output suppressed because no_log was given
2026-05-27 06:00:37.628502 | 
2026-05-27 06:00:37.628652 | LOOP [upload-logs : Compress console log and json output]
2026-05-27 06:00:37.684828 | localhost | skipping: Conditional result was False
2026-05-27 06:00:37.693302 | localhost | skipping: Conditional result was False
2026-05-27 06:00:37.705639 | 
2026-05-27 06:00:37.705832 | LOOP [upload-logs : Upload compressed console log and json output]
2026-05-27 06:00:37.766475 | localhost | skipping: Conditional result was False
2026-05-27 06:00:37.766774 | 
2026-05-27 06:00:37.773125 | localhost | skipping: Conditional result was False
2026-05-27 06:00:37.782554 | 
2026-05-27 06:00:37.782753 | LOOP [upload-logs : Upload console log and json output]