2026-06-15 20:45:21.820319 | Job console starting 2026-06-15 20:45:21.837022 | Updating git repos 2026-06-15 20:45:25.187109 | Cloning repos into workspace 2026-06-15 20:45:39.320389 | Restoring repo states 2026-06-15 20:45:46.366282 | Merging changes 2026-06-15 20:45:47.615516 | Checking out repos 2026-06-15 20:45:49.622961 | Preparing playbooks 2026-06-15 20:45:52.125740 | Running Ansible setup 2026-06-15 20:45:55.606456 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-06-15 20:45:56.238585 | 2026-06-15 20:45:56.238741 | PLAY [localhost] 2026-06-15 20:45:56.247305 | 2026-06-15 20:45:56.247392 | TASK [Gathering Facts] 2026-06-15 20:45:57.154978 | localhost | ok 2026-06-15 20:45:57.165661 | 2026-06-15 20:45:57.165896 | TASK [Setup log path fact] 2026-06-15 20:45:57.191394 | localhost | ok 2026-06-15 20:45:57.204520 | 2026-06-15 20:45:57.204641 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-06-15 20:45:57.235124 | localhost | ok 2026-06-15 20:45:57.242626 | 2026-06-15 20:45:57.242696 | TASK [emit-job-header : Print job information] 2026-06-15 20:45:57.283428 | # Job Information 2026-06-15 20:45:57.283717 | Ansible Version: 2.16.18 2026-06-15 20:45:57.283772 | Job: atmosphere-molecule-keycloak 2026-06-15 20:45:57.283807 | Pipeline: check 2026-06-15 20:45:57.283838 | Executor: 2d72f0692154 2026-06-15 20:45:57.283869 | Triggered by: https://github.com/vexxhost/atmosphere/pull/4011 2026-06-15 20:45:57.283906 | Event ID: eb85a448-68fa-11f1-8f5c-c3bef7040b45 2026-06-15 20:45:57.288573 | 2026-06-15 20:45:57.288751 | LOOP [emit-job-header : Print node information] 2026-06-15 20:45:57.391363 | localhost | ok: 2026-06-15 20:45:57.391508 | localhost | # Node Information 2026-06-15 20:45:57.391537 | localhost | Inventory Hostname: instance 2026-06-15 20:45:57.391559 | localhost | Hostname: np0000180348 2026-06-15 20:45:57.391581 | localhost | Username: zuul 2026-06-15 20:45:57.391603 | localhost | Distro: Ubuntu 22.04 2026-06-15 20:45:57.391623 | localhost | Provider: yul1 2026-06-15 20:45:57.391642 | localhost | Region: ca-ymq-1 2026-06-15 20:45:57.391661 | localhost | Label: ubuntu-jammy 2026-06-15 20:45:57.391680 | localhost | Product Name: OpenStack Nova 2026-06-15 20:45:57.391699 | localhost | Interface IP: 199.204.45.25 2026-06-15 20:45:57.404661 | 2026-06-15 20:45:57.404814 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-06-15 20:45:57.815884 | localhost -> localhost | changed 2026-06-15 20:45:57.825291 | 2026-06-15 20:45:57.825359 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-06-15 20:45:58.733290 | localhost -> localhost | changed 2026-06-15 20:45:58.739032 | 2026-06-15 20:45:58.739090 | PLAY [all] 2026-06-15 20:45:58.746755 | 2026-06-15 20:45:58.746826 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-06-15 20:45:59.414343 | instance -> localhost | ok 2026-06-15 20:45:59.422873 | 2026-06-15 20:45:59.422961 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-06-15 20:45:59.456752 | instance | ok 2026-06-15 20:45:59.473142 | instance | included: /var/lib/zuul/builds/e4f895b06aca41f08e0c46c7d6b083bf/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-06-15 20:45:59.485994 | 2026-06-15 20:45:59.486122 | TASK [add-build-sshkey : Create Temp SSH key] 2026-06-15 20:46:00.445046 | instance -> localhost | Generating public/private rsa key pair. 2026-06-15 20:46:00.445245 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/e4f895b06aca41f08e0c46c7d6b083bf/work/e4f895b06aca41f08e0c46c7d6b083bf_id_rsa 2026-06-15 20:46:00.445289 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/e4f895b06aca41f08e0c46c7d6b083bf/work/e4f895b06aca41f08e0c46c7d6b083bf_id_rsa.pub 2026-06-15 20:46:00.445324 | instance -> localhost | The key fingerprint is: 2026-06-15 20:46:00.445357 | instance -> localhost | SHA256:gKaFBM7QgU1smf/TqfQ21qlUI34op/JN5uS0yT3DFdU zuul-build-sshkey 2026-06-15 20:46:00.445404 | instance -> localhost | The key's randomart image is: 2026-06-15 20:46:00.445439 | instance -> localhost | +---[RSA 3072]----+ 2026-06-15 20:46:00.445482 | instance -> localhost | |oB++ . | 2026-06-15 20:46:00.445518 | instance -> localhost | |=.B. . . E| 2026-06-15 20:46:00.445644 | instance -> localhost | | +..+ . . | 2026-06-15 20:46:00.445678 | instance -> localhost | | +. . . | 2026-06-15 20:46:00.445709 | instance -> localhost | | . . .S.o . | 2026-06-15 20:46:00.445738 | instance -> localhost | | +.o+ .. | 2026-06-15 20:46:00.445771 | instance -> localhost | | ..+Xo.o | 2026-06-15 20:46:00.445803 | instance -> localhost | | . .#==* | 2026-06-15 20:46:00.445838 | instance -> localhost | | oooOo.o | 2026-06-15 20:46:00.445868 | instance -> localhost | +----[SHA256]-----+ 2026-06-15 20:46:00.445940 | instance -> localhost | ok: Runtime: 0:00:00.477632 2026-06-15 20:46:00.453124 | 2026-06-15 20:46:00.453241 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-06-15 20:46:00.484606 | instance | ok 2026-06-15 20:46:00.497049 | instance | included: /var/lib/zuul/builds/e4f895b06aca41f08e0c46c7d6b083bf/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-06-15 20:46:00.508884 | 2026-06-15 20:46:00.593620 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-06-15 20:46:00.618877 | instance | skipping: Conditional result was False 2026-06-15 20:46:00.630424 | 2026-06-15 20:46:00.630567 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-06-15 20:46:01.101165 | instance | changed 2026-06-15 20:46:01.112884 | 2026-06-15 20:46:01.113032 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-06-15 20:46:01.297238 | instance | ok 2026-06-15 20:46:01.302413 | 2026-06-15 20:46:01.302478 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-06-15 20:46:01.789544 | instance | changed 2026-06-15 20:46:01.797064 | 2026-06-15 20:46:01.797154 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-06-15 20:46:02.303040 | instance | changed 2026-06-15 20:46:02.309375 | 2026-06-15 20:46:02.309461 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-06-15 20:46:02.333505 | instance | skipping: Conditional result was False 2026-06-15 20:46:02.357995 | 2026-06-15 20:46:02.358114 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-06-15 20:46:02.820779 | instance -> localhost | changed 2026-06-15 20:46:02.836081 | 2026-06-15 20:46:02.836219 | TASK [add-build-sshkey : Add back temp key] 2026-06-15 20:46:03.180836 | instance -> localhost | Identity added: /var/lib/zuul/builds/e4f895b06aca41f08e0c46c7d6b083bf/work/e4f895b06aca41f08e0c46c7d6b083bf_id_rsa (zuul-build-sshkey) 2026-06-15 20:46:03.181204 | instance -> localhost | ok: Runtime: 0:00:00.031543 2026-06-15 20:46:03.188483 | 2026-06-15 20:46:03.188607 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-06-15 20:46:03.519439 | instance | ok 2026-06-15 20:46:03.524436 | 2026-06-15 20:46:03.524506 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-06-15 20:46:03.549230 | instance | skipping: Conditional result was False 2026-06-15 20:46:03.608622 | 2026-06-15 20:46:03.608773 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-06-15 20:46:03.886368 | instance | ok 2026-06-15 20:46:03.892294 | 2026-06-15 20:46:03.892360 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-06-15 20:46:07.168560 | instance | Output suppressed because no_log was given 2026-06-15 20:46:07.178701 | 2026-06-15 20:46:07.178788 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-06-15 20:46:07.360288 | instance | ok: "logs" 2026-06-15 20:46:07.360507 | instance | ok: All items complete 2026-06-15 20:46:07.360543 | 2026-06-15 20:46:07.512894 | instance | ok: "artifacts" 2026-06-15 20:46:07.666182 | instance | ok: "docs" 2026-06-15 20:46:07.685411 | 2026-06-15 20:46:07.685549 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-06-15 20:46:07.874953 | instance | changed: "logs" 2026-06-15 20:46:08.032579 | instance | changed: "artifacts" 2026-06-15 20:46:08.205215 | instance | changed: "docs" 2026-06-15 20:46:08.225596 | 2026-06-15 20:46:08.225688 | PLAY RECAP 2026-06-15 20:46:08.225735 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-06-15 20:46:08.225765 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-06-15 20:46:08.225789 | 2026-06-15 20:46:08.370667 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-06-15 20:46:08.376027 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-06-15 20:46:09.018973 | 2026-06-15 20:46:09.019092 | PLAY [all] 2026-06-15 20:46:09.030928 | 2026-06-15 20:46:09.031022 | TASK [setup-uv : Extract archive] 2026-06-15 20:46:11.224628 | instance | changed 2026-06-15 20:46:11.232378 | 2026-06-15 20:46:11.232511 | TASK [setup-uv : Print version] 2026-06-15 20:46:11.578816 | instance | uv 0.8.13 2026-06-15 20:46:11.777626 | instance | ok: Runtime: 0:00:00.011760 2026-06-15 20:46:11.804205 | 2026-06-15 20:46:11.804333 | TASK [Install Ansible collection siblings] 2026-06-15 20:46:12.454293 | instance | ERROR 2026-06-15 20:46:12.454593 | instance | { 2026-06-15 20:46:12.454634 | instance | "log": [ 2026-06-15 20:46:12.454663 | instance | "Sibling collection ansible.netcommon at /home/zuul/src/github.com/ansible-collections/ansible.netcommon", 2026-06-15 20:46:12.454689 | instance | "Sibling collection ansible.posix at /home/zuul/src/github.com/ansible-collections/ansible.posix", 2026-06-15 20:46:12.454712 | instance | "Sibling collection ansible.utils at /home/zuul/src/github.com/ansible-collections/ansible.utils", 2026-06-15 20:46:12.454740 | instance | "Sibling collection community.crypto at /home/zuul/src/github.com/ansible-collections/community.crypto", 2026-06-15 20:46:12.454763 | instance | "Sibling collection community.general at /home/zuul/src/github.com/ansible-collections/community.general", 2026-06-15 20:46:12.454787 | instance | "Sibling collection community.mysql at /home/zuul/src/github.com/ansible-collections/community.mysql", 2026-06-15 20:46:12.454812 | instance | "Sibling collection kubernetes.core at /home/zuul/src/github.com/ansible-collections/kubernetes.core", 2026-06-15 20:46:12.454837 | instance | "Sibling collection vexxhost.ceph at /home/zuul/src/github.com/vexxhost/ansible-collection-ceph", 2026-06-15 20:46:12.454860 | instance | "Sibling collection vexxhost.containers at /home/zuul/src/github.com/vexxhost/ansible-collection-containers", 2026-06-15 20:46:12.454881 | instance | "Sibling collection vexxhost.kubernetes at /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes", 2026-06-15 20:46:12.454903 | instance | "Sibling collection atmosphere.common at /home/zuul/src/github.com/vexxhost/atmosphere.common", 2026-06-15 20:46:12.454924 | instance | "Sibling collection openstack.cloud at /home/zuul/src/opendev.org/openstack/ansible-collections-openstack", 2026-06-15 20:46:12.454945 | instance | "Resolved ansible.netcommon to v8.5.2 for >=1.2.0", 2026-06-15 20:46:12.454969 | instance | "Resolved ansible.posix to 1.6.0 for 1.6.0", 2026-06-15 20:46:12.454994 | instance | "Resolved ansible.utils to v6.0.2 for >=2.9.0,>=3.0.0", 2026-06-15 20:46:12.455016 | instance | "Resolved community.crypto to 2.2.3 for 2.2.3", 2026-06-15 20:46:12.455037 | instance | "Resolved community.general to 7.3.0 for 7.3.0", 2026-06-15 20:46:12.455058 | instance | "Resolved community.mysql to 3.6.0 for 3.6.0", 2026-06-15 20:46:12.455079 | instance | "Resolved kubernetes.core to 2.4.0 for 2.4.0", 2026-06-15 20:46:12.455099 | instance | "Resolved openstack.cloud to 1.7.0 for 1.7.0", 2026-06-15 20:46:12.455123 | instance | "Resolved atmosphere.common to v0.7.0 for >=0.6.0", 2026-06-15 20:46:12.455163 | instance | "Resolved vexxhost.ceph to v4.0.0 for >=3.1.2", 2026-06-15 20:46:12.455189 | instance | "Resolved vexxhost.kubernetes to v3.2.0 for >=3.2.0,>=2.5.0", 2026-06-15 20:46:12.455211 | instance | "Resolved ansible.utils to v6.0.2 for >=2.9.0,>=3.0.0,>=6.0.0", 2026-06-15 20:46:12.455233 | instance | "Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2" 2026-06-15 20:46:12.455254 | instance | ], 2026-06-15 20:46:12.455277 | instance | "msg": "No local release tag for openstack.cloud satisfies 1.7.0,>=2.0.0" 2026-06-15 20:46:12.455303 | instance | } failure 2026-06-15 20:46:12.458847 | 2026-06-15 20:46:12.458898 | PLAY RECAP 2026-06-15 20:46:12.458944 | instance | ok: 2 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2026-06-15 20:46:12.458966 | 2026-06-15 20:46:12.602133 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-06-15 20:46:12.606224 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-06-15 20:46:13.256148 | 2026-06-15 20:46:13.256297 | PLAY [all] 2026-06-15 20:46:13.278559 | 2026-06-15 20:46:13.278680 | TASK [fetch-output : Set log path for multiple nodes] 2026-06-15 20:46:13.326230 | instance | skipping: Conditional result was False 2026-06-15 20:46:13.334502 | 2026-06-15 20:46:13.334637 | TASK [fetch-output : Set log path for single node] 2026-06-15 20:46:13.377443 | instance | ok 2026-06-15 20:46:13.385912 | 2026-06-15 20:46:13.386080 | LOOP [fetch-output : Ensure local output dirs] 2026-06-15 20:46:13.809597 | instance -> localhost | ok: "/var/lib/zuul/builds/e4f895b06aca41f08e0c46c7d6b083bf/work/logs" 2026-06-15 20:46:14.035453 | instance -> localhost | changed: "/var/lib/zuul/builds/e4f895b06aca41f08e0c46c7d6b083bf/work/artifacts" 2026-06-15 20:46:14.285786 | instance -> localhost | changed: "/var/lib/zuul/builds/e4f895b06aca41f08e0c46c7d6b083bf/work/docs" 2026-06-15 20:46:14.299336 | 2026-06-15 20:46:14.299499 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-06-15 20:46:14.975769 | instance | changed: .d..t...... ./ 2026-06-15 20:46:14.975959 | instance | changed: All items complete 2026-06-15 20:46:14.975987 | 2026-06-15 20:46:15.444122 | instance | changed: .d..t...... ./ 2026-06-15 20:46:15.916504 | instance | changed: .d..t...... ./ 2026-06-15 20:46:15.934828 | 2026-06-15 20:46:15.934989 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-06-15 20:46:16.372015 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.007222 2026-06-15 20:46:16.610445 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.005931 2026-06-15 20:46:16.628067 | 2026-06-15 20:46:16.628211 | PLAY [all] 2026-06-15 20:46:16.643345 | 2026-06-15 20:46:16.643747 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-06-15 20:46:17.066540 | instance | changed 2026-06-15 20:46:17.076418 | 2026-06-15 20:46:17.076536 | PLAY RECAP 2026-06-15 20:46:17.076586 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-06-15 20:46:17.076608 | 2026-06-15 20:46:17.222723 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-06-15 20:46:17.226757 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-06-15 20:46:17.908518 | 2026-06-15 20:46:17.909282 | PLAY [localhost] 2026-06-15 20:46:17.924941 | 2026-06-15 20:46:17.925174 | TASK [Generate Zuul manifest] 2026-06-15 20:46:17.944378 | localhost | ok 2026-06-15 20:46:17.965206 | 2026-06-15 20:46:17.965371 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-06-15 20:46:18.348262 | localhost | changed 2026-06-15 20:46:18.360363 | 2026-06-15 20:46:18.360509 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-06-15 20:46:18.401988 | localhost | ok 2026-06-15 20:46:18.411118 | 2026-06-15 20:46:18.411252 | TASK [Upload logs] 2026-06-15 20:46:18.431204 | localhost | ok 2026-06-15 20:46:18.495772 | 2026-06-15 20:46:18.495890 | TASK [Set zuul-log-path fact] 2026-06-15 20:46:18.517550 | localhost | ok 2026-06-15 20:46:18.534720 | 2026-06-15 20:46:18.534849 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-06-15 20:46:18.565829 | localhost | ok 2026-06-15 20:46:18.575822 | 2026-06-15 20:46:18.575926 | TASK [upload-logs : Create log directories] 2026-06-15 20:46:18.994235 | localhost | changed 2026-06-15 20:46:19.032586 | 2026-06-15 20:46:19.032714 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-06-15 20:46:19.375977 | localhost -> localhost | ok: Runtime: 0:00:00.004543 2026-06-15 20:46:19.383214 | 2026-06-15 20:46:19.383336 | TASK [upload-logs : Upload logs to log server] 2026-06-15 20:46:19.890860 | localhost | Output suppressed because no_log was given 2026-06-15 20:46:19.895986 | 2026-06-15 20:46:19.896072 | LOOP [upload-logs : Compress console log and json output] 2026-06-15 20:46:19.938208 | localhost | skipping: Conditional result was False 2026-06-15 20:46:19.946617 | localhost | skipping: Conditional result was False 2026-06-15 20:46:19.958123 | 2026-06-15 20:46:19.958228 | LOOP [upload-logs : Upload compressed console log and json output] 2026-06-15 20:46:19.999275 | localhost | skipping: Conditional result was False 2026-06-15 20:46:19.999738 | 2026-06-15 20:46:20.003239 | localhost | skipping: Conditional result was False 2026-06-15 20:46:20.012754 | 2026-06-15 20:46:20.012871 | LOOP [upload-logs : Upload console log and json output]