2026-06-16 05:00:54.430418 | Job console starting 2026-06-16 05:00:54.443200 | Updating git repos 2026-06-16 05:00:56.141243 | Cloning repos into workspace 2026-06-16 05:01:08.979332 | Restoring repo states 2026-06-16 05:01:15.165288 | Merging changes 2026-06-16 05:01:16.393730 | Checking out repos 2026-06-16 05:01:18.141409 | Preparing playbooks 2026-06-16 05:01:20.271568 | Running Ansible setup 2026-06-16 05:01:24.119914 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-06-16 05:01:24.748056 | 2026-06-16 05:01:24.748184 | PLAY [localhost] 2026-06-16 05:01:24.755805 | 2026-06-16 05:01:24.755877 | TASK [Gathering Facts] 2026-06-16 05:01:25.661701 | localhost | ok 2026-06-16 05:01:25.669753 | 2026-06-16 05:01:25.669890 | TASK [Setup log path fact] 2026-06-16 05:01:25.689334 | localhost | ok 2026-06-16 05:01:25.703540 | 2026-06-16 05:01:25.703646 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-06-16 05:01:25.731135 | localhost | ok 2026-06-16 05:01:25.740573 | 2026-06-16 05:01:25.740787 | TASK [emit-job-header : Print job information] 2026-06-16 05:01:25.784578 | # Job Information 2026-06-16 05:01:25.784754 | Ansible Version: 2.16.18 2026-06-16 05:01:25.784803 | Job: atmosphere-molecule-keycloak 2026-06-16 05:01:25.784838 | Pipeline: check 2026-06-16 05:01:25.784868 | Executor: 2d72f0692154 2026-06-16 05:01:25.784898 | Triggered by: https://github.com/vexxhost/atmosphere/pull/3985 2026-06-16 05:01:25.784932 | Event ID: b82f26b0-693f-11f1-9ac8-4f79ff633e65 2026-06-16 05:01:25.789390 | 2026-06-16 05:01:25.789487 | LOOP [emit-job-header : Print node information] 2026-06-16 05:01:26.005071 | localhost | ok: 2026-06-16 05:01:26.005274 | localhost | # Node Information 2026-06-16 05:01:26.005318 | localhost | Inventory Hostname: instance 2026-06-16 05:01:26.005359 | localhost | Hostname: np0000180712 2026-06-16 05:01:26.005395 | localhost | Username: zuul 2026-06-16 05:01:26.005430 | localhost | Distro: Ubuntu 22.04 2026-06-16 05:01:26.005460 | localhost | Provider: yul1 2026-06-16 05:01:26.005488 | localhost | Region: ca-ymq-1 2026-06-16 05:01:26.005516 | localhost | Label: ubuntu-jammy 2026-06-16 05:01:26.005545 | localhost | Product Name: OpenStack Nova 2026-06-16 05:01:26.005573 | localhost | Interface IP: 199.19.213.201 2026-06-16 05:01:26.029315 | 2026-06-16 05:01:26.029475 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-06-16 05:01:26.398845 | localhost -> localhost | changed 2026-06-16 05:01:26.407864 | 2026-06-16 05:01:26.407931 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-06-16 05:01:27.275709 | localhost -> localhost | changed 2026-06-16 05:01:27.285301 | 2026-06-16 05:01:27.285390 | PLAY [all] 2026-06-16 05:01:27.293975 | 2026-06-16 05:01:27.294043 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-06-16 05:01:27.522096 | instance -> localhost | ok 2026-06-16 05:01:27.530046 | 2026-06-16 05:01:27.530137 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-06-16 05:01:27.564070 | instance | ok 2026-06-16 05:01:27.582914 | instance | included: /var/lib/zuul/builds/e68517ceb5ea4074be0e81eaf70066c2/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-06-16 05:01:27.589994 | 2026-06-16 05:01:27.590066 | TASK [add-build-sshkey : Create Temp SSH key] 2026-06-16 05:01:28.513783 | instance -> localhost | Generating public/private rsa key pair. 2026-06-16 05:01:28.514046 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/e68517ceb5ea4074be0e81eaf70066c2/work/e68517ceb5ea4074be0e81eaf70066c2_id_rsa 2026-06-16 05:01:28.514112 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/e68517ceb5ea4074be0e81eaf70066c2/work/e68517ceb5ea4074be0e81eaf70066c2_id_rsa.pub 2026-06-16 05:01:28.514162 | instance -> localhost | The key fingerprint is: 2026-06-16 05:01:28.514210 | instance -> localhost | SHA256:ObZzB5G6ZTGHgr6Xp401zNu2vXMN0bMmTwCygR2VPPo zuul-build-sshkey 2026-06-16 05:01:28.514275 | instance -> localhost | The key's randomart image is: 2026-06-16 05:01:28.514325 | instance -> localhost | +---[RSA 3072]----+ 2026-06-16 05:01:28.514381 | instance -> localhost | | o.+.. | 2026-06-16 05:01:28.514430 | instance -> localhost | | o + B | 2026-06-16 05:01:28.514474 | instance -> localhost | | . . @ + . | 2026-06-16 05:01:28.514519 | instance -> localhost | | . * = ....| 2026-06-16 05:01:28.514565 | instance -> localhost | | . S = ..o| 2026-06-16 05:01:28.514609 | instance -> localhost | | o X E ..+ | 2026-06-16 05:01:28.514653 | instance -> localhost | | . * B . =..| 2026-06-16 05:01:28.514698 | instance -> localhost | | . O =....o| 2026-06-16 05:01:28.514746 | instance -> localhost | | o o.o.o+ | 2026-06-16 05:01:28.514792 | instance -> localhost | +----[SHA256]-----+ 2026-06-16 05:01:28.514888 | instance -> localhost | ok: Runtime: 0:00:00.498781 2026-06-16 05:01:28.521142 | 2026-06-16 05:01:28.521211 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-06-16 05:01:28.553154 | instance | ok 2026-06-16 05:01:28.563775 | instance | included: /var/lib/zuul/builds/e68517ceb5ea4074be0e81eaf70066c2/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-06-16 05:01:28.571748 | 2026-06-16 05:01:28.571807 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-06-16 05:01:28.596664 | instance | skipping: Conditional result was False 2026-06-16 05:01:28.607084 | 2026-06-16 05:01:28.607155 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-06-16 05:01:29.031976 | instance | changed 2026-06-16 05:01:29.036975 | 2026-06-16 05:01:29.037039 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-06-16 05:01:29.212803 | instance | ok 2026-06-16 05:01:29.219426 | 2026-06-16 05:01:29.219495 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-06-16 05:01:29.712651 | instance | changed 2026-06-16 05:01:29.720751 | 2026-06-16 05:01:29.720817 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-06-16 05:01:30.190460 | instance | changed 2026-06-16 05:01:30.749475 | 2026-06-16 05:01:30.749610 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-06-16 05:01:30.774793 | instance | skipping: Conditional result was False 2026-06-16 05:01:30.785141 | 2026-06-16 05:01:30.785228 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-06-16 05:01:31.174946 | instance -> localhost | changed 2026-06-16 05:01:31.192890 | 2026-06-16 05:01:31.193013 | TASK [add-build-sshkey : Add back temp key] 2026-06-16 05:01:31.501684 | instance -> localhost | Identity added: /var/lib/zuul/builds/e68517ceb5ea4074be0e81eaf70066c2/work/e68517ceb5ea4074be0e81eaf70066c2_id_rsa (zuul-build-sshkey) 2026-06-16 05:01:31.502004 | instance -> localhost | ok: Runtime: 0:00:00.014021 2026-06-16 05:01:31.509590 | 2026-06-16 05:01:31.509663 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-06-16 05:01:31.803065 | instance | ok 2026-06-16 05:01:31.809764 | 2026-06-16 05:01:31.809831 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-06-16 05:01:31.838354 | instance | skipping: Conditional result was False 2026-06-16 05:01:31.897424 | 2026-06-16 05:01:32.115935 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-06-16 05:01:32.438484 | instance | ok 2026-06-16 05:01:32.535037 | 2026-06-16 05:01:32.535209 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-06-16 05:01:36.001098 | instance | Output suppressed because no_log was given 2026-06-16 05:01:36.010103 | 2026-06-16 05:01:36.010168 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-06-16 05:01:36.207845 | instance | ok: "logs" 2026-06-16 05:01:36.208121 | instance | ok: All items complete 2026-06-16 05:01:36.208150 | 2026-06-16 05:01:36.365074 | instance | ok: "artifacts" 2026-06-16 05:01:36.523892 | instance | ok: "docs" 2026-06-16 05:01:36.543632 | 2026-06-16 05:01:36.544194 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-06-16 05:01:36.786745 | instance | changed: "logs" 2026-06-16 05:01:36.942405 | instance | changed: "artifacts" 2026-06-16 05:01:37.103667 | instance | changed: "docs" 2026-06-16 05:01:37.114420 | 2026-06-16 05:01:37.114520 | PLAY RECAP 2026-06-16 05:01:37.114565 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-06-16 05:01:37.114595 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-06-16 05:01:37.114617 | 2026-06-16 05:01:37.236671 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-06-16 05:01:37.239685 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-06-16 05:01:37.854617 | 2026-06-16 05:01:37.854734 | PLAY [all] 2026-06-16 05:01:37.865571 | 2026-06-16 05:01:37.865647 | TASK [setup-uv : Extract archive] 2026-06-16 05:01:40.229922 | instance | changed 2026-06-16 05:01:40.236200 | 2026-06-16 05:01:40.236278 | TASK [setup-uv : Print version] 2026-06-16 05:01:40.574478 | instance | uv 0.8.13 2026-06-16 05:01:40.805395 | instance | ok: Runtime: 0:00:00.012441 2026-06-16 05:01:40.825544 | 2026-06-16 05:01:40.825698 | TASK [Install Ansible collection siblings] 2026-06-16 05:01:41.498142 | instance | ERROR 2026-06-16 05:01:41.775328 | instance | { 2026-06-16 05:01:41.775499 | instance | "log": [ 2026-06-16 05:01:41.775553 | instance | "Sibling collection ansible.netcommon at /home/zuul/src/github.com/ansible-collections/ansible.netcommon", 2026-06-16 05:01:41.775598 | instance | "Sibling collection ansible.posix at /home/zuul/src/github.com/ansible-collections/ansible.posix", 2026-06-16 05:01:41.775640 | instance | "Sibling collection ansible.utils at /home/zuul/src/github.com/ansible-collections/ansible.utils", 2026-06-16 05:01:41.775690 | instance | "Sibling collection community.crypto at /home/zuul/src/github.com/ansible-collections/community.crypto", 2026-06-16 05:01:41.775732 | instance | "Sibling collection community.general at /home/zuul/src/github.com/ansible-collections/community.general", 2026-06-16 05:01:41.775777 | instance | "Sibling collection community.mysql at /home/zuul/src/github.com/ansible-collections/community.mysql", 2026-06-16 05:01:41.775817 | instance | "Sibling collection kubernetes.core at /home/zuul/src/github.com/ansible-collections/kubernetes.core", 2026-06-16 05:01:41.775856 | instance | "Sibling collection vexxhost.ceph at /home/zuul/src/github.com/vexxhost/ansible-collection-ceph", 2026-06-16 05:01:41.775894 | instance | "Sibling collection vexxhost.containers at /home/zuul/src/github.com/vexxhost/ansible-collection-containers", 2026-06-16 05:01:41.775931 | instance | "Sibling collection vexxhost.kubernetes at /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes", 2026-06-16 05:01:41.775967 | instance | "Sibling collection atmosphere.common at /home/zuul/src/github.com/vexxhost/atmosphere.common", 2026-06-16 05:01:41.776003 | instance | "Sibling collection openstack.cloud at /home/zuul/src/opendev.org/openstack/ansible-collections-openstack", 2026-06-16 05:01:41.776040 | instance | "Resolved ansible.netcommon to v8.5.2 for >=1.2.0", 2026-06-16 05:01:41.776080 | instance | "Resolved ansible.posix to 1.6.0 for 1.6.0", 2026-06-16 05:01:41.776119 | instance | "Resolved ansible.utils to v6.0.2 for >=2.9.0,>=3.0.0", 2026-06-16 05:01:41.776157 | instance | "Resolved community.crypto to 2.2.3 for 2.2.3", 2026-06-16 05:01:41.776195 | instance | "Resolved community.general to 7.3.0 for 7.3.0", 2026-06-16 05:01:41.776232 | instance | "Resolved community.mysql to 3.6.0 for 3.6.0", 2026-06-16 05:01:41.776268 | instance | "Resolved kubernetes.core to 2.4.0 for 2.4.0", 2026-06-16 05:01:41.776305 | instance | "Resolved openstack.cloud to 1.7.0 for 1.7.0", 2026-06-16 05:01:41.776345 | instance | "Resolved atmosphere.common to v0.7.0 for >=0.6.0", 2026-06-16 05:01:41.776400 | instance | "Resolved vexxhost.ceph to v4.0.0 for >=3.1.2", 2026-06-16 05:01:41.776440 | instance | "Resolved vexxhost.kubernetes to v3.2.0 for >=3.2.0,>=2.5.0", 2026-06-16 05:01:41.776477 | instance | "Resolved ansible.utils to v6.0.2 for >=2.9.0,>=3.0.0,>=6.0.0", 2026-06-16 05:01:41.776544 | instance | "Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2" 2026-06-16 05:01:41.776582 | instance | ], 2026-06-16 05:01:41.776619 | instance | "msg": "No local release tag for openstack.cloud satisfies 1.7.0,>=2.0.0" 2026-06-16 05:01:41.776657 | instance | } failure 2026-06-16 05:01:41.782576 | 2026-06-16 05:01:41.782634 | PLAY RECAP 2026-06-16 05:01:41.782693 | instance | ok: 2 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2026-06-16 05:01:41.782722 | 2026-06-16 05:01:41.959052 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-06-16 05:01:41.963064 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-06-16 05:01:42.538168 | 2026-06-16 05:01:42.538281 | PLAY [all] 2026-06-16 05:01:42.549020 | 2026-06-16 05:01:42.549097 | TASK [fetch-output : Set log path for multiple nodes] 2026-06-16 05:01:42.595226 | instance | skipping: Conditional result was False 2026-06-16 05:01:42.605484 | 2026-06-16 05:01:42.605643 | TASK [fetch-output : Set log path for single node] 2026-06-16 05:01:42.650116 | instance | ok 2026-06-16 05:01:42.657887 | 2026-06-16 05:01:42.657955 | LOOP [fetch-output : Ensure local output dirs] 2026-06-16 05:01:43.044101 | instance -> localhost | ok: "/var/lib/zuul/builds/e68517ceb5ea4074be0e81eaf70066c2/work/logs" 2026-06-16 05:01:43.243192 | instance -> localhost | changed: "/var/lib/zuul/builds/e68517ceb5ea4074be0e81eaf70066c2/work/artifacts" 2026-06-16 05:01:43.453462 | instance -> localhost | changed: "/var/lib/zuul/builds/e68517ceb5ea4074be0e81eaf70066c2/work/docs" 2026-06-16 05:01:43.469036 | 2026-06-16 05:01:43.469132 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-06-16 05:01:44.105463 | instance | changed: .d..t...... ./ 2026-06-16 05:01:44.105719 | instance | changed: All items complete 2026-06-16 05:01:44.105758 | 2026-06-16 05:01:44.562869 | instance | changed: .d..t...... ./ 2026-06-16 05:01:44.997081 | instance | changed: .d..t...... ./ 2026-06-16 05:01:45.015206 | 2026-06-16 05:01:45.015307 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-06-16 05:01:45.475953 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.009345 2026-06-16 05:01:45.704538 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.005848 2026-06-16 05:01:45.726824 | 2026-06-16 05:01:45.727009 | PLAY [all] 2026-06-16 05:01:45.734022 | 2026-06-16 05:01:45.734090 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-06-16 05:01:46.131182 | instance | changed 2026-06-16 05:01:46.138604 | 2026-06-16 05:01:46.138659 | PLAY RECAP 2026-06-16 05:01:46.138707 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-06-16 05:01:46.138728 | 2026-06-16 05:01:46.323444 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-06-16 05:01:46.328122 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-06-16 05:01:46.943116 | 2026-06-16 05:01:46.943297 | PLAY [localhost] 2026-06-16 05:01:46.954193 | 2026-06-16 05:01:46.954282 | TASK [Generate Zuul manifest] 2026-06-16 05:01:46.975993 | localhost | ok 2026-06-16 05:01:46.993802 | 2026-06-16 05:01:46.993929 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-06-16 05:01:47.603022 | localhost | changed 2026-06-16 05:01:47.617172 | 2026-06-16 05:01:47.617246 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-06-16 05:01:47.689102 | localhost | ok 2026-06-16 05:01:47.699131 | 2026-06-16 05:01:47.699206 | TASK [Upload logs] 2026-06-16 05:01:47.720120 | localhost | ok 2026-06-16 05:01:47.784857 | 2026-06-16 05:01:47.785031 | TASK [Set zuul-log-path fact] 2026-06-16 05:01:47.807122 | localhost | ok 2026-06-16 05:01:47.822622 | 2026-06-16 05:01:47.822737 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-06-16 05:01:47.855192 | localhost | ok 2026-06-16 05:01:47.864885 | 2026-06-16 05:01:47.865004 | TASK [upload-logs : Create log directories] 2026-06-16 05:01:48.291667 | localhost | changed 2026-06-16 05:01:48.329564 | 2026-06-16 05:01:48.329691 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-06-16 05:01:48.686665 | localhost -> localhost | ok: Runtime: 0:00:00.005324 2026-06-16 05:01:48.692803 | 2026-06-16 05:01:48.692866 | TASK [upload-logs : Upload logs to log server] 2026-06-16 05:01:49.124900 | localhost | Output suppressed because no_log was given 2026-06-16 05:01:49.130653 | 2026-06-16 05:01:49.130748 | LOOP [upload-logs : Compress console log and json output] 2026-06-16 05:01:49.183507 | localhost | skipping: Conditional result was False 2026-06-16 05:01:49.192774 | localhost | skipping: Conditional result was False 2026-06-16 05:01:49.206589 | 2026-06-16 05:01:49.207199 | LOOP [upload-logs : Upload compressed console log and json output] 2026-06-16 05:01:49.255090 | localhost | skipping: Conditional result was False 2026-06-16 05:01:49.255786 | 2026-06-16 05:01:49.259700 | localhost | skipping: Conditional result was False 2026-06-16 05:01:49.274624 | 2026-06-16 05:01:49.274809 | LOOP [upload-logs : Upload console log and json output]