2026-06-16 04:59:30.693231 | Job console starting
2026-06-16 04:59:30.705571 | Updating git repos
2026-06-16 04:59:31.524976 | Cloning repos into workspace
2026-06-16 04:59:44.866114 | Restoring repo states
2026-06-16 04:59:50.764802 | Merging changes
2026-06-16 04:59:51.647357 | Checking out repos
2026-06-16 04:59:53.378220 | Preparing playbooks
2026-06-16 04:59:55.597534 | Running Ansible setup
2026-06-16 04:59:59.262052 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-06-16 04:59:59.852368 | 
2026-06-16 04:59:59.852496 | PLAY [localhost]
2026-06-16 04:59:59.859769 | 
2026-06-16 04:59:59.859845 | TASK [Gathering Facts]
2026-06-16 05:00:00.717351 | localhost | ok
2026-06-16 05:00:00.725345 | 
2026-06-16 05:00:00.725426 | TASK [Setup log path fact]
2026-06-16 05:00:00.746489 | localhost | ok
2026-06-16 05:00:00.759826 | 
2026-06-16 05:00:00.759890 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-06-16 05:00:00.786530 | localhost | ok
2026-06-16 05:00:00.792986 | 
2026-06-16 05:00:00.793057 | TASK [emit-job-header : Print job information]
2026-06-16 05:00:00.835525 | # Job Information
2026-06-16 05:00:00.835684 | Ansible Version: 2.16.18
2026-06-16 05:00:00.835729 | Job: atmosphere-molecule-keycloak
2026-06-16 05:00:00.835759 | Pipeline: check
2026-06-16 05:00:00.835786 | Executor: 2d72f0692154
2026-06-16 05:00:00.835812 | Triggered by: https://github.com/vexxhost/atmosphere/pull/3991
2026-06-16 05:00:00.835843 | Event ID: ca1dc200-693f-11f1-8a60-6d91f1507d9b
2026-06-16 05:00:00.839498 | 
2026-06-16 05:00:00.839587 | LOOP [emit-job-header : Print node information]
2026-06-16 05:00:00.935728 | localhost | ok:
2026-06-16 05:00:00.936276 | localhost | # Node Information
2026-06-16 05:00:00.936344 | localhost | Inventory Hostname: instance
2026-06-16 05:00:00.936392 | localhost | Hostname: np0000180705
2026-06-16 05:00:00.936437 | localhost | Username: zuul
2026-06-16 05:00:00.936487 | localhost | Distro: Ubuntu 22.04
2026-06-16 05:00:00.936531 | localhost | Provider: yul1
2026-06-16 05:00:00.936574 | localhost | Region: ca-ymq-1
2026-06-16 05:00:00.936616 | localhost | Label: ubuntu-jammy
2026-06-16 05:00:00.936658 | localhost | Product Name: OpenStack Nova
2026-06-16 05:00:00.936699 | localhost | Interface IP: 199.19.213.67
2026-06-16 05:00:00.955984 | 
2026-06-16 05:00:00.956089 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-06-16 05:00:01.365433 | localhost -> localhost | changed
2026-06-16 05:00:01.375491 | 
2026-06-16 05:00:01.375566 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-06-16 05:00:02.356461 | localhost -> localhost | changed
2026-06-16 05:00:02.362933 | 
2026-06-16 05:00:02.362987 | PLAY [all]
2026-06-16 05:00:02.370515 | 
2026-06-16 05:00:02.370580 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-06-16 05:00:02.596820 | instance -> localhost | ok
2026-06-16 05:00:02.606562 | 
2026-06-16 05:00:02.606706 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-06-16 05:00:02.639469 | instance | ok
2026-06-16 05:00:02.657011 | instance | included: /var/lib/zuul/builds/55dbb2e071d6417683e26be792acd08d/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-06-16 05:00:02.662925 | 
2026-06-16 05:00:02.663008 | TASK [add-build-sshkey : Create Temp SSH key]
2026-06-16 05:00:03.383188 | instance -> localhost | Generating public/private rsa key pair.
2026-06-16 05:00:03.383459 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/55dbb2e071d6417683e26be792acd08d/work/55dbb2e071d6417683e26be792acd08d_id_rsa
2026-06-16 05:00:03.383524 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/55dbb2e071d6417683e26be792acd08d/work/55dbb2e071d6417683e26be792acd08d_id_rsa.pub
2026-06-16 05:00:03.383575 | instance -> localhost | The key fingerprint is:
2026-06-16 05:00:03.383623 | instance -> localhost | SHA256:47LtktV3EZYyIq+HIy/tkvq7sICDVXwHu8N+evkU9Qg zuul-build-sshkey
2026-06-16 05:00:03.383686 | instance -> localhost | The key's randomart image is:
2026-06-16 05:00:03.383734 | instance -> localhost | +---[RSA 3072]----+
2026-06-16 05:00:03.383790 | instance -> localhost | |      .        . |
2026-06-16 05:00:03.383838 | instance -> localhost | |   .   o. . o +  |
2026-06-16 05:00:03.383883 | instance -> localhost | |    o o .oE..+ . |
2026-06-16 05:00:03.383927 | instance -> localhost | |   . o o  .o o.  |
2026-06-16 05:00:03.383970 | instance -> localhost | |  .   + S+. . .. |
2026-06-16 05:00:03.384014 | instance -> localhost | |.o   ..o=.o.. .  |
2026-06-16 05:00:03.384057 | instance -> localhost | |+ . . oBo+.. .   |
2026-06-16 05:00:03.384100 | instance -> localhost | | . . o**=.       |
2026-06-16 05:00:03.384149 | instance -> localhost | |    oo*X+..      |
2026-06-16 05:00:03.384194 | instance -> localhost | +----[SHA256]-----+
2026-06-16 05:00:03.384291 | instance -> localhost | ok: Runtime: 0:00:00.308542
2026-06-16 05:00:03.391278 | 
2026-06-16 05:00:03.391414 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-06-16 05:00:03.423550 | instance | ok
2026-06-16 05:00:03.433812 | instance | included: /var/lib/zuul/builds/55dbb2e071d6417683e26be792acd08d/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-06-16 05:00:03.441814 | 
2026-06-16 05:00:03.441875 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-06-16 05:00:03.465378 | instance | skipping: Conditional result was False
2026-06-16 05:00:03.475618 | 
2026-06-16 05:00:03.475736 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-06-16 05:00:03.891318 | instance | changed
2026-06-16 05:00:03.898456 | 
2026-06-16 05:00:03.898551 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-06-16 05:00:04.086449 | instance | ok
2026-06-16 05:00:04.091808 | 
2026-06-16 05:00:04.091897 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-06-16 05:00:04.531803 | instance | changed
2026-06-16 05:00:04.538975 | 
2026-06-16 05:00:04.539110 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-06-16 05:00:04.960020 | instance | changed
2026-06-16 05:00:04.967301 | 
2026-06-16 05:00:04.967410 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-06-16 05:00:04.993873 | instance | skipping: Conditional result was False
2026-06-16 05:00:05.001020 | 
2026-06-16 05:00:05.001098 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-06-16 05:00:05.627934 | instance -> localhost | changed
2026-06-16 05:00:05.645935 | 
2026-06-16 05:00:05.646063 | TASK [add-build-sshkey : Add back temp key]
2026-06-16 05:00:05.924175 | instance -> localhost | Identity added: /var/lib/zuul/builds/55dbb2e071d6417683e26be792acd08d/work/55dbb2e071d6417683e26be792acd08d_id_rsa (zuul-build-sshkey)
2026-06-16 05:00:05.924481 | instance -> localhost | ok: Runtime: 0:00:00.012939
2026-06-16 05:00:05.932334 | 
2026-06-16 05:00:05.932402 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-06-16 05:00:06.236935 | instance | ok
2026-06-16 05:00:06.243347 | 
2026-06-16 05:00:06.244178 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-06-16 05:00:06.269177 | instance | skipping: Conditional result was False
2026-06-16 05:00:06.343327 | 
2026-06-16 05:00:06.343656 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-06-16 05:00:06.619700 | instance | ok
2026-06-16 05:00:06.717168 | 
2026-06-16 05:00:06.717266 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-06-16 05:00:10.068917 | instance | Output suppressed because no_log was given
2026-06-16 05:00:10.083198 | 
2026-06-16 05:00:10.083263 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-06-16 05:00:10.291629 | instance | ok: "logs"
2026-06-16 05:00:10.291908 | instance | ok: All items complete
2026-06-16 05:00:10.291941 | 
2026-06-16 05:00:10.439984 | instance | ok: "artifacts"
2026-06-16 05:00:10.598124 | instance | ok: "docs"
2026-06-16 05:00:10.612486 | 
2026-06-16 05:00:10.612713 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-06-16 05:00:10.832377 | instance | changed: "logs"
2026-06-16 05:00:11.022549 | instance | changed: "artifacts"
2026-06-16 05:00:11.174445 | instance | changed: "docs"
2026-06-16 05:00:11.191051 | 
2026-06-16 05:00:11.191168 | PLAY RECAP
2026-06-16 05:00:11.191214 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-06-16 05:00:11.191242 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-06-16 05:00:11.191266 | 
2026-06-16 05:00:11.309753 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-06-16 05:00:11.314757 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-06-16 05:00:11.922374 | 
2026-06-16 05:00:11.922578 | PLAY [all]
2026-06-16 05:00:11.933800 | 
2026-06-16 05:00:11.933884 | TASK [setup-uv : Extract archive]
2026-06-16 05:00:14.151568 | instance | changed
2026-06-16 05:00:14.162234 | 
2026-06-16 05:00:14.162313 | TASK [setup-uv : Print version]
2026-06-16 05:00:14.569139 | instance | uv 0.8.13
2026-06-16 05:00:14.699071 | instance | ok: Runtime: 0:00:00.015906
2026-06-16 05:00:14.709407 | 
2026-06-16 05:00:14.709533 | TASK [Install Ansible collection siblings]
2026-06-16 05:00:15.342857 | instance | ERROR
2026-06-16 05:00:15.343255 | instance | {
2026-06-16 05:00:15.343326 | instance |   "log": [
2026-06-16 05:00:15.343375 | instance |     "Sibling collection ansible.netcommon at /home/zuul/src/github.com/ansible-collections/ansible.netcommon",
2026-06-16 05:00:15.343420 | instance |     "Sibling collection ansible.posix at /home/zuul/src/github.com/ansible-collections/ansible.posix",
2026-06-16 05:00:15.343462 | instance |     "Sibling collection ansible.utils at /home/zuul/src/github.com/ansible-collections/ansible.utils",
2026-06-16 05:00:15.343509 | instance |     "Sibling collection community.crypto at /home/zuul/src/github.com/ansible-collections/community.crypto",
2026-06-16 05:00:15.343551 | instance |     "Sibling collection community.general at /home/zuul/src/github.com/ansible-collections/community.general",
2026-06-16 05:00:15.343599 | instance |     "Sibling collection community.mysql at /home/zuul/src/github.com/ansible-collections/community.mysql",
2026-06-16 05:00:15.343640 | instance |     "Sibling collection kubernetes.core at /home/zuul/src/github.com/ansible-collections/kubernetes.core",
2026-06-16 05:00:15.343680 | instance |     "Sibling collection vexxhost.ceph at /home/zuul/src/github.com/vexxhost/ansible-collection-ceph",
2026-06-16 05:00:15.343720 | instance |     "Sibling collection vexxhost.containers at /home/zuul/src/github.com/vexxhost/ansible-collection-containers",
2026-06-16 05:00:15.343760 | instance |     "Sibling collection vexxhost.kubernetes at /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes",
2026-06-16 05:00:15.343799 | instance |     "Sibling collection atmosphere.common at /home/zuul/src/github.com/vexxhost/atmosphere.common",
2026-06-16 05:00:15.343838 | instance |     "Sibling collection openstack.cloud at /home/zuul/src/opendev.org/openstack/ansible-collections-openstack",
2026-06-16 05:00:15.343877 | instance |     "Resolved ansible.netcommon to v8.5.2 for >=1.2.0",
2026-06-16 05:00:15.343919 | instance |     "Resolved ansible.posix to 1.6.0 for 1.6.0",
2026-06-16 05:00:15.343960 | instance |     "Resolved ansible.utils to v6.0.2 for >=2.9.0,>=3.0.0",
2026-06-16 05:00:15.343999 | instance |     "Resolved community.crypto to 2.2.3 for 2.2.3",
2026-06-16 05:00:15.344038 | instance |     "Resolved community.general to 7.3.0 for 7.3.0",
2026-06-16 05:00:15.344078 | instance |     "Resolved community.mysql to 3.6.0 for 3.6.0",
2026-06-16 05:00:15.344118 | instance |     "Resolved kubernetes.core to 2.4.0 for 2.4.0",
2026-06-16 05:00:15.344157 | instance |     "Resolved openstack.cloud to 1.7.0 for 1.7.0",
2026-06-16 05:00:15.344200 | instance |     "Resolved atmosphere.common to v0.7.0 for >=0.6.0",
2026-06-16 05:00:15.344253 | instance |     "Resolved vexxhost.ceph to v4.0.0 for >=3.1.2",
2026-06-16 05:00:15.344294 | instance |     "Resolved vexxhost.kubernetes to v3.2.0 for >=3.2.0,>=2.5.0",
2026-06-16 05:00:15.344334 | instance |     "Resolved ansible.utils to v6.0.2 for >=2.9.0,>=3.0.0,>=6.0.0",
2026-06-16 05:00:15.344373 | instance |     "Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2"
2026-06-16 05:00:15.344412 | instance |   ],
2026-06-16 05:00:15.344451 | instance |   "msg": "No local release tag for openstack.cloud satisfies 1.7.0,>=2.0.0"
2026-06-16 05:00:15.344491 | instance | }
failure
2026-06-16 05:00:15.347956 | 
2026-06-16 05:00:15.348001 | PLAY RECAP
2026-06-16 05:00:15.348043 | instance | ok: 2 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-06-16 05:00:15.348065 | 
2026-06-16 05:00:15.567004 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-06-16 05:00:15.574892 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-06-16 05:00:16.179316 | 
2026-06-16 05:00:16.179465 | PLAY [all]
2026-06-16 05:00:16.191347 | 
2026-06-16 05:00:16.191473 | TASK [fetch-output : Set log path for multiple nodes]
2026-06-16 05:00:16.237369 | instance | skipping: Conditional result was False
2026-06-16 05:00:16.247087 | 
2026-06-16 05:00:16.247252 | TASK [fetch-output : Set log path for single node]
2026-06-16 05:00:16.291697 | instance | ok
2026-06-16 05:00:16.299421 | 
2026-06-16 05:00:16.299561 | LOOP [fetch-output : Ensure local output dirs]
2026-06-16 05:00:16.698616 | instance -> localhost | ok: "/var/lib/zuul/builds/55dbb2e071d6417683e26be792acd08d/work/logs"
2026-06-16 05:00:16.947431 | instance -> localhost | changed: "/var/lib/zuul/builds/55dbb2e071d6417683e26be792acd08d/work/artifacts"
2026-06-16 05:00:17.264115 | instance -> localhost | changed: "/var/lib/zuul/builds/55dbb2e071d6417683e26be792acd08d/work/docs"
2026-06-16 05:00:17.291461 | 
2026-06-16 05:00:17.291594 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-06-16 05:00:17.948408 | instance | changed: .d..t...... ./
2026-06-16 05:00:17.948645 | instance | changed: All items complete
2026-06-16 05:00:17.948686 | 
2026-06-16 05:00:18.411238 | instance | changed: .d..t...... ./
2026-06-16 05:00:18.852904 | instance | changed: .d..t...... ./
2026-06-16 05:00:18.875059 | 
2026-06-16 05:00:18.875200 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-06-16 05:00:19.280414 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.007561
2026-06-16 05:00:19.519059 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.022811
2026-06-16 05:00:19.532113 | 
2026-06-16 05:00:19.532251 | PLAY [all]
2026-06-16 05:00:19.538599 | 
2026-06-16 05:00:19.538664 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-06-16 05:00:19.934194 | instance | changed
2026-06-16 05:00:19.941098 | 
2026-06-16 05:00:19.941206 | PLAY RECAP
2026-06-16 05:00:19.941256 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-06-16 05:00:19.941277 | 
2026-06-16 05:00:20.081593 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-06-16 05:00:20.084278 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-06-16 05:00:20.698096 | 
2026-06-16 05:00:20.698220 | PLAY [localhost]
2026-06-16 05:00:20.708080 | 
2026-06-16 05:00:20.708153 | TASK [Generate Zuul manifest]
2026-06-16 05:00:20.731084 | localhost | ok
2026-06-16 05:00:20.746132 | 
2026-06-16 05:00:20.746207 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-06-16 05:00:21.162666 | localhost | changed
2026-06-16 05:00:21.176037 | 
2026-06-16 05:00:21.176116 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-06-16 05:00:21.205789 | localhost | ok
2026-06-16 05:00:21.213293 | 
2026-06-16 05:00:21.213370 | TASK [Upload logs]
2026-06-16 05:00:21.230472 | localhost | ok
2026-06-16 05:00:21.288027 | 
2026-06-16 05:00:21.288135 | TASK [Set zuul-log-path fact]
2026-06-16 05:00:21.307650 | localhost | ok
2026-06-16 05:00:21.322781 | 
2026-06-16 05:00:21.322851 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-06-16 05:00:21.352596 | localhost | ok
2026-06-16 05:00:21.361929 | 
2026-06-16 05:00:21.362012 | TASK [upload-logs : Create log directories]
2026-06-16 05:00:21.748804 | localhost | changed
2026-06-16 05:00:21.785948 | 
2026-06-16 05:00:21.786082 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-06-16 05:00:22.248904 | localhost -> localhost | ok: Runtime: 0:00:00.006066
2026-06-16 05:00:22.253839 | 
2026-06-16 05:00:22.253906 | TASK [upload-logs : Upload logs to log server]
2026-06-16 05:00:22.698084 | localhost | Output suppressed because no_log was given
2026-06-16 05:00:22.703194 | 
2026-06-16 05:00:22.703285 | LOOP [upload-logs : Compress console log and json output]
2026-06-16 05:00:22.753021 | localhost | skipping: Conditional result was False
2026-06-16 05:00:22.762051 | localhost | skipping: Conditional result was False
2026-06-16 05:00:22.778964 | 
2026-06-16 05:00:22.779153 | LOOP [upload-logs : Upload compressed console log and json output]
2026-06-16 05:00:22.823514 | localhost | skipping: Conditional result was False
2026-06-16 05:00:22.823890 | 
2026-06-16 05:00:22.827810 | localhost | skipping: Conditional result was False
2026-06-16 05:00:22.846012 | 
2026-06-16 05:00:22.846224 | LOOP [upload-logs : Upload console log and json output]