2026-06-03 02:35:42.246507 | Job console starting 2026-06-03 02:35:42.278690 | Updating git repos 2026-06-03 02:35:44.486835 | Cloning repos into workspace 2026-06-03 02:36:49.842496 | Restoring repo states 2026-06-03 02:36:57.192182 | Merging changes 2026-06-03 02:36:59.307481 | Checking out repos 2026-06-03 02:37:06.615486 | Preparing playbooks 2026-06-03 02:37:23.518309 | Running Ansible setup 2026-06-03 02:37:27.594319 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-06-03 02:37:28.207666 | 2026-06-03 02:37:28.207803 | PLAY [localhost] 2026-06-03 02:37:28.215286 | 2026-06-03 02:37:28.215359 | TASK [Gathering Facts] 2026-06-03 02:37:29.083257 | localhost | ok 2026-06-03 02:37:29.091184 | 2026-06-03 02:37:29.091264 | TASK [Setup log path fact] 2026-06-03 02:37:29.112700 | localhost | ok 2026-06-03 02:37:29.128927 | 2026-06-03 02:37:29.129159 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-06-03 02:37:29.161258 | localhost | ok 2026-06-03 02:37:29.170263 | 2026-06-03 02:37:29.170420 | TASK [emit-job-header : Print job information] 2026-06-03 02:37:29.216651 | # Job Information 2026-06-03 02:37:29.216835 | Ansible Version: 2.16.18 2026-06-03 02:37:29.216869 | Job: atmosphere-molecule-keycloak 2026-06-03 02:37:29.216891 | Pipeline: check 2026-06-03 02:37:29.216912 | Executor: 2d72f0692154 2026-06-03 02:37:29.216931 | Triggered by: https://github.com/vexxhost/atmosphere/pull/3985 2026-06-03 02:37:29.216972 | Event ID: faece8d0-5ef3-11f1-99a4-bead80d91f77 2026-06-03 02:37:29.220122 | 2026-06-03 02:37:29.220193 | LOOP [emit-job-header : Print node information] 2026-06-03 02:37:29.317704 | localhost | ok: 2026-06-03 02:37:29.318071 | localhost | # Node Information 2026-06-03 02:37:29.318159 | localhost | Inventory Hostname: instance 2026-06-03 02:37:29.318220 | localhost | Hostname: np0000178393 2026-06-03 02:37:29.318272 | localhost | Username: zuul 2026-06-03 02:37:29.318330 | localhost | Distro: Ubuntu 22.04 2026-06-03 02:37:29.318374 | localhost | Provider: yul1 2026-06-03 02:37:29.318416 | localhost | Region: ca-ymq-1 2026-06-03 02:37:29.318465 | localhost | Label: ubuntu-jammy 2026-06-03 02:37:29.318514 | localhost | Product Name: OpenStack Nova 2026-06-03 02:37:29.318562 | localhost | Interface IP: 199.19.213.130 2026-06-03 02:37:29.329751 | 2026-06-03 02:37:29.329927 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-06-03 02:37:29.722574 | localhost -> localhost | changed 2026-06-03 02:37:29.731747 | 2026-06-03 02:37:29.731848 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-06-03 02:37:30.599873 | localhost -> localhost | changed 2026-06-03 02:37:30.612034 | 2026-06-03 02:37:30.612138 | PLAY [all] 2026-06-03 02:37:30.622728 | 2026-06-03 02:37:30.622816 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-06-03 02:37:30.861209 | instance -> localhost | ok 2026-06-03 02:37:30.871903 | 2026-06-03 02:37:30.871995 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-06-03 02:37:30.905511 | instance | ok 2026-06-03 02:37:30.923403 | instance | included: /var/lib/zuul/builds/d37551eb9189499d9ed8e4bd245acc06/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-06-03 02:37:30.929459 | 2026-06-03 02:37:30.929518 | TASK [add-build-sshkey : Create Temp SSH key] 2026-06-03 02:37:32.410384 | instance -> localhost | Generating public/private rsa key pair. 2026-06-03 02:37:32.410606 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/d37551eb9189499d9ed8e4bd245acc06/work/d37551eb9189499d9ed8e4bd245acc06_id_rsa 2026-06-03 02:37:32.410650 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/d37551eb9189499d9ed8e4bd245acc06/work/d37551eb9189499d9ed8e4bd245acc06_id_rsa.pub 2026-06-03 02:37:32.410684 | instance -> localhost | The key fingerprint is: 2026-06-03 02:37:32.410717 | instance -> localhost | SHA256:/jyjtLEdX6XOaFbTcMRviBE7fluUFdh8ZNF3R+uEiqU zuul-build-sshkey 2026-06-03 02:37:32.410763 | instance -> localhost | The key's randomart image is: 2026-06-03 02:37:32.410796 | instance -> localhost | +---[RSA 3072]----+ 2026-06-03 02:37:32.410832 | instance -> localhost | | ..+*O| 2026-06-03 02:37:32.410864 | instance -> localhost | | .o.+%| 2026-06-03 02:37:32.410895 | instance -> localhost | | .o+ *B| 2026-06-03 02:37:32.410925 | instance -> localhost | | +.o.=.+| 2026-06-03 02:37:32.410956 | instance -> localhost | | SE .. .*o| 2026-06-03 02:37:32.410985 | instance -> localhost | | . .o=.| 2026-06-03 02:37:32.411015 | instance -> localhost | | + . .+. | 2026-06-03 02:37:32.411045 | instance -> localhost | | . Booo= | 2026-06-03 02:37:32.411078 | instance -> localhost | | +.+=o o | 2026-06-03 02:37:32.411113 | instance -> localhost | +----[SHA256]-----+ 2026-06-03 02:37:32.411193 | instance -> localhost | ok: Runtime: 0:00:01.030072 2026-06-03 02:37:32.417429 | 2026-06-03 02:37:32.417517 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-06-03 02:37:32.451879 | instance | ok 2026-06-03 02:37:32.463116 | instance | included: /var/lib/zuul/builds/d37551eb9189499d9ed8e4bd245acc06/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-06-03 02:37:32.474885 | 2026-06-03 02:37:32.475840 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-06-03 02:37:32.502988 | instance | skipping: Conditional result was False 2026-06-03 02:37:32.510906 | 2026-06-03 02:37:32.510979 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-06-03 02:37:32.947209 | instance | changed 2026-06-03 02:37:32.954107 | 2026-06-03 02:37:32.954182 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-06-03 02:37:33.133361 | instance | ok 2026-06-03 02:37:33.140796 | 2026-06-03 02:37:33.140863 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-06-03 02:37:33.641240 | instance | changed 2026-06-03 02:37:33.646217 | 2026-06-03 02:37:33.646278 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-06-03 02:37:34.102407 | instance | changed 2026-06-03 02:37:34.109904 | 2026-06-03 02:37:34.110019 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-06-03 02:37:34.138346 | instance | skipping: Conditional result was False 2026-06-03 02:37:34.152400 | 2026-06-03 02:37:34.152633 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-06-03 02:37:34.528494 | instance -> localhost | changed 2026-06-03 02:37:34.545429 | 2026-06-03 02:37:34.545546 | TASK [add-build-sshkey : Add back temp key] 2026-06-03 02:37:34.846730 | instance -> localhost | Identity added: /var/lib/zuul/builds/d37551eb9189499d9ed8e4bd245acc06/work/d37551eb9189499d9ed8e4bd245acc06_id_rsa (zuul-build-sshkey) 2026-06-03 02:37:34.847094 | instance -> localhost | ok: Runtime: 0:00:00.014849 2026-06-03 02:37:34.853778 | 2026-06-03 02:37:34.853857 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-06-03 02:37:35.167668 | instance | ok 2026-06-03 02:37:35.173483 | 2026-06-03 02:37:35.173550 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-06-03 02:37:35.199547 | instance | skipping: Conditional result was False 2026-06-03 02:37:35.260671 | 2026-06-03 02:37:35.260828 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-06-03 02:37:35.554921 | instance | ok 2026-06-03 02:37:35.563469 | 2026-06-03 02:37:35.563615 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-06-03 02:37:39.722421 | instance | Output suppressed because no_log was given 2026-06-03 02:37:39.732911 | 2026-06-03 02:37:39.733008 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-06-03 02:37:39.916225 | instance | ok: "logs" 2026-06-03 02:37:39.917050 | instance | ok: All items complete 2026-06-03 02:37:39.917102 | 2026-06-03 02:37:40.058166 | instance | ok: "artifacts" 2026-06-03 02:37:40.219682 | instance | ok: "docs" 2026-06-03 02:37:40.230393 | 2026-06-03 02:37:40.230579 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-06-03 02:37:40.407240 | instance | changed: "logs" 2026-06-03 02:37:40.570348 | instance | changed: "artifacts" 2026-06-03 02:37:40.722888 | instance | changed: "docs" 2026-06-03 02:37:40.738523 | 2026-06-03 02:37:40.738674 | PLAY RECAP 2026-06-03 02:37:40.738744 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-06-03 02:37:40.738784 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-06-03 02:37:40.738816 | 2026-06-03 02:37:40.895228 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-06-03 02:37:40.960794 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-06-03 02:37:41.578289 | 2026-06-03 02:37:41.578424 | PLAY [all] 2026-06-03 02:37:41.596267 | 2026-06-03 02:37:41.596398 | TASK [setup-uv : Extract archive] 2026-06-03 02:37:43.778677 | instance | changed 2026-06-03 02:37:43.786598 | 2026-06-03 02:37:43.786712 | TASK [setup-uv : Print version] 2026-06-03 02:37:44.121061 | instance | uv 0.8.13 2026-06-03 02:37:44.326198 | instance | ok: Runtime: 0:00:00.012061 2026-06-03 02:37:44.337137 | 2026-06-03 02:37:44.337217 | TASK [Install Ansible collection siblings] 2026-06-03 02:37:44.922604 | instance | ERROR 2026-06-03 02:37:44.922972 | instance | { 2026-06-03 02:37:44.923025 | instance | "log": [ 2026-06-03 02:37:44.923057 | instance | "Sibling collection ansible.netcommon at /home/zuul/src/github.com/ansible-collections/ansible.netcommon", 2026-06-03 02:37:44.923085 | instance | "Sibling collection ansible.posix at /home/zuul/src/github.com/ansible-collections/ansible.posix", 2026-06-03 02:37:44.923110 | instance | "Sibling collection ansible.utils at /home/zuul/src/github.com/ansible-collections/ansible.utils", 2026-06-03 02:37:44.923140 | instance | "Sibling collection community.crypto at /home/zuul/src/github.com/ansible-collections/community.crypto", 2026-06-03 02:37:44.923166 | instance | "Sibling collection community.general at /home/zuul/src/github.com/ansible-collections/community.general", 2026-06-03 02:37:44.923193 | instance | "Sibling collection community.mysql at /home/zuul/src/github.com/ansible-collections/community.mysql", 2026-06-03 02:37:44.923218 | instance | "Sibling collection kubernetes.core at /home/zuul/src/github.com/ansible-collections/kubernetes.core", 2026-06-03 02:37:44.923242 | instance | "Sibling collection vexxhost.ceph at /home/zuul/src/github.com/vexxhost/ansible-collection-ceph", 2026-06-03 02:37:44.923266 | instance | "Sibling collection vexxhost.containers at /home/zuul/src/github.com/vexxhost/ansible-collection-containers", 2026-06-03 02:37:44.923290 | instance | "Sibling collection vexxhost.kubernetes at /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes", 2026-06-03 02:37:44.923314 | instance | "Sibling collection atmosphere.common at /home/zuul/src/github.com/vexxhost/atmosphere.common", 2026-06-03 02:37:44.923337 | instance | "Sibling collection openstack.cloud at /home/zuul/src/opendev.org/openstack/ansible-collections-openstack", 2026-06-03 02:37:44.923361 | instance | "Resolved ansible.netcommon to v8.5.2 for >=1.2.0", 2026-06-03 02:37:44.923388 | instance | "Resolved ansible.posix to 1.6.0 for 1.6.0", 2026-06-03 02:37:44.923413 | instance | "Resolved ansible.utils to v6.0.2 for >=2.9.0,>=3.0.0", 2026-06-03 02:37:44.923437 | instance | "Resolved community.crypto to 2.2.3 for 2.2.3", 2026-06-03 02:37:44.923461 | instance | "Resolved community.general to 7.3.0 for 7.3.0", 2026-06-03 02:37:44.923484 | instance | "Resolved community.mysql to 3.6.0 for 3.6.0", 2026-06-03 02:37:44.923507 | instance | "Resolved kubernetes.core to 2.4.0 for 2.4.0", 2026-06-03 02:37:44.923530 | instance | "Resolved openstack.cloud to 1.7.0 for 1.7.0", 2026-06-03 02:37:44.923555 | instance | "Resolved atmosphere.common to v0.7.0 for >=0.6.0", 2026-06-03 02:37:44.923600 | instance | "Resolved vexxhost.ceph to v4.0.0 for >=3.1.2", 2026-06-03 02:37:44.923625 | instance | "Resolved vexxhost.kubernetes to v3.1.0 for >=2.3.2,>=2.5.0", 2026-06-03 02:37:44.923649 | instance | "Resolved ansible.utils to v6.0.2 for >=2.9.0,>=3.0.0,>=6.0.0", 2026-06-03 02:37:44.923672 | instance | "Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2" 2026-06-03 02:37:44.923695 | instance | ], 2026-06-03 02:37:44.923718 | instance | "msg": "No local release tag for openstack.cloud satisfies 1.7.0,>=2.0.0" 2026-06-03 02:37:44.923741 | instance | } failure 2026-06-03 02:37:44.928217 | 2026-06-03 02:37:44.928273 | PLAY RECAP 2026-06-03 02:37:44.928319 | instance | ok: 2 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2026-06-03 02:37:44.928341 | 2026-06-03 02:37:45.063148 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-06-03 02:37:45.069708 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-06-03 02:37:45.738171 | 2026-06-03 02:37:45.738300 | PLAY [all] 2026-06-03 02:37:45.749785 | 2026-06-03 02:37:45.749861 | TASK [fetch-output : Set log path for multiple nodes] 2026-06-03 02:37:45.785920 | instance | skipping: Conditional result was False 2026-06-03 02:37:45.794337 | 2026-06-03 02:37:45.794441 | TASK [fetch-output : Set log path for single node] 2026-06-03 02:37:45.837612 | instance | ok 2026-06-03 02:37:45.844809 | 2026-06-03 02:37:45.844878 | LOOP [fetch-output : Ensure local output dirs] 2026-06-03 02:37:46.239040 | instance -> localhost | ok: "/var/lib/zuul/builds/d37551eb9189499d9ed8e4bd245acc06/work/logs" 2026-06-03 02:37:46.473306 | instance -> localhost | changed: "/var/lib/zuul/builds/d37551eb9189499d9ed8e4bd245acc06/work/artifacts" 2026-06-03 02:37:46.703553 | instance -> localhost | changed: "/var/lib/zuul/builds/d37551eb9189499d9ed8e4bd245acc06/work/docs" 2026-06-03 02:37:46.722457 | 2026-06-03 02:37:46.722626 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-06-03 02:37:47.336615 | instance | ok 2026-06-03 02:37:47.336908 | instance | changed: All items complete 2026-06-03 02:37:47.336957 | 2026-06-03 02:37:47.826858 | instance | changed: .d..t...... ./ 2026-06-03 02:37:48.273454 | instance | changed: .d..t...... ./ 2026-06-03 02:37:48.296587 | 2026-06-03 02:37:48.296703 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-06-03 02:37:48.770251 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.007446 2026-06-03 02:37:49.002665 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.009143 2026-06-03 02:37:49.026014 | 2026-06-03 02:37:49.026142 | PLAY [all] 2026-06-03 02:37:49.032186 | 2026-06-03 02:37:49.032254 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-06-03 02:37:49.443747 | instance | changed 2026-06-03 02:37:49.451672 | 2026-06-03 02:37:49.451787 | PLAY RECAP 2026-06-03 02:37:49.451836 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-06-03 02:37:49.451859 | 2026-06-03 02:37:49.597334 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-06-03 02:37:49.599971 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-06-03 02:37:50.294984 | 2026-06-03 02:37:50.295137 | PLAY [localhost] 2026-06-03 02:37:50.306303 | 2026-06-03 02:37:50.306720 | TASK [Generate Zuul manifest] 2026-06-03 02:37:50.327046 | localhost | ok 2026-06-03 02:37:50.343277 | 2026-06-03 02:37:50.343366 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-06-03 02:37:50.720950 | localhost | changed 2026-06-03 02:37:50.736506 | 2026-06-03 02:37:50.736617 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-06-03 02:37:50.790086 | localhost | ok 2026-06-03 02:37:50.798569 | 2026-06-03 02:37:50.798668 | TASK [Upload logs] 2026-06-03 02:37:50.821070 | localhost | ok 2026-06-03 02:37:50.884142 | 2026-06-03 02:37:50.884324 | TASK [Set zuul-log-path fact] 2026-06-03 02:37:50.906225 | localhost | ok 2026-06-03 02:37:50.920216 | 2026-06-03 02:37:50.920365 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-06-03 02:37:50.953045 | localhost | ok 2026-06-03 02:37:50.962372 | 2026-06-03 02:37:50.962454 | TASK [upload-logs : Create log directories] 2026-06-03 02:37:51.361741 | localhost | changed 2026-06-03 02:37:51.418056 | 2026-06-03 02:37:51.418203 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-06-03 02:37:51.826758 | localhost -> localhost | ok: Runtime: 0:00:00.006135 2026-06-03 02:37:51.833466 | 2026-06-03 02:37:51.833536 | TASK [upload-logs : Upload logs to log server] 2026-06-03 02:37:52.274010 | localhost | Output suppressed because no_log was given 2026-06-03 02:37:52.278423 | 2026-06-03 02:37:52.278511 | LOOP [upload-logs : Compress console log and json output] 2026-06-03 02:37:52.327552 | localhost | skipping: Conditional result was False 2026-06-03 02:37:52.336621 | localhost | skipping: Conditional result was False 2026-06-03 02:37:52.354232 | 2026-06-03 02:37:52.354488 | LOOP [upload-logs : Upload compressed console log and json output] 2026-06-03 02:37:52.398656 | localhost | skipping: Conditional result was False 2026-06-03 02:37:52.399091 | 2026-06-03 02:37:52.402487 | localhost | skipping: Conditional result was False 2026-06-03 02:37:52.419250 | 2026-06-03 02:37:52.419462 | LOOP [upload-logs : Upload console log and json output]