2026-06-02 06:48:05.681553 | Job console starting 2026-06-02 06:48:05.701578 | Updating git repos 2026-06-02 06:48:07.645441 | Cloning repos into workspace 2026-06-02 06:48:26.024313 | Restoring repo states 2026-06-02 06:48:36.830188 | Merging changes 2026-06-02 06:48:37.848726 | Checking out repos 2026-06-02 06:48:39.587193 | Preparing playbooks 2026-06-02 06:48:42.338718 | Running Ansible setup 2026-06-02 06:48:46.466517 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-06-02 06:48:47.081809 | 2026-06-02 06:48:47.082236 | PLAY [localhost] 2026-06-02 06:48:47.090385 | 2026-06-02 06:48:47.090462 | TASK [Gathering Facts] 2026-06-02 06:48:47.986268 | localhost | ok 2026-06-02 06:48:47.998442 | 2026-06-02 06:48:47.998565 | TASK [Setup log path fact] 2026-06-02 06:48:48.019295 | localhost | ok 2026-06-02 06:48:48.034531 | 2026-06-02 06:48:48.034695 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-06-02 06:48:48.068150 | localhost | ok 2026-06-02 06:48:48.079331 | 2026-06-02 06:48:48.079497 | TASK [emit-job-header : Print job information] 2026-06-02 06:48:48.125341 | # Job Information 2026-06-02 06:48:48.125611 | Ansible Version: 2.16.18 2026-06-02 06:48:48.125664 | Job: atmosphere-molecule-keycloak 2026-06-02 06:48:48.125703 | Pipeline: check 2026-06-02 06:48:48.125738 | Executor: 2d72f0692154 2026-06-02 06:48:48.125776 | Triggered by: https://github.com/vexxhost/atmosphere/pull/3991 2026-06-02 06:48:48.125815 | Event ID: 998a1720-5e4e-11f1-80d8-d0a991a091f0 2026-06-02 06:48:48.130249 | 2026-06-02 06:48:48.130348 | LOOP [emit-job-header : Print node information] 2026-06-02 06:48:48.225865 | localhost | ok: 2026-06-02 06:48:48.226092 | localhost | # Node Information 2026-06-02 06:48:48.226133 | localhost | Inventory Hostname: instance 2026-06-02 06:48:48.226164 | localhost | Hostname: np0000178024 2026-06-02 06:48:48.226192 | localhost | Username: zuul 2026-06-02 06:48:48.226223 | localhost | Distro: Ubuntu 22.04 2026-06-02 06:48:48.226250 | localhost | Provider: yul1 2026-06-02 06:48:48.226277 | localhost | Region: ca-ymq-1 2026-06-02 06:48:48.226303 | localhost | Label: ubuntu-jammy 2026-06-02 06:48:48.226328 | localhost | Product Name: OpenStack Nova 2026-06-02 06:48:48.226353 | localhost | Interface IP: 199.19.213.225 2026-06-02 06:48:48.246267 | 2026-06-02 06:48:48.246423 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-06-02 06:48:48.615505 | localhost -> localhost | changed 2026-06-02 06:48:48.624039 | 2026-06-02 06:48:48.624127 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-06-02 06:48:49.469408 | localhost -> localhost | changed 2026-06-02 06:48:49.479070 | 2026-06-02 06:48:49.479148 | PLAY [all] 2026-06-02 06:48:49.487800 | 2026-06-02 06:48:49.487871 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-06-02 06:48:49.707226 | instance -> localhost | ok 2026-06-02 06:48:49.718603 | 2026-06-02 06:48:49.718675 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-06-02 06:48:49.754306 | instance | ok 2026-06-02 06:48:49.772637 | instance | included: /var/lib/zuul/builds/678d3219697248eaa829466594d239c4/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-06-02 06:48:49.778950 | 2026-06-02 06:48:49.779021 | TASK [add-build-sshkey : Create Temp SSH key] 2026-06-02 06:48:50.648054 | instance -> localhost | Generating public/private rsa key pair. 2026-06-02 06:48:50.648247 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/678d3219697248eaa829466594d239c4/work/678d3219697248eaa829466594d239c4_id_rsa 2026-06-02 06:48:50.648291 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/678d3219697248eaa829466594d239c4/work/678d3219697248eaa829466594d239c4_id_rsa.pub 2026-06-02 06:48:50.648325 | instance -> localhost | The key fingerprint is: 2026-06-02 06:48:50.648357 | instance -> localhost | SHA256:ZofscwxyKCwNrSmGcAjQ6V3Y220E3tM+bxtdzsN3/D8 zuul-build-sshkey 2026-06-02 06:48:50.648405 | instance -> localhost | The key's randomart image is: 2026-06-02 06:48:50.648436 | instance -> localhost | +---[RSA 3072]----+ 2026-06-02 06:48:50.648475 | instance -> localhost | |+. . o .. | 2026-06-02 06:48:50.648507 | instance -> localhost | |..o.. o. ... | 2026-06-02 06:48:50.648537 | instance -> localhost | |o.o... o.oo . | 2026-06-02 06:48:50.648567 | instance -> localhost | |o..*. .o..oo | 2026-06-02 06:48:50.648597 | instance -> localhost | |o.+ + o S.. o .| 2026-06-02 06:48:50.648626 | instance -> localhost | |.. . . * + o.=.| 2026-06-02 06:48:50.648656 | instance -> localhost | | o o +oB| 2026-06-02 06:48:50.648685 | instance -> localhost | | o . E=| 2026-06-02 06:48:50.648716 | instance -> localhost | | . =| 2026-06-02 06:48:50.648747 | instance -> localhost | +----[SHA256]-----+ 2026-06-02 06:48:50.648810 | instance -> localhost | ok: Runtime: 0:00:00.451636 2026-06-02 06:48:50.654607 | 2026-06-02 06:48:50.654668 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-06-02 06:48:50.687018 | instance | ok 2026-06-02 06:48:50.695647 | instance | included: /var/lib/zuul/builds/678d3219697248eaa829466594d239c4/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-06-02 06:48:50.704150 | 2026-06-02 06:48:50.704211 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-06-02 06:48:50.728691 | instance | skipping: Conditional result was False 2026-06-02 06:48:50.743199 | 2026-06-02 06:48:50.743281 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-06-02 06:48:51.162546 | instance | changed 2026-06-02 06:48:51.167372 | 2026-06-02 06:48:51.167435 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-06-02 06:48:51.337533 | instance | ok 2026-06-02 06:48:51.343987 | 2026-06-02 06:48:51.344057 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-06-02 06:48:51.803885 | instance | changed 2026-06-02 06:48:51.810528 | 2026-06-02 06:48:51.810621 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-06-02 06:48:52.260059 | instance | changed 2026-06-02 06:48:52.265519 | 2026-06-02 06:48:52.265581 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-06-02 06:48:52.289691 | instance | skipping: Conditional result was False 2026-06-02 06:48:52.299266 | 2026-06-02 06:48:52.299336 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-06-02 06:48:52.678596 | instance -> localhost | changed 2026-06-02 06:48:52.694840 | 2026-06-02 06:48:52.694930 | TASK [add-build-sshkey : Add back temp key] 2026-06-02 06:48:52.960460 | instance -> localhost | Identity added: /var/lib/zuul/builds/678d3219697248eaa829466594d239c4/work/678d3219697248eaa829466594d239c4_id_rsa (zuul-build-sshkey) 2026-06-02 06:48:52.960670 | instance -> localhost | ok: Runtime: 0:00:00.012083 2026-06-02 06:48:52.967300 | 2026-06-02 06:48:52.967384 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-06-02 06:48:53.264604 | instance | ok 2026-06-02 06:48:53.269366 | 2026-06-02 06:48:53.269462 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-06-02 06:48:53.293477 | instance | skipping: Conditional result was False 2026-06-02 06:48:53.488659 | 2026-06-02 06:48:53.488786 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-06-02 06:48:53.775440 | instance | ok 2026-06-02 06:48:53.780885 | 2026-06-02 06:48:53.780947 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-06-02 06:48:57.024083 | instance | Output suppressed because no_log was given 2026-06-02 06:48:57.035284 | 2026-06-02 06:48:57.035382 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-06-02 06:48:57.220399 | instance | ok: "logs" 2026-06-02 06:48:57.220719 | instance | ok: All items complete 2026-06-02 06:48:57.220751 | 2026-06-02 06:48:57.360607 | instance | ok: "artifacts" 2026-06-02 06:48:57.520497 | instance | ok: "docs" 2026-06-02 06:48:57.764555 | 2026-06-02 06:48:57.764629 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-06-02 06:48:57.943357 | instance | changed: "logs" 2026-06-02 06:48:58.108266 | instance | changed: "artifacts" 2026-06-02 06:48:58.268266 | instance | changed: "docs" 2026-06-02 06:48:58.288487 | 2026-06-02 06:48:58.288557 | PLAY RECAP 2026-06-02 06:48:58.288623 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-06-02 06:48:58.288664 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-06-02 06:48:58.288698 | 2026-06-02 06:48:58.483703 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-06-02 06:48:58.527157 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-06-02 06:48:59.101171 | 2026-06-02 06:48:59.101278 | PLAY [all] 2026-06-02 06:48:59.112577 | 2026-06-02 06:48:59.112685 | TASK [setup-uv : Extract archive] 2026-06-02 06:49:02.234390 | instance | changed 2026-06-02 06:49:02.241286 | 2026-06-02 06:49:02.241384 | TASK [setup-uv : Print version] 2026-06-02 06:49:02.562413 | instance | uv 0.8.13 2026-06-02 06:49:02.786911 | instance | ok: Runtime: 0:00:00.012629 2026-06-02 06:49:02.797594 | 2026-06-02 06:49:02.797664 | TASK [Install Ansible collection siblings] 2026-06-02 06:49:03.425179 | instance | ERROR 2026-06-02 06:49:03.425442 | instance | { 2026-06-02 06:49:03.425486 | instance | "log": [ 2026-06-02 06:49:03.425516 | instance | "Sibling collection ansible.netcommon at /home/zuul/src/github.com/ansible-collections/ansible.netcommon", 2026-06-02 06:49:03.425544 | instance | "Sibling collection ansible.posix at /home/zuul/src/github.com/ansible-collections/ansible.posix", 2026-06-02 06:49:03.425571 | instance | "Sibling collection ansible.utils at /home/zuul/src/github.com/ansible-collections/ansible.utils", 2026-06-02 06:49:03.425602 | instance | "Sibling collection community.crypto at /home/zuul/src/github.com/ansible-collections/community.crypto", 2026-06-02 06:49:03.425629 | instance | "Sibling collection community.general at /home/zuul/src/github.com/ansible-collections/community.general", 2026-06-02 06:49:03.425658 | instance | "Sibling collection community.mysql at /home/zuul/src/github.com/ansible-collections/community.mysql", 2026-06-02 06:49:03.425683 | instance | "Sibling collection kubernetes.core at /home/zuul/src/github.com/ansible-collections/kubernetes.core", 2026-06-02 06:49:03.425708 | instance | "Sibling collection vexxhost.ceph at /home/zuul/src/github.com/vexxhost/ansible-collection-ceph", 2026-06-02 06:49:03.425733 | instance | "Sibling collection vexxhost.containers at /home/zuul/src/github.com/vexxhost/ansible-collection-containers", 2026-06-02 06:49:03.425758 | instance | "Sibling collection vexxhost.kubernetes at /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes", 2026-06-02 06:49:03.425782 | instance | "Sibling collection atmosphere.common at /home/zuul/src/github.com/vexxhost/atmosphere.common", 2026-06-02 06:49:03.425807 | instance | "Sibling collection openstack.cloud at /home/zuul/src/opendev.org/openstack/ansible-collections-openstack", 2026-06-02 06:49:03.425831 | instance | "Resolved ansible.netcommon to v8.5.2 for >=1.2.0", 2026-06-02 06:49:03.425857 | instance | "Resolved ansible.posix to 1.6.0 for 1.6.0", 2026-06-02 06:49:03.425883 | instance | "Resolved ansible.utils to v6.0.2 for >=2.9.0,>=3.0.0", 2026-06-02 06:49:03.425908 | instance | "Resolved community.crypto to 2.2.3 for 2.2.3", 2026-06-02 06:49:03.425933 | instance | "Resolved community.general to 7.3.0 for 7.3.0", 2026-06-02 06:49:03.425958 | instance | "Resolved community.mysql to 3.6.0 for 3.6.0", 2026-06-02 06:49:03.425982 | instance | "Resolved kubernetes.core to 2.4.0 for 2.4.0", 2026-06-02 06:49:03.426006 | instance | "Resolved openstack.cloud to 1.7.0 for 1.7.0", 2026-06-02 06:49:03.426032 | instance | "Resolved atmosphere.common to v0.7.0 for >=0.6.0", 2026-06-02 06:49:03.426066 | instance | "Resolved vexxhost.ceph to v4.0.0 for >=3.1.2", 2026-06-02 06:49:03.426092 | instance | "Resolved vexxhost.kubernetes to v3.1.0 for >=2.3.2,>=2.5.0", 2026-06-02 06:49:03.426116 | instance | "Resolved ansible.utils to v6.0.2 for >=2.9.0,>=3.0.0,>=6.0.0", 2026-06-02 06:49:03.426140 | instance | "Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2" 2026-06-02 06:49:03.426165 | instance | ], 2026-06-02 06:49:03.426189 | instance | "msg": "No local release tag for openstack.cloud satisfies 1.7.0,>=2.0.0" 2026-06-02 06:49:03.426214 | instance | } failure 2026-06-02 06:49:03.430207 | 2026-06-02 06:49:03.430270 | PLAY RECAP 2026-06-02 06:49:03.430328 | instance | ok: 2 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2026-06-02 06:49:03.430359 | 2026-06-02 06:49:03.589898 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-06-02 06:49:03.669388 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-06-02 06:49:04.266248 | 2026-06-02 06:49:04.266386 | PLAY [all] 2026-06-02 06:49:04.277601 | 2026-06-02 06:49:04.277709 | TASK [fetch-output : Set log path for multiple nodes] 2026-06-02 06:49:04.312218 | instance | skipping: Conditional result was False 2026-06-02 06:49:04.322823 | 2026-06-02 06:49:04.323003 | TASK [fetch-output : Set log path for single node] 2026-06-02 06:49:04.356588 | instance | ok 2026-06-02 06:49:04.362048 | 2026-06-02 06:49:04.362116 | LOOP [fetch-output : Ensure local output dirs] 2026-06-02 06:49:04.757653 | instance -> localhost | ok: "/var/lib/zuul/builds/678d3219697248eaa829466594d239c4/work/logs" 2026-06-02 06:49:04.983569 | instance -> localhost | changed: "/var/lib/zuul/builds/678d3219697248eaa829466594d239c4/work/artifacts" 2026-06-02 06:49:05.180684 | instance -> localhost | changed: "/var/lib/zuul/builds/678d3219697248eaa829466594d239c4/work/docs" 2026-06-02 06:49:05.190037 | 2026-06-02 06:49:05.190155 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-06-02 06:49:05.811464 | instance | changed: .d..t...... ./ 2026-06-02 06:49:05.811630 | instance | changed: All items complete 2026-06-02 06:49:05.811658 | 2026-06-02 06:49:06.273650 | instance | changed: .d..t...... ./ 2026-06-02 06:49:06.717569 | instance | changed: .d..t...... ./ 2026-06-02 06:49:06.741078 | 2026-06-02 06:49:06.741233 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-06-02 06:49:07.127350 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.006795 2026-06-02 06:49:07.337364 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.007990 2026-06-02 06:49:07.349096 | 2026-06-02 06:49:07.349194 | PLAY [all] 2026-06-02 06:49:07.354945 | 2026-06-02 06:49:07.355011 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-06-02 06:49:07.775903 | instance | changed 2026-06-02 06:49:08.560178 | 2026-06-02 06:49:08.560307 | PLAY RECAP 2026-06-02 06:49:08.560375 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-06-02 06:49:08.560406 | 2026-06-02 06:49:08.765961 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-06-02 06:49:08.770419 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-06-02 06:49:09.355983 | 2026-06-02 06:49:09.356336 | PLAY [localhost] 2026-06-02 06:49:09.367056 | 2026-06-02 06:49:09.367152 | TASK [Generate Zuul manifest] 2026-06-02 06:49:09.388115 | localhost | ok 2026-06-02 06:49:09.403945 | 2026-06-02 06:49:10.182092 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-06-02 06:49:10.532904 | localhost | changed 2026-06-02 06:49:10.546591 | 2026-06-02 06:49:10.546712 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-06-02 06:49:10.608841 | localhost | ok 2026-06-02 06:49:10.617752 | 2026-06-02 06:49:10.617849 | TASK [Upload logs] 2026-06-02 06:49:10.638831 | localhost | ok 2026-06-02 06:49:10.701535 | 2026-06-02 06:49:10.701650 | TASK [Set zuul-log-path fact] 2026-06-02 06:49:10.718661 | localhost | ok 2026-06-02 06:49:10.729198 | 2026-06-02 06:49:10.729283 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-06-02 06:49:10.757354 | localhost | ok 2026-06-02 06:49:10.764191 | 2026-06-02 06:49:10.764258 | TASK [upload-logs : Create log directories] 2026-06-02 06:49:11.177662 | localhost | changed 2026-06-02 06:49:11.207931 | 2026-06-02 06:49:11.208047 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-06-02 06:49:11.556123 | localhost -> localhost | ok: Runtime: 0:00:00.004685 2026-06-02 06:49:11.562985 | 2026-06-02 06:49:11.563097 | TASK [upload-logs : Upload logs to log server] 2026-06-02 06:49:11.967121 | localhost | Output suppressed because no_log was given 2026-06-02 06:49:11.970885 | 2026-06-02 06:49:11.970997 | LOOP [upload-logs : Compress console log and json output] 2026-06-02 06:49:12.011849 | localhost | skipping: Conditional result was False 2026-06-02 06:49:12.020535 | localhost | skipping: Conditional result was False 2026-06-02 06:49:12.029964 | 2026-06-02 06:49:12.030077 | LOOP [upload-logs : Upload compressed console log and json output] 2026-06-02 06:49:12.073155 | localhost | skipping: Conditional result was False 2026-06-02 06:49:12.073403 | 2026-06-02 06:49:12.078702 | localhost | skipping: Conditional result was False 2026-06-02 06:49:12.092255 | 2026-06-02 06:49:12.092401 | LOOP [upload-logs : Upload console log and json output]