2026-05-26 05:45:43.459645 | Job console starting
2026-05-26 05:45:43.471165 | Updating git repos
2026-05-26 05:45:44.979300 | Cloning repos into workspace
2026-05-26 05:46:01.284352 | Restoring repo states
2026-05-26 05:46:12.911729 | Merging changes
2026-05-26 05:46:14.656367 | Checking out repos
2026-05-26 05:46:24.379824 | Preparing playbooks
2026-05-26 05:46:39.867055 | Running Ansible setup
2026-05-26 05:46:43.670344 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-05-26 05:46:44.325813 | 
2026-05-26 05:46:44.325984 | PLAY [localhost]
2026-05-26 05:46:44.334032 | 
2026-05-26 05:46:44.334103 | TASK [Gathering Facts]
2026-05-26 05:46:45.236200 | localhost | ok
2026-05-26 05:46:45.248011 | 
2026-05-26 05:46:45.248089 | TASK [Setup log path fact]
2026-05-26 05:46:45.269232 | localhost | ok
2026-05-26 05:46:45.281211 | 
2026-05-26 05:46:45.281294 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-05-26 05:46:45.311512 | localhost | ok
2026-05-26 05:46:45.319270 | 
2026-05-26 05:46:45.319337 | TASK [emit-job-header : Print job information]
2026-05-26 05:46:45.362152 | # Job Information
2026-05-26 05:46:45.362283 | Ansible Version: 2.16.18
2026-05-26 05:46:45.362314 | Job: atmosphere-molecule-keycloak
2026-05-26 05:46:45.362337 | Pipeline: check
2026-05-26 05:46:45.362357 | Executor: 2d72f0692154
2026-05-26 05:46:45.362376 | Triggered by: https://github.com/vexxhost/atmosphere/pull/3991
2026-05-26 05:46:45.362398 | Event ID: 4af667a0-58c5-11f1-90f3-935b3405b672
2026-05-26 05:46:45.365258 | 
2026-05-26 05:46:45.365324 | LOOP [emit-job-header : Print node information]
2026-05-26 05:46:45.460595 | localhost | ok:
2026-05-26 05:46:45.461573 | localhost | # Node Information
2026-05-26 05:46:45.461657 | localhost | Inventory Hostname: instance
2026-05-26 05:46:45.461707 | localhost | Hostname: np0000176451
2026-05-26 05:46:45.461752 | localhost | Username: zuul
2026-05-26 05:46:45.461801 | localhost | Distro: Ubuntu 22.04
2026-05-26 05:46:45.461846 | localhost | Provider: yul1
2026-05-26 05:46:45.461887 | localhost | Region: ca-ymq-1
2026-05-26 05:46:45.461929 | localhost | Label: ubuntu-jammy
2026-05-26 05:46:45.461969 | localhost | Product Name: OpenStack Nova
2026-05-26 05:46:45.462009 | localhost | Interface IP: 199.204.45.216
2026-05-26 05:46:45.482912 | 
2026-05-26 05:46:45.483066 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-05-26 05:46:45.853318 | localhost -> localhost | changed
2026-05-26 05:46:45.871055 | 
2026-05-26 05:46:45.871123 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-05-26 05:46:46.733436 | localhost -> localhost | changed
2026-05-26 05:46:46.745012 | 
2026-05-26 05:46:46.745146 | PLAY [all]
2026-05-26 05:46:46.754280 | 
2026-05-26 05:46:46.754426 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-05-26 05:46:46.971849 | instance -> localhost | ok
2026-05-26 05:46:46.983412 | 
2026-05-26 05:46:46.983546 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-05-26 05:46:47.016984 | instance | ok
2026-05-26 05:46:47.033068 | instance | included: /var/lib/zuul/builds/dc637db5a91149d58d0d91b8ce854ee3/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-05-26 05:46:47.038536 | 
2026-05-26 05:46:47.038601 | TASK [add-build-sshkey : Create Temp SSH key]
2026-05-26 05:46:48.046491 | instance -> localhost | Generating public/private rsa key pair.
2026-05-26 05:46:48.046689 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/dc637db5a91149d58d0d91b8ce854ee3/work/dc637db5a91149d58d0d91b8ce854ee3_id_rsa
2026-05-26 05:46:48.046729 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/dc637db5a91149d58d0d91b8ce854ee3/work/dc637db5a91149d58d0d91b8ce854ee3_id_rsa.pub
2026-05-26 05:46:48.046761 | instance -> localhost | The key fingerprint is:
2026-05-26 05:46:48.046791 | instance -> localhost | SHA256:ULTeTqGBXYP/zalKr9BPCmUxW263iCGHu+/VueaF9/k zuul-build-sshkey
2026-05-26 05:46:48.046836 | instance -> localhost | The key's randomart image is:
2026-05-26 05:46:48.046865 | instance -> localhost | +---[RSA 3072]----+
2026-05-26 05:46:48.046902 | instance -> localhost | |       .o.o      |
2026-05-26 05:46:48.046933 | instance -> localhost | |       +.o .     |
2026-05-26 05:46:48.046962 | instance -> localhost | |      o +.+ .    |
2026-05-26 05:46:48.046991 | instance -> localhost | |       o =.B     |
2026-05-26 05:46:48.047019 | instance -> localhost | |        S O.oo.. |
2026-05-26 05:46:48.047048 | instance -> localhost | |         X +.+++ |
2026-05-26 05:46:48.047076 | instance -> localhost | |        + = +.= o|
2026-05-26 05:46:48.047104 | instance -> localhost | |         = *. .+o|
2026-05-26 05:46:48.047135 | instance -> localhost | |        .o*oooo.E|
2026-05-26 05:46:48.047165 | instance -> localhost | +----[SHA256]-----+
2026-05-26 05:46:48.047228 | instance -> localhost | ok: Runtime: 0:00:00.575624
2026-05-26 05:46:48.053790 | 
2026-05-26 05:46:48.053850 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-05-26 05:46:48.087209 | instance | ok
2026-05-26 05:46:48.098738 | instance | included: /var/lib/zuul/builds/dc637db5a91149d58d0d91b8ce854ee3/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-05-26 05:46:48.107948 | 
2026-05-26 05:46:48.108084 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-05-26 05:46:48.134122 | instance | skipping: Conditional result was False
2026-05-26 05:46:48.142944 | 
2026-05-26 05:46:48.143045 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-05-26 05:46:48.605973 | instance | changed
2026-05-26 05:46:48.613237 | 
2026-05-26 05:46:48.613341 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-05-26 05:46:48.810688 | instance | ok
2026-05-26 05:46:48.817045 | 
2026-05-26 05:46:48.817128 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-05-26 05:46:49.284132 | instance | changed
2026-05-26 05:46:49.289443 | 
2026-05-26 05:46:49.289511 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-05-26 05:46:49.759897 | instance | changed
2026-05-26 05:46:49.764997 | 
2026-05-26 05:46:49.765068 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-05-26 05:46:49.790745 | instance | skipping: Conditional result was False
2026-05-26 05:46:49.799491 | 
2026-05-26 05:46:49.799584 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-05-26 05:46:50.170275 | instance -> localhost | changed
2026-05-26 05:46:50.186092 | 
2026-05-26 05:46:50.186230 | TASK [add-build-sshkey : Add back temp key]
2026-05-26 05:46:50.483040 | instance -> localhost | Identity added: /var/lib/zuul/builds/dc637db5a91149d58d0d91b8ce854ee3/work/dc637db5a91149d58d0d91b8ce854ee3_id_rsa (zuul-build-sshkey)
2026-05-26 05:46:50.483301 | instance -> localhost | ok: Runtime: 0:00:00.015420
2026-05-26 05:46:50.489468 | 
2026-05-26 05:46:50.489532 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-05-26 05:46:51.009200 | instance | ok
2026-05-26 05:46:51.015801 | 
2026-05-26 05:46:51.015867 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-05-26 05:46:51.041499 | instance | skipping: Conditional result was False
2026-05-26 05:46:51.105359 | 
2026-05-26 05:46:51.105537 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-05-26 05:46:51.395551 | instance | ok
2026-05-26 05:46:51.403427 | 
2026-05-26 05:46:51.403491 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-05-26 05:46:54.514317 | instance | Output suppressed because no_log was given
2026-05-26 05:46:54.523109 | 
2026-05-26 05:46:54.523175 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-05-26 05:46:54.727894 | instance | ok: "logs"
2026-05-26 05:46:54.728254 | instance | ok: All items complete
2026-05-26 05:46:54.728298 | 
2026-05-26 05:46:54.884409 | instance | ok: "artifacts"
2026-05-26 05:46:55.038586 | instance | ok: "docs"
2026-05-26 05:46:55.052394 | 
2026-05-26 05:46:55.052554 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-05-26 05:46:55.240573 | instance | changed: "logs"
2026-05-26 05:46:55.390354 | instance | changed: "artifacts"
2026-05-26 05:46:55.550399 | instance | changed: "docs"
2026-05-26 05:46:55.576643 | 
2026-05-26 05:46:55.576788 | PLAY RECAP
2026-05-26 05:46:55.576848 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-05-26 05:46:55.576907 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-05-26 05:46:55.576935 | 
2026-05-26 05:46:55.773332 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-05-26 05:46:55.778849 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-05-26 05:46:56.420068 | 
2026-05-26 05:46:56.420251 | PLAY [all]
2026-05-26 05:46:56.433193 | 
2026-05-26 05:46:56.433303 | TASK [setup-uv : Extract archive]
2026-05-26 05:46:58.677086 | instance | changed
2026-05-26 05:46:58.683573 | 
2026-05-26 05:46:58.683679 | TASK [setup-uv : Print version]
2026-05-26 05:46:59.039353 | instance | uv 0.8.13
2026-05-26 05:46:59.222163 | instance | ok: Runtime: 0:00:00.012257
2026-05-26 05:46:59.233564 | 
2026-05-26 05:46:59.233633 | TASK [Install Ansible collection siblings]
2026-05-26 05:46:59.840632 | instance | ERROR
2026-05-26 05:46:59.840914 | instance | {
2026-05-26 05:46:59.840947 | instance |   "log": [
2026-05-26 05:46:59.840997 | instance |     "Sibling collection ansible.netcommon at /home/zuul/src/github.com/ansible-collections/ansible.netcommon",
2026-05-26 05:46:59.841018 | instance |     "Sibling collection ansible.posix at /home/zuul/src/github.com/ansible-collections/ansible.posix",
2026-05-26 05:46:59.841037 | instance |     "Sibling collection ansible.utils at /home/zuul/src/github.com/ansible-collections/ansible.utils",
2026-05-26 05:46:59.841060 | instance |     "Sibling collection community.crypto at /home/zuul/src/github.com/ansible-collections/community.crypto",
2026-05-26 05:46:59.841079 | instance |     "Sibling collection community.general at /home/zuul/src/github.com/ansible-collections/community.general",
2026-05-26 05:46:59.841099 | instance |     "Sibling collection community.mysql at /home/zuul/src/github.com/ansible-collections/community.mysql",
2026-05-26 05:46:59.841117 | instance |     "Sibling collection kubernetes.core at /home/zuul/src/github.com/ansible-collections/kubernetes.core",
2026-05-26 05:46:59.841135 | instance |     "Sibling collection vexxhost.ceph at /home/zuul/src/github.com/vexxhost/ansible-collection-ceph",
2026-05-26 05:46:59.841153 | instance |     "Sibling collection vexxhost.containers at /home/zuul/src/github.com/vexxhost/ansible-collection-containers",
2026-05-26 05:46:59.841170 | instance |     "Sibling collection vexxhost.kubernetes at /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes",
2026-05-26 05:46:59.841187 | instance |     "Sibling collection atmosphere.common at /home/zuul/src/github.com/vexxhost/atmosphere.common",
2026-05-26 05:46:59.841204 | instance |     "Sibling collection openstack.cloud at /home/zuul/src/opendev.org/openstack/ansible-collections-openstack",
2026-05-26 05:46:59.841221 | instance |     "Resolved ansible.netcommon to v8.5.2 for >=1.2.0",
2026-05-26 05:46:59.841239 | instance |     "Resolved ansible.posix to 1.6.0 for 1.6.0",
2026-05-26 05:46:59.841257 | instance |     "Resolved ansible.utils to v6.0.2 for >=2.9.0,>=3.0.0",
2026-05-26 05:46:59.841274 | instance |     "Resolved community.crypto to 2.2.3 for 2.2.3",
2026-05-26 05:46:59.841291 | instance |     "Resolved community.general to 7.3.0 for 7.3.0",
2026-05-26 05:46:59.841309 | instance |     "Resolved community.mysql to 3.6.0 for 3.6.0",
2026-05-26 05:46:59.841326 | instance |     "Resolved kubernetes.core to 2.4.0 for 2.4.0",
2026-05-26 05:46:59.841343 | instance |     "Resolved openstack.cloud to 1.7.0 for 1.7.0",
2026-05-26 05:46:59.841362 | instance |     "Resolved atmosphere.common to v0.7.0 for >=0.6.0",
2026-05-26 05:46:59.841391 | instance |     "Resolved vexxhost.ceph to v4.0.0 for >=3.1.2",
2026-05-26 05:46:59.841409 | instance |     "Resolved vexxhost.kubernetes to v3.1.0 for >=2.3.2,>=2.5.0",
2026-05-26 05:46:59.841426 | instance |     "Resolved ansible.utils to v6.0.2 for >=2.9.0,>=3.0.0,>=6.0.0",
2026-05-26 05:46:59.841443 | instance |     "Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2"
2026-05-26 05:46:59.841460 | instance |   ],
2026-05-26 05:46:59.841477 | instance |   "msg": "No local release tag for openstack.cloud satisfies 1.7.0,>=2.0.0"
2026-05-26 05:46:59.841495 | instance | }
failure
2026-05-26 05:46:59.844649 | 
2026-05-26 05:46:59.844698 | PLAY RECAP
2026-05-26 05:46:59.844745 | instance | ok: 2 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-05-26 05:46:59.844766 | 
2026-05-26 05:46:59.973802 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-05-26 05:46:59.980533 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-05-26 05:47:00.620243 | 
2026-05-26 05:47:00.620403 | PLAY [all]
2026-05-26 05:47:00.632204 | 
2026-05-26 05:47:00.632313 | TASK [fetch-output : Set log path for multiple nodes]
2026-05-26 05:47:00.681209 | instance | skipping: Conditional result was False
2026-05-26 05:47:00.690310 | 
2026-05-26 05:47:00.690428 | TASK [fetch-output : Set log path for single node]
2026-05-26 05:47:00.733267 | instance | ok
2026-05-26 05:47:00.739974 | 
2026-05-26 05:47:00.740052 | LOOP [fetch-output : Ensure local output dirs]
2026-05-26 05:47:01.149672 | instance -> localhost | ok: "/var/lib/zuul/builds/dc637db5a91149d58d0d91b8ce854ee3/work/logs"
2026-05-26 05:47:01.360057 | instance -> localhost | changed: "/var/lib/zuul/builds/dc637db5a91149d58d0d91b8ce854ee3/work/artifacts"
2026-05-26 05:47:01.570328 | instance -> localhost | changed: "/var/lib/zuul/builds/dc637db5a91149d58d0d91b8ce854ee3/work/docs"
2026-05-26 05:47:01.591262 | 
2026-05-26 05:47:01.591423 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-05-26 05:47:02.200533 | instance | ok
2026-05-26 05:47:02.200808 | instance | changed: All items complete
2026-05-26 05:47:02.200844 | 
2026-05-26 05:47:02.652590 | instance | changed: .d..t...... ./
2026-05-26 05:47:03.119228 | instance | changed: .d..t...... ./
2026-05-26 05:47:03.132936 | 
2026-05-26 05:47:03.133097 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-05-26 05:47:03.558146 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.008909
2026-05-26 05:47:03.777125 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.006006
2026-05-26 05:47:03.797325 | 
2026-05-26 05:47:03.797448 | PLAY [all]
2026-05-26 05:47:03.803845 | 
2026-05-26 05:47:03.803911 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-05-26 05:47:04.254257 | instance | changed
2026-05-26 05:47:04.262586 | 
2026-05-26 05:47:04.262636 | PLAY RECAP
2026-05-26 05:47:04.262680 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-05-26 05:47:04.262702 | 
2026-05-26 05:47:04.376252 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-05-26 05:47:04.379783 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-05-26 05:47:04.949910 | 
2026-05-26 05:47:04.950346 | PLAY [localhost]
2026-05-26 05:47:04.960614 | 
2026-05-26 05:47:04.960691 | TASK [Generate Zuul manifest]
2026-05-26 05:47:04.982371 | localhost | ok
2026-05-26 05:47:04.996596 | 
2026-05-26 05:47:04.996679 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-05-26 05:47:05.364775 | localhost | changed
2026-05-26 05:47:05.382971 | 
2026-05-26 05:47:05.383167 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-05-26 05:47:05.426537 | localhost | ok
2026-05-26 05:47:05.435497 | 
2026-05-26 05:47:05.435589 | TASK [Upload logs]
2026-05-26 05:47:05.468213 | localhost | ok
2026-05-26 05:47:05.540773 | 
2026-05-26 05:47:05.540938 | TASK [Set zuul-log-path fact]
2026-05-26 05:47:05.562818 | localhost | ok
2026-05-26 05:47:05.576503 | 
2026-05-26 05:47:05.576657 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-05-26 05:47:05.609298 | localhost | ok
2026-05-26 05:47:05.616486 | 
2026-05-26 05:47:05.616650 | TASK [upload-logs : Create log directories]
2026-05-26 05:47:06.018336 | localhost | changed
2026-05-26 05:47:06.055563 | 
2026-05-26 05:47:06.055692 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-05-26 05:47:06.454523 | localhost -> localhost | ok: Runtime: 0:00:00.006004
2026-05-26 05:47:06.459889 | 
2026-05-26 05:47:06.459992 | TASK [upload-logs : Upload logs to log server]
2026-05-26 05:47:06.901804 | localhost | Output suppressed because no_log was given
2026-05-26 05:47:06.906191 | 
2026-05-26 05:47:06.906257 | LOOP [upload-logs : Compress console log and json output]
2026-05-26 05:47:06.956757 | localhost | skipping: Conditional result was False
2026-05-26 05:47:06.965123 | localhost | skipping: Conditional result was False
2026-05-26 05:47:06.974617 | 
2026-05-26 05:47:06.974803 | LOOP [upload-logs : Upload compressed console log and json output]
2026-05-26 05:47:07.020549 | localhost | skipping: Conditional result was False
2026-05-26 05:47:07.020952 | 
2026-05-26 05:47:07.024896 | localhost | skipping: Conditional result was False
2026-05-26 05:47:07.040946 | 
2026-05-26 05:47:07.041224 | LOOP [upload-logs : Upload console log and json output]