2026-05-27 13:41:02.360096 | Job console starting 2026-05-27 13:41:02.388821 | Updating git repos 2026-05-27 13:41:02.968321 | Cloning repos into workspace 2026-05-27 13:41:17.785920 | Restoring repo states 2026-05-27 13:41:26.399136 | Merging changes 2026-05-27 13:41:28.819221 | Checking out repos 2026-05-27 13:41:31.689574 | Preparing playbooks 2026-05-27 13:41:39.601198 | Running Ansible setup 2026-05-27 13:41:43.244321 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-05-27 13:41:43.950372 | 2026-05-27 13:41:43.950558 | PLAY [localhost] 2026-05-27 13:41:43.962711 | 2026-05-27 13:41:43.962878 | TASK [Gathering Facts] 2026-05-27 13:41:44.983418 | localhost | ok 2026-05-27 13:41:44.994957 | 2026-05-27 13:41:45.037417 | TASK [Setup log path fact] 2026-05-27 13:41:45.060395 | localhost | ok 2026-05-27 13:41:45.074149 | 2026-05-27 13:41:45.074296 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-27 13:41:45.107742 | localhost | ok 2026-05-27 13:41:45.115657 | 2026-05-27 13:41:45.115833 | TASK [emit-job-header : Print job information] 2026-05-27 13:41:45.161668 | # Job Information 2026-05-27 13:41:45.161872 | Ansible Version: 2.16.18 2026-05-27 13:41:45.161906 | Job: atmosphere-molecule-keycloak 2026-05-27 13:41:45.161929 | Pipeline: check 2026-05-27 13:41:45.161950 | Executor: 2d72f0692154 2026-05-27 13:41:45.161970 | Triggered by: https://github.com/vexxhost/atmosphere/pull/3985 2026-05-27 13:41:45.161993 | Event ID: 2e0b3780-59d1-11f1-9a24-82821c8c3883 2026-05-27 13:41:45.165676 | 2026-05-27 13:41:45.165750 | LOOP [emit-job-header : Print node information] 2026-05-27 13:41:45.272681 | localhost | ok: 2026-05-27 13:41:45.273068 | localhost | # Node Information 2026-05-27 13:41:45.273136 | localhost | Inventory Hostname: instance 2026-05-27 13:41:45.273184 | localhost | Hostname: np0000176984 2026-05-27 13:41:45.273229 | localhost | Username: zuul 2026-05-27 13:41:45.273267 | localhost | Distro: Ubuntu 22.04 2026-05-27 13:41:45.273297 | localhost | Provider: yul1 2026-05-27 13:41:45.273326 | localhost | Region: ca-ymq-1 2026-05-27 13:41:45.273354 | localhost | Label: ubuntu-jammy 2026-05-27 13:41:45.273381 | localhost | Product Name: OpenStack Nova 2026-05-27 13:41:45.273408 | localhost | Interface IP: 199.204.45.116 2026-05-27 13:41:45.286870 | 2026-05-27 13:41:45.287059 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-05-27 13:41:45.874523 | localhost -> localhost | changed 2026-05-27 13:41:45.883349 | 2026-05-27 13:41:45.883452 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-05-27 13:41:46.793509 | localhost -> localhost | changed 2026-05-27 13:41:46.800889 | 2026-05-27 13:41:46.800949 | PLAY [all] 2026-05-27 13:41:46.810088 | 2026-05-27 13:41:46.810182 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-05-27 13:41:47.478128 | instance -> localhost | ok 2026-05-27 13:41:47.487019 | 2026-05-27 13:41:47.487096 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-05-27 13:41:47.518846 | instance | ok 2026-05-27 13:41:47.537964 | instance | included: /var/lib/zuul/builds/d7132b1d71f0462a854f842e2d671baf/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-05-27 13:41:47.544485 | 2026-05-27 13:41:47.544562 | TASK [add-build-sshkey : Create Temp SSH key] 2026-05-27 13:41:48.924552 | instance -> localhost | Generating public/private rsa key pair. 2026-05-27 13:41:48.924975 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/d7132b1d71f0462a854f842e2d671baf/work/d7132b1d71f0462a854f842e2d671baf_id_rsa 2026-05-27 13:41:48.925049 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/d7132b1d71f0462a854f842e2d671baf/work/d7132b1d71f0462a854f842e2d671baf_id_rsa.pub 2026-05-27 13:41:48.925086 | instance -> localhost | The key fingerprint is: 2026-05-27 13:41:48.925160 | instance -> localhost | SHA256:4FsvikIH6VjRS0HCIDdAAJU3q/ybFDllniSx2KUhKzo zuul-build-sshkey 2026-05-27 13:41:48.925208 | instance -> localhost | The key's randomart image is: 2026-05-27 13:41:48.925241 | instance -> localhost | +---[RSA 3072]----+ 2026-05-27 13:41:48.925310 | instance -> localhost | |X**+=.. | 2026-05-27 13:41:48.925348 | instance -> localhost | |..+B=* | 2026-05-27 13:41:48.925380 | instance -> localhost | |. o==+= | 2026-05-27 13:41:48.925642 | instance -> localhost | |..+ oO o | 2026-05-27 13:41:48.925708 | instance -> localhost | |E= o+ + S | 2026-05-27 13:41:48.925745 | instance -> localhost | |..= .o o . | 2026-05-27 13:41:48.925777 | instance -> localhost | | . o. . . . | 2026-05-27 13:41:48.925808 | instance -> localhost | | ...o . . | 2026-05-27 13:41:48.925842 | instance -> localhost | | .+.. | 2026-05-27 13:41:48.925872 | instance -> localhost | +----[SHA256]-----+ 2026-05-27 13:41:48.925939 | instance -> localhost | ok: Runtime: 0:00:00.909175 2026-05-27 13:41:48.933770 | 2026-05-27 13:41:48.933892 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-05-27 13:41:48.968731 | instance | ok 2026-05-27 13:41:48.980416 | instance | included: /var/lib/zuul/builds/d7132b1d71f0462a854f842e2d671baf/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-05-27 13:41:48.989343 | 2026-05-27 13:41:48.989410 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-05-27 13:41:49.014139 | instance | skipping: Conditional result was False 2026-05-27 13:41:49.025154 | 2026-05-27 13:41:49.025262 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-05-27 13:41:49.519287 | instance | changed 2026-05-27 13:41:49.526364 | 2026-05-27 13:41:49.526434 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-05-27 13:41:49.711401 | instance | ok 2026-05-27 13:41:49.852309 | 2026-05-27 13:41:49.852487 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-05-27 13:41:50.347949 | instance | changed 2026-05-27 13:41:50.354446 | 2026-05-27 13:41:50.354526 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-05-27 13:41:50.832710 | instance | changed 2026-05-27 13:41:50.839637 | 2026-05-27 13:41:50.839757 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-05-27 13:41:50.865932 | instance | skipping: Conditional result was False 2026-05-27 13:41:50.874911 | 2026-05-27 13:41:50.875022 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-05-27 13:41:51.249884 | instance -> localhost | changed 2026-05-27 13:41:51.266896 | 2026-05-27 13:41:51.267292 | TASK [add-build-sshkey : Add back temp key] 2026-05-27 13:41:51.707729 | instance -> localhost | Identity added: /var/lib/zuul/builds/d7132b1d71f0462a854f842e2d671baf/work/d7132b1d71f0462a854f842e2d671baf_id_rsa (zuul-build-sshkey) 2026-05-27 13:41:51.707944 | instance -> localhost | ok: Runtime: 0:00:00.012291 2026-05-27 13:41:51.719725 | 2026-05-27 13:41:51.719810 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-05-27 13:41:52.070586 | instance | ok 2026-05-27 13:41:52.075335 | 2026-05-27 13:41:52.075400 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-05-27 13:41:52.099856 | instance | skipping: Conditional result was False 2026-05-27 13:41:52.188092 | 2026-05-27 13:41:52.512520 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-05-27 13:41:52.801702 | instance | ok 2026-05-27 13:41:53.049321 | 2026-05-27 13:41:53.049482 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-05-27 13:41:56.925090 | instance | Output suppressed because no_log was given 2026-05-27 13:41:57.102103 | 2026-05-27 13:41:57.102231 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-05-27 13:41:57.297692 | instance | ok: "logs" 2026-05-27 13:41:57.297954 | instance | ok: All items complete 2026-05-27 13:41:57.297984 | 2026-05-27 13:41:57.444660 | instance | ok: "artifacts" 2026-05-27 13:41:57.604364 | instance | ok: "docs" 2026-05-27 13:41:57.954139 | 2026-05-27 13:41:57.954269 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-05-27 13:41:58.166133 | instance | changed: "logs" 2026-05-27 13:41:58.327420 | instance | changed: "artifacts" 2026-05-27 13:41:58.496677 | instance | changed: "docs" 2026-05-27 13:41:58.515970 | 2026-05-27 13:41:58.516095 | PLAY RECAP 2026-05-27 13:41:58.516152 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-05-27 13:41:58.516182 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-05-27 13:41:58.516204 | 2026-05-27 13:41:58.799715 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-05-27 13:41:58.808175 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-05-27 13:41:59.483847 | 2026-05-27 13:41:59.483996 | PLAY [all] 2026-05-27 13:41:59.496720 | 2026-05-27 13:41:59.496827 | TASK [setup-uv : Extract archive] 2026-05-27 13:42:01.813409 | instance | changed 2026-05-27 13:42:01.823837 | 2026-05-27 13:42:01.824061 | TASK [setup-uv : Print version] 2026-05-27 13:42:02.169196 | instance | uv 0.8.13 2026-05-27 13:42:02.371703 | instance | ok: Runtime: 0:00:00.017262 2026-05-27 13:42:02.381838 | 2026-05-27 13:42:02.381927 | TASK [Install Ansible collection siblings] 2026-05-27 13:42:03.016006 | instance | ERROR 2026-05-27 13:42:03.479024 | instance | { 2026-05-27 13:42:03.479230 | instance | "log": [ 2026-05-27 13:42:03.479290 | instance | "Sibling collection ansible.netcommon at /home/zuul/src/github.com/ansible-collections/ansible.netcommon", 2026-05-27 13:42:03.479338 | instance | "Sibling collection ansible.posix at /home/zuul/src/github.com/ansible-collections/ansible.posix", 2026-05-27 13:42:03.479384 | instance | "Sibling collection ansible.utils at /home/zuul/src/github.com/ansible-collections/ansible.utils", 2026-05-27 13:42:03.479433 | instance | "Sibling collection community.crypto at /home/zuul/src/github.com/ansible-collections/community.crypto", 2026-05-27 13:42:03.479477 | instance | "Sibling collection community.general at /home/zuul/src/github.com/ansible-collections/community.general", 2026-05-27 13:42:03.479526 | instance | "Sibling collection community.mysql at /home/zuul/src/github.com/ansible-collections/community.mysql", 2026-05-27 13:42:03.479571 | instance | "Sibling collection kubernetes.core at /home/zuul/src/github.com/ansible-collections/kubernetes.core", 2026-05-27 13:42:03.479612 | instance | "Sibling collection vexxhost.ceph at /home/zuul/src/github.com/vexxhost/ansible-collection-ceph", 2026-05-27 13:42:03.479654 | instance | "Sibling collection vexxhost.containers at /home/zuul/src/github.com/vexxhost/ansible-collection-containers", 2026-05-27 13:42:03.479696 | instance | "Sibling collection vexxhost.kubernetes at /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes", 2026-05-27 13:42:03.479736 | instance | "Sibling collection atmosphere.common at /home/zuul/src/github.com/vexxhost/atmosphere.common", 2026-05-27 13:42:03.479777 | instance | "Sibling collection openstack.cloud at /home/zuul/src/opendev.org/openstack/ansible-collections-openstack", 2026-05-27 13:42:03.479819 | instance | "Resolved ansible.netcommon to v8.5.2 for >=1.2.0", 2026-05-27 13:42:03.479863 | instance | "Resolved ansible.posix to 1.6.0 for 1.6.0", 2026-05-27 13:42:03.479905 | instance | "Resolved ansible.utils to v6.0.2 for >=2.9.0,>=3.0.0", 2026-05-27 13:42:03.479947 | instance | "Resolved community.crypto to 2.2.3 for 2.2.3", 2026-05-27 13:42:03.479989 | instance | "Resolved community.general to 7.3.0 for 7.3.0", 2026-05-27 13:42:03.480030 | instance | "Resolved community.mysql to 3.6.0 for 3.6.0", 2026-05-27 13:42:03.480072 | instance | "Resolved kubernetes.core to 2.4.0 for 2.4.0", 2026-05-27 13:42:03.480113 | instance | "Resolved openstack.cloud to 1.7.0 for 1.7.0", 2026-05-27 13:42:03.480157 | instance | "Resolved atmosphere.common to v0.7.0 for >=0.6.0", 2026-05-27 13:42:03.480227 | instance | "Resolved vexxhost.ceph to v4.0.0 for >=3.1.2", 2026-05-27 13:42:03.480270 | instance | "Resolved vexxhost.kubernetes to v3.1.0 for >=2.3.2,>=2.5.0", 2026-05-27 13:42:03.480311 | instance | "Resolved ansible.utils to v6.0.2 for >=2.9.0,>=3.0.0,>=6.0.0", 2026-05-27 13:42:03.480353 | instance | "Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2" 2026-05-27 13:42:03.480393 | instance | ], 2026-05-27 13:42:03.480435 | instance | "msg": "No local release tag for openstack.cloud satisfies 1.7.0,>=2.0.0" 2026-05-27 13:42:03.480476 | instance | } failure 2026-05-27 13:42:03.487339 | 2026-05-27 13:42:03.487446 | PLAY RECAP 2026-05-27 13:42:03.487553 | instance | ok: 2 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2026-05-27 13:42:03.487603 | 2026-05-27 13:42:03.635929 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-05-27 13:42:04.438319 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-05-27 13:42:05.162270 | 2026-05-27 13:42:05.162484 | PLAY [all] 2026-05-27 13:42:05.175591 | 2026-05-27 13:42:05.175689 | TASK [fetch-output : Set log path for multiple nodes] 2026-05-27 13:42:05.230502 | instance | skipping: Conditional result was False 2026-05-27 13:42:05.241100 | 2026-05-27 13:42:06.552580 | TASK [fetch-output : Set log path for single node] 2026-05-27 13:42:06.597925 | instance | ok 2026-05-27 13:42:06.605961 | 2026-05-27 13:42:06.606102 | LOOP [fetch-output : Ensure local output dirs] 2026-05-27 13:42:07.005351 | instance -> localhost | ok: "/var/lib/zuul/builds/d7132b1d71f0462a854f842e2d671baf/work/logs" 2026-05-27 13:42:07.842727 | instance -> localhost | changed: "/var/lib/zuul/builds/d7132b1d71f0462a854f842e2d671baf/work/artifacts" 2026-05-27 13:42:08.116685 | instance -> localhost | changed: "/var/lib/zuul/builds/d7132b1d71f0462a854f842e2d671baf/work/docs" 2026-05-27 13:42:08.133316 | 2026-05-27 13:42:08.133853 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-05-27 13:42:08.869013 | instance | ok 2026-05-27 13:42:08.869288 | instance | changed: All items complete 2026-05-27 13:42:08.869319 | 2026-05-27 13:42:09.332992 | instance | changed: .d..t...... ./ 2026-05-27 13:42:10.135923 | instance | changed: .d..t...... ./ 2026-05-27 13:42:10.168203 | 2026-05-27 13:42:10.168588 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-05-27 13:42:10.678280 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.008652 2026-05-27 13:42:11.856325 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.889772 2026-05-27 13:42:11.875030 | 2026-05-27 13:42:11.875163 | PLAY [all] 2026-05-27 13:42:11.881290 | 2026-05-27 13:42:11.881381 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-05-27 13:42:12.336676 | instance | changed 2026-05-27 13:42:12.345333 | 2026-05-27 13:42:12.345433 | PLAY RECAP 2026-05-27 13:42:12.345485 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-05-27 13:42:12.345507 | 2026-05-27 13:42:12.570415 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-05-27 13:42:13.127303 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-05-27 13:42:13.780643 | 2026-05-27 13:42:13.780800 | PLAY [localhost] 2026-05-27 13:42:13.792555 | 2026-05-27 13:42:13.792681 | TASK [Generate Zuul manifest] 2026-05-27 13:42:13.813204 | localhost | ok 2026-05-27 13:42:13.830558 | 2026-05-27 13:42:13.830691 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-05-27 13:42:14.205194 | localhost | changed 2026-05-27 13:42:14.217963 | 2026-05-27 13:42:14.218056 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-05-27 13:42:14.420148 | localhost | ok 2026-05-27 13:42:14.428854 | 2026-05-27 13:42:14.429034 | TASK [Upload logs] 2026-05-27 13:42:14.455220 | localhost | ok 2026-05-27 13:42:14.535663 | 2026-05-27 13:42:14.661243 | TASK [Set zuul-log-path fact] 2026-05-27 13:42:14.687369 | localhost | ok 2026-05-27 13:42:14.704574 | 2026-05-27 13:42:14.704754 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-27 13:42:14.756343 | localhost | ok 2026-05-27 13:42:14.767252 | 2026-05-27 13:42:14.767403 | TASK [upload-logs : Create log directories] 2026-05-27 13:42:15.341671 | localhost | changed 2026-05-27 13:42:15.391298 | 2026-05-27 13:42:15.391425 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-05-27 13:42:15.885577 | localhost -> localhost | ok: Runtime: 0:00:00.004310 2026-05-27 13:42:15.891411 | 2026-05-27 13:42:15.891542 | TASK [upload-logs : Upload logs to log server] 2026-05-27 13:42:16.662334 | localhost | Output suppressed because no_log was given 2026-05-27 13:42:16.666230 | 2026-05-27 13:42:16.666303 | LOOP [upload-logs : Compress console log and json output] 2026-05-27 13:42:16.716659 | localhost | skipping: Conditional result was False 2026-05-27 13:42:16.726335 | localhost | skipping: Conditional result was False 2026-05-27 13:42:16.740917 | 2026-05-27 13:42:16.820114 | LOOP [upload-logs : Upload compressed console log and json output] 2026-05-27 13:42:16.888203 | localhost | skipping: Conditional result was False 2026-05-27 13:42:16.888804 | 2026-05-27 13:42:16.892155 | localhost | skipping: Conditional result was False 2026-05-27 13:42:16.909373 | 2026-05-27 13:42:16.909681 | LOOP [upload-logs : Upload console log and json output]