2026-06-12 01:16:00.257956 | Job console starting 2026-06-12 01:16:00.268793 | Updating git repos 2026-06-12 01:16:02.107326 | Cloning repos into workspace 2026-06-12 01:16:14.676276 | Restoring repo states 2026-06-12 01:16:22.830717 | Merging changes 2026-06-12 01:16:23.884947 | Checking out repos 2026-06-12 01:16:25.473742 | Preparing playbooks 2026-06-12 01:16:27.856573 | Running Ansible setup 2026-06-12 01:16:31.315250 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-06-12 01:16:31.900726 | 2026-06-12 01:16:31.900856 | PLAY [localhost] 2026-06-12 01:16:31.908743 | 2026-06-12 01:16:31.908811 | TASK [Gathering Facts] 2026-06-12 01:16:33.278006 | localhost | ok 2026-06-12 01:16:33.290706 | 2026-06-12 01:16:33.290810 | TASK [Setup log path fact] 2026-06-12 01:16:33.310999 | localhost | ok 2026-06-12 01:16:33.322415 | 2026-06-12 01:16:33.322478 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-06-12 01:16:33.354990 | localhost | ok 2026-06-12 01:16:33.363751 | 2026-06-12 01:16:33.363820 | TASK [emit-job-header : Print job information] 2026-06-12 01:16:33.406492 | # Job Information 2026-06-12 01:16:33.406658 | Ansible Version: 2.16.18 2026-06-12 01:16:33.406705 | Job: atmosphere-molecule-keycloak 2026-06-12 01:16:33.406735 | Pipeline: check 2026-06-12 01:16:33.406763 | Executor: 2d72f0692154 2026-06-12 01:16:33.406790 | Triggered by: https://github.com/vexxhost/atmosphere/pull/3985 2026-06-12 01:16:33.406821 | Event ID: 9ab5d300-65fb-11f1-875d-8f5e9ed90de6 2026-06-12 01:16:33.410956 | 2026-06-12 01:16:33.411068 | LOOP [emit-job-header : Print node information] 2026-06-12 01:16:33.502513 | localhost | ok: 2026-06-12 01:16:33.502725 | localhost | # Node Information 2026-06-12 01:16:33.502765 | localhost | Inventory Hostname: instance 2026-06-12 01:16:33.502796 | localhost | Hostname: np0000179754 2026-06-12 01:16:33.502824 | localhost | Username: zuul 2026-06-12 01:16:33.502856 | localhost | Distro: Ubuntu 22.04 2026-06-12 01:16:33.502884 | localhost | Provider: yul1 2026-06-12 01:16:33.502911 | localhost | Region: ca-ymq-1 2026-06-12 01:16:33.502936 | localhost | Label: ubuntu-jammy 2026-06-12 01:16:33.502962 | localhost | Product Name: OpenStack Nova 2026-06-12 01:16:33.502987 | localhost | Interface IP: 199.204.45.132 2026-06-12 01:16:33.517632 | 2026-06-12 01:16:33.517733 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-06-12 01:16:33.876888 | localhost -> localhost | changed 2026-06-12 01:16:33.885296 | 2026-06-12 01:16:33.885390 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-06-12 01:16:34.880717 | localhost -> localhost | changed 2026-06-12 01:16:34.892503 | 2026-06-12 01:16:34.892570 | PLAY [all] 2026-06-12 01:16:34.900569 | 2026-06-12 01:16:34.900640 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-06-12 01:16:35.119655 | instance -> localhost | ok 2026-06-12 01:16:35.129534 | 2026-06-12 01:16:35.129610 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-06-12 01:16:35.163054 | instance | ok 2026-06-12 01:16:35.177634 | instance | included: /var/lib/zuul/builds/e8652d89baf84a9db4d3b2949d8af5e7/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-06-12 01:16:35.183809 | 2026-06-12 01:16:35.183872 | TASK [add-build-sshkey : Create Temp SSH key] 2026-06-12 01:16:36.057761 | instance -> localhost | Generating public/private rsa key pair. 2026-06-12 01:16:36.058001 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/e8652d89baf84a9db4d3b2949d8af5e7/work/e8652d89baf84a9db4d3b2949d8af5e7_id_rsa 2026-06-12 01:16:36.058043 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/e8652d89baf84a9db4d3b2949d8af5e7/work/e8652d89baf84a9db4d3b2949d8af5e7_id_rsa.pub 2026-06-12 01:16:36.058076 | instance -> localhost | The key fingerprint is: 2026-06-12 01:16:36.058107 | instance -> localhost | SHA256:uWhs7A+tYuE5DkWbhihB3xhJmCjHjB8F3kfOMrF1Io4 zuul-build-sshkey 2026-06-12 01:16:36.058159 | instance -> localhost | The key's randomart image is: 2026-06-12 01:16:36.058190 | instance -> localhost | +---[RSA 3072]----+ 2026-06-12 01:16:36.058229 | instance -> localhost | |.==== + . | 2026-06-12 01:16:36.058260 | instance -> localhost | |=+*=+O o | 2026-06-12 01:16:36.058290 | instance -> localhost | |ooE+B.+ | 2026-06-12 01:16:36.058319 | instance -> localhost | | o.o * . | 2026-06-12 01:16:36.058349 | instance -> localhost | |o . = S | 2026-06-12 01:16:36.058378 | instance -> localhost | |. o.o o . | 2026-06-12 01:16:36.058406 | instance -> localhost | | .. oB o | 2026-06-12 01:16:36.058435 | instance -> localhost | | .*+ o | 2026-06-12 01:16:36.058466 | instance -> localhost | | o.oo.. | 2026-06-12 01:16:36.058495 | instance -> localhost | +----[SHA256]-----+ 2026-06-12 01:16:36.058561 | instance -> localhost | ok: Runtime: 0:00:00.404534 2026-06-12 01:16:36.065327 | 2026-06-12 01:16:36.065392 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-06-12 01:16:36.099364 | instance | ok 2026-06-12 01:16:36.108076 | instance | included: /var/lib/zuul/builds/e8652d89baf84a9db4d3b2949d8af5e7/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-06-12 01:16:36.287325 | 2026-06-12 01:16:36.287411 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-06-12 01:16:36.312033 | instance | skipping: Conditional result was False 2026-06-12 01:16:36.320857 | 2026-06-12 01:16:36.320975 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-06-12 01:16:36.752639 | instance | changed 2026-06-12 01:16:36.757801 | 2026-06-12 01:16:36.757867 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-06-12 01:16:36.945247 | instance | ok 2026-06-12 01:16:36.952270 | 2026-06-12 01:16:36.952363 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-06-12 01:16:37.419661 | instance | changed 2026-06-12 01:16:37.426410 | 2026-06-12 01:16:37.426499 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-06-12 01:16:37.898101 | instance | changed 2026-06-12 01:16:37.903258 | 2026-06-12 01:16:37.903324 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-06-12 01:16:37.927636 | instance | skipping: Conditional result was False 2026-06-12 01:16:37.933381 | 2026-06-12 01:16:37.933445 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-06-12 01:16:38.311725 | instance -> localhost | changed 2026-06-12 01:16:38.326606 | 2026-06-12 01:16:38.326675 | TASK [add-build-sshkey : Add back temp key] 2026-06-12 01:16:38.694954 | instance -> localhost | Identity added: /var/lib/zuul/builds/e8652d89baf84a9db4d3b2949d8af5e7/work/e8652d89baf84a9db4d3b2949d8af5e7_id_rsa (zuul-build-sshkey) 2026-06-12 01:16:38.695195 | instance -> localhost | ok: Runtime: 0:00:00.015808 2026-06-12 01:16:38.700343 | 2026-06-12 01:16:38.700412 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-06-12 01:16:39.038811 | instance | ok 2026-06-12 01:16:39.043510 | 2026-06-12 01:16:39.043573 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-06-12 01:16:39.068686 | instance | skipping: Conditional result was False 2026-06-12 01:16:39.127823 | 2026-06-12 01:16:39.127985 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-06-12 01:16:39.415142 | instance | ok 2026-06-12 01:16:39.422501 | 2026-06-12 01:16:39.422568 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-06-12 01:16:42.695977 | instance | Output suppressed because no_log was given 2026-06-12 01:16:42.716448 | 2026-06-12 01:16:42.716522 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-06-12 01:16:42.914807 | instance | ok: "logs" 2026-06-12 01:16:42.915128 | instance | ok: All items complete 2026-06-12 01:16:42.915168 | 2026-06-12 01:16:43.072527 | instance | ok: "artifacts" 2026-06-12 01:16:43.226399 | instance | ok: "docs" 2026-06-12 01:16:43.245789 | 2026-06-12 01:16:43.245964 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-06-12 01:16:43.431627 | instance | changed: "logs" 2026-06-12 01:16:43.585023 | instance | changed: "artifacts" 2026-06-12 01:16:44.011494 | instance | changed: "docs" 2026-06-12 01:16:44.027095 | 2026-06-12 01:16:44.027231 | PLAY RECAP 2026-06-12 01:16:44.027285 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-06-12 01:16:44.027314 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-06-12 01:16:44.027338 | 2026-06-12 01:16:44.209057 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-06-12 01:16:44.214769 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-06-12 01:16:44.802869 | 2026-06-12 01:16:44.802996 | PLAY [all] 2026-06-12 01:16:44.814070 | 2026-06-12 01:16:44.814145 | TASK [setup-uv : Extract archive] 2026-06-12 01:16:46.985713 | instance | changed 2026-06-12 01:16:46.991736 | 2026-06-12 01:16:46.991815 | TASK [setup-uv : Print version] 2026-06-12 01:16:47.315899 | instance | uv 0.8.13 2026-06-12 01:16:47.526341 | instance | ok: Runtime: 0:00:00.012643 2026-06-12 01:16:47.535250 | 2026-06-12 01:16:47.535317 | TASK [Install Ansible collection siblings] 2026-06-12 01:16:48.169565 | instance | ERROR 2026-06-12 01:16:48.169989 | instance | { 2026-06-12 01:16:48.170067 | instance | "log": [ 2026-06-12 01:16:48.170124 | instance | "Sibling collection ansible.netcommon at /home/zuul/src/github.com/ansible-collections/ansible.netcommon", 2026-06-12 01:16:48.170158 | instance | "Sibling collection ansible.posix at /home/zuul/src/github.com/ansible-collections/ansible.posix", 2026-06-12 01:16:48.170183 | instance | "Sibling collection ansible.utils at /home/zuul/src/github.com/ansible-collections/ansible.utils", 2026-06-12 01:16:48.170210 | instance | "Sibling collection community.crypto at /home/zuul/src/github.com/ansible-collections/community.crypto", 2026-06-12 01:16:48.170233 | instance | "Sibling collection community.general at /home/zuul/src/github.com/ansible-collections/community.general", 2026-06-12 01:16:48.170256 | instance | "Sibling collection community.mysql at /home/zuul/src/github.com/ansible-collections/community.mysql", 2026-06-12 01:16:48.170274 | instance | "Sibling collection kubernetes.core at /home/zuul/src/github.com/ansible-collections/kubernetes.core", 2026-06-12 01:16:48.170291 | instance | "Sibling collection vexxhost.ceph at /home/zuul/src/github.com/vexxhost/ansible-collection-ceph", 2026-06-12 01:16:48.170309 | instance | "Sibling collection vexxhost.containers at /home/zuul/src/github.com/vexxhost/ansible-collection-containers", 2026-06-12 01:16:48.170327 | instance | "Sibling collection vexxhost.kubernetes at /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes", 2026-06-12 01:16:48.170345 | instance | "Sibling collection atmosphere.common at /home/zuul/src/github.com/vexxhost/atmosphere.common", 2026-06-12 01:16:48.170362 | instance | "Sibling collection openstack.cloud at /home/zuul/src/opendev.org/openstack/ansible-collections-openstack", 2026-06-12 01:16:48.170380 | instance | "Resolved ansible.netcommon to v8.5.2 for >=1.2.0", 2026-06-12 01:16:48.170398 | instance | "Resolved ansible.posix to 1.6.0 for 1.6.0", 2026-06-12 01:16:48.170416 | instance | "Resolved ansible.utils to v6.0.2 for >=2.9.0,>=3.0.0", 2026-06-12 01:16:48.170434 | instance | "Resolved community.crypto to 2.2.3 for 2.2.3", 2026-06-12 01:16:48.170451 | instance | "Resolved community.general to 7.3.0 for 7.3.0", 2026-06-12 01:16:48.170469 | instance | "Resolved community.mysql to 3.6.0 for 3.6.0", 2026-06-12 01:16:48.170486 | instance | "Resolved kubernetes.core to 2.4.0 for 2.4.0", 2026-06-12 01:16:48.170503 | instance | "Resolved openstack.cloud to 1.7.0 for 1.7.0", 2026-06-12 01:16:48.170522 | instance | "Resolved atmosphere.common to v0.7.0 for >=0.6.0", 2026-06-12 01:16:48.170554 | instance | "Resolved vexxhost.ceph to v4.0.0 for >=3.1.2", 2026-06-12 01:16:48.170573 | instance | "Resolved vexxhost.kubernetes to v3.2.0 for >=2.3.2,>=2.5.0", 2026-06-12 01:16:48.170590 | instance | "Resolved ansible.utils to v6.0.2 for >=2.9.0,>=3.0.0,>=6.0.0", 2026-06-12 01:16:48.170608 | instance | "Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2" 2026-06-12 01:16:48.170625 | instance | ], 2026-06-12 01:16:48.170643 | instance | "msg": "No local release tag for openstack.cloud satisfies 1.7.0,>=2.0.0" 2026-06-12 01:16:48.170660 | instance | } failure 2026-06-12 01:16:48.173935 | 2026-06-12 01:16:48.173981 | PLAY RECAP 2026-06-12 01:16:48.174027 | instance | ok: 2 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2026-06-12 01:16:48.174049 | 2026-06-12 01:16:48.339182 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-06-12 01:16:48.345287 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-06-12 01:16:48.948584 | 2026-06-12 01:16:48.948729 | PLAY [all] 2026-06-12 01:16:48.959725 | 2026-06-12 01:16:48.959799 | TASK [fetch-output : Set log path for multiple nodes] 2026-06-12 01:16:49.004986 | instance | skipping: Conditional result was False 2026-06-12 01:16:49.015081 | 2026-06-12 01:16:49.015164 | TASK [fetch-output : Set log path for single node] 2026-06-12 01:16:49.057768 | instance | ok 2026-06-12 01:16:49.064693 | 2026-06-12 01:16:49.064764 | LOOP [fetch-output : Ensure local output dirs] 2026-06-12 01:16:49.435987 | instance -> localhost | ok: "/var/lib/zuul/builds/e8652d89baf84a9db4d3b2949d8af5e7/work/logs" 2026-06-12 01:16:49.652573 | instance -> localhost | changed: "/var/lib/zuul/builds/e8652d89baf84a9db4d3b2949d8af5e7/work/artifacts" 2026-06-12 01:16:49.876234 | instance -> localhost | changed: "/var/lib/zuul/builds/e8652d89baf84a9db4d3b2949d8af5e7/work/docs" 2026-06-12 01:16:49.894905 | 2026-06-12 01:16:49.895077 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-06-12 01:16:50.561040 | instance | changed: .d..t...... ./ 2026-06-12 01:16:50.561357 | instance | changed: All items complete 2026-06-12 01:16:50.561406 | 2026-06-12 01:16:51.012291 | instance | changed: .d..t...... ./ 2026-06-12 01:16:51.468142 | instance | changed: .d..t...... ./ 2026-06-12 01:16:51.493458 | 2026-06-12 01:16:51.493591 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-06-12 01:16:52.005375 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.110931 2026-06-12 01:16:52.232650 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.007810 2026-06-12 01:16:52.252358 | 2026-06-12 01:16:52.252513 | PLAY [all] 2026-06-12 01:16:52.258700 | 2026-06-12 01:16:52.258772 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-06-12 01:16:52.668663 | instance | changed 2026-06-12 01:16:52.675401 | 2026-06-12 01:16:52.675455 | PLAY RECAP 2026-06-12 01:16:52.675502 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-06-12 01:16:52.675522 | 2026-06-12 01:16:52.811478 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-06-12 01:16:52.817145 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-06-12 01:16:53.424912 | 2026-06-12 01:16:53.425109 | PLAY [localhost] 2026-06-12 01:16:53.436204 | 2026-06-12 01:16:53.436275 | TASK [Generate Zuul manifest] 2026-06-12 01:16:53.457909 | localhost | ok 2026-06-12 01:16:53.473658 | 2026-06-12 01:16:53.473733 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-06-12 01:16:53.839054 | localhost | changed 2026-06-12 01:16:53.852107 | 2026-06-12 01:16:53.852183 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-06-12 01:16:53.883244 | localhost | ok 2026-06-12 01:16:53.891423 | 2026-06-12 01:16:53.891485 | TASK [Upload logs] 2026-06-12 01:16:53.910615 | localhost | ok 2026-06-12 01:16:53.968665 | 2026-06-12 01:16:53.968765 | TASK [Set zuul-log-path fact] 2026-06-12 01:16:53.989745 | localhost | ok 2026-06-12 01:16:54.002830 | 2026-06-12 01:16:54.002896 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-06-12 01:16:54.034231 | localhost | ok 2026-06-12 01:16:54.043081 | 2026-06-12 01:16:54.043141 | TASK [upload-logs : Create log directories] 2026-06-12 01:16:54.439207 | localhost | changed 2026-06-12 01:16:54.479378 | 2026-06-12 01:16:54.479527 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-06-12 01:16:54.829599 | localhost -> localhost | ok: Runtime: 0:00:00.005361 2026-06-12 01:16:54.835083 | 2026-06-12 01:16:54.835149 | TASK [upload-logs : Upload logs to log server] 2026-06-12 01:16:55.246427 | localhost | Output suppressed because no_log was given 2026-06-12 01:16:55.250107 | 2026-06-12 01:16:55.250170 | LOOP [upload-logs : Compress console log and json output] 2026-06-12 01:16:55.293233 | localhost | skipping: Conditional result was False 2026-06-12 01:16:55.301275 | localhost | skipping: Conditional result was False 2026-06-12 01:16:55.308913 | 2026-06-12 01:16:55.309040 | LOOP [upload-logs : Upload compressed console log and json output] 2026-06-12 01:16:55.378451 | localhost | skipping: Conditional result was False 2026-06-12 01:16:55.378819 | 2026-06-12 01:16:55.382617 | localhost | skipping: Conditional result was False 2026-06-12 01:16:55.388234 | 2026-06-12 01:16:55.388302 | LOOP [upload-logs : Upload console log and json output]