2026-05-29 02:03:29.820800 | Job console starting 2026-05-29 02:03:29.832797 | Updating git repos 2026-05-29 02:03:31.306431 | Cloning repos into workspace 2026-05-29 02:03:45.431588 | Restoring repo states 2026-05-29 02:03:50.214913 | Merging changes 2026-05-29 02:03:51.779351 | Checking out repos 2026-05-29 02:03:54.249228 | Preparing playbooks 2026-05-29 02:03:59.089805 | Running Ansible setup 2026-05-29 02:04:02.489562 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-05-29 02:04:03.105555 | 2026-05-29 02:04:03.105703 | PLAY [localhost] 2026-05-29 02:04:03.113407 | 2026-05-29 02:04:03.113476 | TASK [Gathering Facts] 2026-05-29 02:04:03.992108 | localhost | ok 2026-05-29 02:04:04.005161 | 2026-05-29 02:04:04.005293 | TASK [Setup log path fact] 2026-05-29 02:04:04.025311 | localhost | ok 2026-05-29 02:04:04.040768 | 2026-05-29 02:04:04.040907 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-29 02:04:04.072346 | localhost | ok 2026-05-29 02:04:04.080494 | 2026-05-29 02:04:04.080583 | TASK [emit-job-header : Print job information] 2026-05-29 02:04:04.122615 | # Job Information 2026-05-29 02:04:04.122820 | Ansible Version: 2.16.18 2026-05-29 02:04:04.122889 | Job: atmosphere-molecule-csi-rbd 2026-05-29 02:04:04.122935 | Pipeline: check 2026-05-29 02:04:04.122980 | Executor: 2d72f0692154 2026-05-29 02:04:04.123023 | Triggered by: https://github.com/vexxhost/atmosphere/pull/3991 2026-05-29 02:04:04.123072 | Event ID: fa808e60-5b01-11f1-865d-24a5b51dd999 2026-05-29 02:04:04.128836 | 2026-05-29 02:04:04.128993 | LOOP [emit-job-header : Print node information] 2026-05-29 02:04:04.224829 | localhost | ok: 2026-05-29 02:04:04.225178 | localhost | # Node Information 2026-05-29 02:04:04.225242 | localhost | Inventory Hostname: instance 2026-05-29 02:04:04.225290 | localhost | Hostname: np0000177434 2026-05-29 02:04:04.225442 | localhost | Username: zuul 2026-05-29 02:04:04.225629 | localhost | Distro: Ubuntu 22.04 2026-05-29 02:04:04.225747 | localhost | Provider: yul1 2026-05-29 02:04:04.225808 | localhost | Region: ca-ymq-1 2026-05-29 02:04:04.225859 | localhost | Label: ubuntu-jammy 2026-05-29 02:04:04.225907 | localhost | Product Name: OpenStack Nova 2026-05-29 02:04:04.225956 | localhost | Interface IP: 199.204.45.250 2026-05-29 02:04:04.236478 | 2026-05-29 02:04:04.236605 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-05-29 02:04:04.637508 | localhost -> localhost | changed 2026-05-29 02:04:04.645918 | 2026-05-29 02:04:04.646008 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-05-29 02:04:05.632722 | localhost -> localhost | changed 2026-05-29 02:04:05.641725 | 2026-05-29 02:04:05.641799 | PLAY [all] 2026-05-29 02:04:05.653663 | 2026-05-29 02:04:05.653750 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-05-29 02:04:05.881432 | instance -> localhost | ok 2026-05-29 02:04:05.891867 | 2026-05-29 02:04:05.891936 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-05-29 02:04:05.926214 | instance | ok 2026-05-29 02:04:05.940194 | instance | included: /var/lib/zuul/builds/abc1f170679745429667239750fd6269/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-05-29 02:04:05.945815 | 2026-05-29 02:04:05.945873 | TASK [add-build-sshkey : Create Temp SSH key] 2026-05-29 02:04:07.012715 | instance -> localhost | Generating public/private rsa key pair. 2026-05-29 02:04:07.013018 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/abc1f170679745429667239750fd6269/work/abc1f170679745429667239750fd6269_id_rsa 2026-05-29 02:04:07.013065 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/abc1f170679745429667239750fd6269/work/abc1f170679745429667239750fd6269_id_rsa.pub 2026-05-29 02:04:07.013097 | instance -> localhost | The key fingerprint is: 2026-05-29 02:04:07.013126 | instance -> localhost | SHA256:8+IBgL5/VWoawlXh9Nn2xw4hZcutMdtM+ooS/MxeA60 zuul-build-sshkey 2026-05-29 02:04:07.013194 | instance -> localhost | The key's randomart image is: 2026-05-29 02:04:07.013223 | instance -> localhost | +---[RSA 3072]----+ 2026-05-29 02:04:07.013260 | instance -> localhost | | o. o | 2026-05-29 02:04:07.013289 | instance -> localhost | | . o.. o + o | 2026-05-29 02:04:07.013316 | instance -> localhost | | . . .. o + * o| 2026-05-29 02:04:07.013343 | instance -> localhost | | . .. .. + % | 2026-05-29 02:04:07.013369 | instance -> localhost | | .. .. S+ . B =| 2026-05-29 02:04:07.013395 | instance -> localhost | | .o ..+oo o = | 2026-05-29 02:04:07.013421 | instance -> localhost | | . . =o .=E o o| 2026-05-29 02:04:07.013448 | instance -> localhost | | . o. o. +o o | 2026-05-29 02:04:07.013478 | instance -> localhost | | .. . oo . | 2026-05-29 02:04:07.013505 | instance -> localhost | +----[SHA256]-----+ 2026-05-29 02:04:07.013569 | instance -> localhost | ok: Runtime: 0:00:00.657190 2026-05-29 02:04:07.020832 | 2026-05-29 02:04:07.020895 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-05-29 02:04:07.067103 | instance | ok 2026-05-29 02:04:07.079731 | instance | included: /var/lib/zuul/builds/abc1f170679745429667239750fd6269/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-05-29 02:04:07.087137 | 2026-05-29 02:04:07.087201 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-05-29 02:04:07.112575 | instance | skipping: Conditional result was False 2026-05-29 02:04:07.123758 | 2026-05-29 02:04:07.123935 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-05-29 02:04:07.555035 | instance | changed 2026-05-29 02:04:07.564438 | 2026-05-29 02:04:07.564530 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-05-29 02:04:07.751096 | instance | ok 2026-05-29 02:04:07.758320 | 2026-05-29 02:04:07.758405 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-05-29 02:04:08.208442 | instance | changed 2026-05-29 02:04:08.213777 | 2026-05-29 02:04:08.213841 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-05-29 02:04:08.675516 | instance | changed 2026-05-29 02:04:08.681885 | 2026-05-29 02:04:08.681997 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-05-29 02:04:08.706719 | instance | skipping: Conditional result was False 2026-05-29 02:04:08.713506 | 2026-05-29 02:04:08.713632 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-05-29 02:04:09.111755 | instance -> localhost | changed 2026-05-29 02:04:09.128518 | 2026-05-29 02:04:09.128655 | TASK [add-build-sshkey : Add back temp key] 2026-05-29 02:04:09.406931 | instance -> localhost | Identity added: /var/lib/zuul/builds/abc1f170679745429667239750fd6269/work/abc1f170679745429667239750fd6269_id_rsa (zuul-build-sshkey) 2026-05-29 02:04:09.407127 | instance -> localhost | ok: Runtime: 0:00:00.012958 2026-05-29 02:04:09.411975 | 2026-05-29 02:04:09.412036 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-05-29 02:04:09.738618 | instance | ok 2026-05-29 02:04:09.744731 | 2026-05-29 02:04:09.744811 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-05-29 02:04:09.770449 | instance | skipping: Conditional result was False 2026-05-29 02:04:09.781504 | 2026-05-29 02:04:09.781594 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-05-29 02:04:10.108144 | instance | ok 2026-05-29 02:04:10.124082 | 2026-05-29 02:04:10.313291 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-05-29 02:04:13.861978 | instance | Output suppressed because no_log was given 2026-05-29 02:04:13.873577 | 2026-05-29 02:04:13.873741 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-05-29 02:04:14.060839 | instance | ok: "logs" 2026-05-29 02:04:14.081218 | instance | ok: All items complete 2026-05-29 02:04:14.081316 | 2026-05-29 02:04:14.209900 | instance | ok: "artifacts" 2026-05-29 02:04:14.358682 | instance | ok: "docs" 2026-05-29 02:04:14.372086 | 2026-05-29 02:04:14.372286 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-05-29 02:04:14.551683 | instance | changed: "logs" 2026-05-29 02:04:14.714475 | instance | changed: "artifacts" 2026-05-29 02:04:14.880463 | instance | changed: "docs" 2026-05-29 02:04:14.897485 | 2026-05-29 02:04:14.897643 | PLAY RECAP 2026-05-29 02:04:14.897709 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-05-29 02:04:14.897747 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-05-29 02:04:14.897839 | 2026-05-29 02:04:15.048203 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-05-29 02:04:15.050436 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-05-29 02:04:15.634430 | 2026-05-29 02:04:15.685677 | PLAY [all] 2026-05-29 02:04:15.700915 | 2026-05-29 02:04:15.701007 | TASK [setup-uv : Extract archive] 2026-05-29 02:04:17.893752 | instance | changed 2026-05-29 02:04:17.901513 | 2026-05-29 02:04:17.901596 | TASK [setup-uv : Print version] 2026-05-29 02:04:18.297726 | instance | uv 0.8.13 2026-05-29 02:04:18.444113 | instance | ok: Runtime: 0:00:00.011714 2026-05-29 02:04:18.456832 | 2026-05-29 02:04:18.456938 | TASK [Install Ansible collection siblings] 2026-05-29 02:04:19.090029 | instance | ERROR 2026-05-29 02:04:19.090296 | instance | { 2026-05-29 02:04:19.090329 | instance | "log": [ 2026-05-29 02:04:19.090351 | instance | "Sibling collection ansible.netcommon at /home/zuul/src/github.com/ansible-collections/ansible.netcommon", 2026-05-29 02:04:19.090371 | instance | "Sibling collection ansible.posix at /home/zuul/src/github.com/ansible-collections/ansible.posix", 2026-05-29 02:04:19.090391 | instance | "Sibling collection ansible.utils at /home/zuul/src/github.com/ansible-collections/ansible.utils", 2026-05-29 02:04:19.090413 | instance | "Sibling collection community.crypto at /home/zuul/src/github.com/ansible-collections/community.crypto", 2026-05-29 02:04:19.090433 | instance | "Sibling collection community.general at /home/zuul/src/github.com/ansible-collections/community.general", 2026-05-29 02:04:19.090453 | instance | "Sibling collection community.mysql at /home/zuul/src/github.com/ansible-collections/community.mysql", 2026-05-29 02:04:19.090472 | instance | "Sibling collection kubernetes.core at /home/zuul/src/github.com/ansible-collections/kubernetes.core", 2026-05-29 02:04:19.090491 | instance | "Sibling collection vexxhost.ceph at /home/zuul/src/github.com/vexxhost/ansible-collection-ceph", 2026-05-29 02:04:19.090509 | instance | "Sibling collection vexxhost.containers at /home/zuul/src/github.com/vexxhost/ansible-collection-containers", 2026-05-29 02:04:19.090527 | instance | "Sibling collection vexxhost.kubernetes at /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes", 2026-05-29 02:04:19.090544 | instance | "Sibling collection atmosphere.common at /home/zuul/src/github.com/vexxhost/atmosphere.common", 2026-05-29 02:04:19.090561 | instance | "Sibling collection openstack.cloud at /home/zuul/src/opendev.org/openstack/ansible-collections-openstack", 2026-05-29 02:04:19.090579 | instance | "Resolved ansible.netcommon to v8.5.2 for >=1.2.0", 2026-05-29 02:04:19.090597 | instance | "Resolved ansible.posix to 1.6.0 for 1.6.0", 2026-05-29 02:04:19.090616 | instance | "Resolved ansible.utils to v6.0.2 for >=2.9.0,>=3.0.0", 2026-05-29 02:04:19.090633 | instance | "Resolved community.crypto to 2.2.3 for 2.2.3", 2026-05-29 02:04:19.090651 | instance | "Resolved community.general to 7.3.0 for 7.3.0", 2026-05-29 02:04:19.090669 | instance | "Resolved community.mysql to 3.6.0 for 3.6.0", 2026-05-29 02:04:19.090687 | instance | "Resolved kubernetes.core to 2.4.0 for 2.4.0", 2026-05-29 02:04:19.090705 | instance | "Resolved openstack.cloud to 1.7.0 for 1.7.0", 2026-05-29 02:04:19.090723 | instance | "Resolved atmosphere.common to v0.7.0 for >=0.6.0", 2026-05-29 02:04:19.090752 | instance | "Resolved vexxhost.ceph to v4.0.0 for >=3.1.2", 2026-05-29 02:04:19.090771 | instance | "Resolved vexxhost.kubernetes to v3.1.0 for >=2.3.2,>=2.5.0", 2026-05-29 02:04:19.090789 | instance | "Resolved ansible.utils to v6.0.2 for >=2.9.0,>=3.0.0,>=6.0.0", 2026-05-29 02:04:19.090806 | instance | "Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2" 2026-05-29 02:04:19.090823 | instance | ], 2026-05-29 02:04:19.090840 | instance | "msg": "No local release tag for openstack.cloud satisfies 1.7.0,>=2.0.0" 2026-05-29 02:04:19.090858 | instance | } failure 2026-05-29 02:04:19.093994 | 2026-05-29 02:04:19.094040 | PLAY RECAP 2026-05-29 02:04:19.094088 | instance | ok: 2 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2026-05-29 02:04:19.094110 | 2026-05-29 02:04:19.205847 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-05-29 02:04:19.211359 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-05-29 02:04:19.821293 | 2026-05-29 02:04:19.821449 | PLAY [all] 2026-05-29 02:04:19.933789 | 2026-05-29 02:04:19.933921 | TASK [fetch-output : Set log path for multiple nodes] 2026-05-29 02:04:19.979495 | instance | skipping: Conditional result was False 2026-05-29 02:04:19.990319 | 2026-05-29 02:04:19.990438 | TASK [fetch-output : Set log path for single node] 2026-05-29 02:04:20.035942 | instance | ok 2026-05-29 02:04:20.042870 | 2026-05-29 02:04:20.042976 | LOOP [fetch-output : Ensure local output dirs] 2026-05-29 02:04:20.440238 | instance -> localhost | ok: "/var/lib/zuul/builds/abc1f170679745429667239750fd6269/work/logs" 2026-05-29 02:04:20.667940 | instance -> localhost | changed: "/var/lib/zuul/builds/abc1f170679745429667239750fd6269/work/artifacts" 2026-05-29 02:04:20.901132 | instance -> localhost | changed: "/var/lib/zuul/builds/abc1f170679745429667239750fd6269/work/docs" 2026-05-29 02:04:20.921788 | 2026-05-29 02:04:20.921968 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-05-29 02:04:21.530101 | instance | ok 2026-05-29 02:04:21.530446 | instance | changed: All items complete 2026-05-29 02:04:21.530492 | 2026-05-29 02:04:22.147358 | instance | changed: .d..t...... ./ 2026-05-29 02:04:22.610656 | instance | changed: .d..t...... ./ 2026-05-29 02:04:22.638293 | 2026-05-29 02:04:22.638771 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-05-29 02:04:23.068944 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.007621 2026-05-29 02:04:23.301320 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.006931 2026-05-29 02:04:23.313510 | 2026-05-29 02:04:23.313643 | PLAY [all] 2026-05-29 02:04:23.320406 | 2026-05-29 02:04:23.320472 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-05-29 02:04:23.725429 | instance | changed 2026-05-29 02:04:23.732380 | 2026-05-29 02:04:23.732441 | PLAY RECAP 2026-05-29 02:04:23.732499 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-05-29 02:04:23.732531 | 2026-05-29 02:04:23.848730 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-05-29 02:04:23.853602 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-05-29 02:04:24.471815 | 2026-05-29 02:04:24.471963 | PLAY [localhost] 2026-05-29 02:04:24.490905 | 2026-05-29 02:04:24.491140 | TASK [Generate Zuul manifest] 2026-05-29 02:04:24.518090 | localhost | ok 2026-05-29 02:04:24.539252 | 2026-05-29 02:04:24.539358 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-05-29 02:04:24.895422 | localhost | changed 2026-05-29 02:04:24.909088 | 2026-05-29 02:04:24.909170 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-05-29 02:04:24.942215 | localhost | ok 2026-05-29 02:04:24.951839 | 2026-05-29 02:04:24.951911 | TASK [Upload logs] 2026-05-29 02:04:24.975090 | localhost | ok 2026-05-29 02:04:25.030362 | 2026-05-29 02:04:25.030506 | TASK [Set zuul-log-path fact] 2026-05-29 02:04:25.053386 | localhost | ok 2026-05-29 02:04:25.065945 | 2026-05-29 02:04:25.066018 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-29 02:04:25.096711 | localhost | ok 2026-05-29 02:04:25.106082 | 2026-05-29 02:04:25.106178 | TASK [upload-logs : Create log directories] 2026-05-29 02:04:25.541422 | localhost | changed 2026-05-29 02:04:25.546977 | 2026-05-29 02:04:25.547057 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-05-29 02:04:25.984505 | localhost -> localhost | ok: Runtime: 0:00:00.005808 2026-05-29 02:04:25.991535 | 2026-05-29 02:04:25.991629 | TASK [upload-logs : Upload logs to log server] 2026-05-29 02:04:26.473194 | localhost | Output suppressed because no_log was given 2026-05-29 02:04:26.521208 | 2026-05-29 02:04:26.521355 | LOOP [upload-logs : Compress console log and json output] 2026-05-29 02:04:26.579666 | localhost | skipping: Conditional result was False 2026-05-29 02:04:26.589939 | localhost | skipping: Conditional result was False 2026-05-29 02:04:26.608425 | 2026-05-29 02:04:26.608640 | LOOP [upload-logs : Upload compressed console log and json output] 2026-05-29 02:04:26.665262 | localhost | skipping: Conditional result was False 2026-05-29 02:04:26.665674 | 2026-05-29 02:04:26.675627 | localhost | skipping: Conditional result was False 2026-05-29 02:04:26.683257 | 2026-05-29 02:04:26.683342 | LOOP [upload-logs : Upload console log and json output]