2025-11-25 09:22:15.722444 | Job console starting 2025-11-25 09:22:15.737598 | Updating git repos 2025-11-25 09:22:15.761361 | Cloning repos into workspace 2025-11-25 09:22:15.808947 | Restoring repo states 2025-11-25 09:22:15.826274 | Merging changes 2025-11-25 09:22:15.826320 | Checking out repos 2025-11-25 09:22:15.907189 | Preparing playbooks 2025-11-25 09:22:16.744997 | Running Ansible setup 2025-11-25 09:22:22.636796 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-11-25 09:22:23.389998 | 2025-11-25 09:22:23.390174 | PLAY [Base pre] 2025-11-25 09:22:23.407860 | 2025-11-25 09:22:23.408003 | TASK [Setup log path fact] 2025-11-25 09:22:23.444579 | debian-bookworm | ok 2025-11-25 09:22:23.464288 | 2025-11-25 09:22:23.464473 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-11-25 09:22:23.512925 | debian-bookworm | ok 2025-11-25 09:22:23.529722 | 2025-11-25 09:22:23.529918 | TASK [emit-job-header : Print job information] 2025-11-25 09:22:23.590158 | # Job Information 2025-11-25 09:22:23.590525 | Ansible Version: 2.16.14 2025-11-25 09:22:23.590608 | Job: container-image-inventory-reconciler-push 2025-11-25 09:22:23.590678 | Pipeline: tag 2025-11-25 09:22:23.590731 | Executor: 521e9411259a 2025-11-25 09:22:23.590792 | Triggered by: https://github.com/osism/container-image-inventory-reconciler/releases/tag/v0.20251125.0 2025-11-25 09:22:23.590870 | Event ID: 385b6452-c9e0-11f0-96af-321fa26d5d2b 2025-11-25 09:22:23.599510 | 2025-11-25 09:22:23.599678 | LOOP [emit-job-header : Print node information] 2025-11-25 09:22:23.726670 | debian-bookworm | ok: 2025-11-25 09:22:23.727048 | debian-bookworm | # Node Information 2025-11-25 09:22:23.727108 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-11-25 09:22:23.727152 | debian-bookworm | Hostname: debian 2025-11-25 09:22:23.727189 | debian-bookworm | Username: zuul 2025-11-25 09:22:23.727224 | debian-bookworm | Distro: Debian 12.12 2025-11-25 09:22:23.727264 | debian-bookworm | Provider: regiocloud-a 2025-11-25 09:22:23.727300 | debian-bookworm | Region: 2025-11-25 09:22:23.727335 | debian-bookworm | Label: debian-bookworm 2025-11-25 09:22:23.727367 | debian-bookworm | Product Name: OpenStack Nova 2025-11-25 09:22:23.727446 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe4d:b1e6 2025-11-25 09:22:23.758225 | 2025-11-25 09:22:23.758531 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-11-25 09:22:24.274452 | debian-bookworm -> localhost | changed 2025-11-25 09:22:24.292258 | 2025-11-25 09:22:24.292551 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-11-25 09:22:25.397840 | debian-bookworm -> localhost | changed 2025-11-25 09:22:25.417582 | 2025-11-25 09:22:25.417737 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-11-25 09:22:25.741337 | debian-bookworm -> localhost | ok 2025-11-25 09:22:25.757304 | 2025-11-25 09:22:25.757542 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-11-25 09:22:25.805676 | debian-bookworm | ok 2025-11-25 09:22:25.827276 | debian-bookworm | included: /var/lib/zuul/builds/394e78d870904d34a08eb80e8a3e993d/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-11-25 09:22:25.833369 | 2025-11-25 09:22:25.833490 | TASK [add-build-sshkey : Create Temp SSH key] 2025-11-25 09:22:26.853272 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-11-25 09:22:26.853559 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/394e78d870904d34a08eb80e8a3e993d/work/394e78d870904d34a08eb80e8a3e993d_id_rsa 2025-11-25 09:22:26.853605 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/394e78d870904d34a08eb80e8a3e993d/work/394e78d870904d34a08eb80e8a3e993d_id_rsa.pub 2025-11-25 09:22:26.853637 | debian-bookworm -> localhost | The key fingerprint is: 2025-11-25 09:22:26.853665 | debian-bookworm -> localhost | SHA256:wgdXSgVXr7zDWHbm7yJ9OD9TrovLO93QT1D0el0XqJU zuul-build-sshkey 2025-11-25 09:22:26.853704 | debian-bookworm -> localhost | The key's randomart image is: 2025-11-25 09:22:26.853730 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-11-25 09:22:26.853754 | debian-bookworm -> localhost | | oo+.. oo.| 2025-11-25 09:22:26.853778 | debian-bookworm -> localhost | | . + E +| 2025-11-25 09:22:26.853801 | debian-bookworm -> localhost | | . o o ..+| 2025-11-25 09:22:26.853824 | debian-bookworm -> localhost | | . o o ...+| 2025-11-25 09:22:26.853846 | debian-bookworm -> localhost | | o S = +oo| 2025-11-25 09:22:26.853879 | debian-bookworm -> localhost | | o = =..+| 2025-11-25 09:22:26.853902 | debian-bookworm -> localhost | | . +o.*o| 2025-11-25 09:22:26.853924 | debian-bookworm -> localhost | | .oo*+=| 2025-11-25 09:22:26.853948 | debian-bookworm -> localhost | | ==oO*| 2025-11-25 09:22:26.853971 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-11-25 09:22:26.854033 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.456235 2025-11-25 09:22:26.861101 | 2025-11-25 09:22:26.861216 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-11-25 09:22:26.894157 | debian-bookworm | ok 2025-11-25 09:22:26.906186 | debian-bookworm | included: /var/lib/zuul/builds/394e78d870904d34a08eb80e8a3e993d/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-11-25 09:22:26.915625 | 2025-11-25 09:22:26.915733 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-11-25 09:22:26.939674 | debian-bookworm | skipping: Conditional result was False 2025-11-25 09:22:26.952920 | 2025-11-25 09:22:26.953074 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-11-25 09:22:27.577785 | debian-bookworm | changed 2025-11-25 09:22:27.586211 | 2025-11-25 09:22:27.586389 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-11-25 09:22:27.867149 | debian-bookworm | ok 2025-11-25 09:22:27.875946 | 2025-11-25 09:22:27.876101 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-11-25 09:22:28.576843 | debian-bookworm | changed 2025-11-25 09:22:28.590459 | 2025-11-25 09:22:28.590639 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-11-25 09:22:29.248895 | debian-bookworm | changed 2025-11-25 09:22:29.258252 | 2025-11-25 09:22:29.258423 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-11-25 09:22:29.285078 | debian-bookworm | skipping: Conditional result was False 2025-11-25 09:22:29.299517 | 2025-11-25 09:22:29.299693 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-11-25 09:22:29.791765 | debian-bookworm -> localhost | changed 2025-11-25 09:22:29.813704 | 2025-11-25 09:22:29.813893 | TASK [add-build-sshkey : Add back temp key] 2025-11-25 09:22:30.174497 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/394e78d870904d34a08eb80e8a3e993d/work/394e78d870904d34a08eb80e8a3e993d_id_rsa (zuul-build-sshkey) 2025-11-25 09:22:30.174816 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.019775 2025-11-25 09:22:30.184155 | 2025-11-25 09:22:30.184282 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-11-25 09:22:30.618993 | debian-bookworm | ok 2025-11-25 09:22:30.629488 | 2025-11-25 09:22:30.629766 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-11-25 09:22:30.666947 | debian-bookworm | skipping: Conditional result was False 2025-11-25 09:22:30.687207 | 2025-11-25 09:22:30.687366 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-11-25 09:22:31.163956 | debian-bookworm | ok 2025-11-25 09:22:31.188295 | 2025-11-25 09:22:31.188598 | TASK [validate-host : Define zuul_info_dir fact] 2025-11-25 09:22:31.237668 | debian-bookworm | ok 2025-11-25 09:22:31.245980 | 2025-11-25 09:22:31.246121 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-11-25 09:22:31.581330 | debian-bookworm -> localhost | ok 2025-11-25 09:22:31.594635 | 2025-11-25 09:22:31.594875 | TASK [validate-host : Collect information about the host] 2025-11-25 09:22:32.732647 | debian-bookworm | ok 2025-11-25 09:22:32.746795 | 2025-11-25 09:22:32.746958 | TASK [validate-host : Sanitize hostname] 2025-11-25 09:22:32.856491 | debian-bookworm | ok 2025-11-25 09:22:32.901649 | 2025-11-25 09:22:32.901798 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-11-25 09:22:33.524240 | debian-bookworm -> localhost | changed 2025-11-25 09:22:33.538425 | 2025-11-25 09:22:33.538628 | TASK [validate-host : Collect information about zuul worker] 2025-11-25 09:22:33.951335 | debian-bookworm | ok 2025-11-25 09:22:33.960022 | 2025-11-25 09:22:33.960192 | TASK [validate-host : Write out all zuul information for each host] 2025-11-25 09:22:34.534941 | debian-bookworm -> localhost | changed 2025-11-25 09:22:34.552442 | 2025-11-25 09:22:34.552581 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-11-25 09:22:34.844739 | debian-bookworm | ok 2025-11-25 09:22:34.855079 | 2025-11-25 09:22:34.855266 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-11-25 09:22:35.768934 | debian-bookworm | changed: 2025-11-25 09:22:35.769247 | debian-bookworm | cd+++++++++ src/ 2025-11-25 09:22:35.769312 | debian-bookworm | cd+++++++++ src/github.com/ 2025-11-25 09:22:35.769356 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-11-25 09:22:35.769394 | debian-bookworm | cd+++++++++ src/github.com/osism/container-image-inventory-reconciler/ 2025-11-25 09:22:35.769490 | debian-bookworm | Containerfile 2025-11-25 09:22:35.769725 | debian-bookworm | [builder 8/22] RUN <>> RUN <>> set -e 2025-11-25 09:23:45.213381 | debian-bookworm | 49 | >>> set -x 2025-11-25 09:23:45.213387 | debian-bookworm | 50 | >>> 2025-11-25 09:23:45.213393 | debian-bookworm | 51 | >>> git clone https://github.com/osism/release /release 2025-11-25 09:23:45.213400 | debian-bookworm | 52 | >>> git clone https://github.com/osism/defaults /defaults 2025-11-25 09:23:45.213406 | debian-bookworm | 53 | >>> git clone https://github.com/osism/cfg-generics /generics 2025-11-25 09:23:45.213412 | debian-bookworm | 54 | >>> 2025-11-25 09:23:45.213418 | debian-bookworm | 55 | >>> if [ "$VERSION" != "latest" ]; then 2025-11-25 09:23:45.213424 | debian-bookworm | 56 | >>> ( cd /release || exit; git fetch --all --force; git checkout "inventory-reconciler-$VERSION" ) 2025-11-25 09:23:45.213431 | debian-bookworm | 57 | >>> ( cd /defaults || exit; git fetch --all --force; git checkout "$(yq -M -r .defaults_version "/release/latest/base.yml")" ) 2025-11-25 09:23:45.213439 | debian-bookworm | 58 | >>> ( cd /generics || exit; git fetch --all --force; git checkout "$(yq -M -r .generics_version "/release/latest/base.yml")" ) 2025-11-25 09:23:45.213445 | debian-bookworm | 59 | >>> fi 2025-11-25 09:23:45.213451 | debian-bookworm | 60 | >>> EOF 2025-11-25 09:23:45.213456 | debian-bookworm | 61 | 2025-11-25 09:23:45.213463 | debian-bookworm | -------------------- 2025-11-25 09:23:45.213472 | debian-bookworm | ERROR: failed to build: failed to solve: process "/bin/sh -c set -e\nset -x\n\ngit clone https://github.com/osism/release /release\ngit clone https://github.com/osism/defaults /defaults\ngit clone https://github.com/osism/cfg-generics /generics\n\nif [ \"$VERSION\" != \"latest\" ]; then\n ( cd /release || exit; git fetch --all --force; git checkout \"inventory-reconciler-$VERSION\" )\n ( cd /defaults || exit; git fetch --all --force; git checkout \"$(yq -M -r .defaults_version \"/release/latest/base.yml\")\" )\n ( cd /generics || exit; git fetch --all --force; git checkout \"$(yq -M -r .generics_version \"/release/latest/base.yml\")\" )\nfi\n" did not complete successfully: exit code: 1 2025-11-25 09:23:45.582739 | debian-bookworm | ERROR 2025-11-25 09:23:45.583332 | debian-bookworm | { 2025-11-25 09:23:45.583511 | debian-bookworm | "delta": "0:00:18.216625", 2025-11-25 09:23:45.583615 | debian-bookworm | "end": "2025-11-25 09:23:45.221696", 2025-11-25 09:23:45.583704 | debian-bookworm | "msg": "non-zero return code", 2025-11-25 09:23:45.583788 | debian-bookworm | "rc": 1, 2025-11-25 09:23:45.583871 | debian-bookworm | "start": "2025-11-25 09:23:27.005071" 2025-11-25 09:23:45.583968 | debian-bookworm | } failure 2025-11-25 09:23:45.595204 | 2025-11-25 09:23:45.595448 | PLAY RECAP 2025-11-25 09:23:45.595639 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2025-11-25 09:23:45.595749 | 2025-11-25 09:23:45.742689 | RUN END RESULT_NORMAL: [untrusted : github.com/osism/container-image-inventory-reconciler/playbooks/build.yml@main] 2025-11-25 09:23:45.744840 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-11-25 09:23:46.520749 | 2025-11-25 09:23:46.520926 | PLAY [Base post] 2025-11-25 09:23:46.535526 | 2025-11-25 09:23:46.535688 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-11-25 09:23:47.354358 | debian-bookworm | changed 2025-11-25 09:23:47.364132 | 2025-11-25 09:23:47.364258 | PLAY RECAP 2025-11-25 09:23:47.364332 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-11-25 09:23:47.364433 | 2025-11-25 09:23:47.485381 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-11-25 09:23:47.488229 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2025-11-25 09:23:48.287348 | 2025-11-25 09:23:48.287547 | PLAY [Base post-logs] 2025-11-25 09:23:48.299167 | 2025-11-25 09:23:48.299321 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-11-25 09:23:48.864048 | localhost | changed 2025-11-25 09:23:48.874244 | 2025-11-25 09:23:48.874394 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-11-25 09:23:48.910682 | localhost | ok 2025-11-25 09:23:48.915287 | 2025-11-25 09:23:48.915426 | TASK [Set zuul-log-path fact] 2025-11-25 09:23:48.931230 | localhost | ok 2025-11-25 09:23:48.941127 | 2025-11-25 09:23:48.941247 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-11-25 09:23:48.966927 | localhost | ok 2025-11-25 09:23:48.971766 | 2025-11-25 09:23:48.971928 | TASK [upload-logs : Create log directories] 2025-11-25 09:23:49.502891 | localhost | changed 2025-11-25 09:23:49.509312 | 2025-11-25 09:23:49.509550 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-11-25 09:23:50.023805 | localhost -> localhost | ok: Runtime: 0:00:00.007115 2025-11-25 09:23:50.033952 | 2025-11-25 09:23:50.034145 | TASK [upload-logs : Upload logs to log server] 2025-11-25 09:23:50.622393 | localhost | Output suppressed because no_log was given 2025-11-25 09:23:50.625897 | 2025-11-25 09:23:50.626027 | LOOP [upload-logs : Compress console log and json output] 2025-11-25 09:23:50.673528 | localhost | skipping: Conditional result was False 2025-11-25 09:23:50.678481 | localhost | skipping: Conditional result was False 2025-11-25 09:23:50.687366 | 2025-11-25 09:23:50.687726 | LOOP [upload-logs : Upload compressed console log and json output] 2025-11-25 09:23:50.744700 | localhost | skipping: Conditional result was False 2025-11-25 09:23:50.745503 | 2025-11-25 09:23:50.748675 | localhost | skipping: Conditional result was False 2025-11-25 09:23:50.759327 | 2025-11-25 09:23:50.759515 | LOOP [upload-logs : Upload console log and json output]