2025-09-27 20:00:59.800107 | Job console starting 2025-09-27 20:00:59.817864 | Updating git repos 2025-09-27 20:00:59.858713 | Cloning repos into workspace 2025-09-27 20:00:59.907298 | Restoring repo states 2025-09-27 20:00:59.922682 | Merging changes 2025-09-27 20:00:59.922702 | Checking out repos 2025-09-27 20:01:00.000897 | Preparing playbooks 2025-09-27 20:01:00.703760 | Running Ansible setup 2025-09-27 20:01:05.125046 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-09-27 20:01:05.887872 | 2025-09-27 20:01:05.888021 | PLAY [Base pre] 2025-09-27 20:01:05.904149 | 2025-09-27 20:01:05.904330 | TASK [Setup log path fact] 2025-09-27 20:01:05.933539 | debian-bookworm | ok 2025-09-27 20:01:05.948505 | 2025-09-27 20:01:05.948639 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-09-27 20:01:05.977980 | debian-bookworm | ok 2025-09-27 20:01:05.987652 | 2025-09-27 20:01:05.987768 | TASK [emit-job-header : Print job information] 2025-09-27 20:01:06.026318 | # Job Information 2025-09-27 20:01:06.026500 | Ansible Version: 2.16.14 2025-09-27 20:01:06.026534 | Job: container-image-kolla-ansible-push-2024.1 2025-09-27 20:01:06.026566 | Pipeline: post 2025-09-27 20:01:06.026589 | Executor: 521e9411259a 2025-09-27 20:01:06.026609 | Triggered by: https://github.com/osism/container-image-kolla-ansible/commit/811150db6ba52e6c3304963cb2f360d989e623fc 2025-09-27 20:01:06.026632 | Event ID: ac7f4128-9bdc-11f0-919f-5b4b8c1cbaeb 2025-09-27 20:01:06.031574 | 2025-09-27 20:01:06.031683 | LOOP [emit-job-header : Print node information] 2025-09-27 20:01:06.132745 | debian-bookworm | ok: 2025-09-27 20:01:06.132934 | debian-bookworm | # Node Information 2025-09-27 20:01:06.132968 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-09-27 20:01:06.132993 | debian-bookworm | Hostname: debian 2025-09-27 20:01:06.133015 | debian-bookworm | Username: zuul 2025-09-27 20:01:06.133036 | debian-bookworm | Distro: Debian 12.12 2025-09-27 20:01:06.133059 | debian-bookworm | Provider: regiocloud-a 2025-09-27 20:01:06.133080 | debian-bookworm | Region: 2025-09-27 20:01:06.133101 | debian-bookworm | Label: debian-bookworm 2025-09-27 20:01:06.133121 | debian-bookworm | Product Name: OpenStack Nova 2025-09-27 20:01:06.133140 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe41:44bf 2025-09-27 20:01:06.146402 | 2025-09-27 20:01:06.146516 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-09-27 20:01:06.595547 | debian-bookworm -> localhost | changed 2025-09-27 20:01:06.603041 | 2025-09-27 20:01:06.603169 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-09-27 20:01:07.649756 | debian-bookworm -> localhost | changed 2025-09-27 20:01:07.661373 | 2025-09-27 20:01:07.661495 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-09-27 20:01:07.937961 | debian-bookworm -> localhost | ok 2025-09-27 20:01:07.944805 | 2025-09-27 20:01:07.944919 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-09-27 20:01:07.965274 | debian-bookworm | ok 2025-09-27 20:01:07.982762 | debian-bookworm | included: /var/lib/zuul/builds/9ce680391a404e7ebec2416e6f974baa/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-09-27 20:01:07.989121 | 2025-09-27 20:01:07.989241 | TASK [add-build-sshkey : Create Temp SSH key] 2025-09-27 20:01:08.946811 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-09-27 20:01:08.947122 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/9ce680391a404e7ebec2416e6f974baa/work/9ce680391a404e7ebec2416e6f974baa_id_rsa 2025-09-27 20:01:08.947160 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/9ce680391a404e7ebec2416e6f974baa/work/9ce680391a404e7ebec2416e6f974baa_id_rsa.pub 2025-09-27 20:01:08.947202 | debian-bookworm -> localhost | The key fingerprint is: 2025-09-27 20:01:08.947227 | debian-bookworm -> localhost | SHA256:LoZ8g3qNjWDxR1SMXaaxEHyakACv7Osd+BQ6gbleRh4 zuul-build-sshkey 2025-09-27 20:01:08.947269 | debian-bookworm -> localhost | The key's randomart image is: 2025-09-27 20:01:08.947292 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-09-27 20:01:08.947313 | debian-bookworm -> localhost | |.... oo*o.o | 2025-09-27 20:01:08.947334 | debian-bookworm -> localhost | | . o +.+= | 2025-09-27 20:01:08.947355 | debian-bookworm -> localhost | | . o +o | 2025-09-27 20:01:08.947375 | debian-bookworm -> localhost | |oo. + | 2025-09-27 20:01:08.947395 | debian-bookworm -> localhost | |+o E . S | 2025-09-27 20:01:08.947423 | debian-bookworm -> localhost | |..O.+o.. | 2025-09-27 20:01:08.947444 | debian-bookworm -> localhost | |.* B+*= . | 2025-09-27 20:01:08.947464 | debian-bookworm -> localhost | |. O.=ooo | 2025-09-27 20:01:08.947485 | debian-bookworm -> localhost | |.+.+ | 2025-09-27 20:01:08.947505 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-09-27 20:01:08.947556 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.487383 2025-09-27 20:01:08.954219 | 2025-09-27 20:01:08.954339 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-09-27 20:01:08.982639 | debian-bookworm | ok 2025-09-27 20:01:08.992441 | debian-bookworm | included: /var/lib/zuul/builds/9ce680391a404e7ebec2416e6f974baa/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-09-27 20:01:09.001753 | 2025-09-27 20:01:09.001849 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-09-27 20:01:09.025212 | debian-bookworm | skipping: Conditional result was False 2025-09-27 20:01:09.031437 | 2025-09-27 20:01:09.031556 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-09-27 20:01:09.712152 | debian-bookworm | changed 2025-09-27 20:01:09.737141 | 2025-09-27 20:01:09.737380 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-09-27 20:01:10.003602 | debian-bookworm | ok 2025-09-27 20:01:10.020937 | 2025-09-27 20:01:10.021063 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-09-27 20:01:10.677344 | debian-bookworm | changed 2025-09-27 20:01:10.684359 | 2025-09-27 20:01:10.684468 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-09-27 20:01:11.326277 | debian-bookworm | changed 2025-09-27 20:01:11.333688 | 2025-09-27 20:01:11.333815 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-09-27 20:01:11.357609 | debian-bookworm | skipping: Conditional result was False 2025-09-27 20:01:11.363810 | 2025-09-27 20:01:11.363916 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-09-27 20:01:11.825755 | debian-bookworm -> localhost | changed 2025-09-27 20:01:11.839632 | 2025-09-27 20:01:11.839767 | TASK [add-build-sshkey : Add back temp key] 2025-09-27 20:01:12.202540 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/9ce680391a404e7ebec2416e6f974baa/work/9ce680391a404e7ebec2416e6f974baa_id_rsa (zuul-build-sshkey) 2025-09-27 20:01:12.202789 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.016387 2025-09-27 20:01:12.212341 | 2025-09-27 20:01:12.212463 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-09-27 20:01:12.639608 | debian-bookworm | ok 2025-09-27 20:01:12.647539 | 2025-09-27 20:01:12.647673 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-09-27 20:01:12.672168 | debian-bookworm | skipping: Conditional result was False 2025-09-27 20:01:12.682815 | 2025-09-27 20:01:12.682954 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-09-27 20:01:13.107397 | debian-bookworm | ok 2025-09-27 20:01:13.118544 | 2025-09-27 20:01:13.118654 | TASK [validate-host : Define zuul_info_dir fact] 2025-09-27 20:01:13.157754 | debian-bookworm | ok 2025-09-27 20:01:13.162825 | 2025-09-27 20:01:13.162959 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-09-27 20:01:13.441650 | debian-bookworm -> localhost | ok 2025-09-27 20:01:13.448415 | 2025-09-27 20:01:13.448521 | TASK [validate-host : Collect information about the host] 2025-09-27 20:01:14.548642 | debian-bookworm | ok 2025-09-27 20:01:14.559011 | 2025-09-27 20:01:14.559133 | TASK [validate-host : Sanitize hostname] 2025-09-27 20:01:14.660919 | debian-bookworm | ok 2025-09-27 20:01:14.698482 | 2025-09-27 20:01:14.698600 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-09-27 20:01:15.220392 | debian-bookworm -> localhost | changed 2025-09-27 20:01:15.231619 | 2025-09-27 20:01:15.231851 | TASK [validate-host : Collect information about zuul worker] 2025-09-27 20:01:15.632855 | debian-bookworm | ok 2025-09-27 20:01:15.640519 | 2025-09-27 20:01:15.640659 | TASK [validate-host : Write out all zuul information for each host] 2025-09-27 20:01:16.200963 | debian-bookworm -> localhost | changed 2025-09-27 20:01:16.211131 | 2025-09-27 20:01:16.211286 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-09-27 20:01:16.490531 | debian-bookworm | ok 2025-09-27 20:01:16.499683 | 2025-09-27 20:01:16.499812 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-09-27 20:01:17.756816 | debian-bookworm | changed: 2025-09-27 20:01:17.757073 | debian-bookworm | cd+++++++++ src/ 2025-09-27 20:01:17.757116 | debian-bookworm | cd+++++++++ src/github.com/ 2025-09-27 20:01:17.757141 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-09-27 20:01:17.757164 | debian-bookworm | cd+++++++++ src/github.com/osism/container-image-kolla-ansible/ 2025-09-27 20:01:17.757187 | debian-bookworm | Containerfile 2025-09-27 20:01:17.757377 | debian-bookworm | 2025-09-27 20:19:14.327993 | debian-bookworm | ERROR 2025-09-27 20:19:14.328209 | debian-bookworm | { 2025-09-27 20:19:14.328250 | debian-bookworm | "delta": "0:00:21.324235", 2025-09-27 20:19:14.328275 | debian-bookworm | "end": "2025-09-27 20:19:13.903443", 2025-09-27 20:19:14.328300 | debian-bookworm | "msg": "non-zero return code", 2025-09-27 20:19:14.328342 | debian-bookworm | "rc": 1, 2025-09-27 20:19:14.328362 | debian-bookworm | "start": "2025-09-27 20:18:52.579208" 2025-09-27 20:19:14.328381 | debian-bookworm | } failure 2025-09-27 20:19:14.330155 | 2025-09-27 20:19:14.330234 | PLAY RECAP 2025-09-27 20:19:14.330289 | debian-bookworm | ok: 4 changed: 3 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2025-09-27 20:19:14.330337 | 2025-09-27 20:19:14.466567 | RUN END RESULT_NORMAL: [untrusted : github.com/osism/container-image-kolla-ansible/playbooks/build.yml@main] 2025-09-27 20:19:14.468335 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-09-27 20:19:15.508924 | 2025-09-27 20:19:15.509074 | PLAY [Base post] 2025-09-27 20:19:15.522596 | 2025-09-27 20:19:15.522719 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-09-27 20:19:16.372748 | debian-bookworm | changed 2025-09-27 20:19:16.379440 | 2025-09-27 20:19:16.379555 | PLAY RECAP 2025-09-27 20:19:16.379620 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-09-27 20:19:16.379685 | 2025-09-27 20:19:16.502696 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-09-27 20:19:16.507748 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2025-09-27 20:19:17.384028 | 2025-09-27 20:19:17.384203 | PLAY [Base post-logs] 2025-09-27 20:19:17.395532 | 2025-09-27 20:19:17.395671 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-09-27 20:19:17.863882 | localhost | changed 2025-09-27 20:19:17.882604 | 2025-09-27 20:19:17.882797 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-09-27 20:19:17.911270 | localhost | ok 2025-09-27 20:19:17.917613 | 2025-09-27 20:19:17.917766 | TASK [Set zuul-log-path fact] 2025-09-27 20:19:17.935806 | localhost | ok 2025-09-27 20:19:17.951953 | 2025-09-27 20:19:17.952092 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-09-27 20:19:17.977718 | localhost | ok 2025-09-27 20:19:17.980809 | 2025-09-27 20:19:17.980914 | TASK [upload-logs : Create log directories] 2025-09-27 20:19:18.493997 | localhost | changed 2025-09-27 20:19:18.497009 | 2025-09-27 20:19:18.497123 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-09-27 20:19:18.980370 | localhost -> localhost | ok: Runtime: 0:00:00.005913 2025-09-27 20:19:18.989294 | 2025-09-27 20:19:18.989477 | TASK [upload-logs : Upload logs to log server] 2025-09-27 20:19:19.572000 | localhost | Output suppressed because no_log was given 2025-09-27 20:19:19.578971 | 2025-09-27 20:19:19.579139 | LOOP [upload-logs : Compress console log and json output] 2025-09-27 20:19:19.637375 | localhost | skipping: Conditional result was False 2025-09-27 20:19:19.642335 | localhost | skipping: Conditional result was False 2025-09-27 20:19:19.647630 | 2025-09-27 20:19:19.647741 | LOOP [upload-logs : Upload compressed console log and json output] 2025-09-27 20:19:19.701540 | localhost | skipping: Conditional result was False 2025-09-27 20:19:19.701845 | 2025-09-27 20:19:19.708542 | localhost | skipping: Conditional result was False 2025-09-27 20:19:19.714893 | 2025-09-27 20:19:19.715004 | LOOP [upload-logs : Upload console log and json output]