2025-10-04 17:37:47.564189 | Job console starting 2025-10-04 17:37:47.612766 | Updating git repos 2025-10-04 17:37:47.672500 | Cloning repos into workspace 2025-10-04 17:37:47.771600 | Restoring repo states 2025-10-04 17:37:47.790131 | Merging changes 2025-10-04 17:37:47.790153 | Checking out repos 2025-10-04 17:37:47.899894 | Preparing playbooks 2025-10-04 17:37:48.531326 | Running Ansible setup 2025-10-04 17:37:52.732834 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-10-04 17:37:53.514344 | 2025-10-04 17:37:53.514508 | PLAY [Base pre] 2025-10-04 17:37:53.530808 | 2025-10-04 17:37:53.530972 | TASK [Setup log path fact] 2025-10-04 17:37:53.560324 | debian-bookworm | ok 2025-10-04 17:37:53.577767 | 2025-10-04 17:37:53.577916 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-04 17:37:53.607868 | debian-bookworm | ok 2025-10-04 17:37:53.618033 | 2025-10-04 17:37:53.618173 | TASK [emit-job-header : Print job information] 2025-10-04 17:37:53.657258 | # Job Information 2025-10-04 17:37:53.657429 | Ansible Version: 2.16.14 2025-10-04 17:37:53.657463 | Job: container-image-kolla-ansible-push-2025.1 2025-10-04 17:37:53.657496 | Pipeline: post 2025-10-04 17:37:53.657518 | Executor: 521e9411259a 2025-10-04 17:37:53.657538 | Triggered by: https://github.com/osism/container-image-kolla-ansible 2025-10-04 17:37:53.657559 | Event ID: b587e8a4cfc84fc98775230ec8350d96 2025-10-04 17:37:53.662488 | 2025-10-04 17:37:53.662599 | LOOP [emit-job-header : Print node information] 2025-10-04 17:37:53.764200 | debian-bookworm | ok: 2025-10-04 17:37:53.764386 | debian-bookworm | # Node Information 2025-10-04 17:37:53.764422 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-10-04 17:37:53.764447 | debian-bookworm | Hostname: debian 2025-10-04 17:37:53.764469 | debian-bookworm | Username: zuul 2025-10-04 17:37:53.764490 | debian-bookworm | Distro: Debian 12.12 2025-10-04 17:37:53.764513 | debian-bookworm | Provider: regiocloud-a 2025-10-04 17:37:53.764534 | debian-bookworm | Region: 2025-10-04 17:37:53.764555 | debian-bookworm | Label: debian-bookworm 2025-10-04 17:37:53.764575 | debian-bookworm | Product Name: OpenStack Nova 2025-10-04 17:37:53.764594 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe97:a364 2025-10-04 17:37:53.783063 | 2025-10-04 17:37:53.783190 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-10-04 17:37:54.347297 | debian-bookworm -> localhost | changed 2025-10-04 17:37:54.355292 | 2025-10-04 17:37:54.355419 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-10-04 17:37:56.193678 | debian-bookworm -> localhost | changed 2025-10-04 17:37:56.219387 | 2025-10-04 17:37:56.220397 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-10-04 17:37:56.948537 | debian-bookworm -> localhost | ok 2025-10-04 17:37:56.953498 | 2025-10-04 17:37:56.953588 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-10-04 17:37:56.989788 | debian-bookworm | ok 2025-10-04 17:37:57.002926 | debian-bookworm | included: /var/lib/zuul/builds/7c331a2b1c31476a89aa02ce5e82ccad/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-10-04 17:37:57.007867 | 2025-10-04 17:37:57.007937 | TASK [add-build-sshkey : Create Temp SSH key] 2025-10-04 17:37:58.045253 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-10-04 17:37:58.045423 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/7c331a2b1c31476a89aa02ce5e82ccad/work/7c331a2b1c31476a89aa02ce5e82ccad_id_rsa 2025-10-04 17:37:58.045455 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/7c331a2b1c31476a89aa02ce5e82ccad/work/7c331a2b1c31476a89aa02ce5e82ccad_id_rsa.pub 2025-10-04 17:37:58.045475 | debian-bookworm -> localhost | The key fingerprint is: 2025-10-04 17:37:58.045494 | debian-bookworm -> localhost | SHA256:1ktSCBJIsKdX5/TYGeBrGEkd0wO/9D6qmn6WnkLZMLo zuul-build-sshkey 2025-10-04 17:37:58.045525 | debian-bookworm -> localhost | The key's randomart image is: 2025-10-04 17:37:58.045545 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-10-04 17:37:58.045562 | debian-bookworm -> localhost | |.o..+o*+ | 2025-10-04 17:37:58.045579 | debian-bookworm -> localhost | | ... +.=o. | 2025-10-04 17:37:58.045596 | debian-bookworm -> localhost | |. . + + =.. | 2025-10-04 17:37:58.045612 | debian-bookworm -> localhost | | o .o* * B | 2025-10-04 17:37:58.045635 | debian-bookworm -> localhost | |. ...== S + | 2025-10-04 17:37:58.045655 | debian-bookworm -> localhost | | .. o... + . | 2025-10-04 17:37:58.045672 | debian-bookworm -> localhost | | o . + | 2025-10-04 17:37:58.045688 | debian-bookworm -> localhost | | E ..+. . . | 2025-10-04 17:37:58.045705 | debian-bookworm -> localhost | | .+*+.. | 2025-10-04 17:37:58.045720 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-10-04 17:37:58.045762 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.694384 2025-10-04 17:37:58.050979 | 2025-10-04 17:37:58.051080 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-10-04 17:37:58.066859 | debian-bookworm | ok 2025-10-04 17:37:58.073967 | debian-bookworm | included: /var/lib/zuul/builds/7c331a2b1c31476a89aa02ce5e82ccad/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-10-04 17:37:58.080912 | 2025-10-04 17:37:58.081033 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-10-04 17:37:58.093039 | debian-bookworm | skipping: Conditional result was False 2025-10-04 17:37:58.097451 | 2025-10-04 17:37:58.097519 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-10-04 17:37:58.653287 | debian-bookworm | changed 2025-10-04 17:37:58.657548 | 2025-10-04 17:37:58.657622 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-10-04 17:37:58.920625 | debian-bookworm | ok 2025-10-04 17:37:58.926363 | 2025-10-04 17:37:58.926455 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-10-04 17:37:59.509284 | debian-bookworm | changed 2025-10-04 17:37:59.515758 | 2025-10-04 17:37:59.515852 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-10-04 17:38:00.095148 | debian-bookworm | changed 2025-10-04 17:38:00.102664 | 2025-10-04 17:38:00.102771 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-10-04 17:38:00.125658 | debian-bookworm | skipping: Conditional result was False 2025-10-04 17:38:00.131421 | 2025-10-04 17:38:00.131697 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-10-04 17:38:00.497903 | debian-bookworm -> localhost | changed 2025-10-04 17:38:00.508620 | 2025-10-04 17:38:00.508724 | TASK [add-build-sshkey : Add back temp key] 2025-10-04 17:38:00.845806 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/7c331a2b1c31476a89aa02ce5e82ccad/work/7c331a2b1c31476a89aa02ce5e82ccad_id_rsa (zuul-build-sshkey) 2025-10-04 17:38:00.845983 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.023288 2025-10-04 17:38:00.852355 | 2025-10-04 17:38:00.852433 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-10-04 17:38:01.383296 | debian-bookworm | ok 2025-10-04 17:38:01.387310 | 2025-10-04 17:38:01.387384 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-10-04 17:38:01.399857 | debian-bookworm | skipping: Conditional result was False 2025-10-04 17:38:01.408579 | 2025-10-04 17:38:01.408668 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-10-04 17:38:01.790212 | debian-bookworm | ok 2025-10-04 17:38:01.798692 | 2025-10-04 17:38:01.798772 | TASK [validate-host : Define zuul_info_dir fact] 2025-10-04 17:38:01.839691 | debian-bookworm | ok 2025-10-04 17:38:01.843996 | 2025-10-04 17:38:01.844101 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-10-04 17:38:02.139763 | debian-bookworm -> localhost | ok 2025-10-04 17:38:02.144913 | 2025-10-04 17:38:02.144987 | TASK [validate-host : Collect information about the host] 2025-10-04 17:38:03.291079 | debian-bookworm | ok 2025-10-04 17:38:03.313954 | 2025-10-04 17:38:03.314046 | TASK [validate-host : Sanitize hostname] 2025-10-04 17:38:03.395916 | debian-bookworm | ok 2025-10-04 17:38:03.422111 | 2025-10-04 17:38:03.422231 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-10-04 17:38:04.141823 | debian-bookworm -> localhost | changed 2025-10-04 17:38:04.146280 | 2025-10-04 17:38:04.146352 | TASK [validate-host : Collect information about zuul worker] 2025-10-04 17:38:04.505473 | debian-bookworm | ok 2025-10-04 17:38:04.509203 | 2025-10-04 17:38:04.509280 | TASK [validate-host : Write out all zuul information for each host] 2025-10-04 17:38:05.097940 | debian-bookworm -> localhost | changed 2025-10-04 17:38:05.105271 | 2025-10-04 17:38:05.105351 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-10-04 17:38:05.362922 | debian-bookworm | ok 2025-10-04 17:38:05.371217 | 2025-10-04 17:38:05.371359 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-10-04 17:38:06.439430 | debian-bookworm | changed: 2025-10-04 17:38:06.439597 | debian-bookworm | cd+++++++++ src/ 2025-10-04 17:38:06.439631 | debian-bookworm | cd+++++++++ src/github.com/ 2025-10-04 17:38:06.439656 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-10-04 17:38:06.439677 | debian-bookworm | cd+++++++++ src/github.com/osism/container-image-kolla-ansible/ 2025-10-04 17:38:06.439697 | debian-bookworm | Containerfile 2025-10-04 17:38:06.439882 | debian-bookworm | 2025-10-04 17:48:15.369718 | debian-bookworm | ERROR 2025-10-04 17:48:15.370183 | debian-bookworm | { 2025-10-04 17:48:15.370325 | debian-bookworm | "delta": "0:00:20.949093", 2025-10-04 17:48:15.370399 | debian-bookworm | "end": "2025-10-04 17:48:15.026378", 2025-10-04 17:48:15.370472 | debian-bookworm | "msg": "non-zero return code", 2025-10-04 17:48:15.370529 | debian-bookworm | "rc": 1, 2025-10-04 17:48:15.370583 | debian-bookworm | "start": "2025-10-04 17:47:54.077285" 2025-10-04 17:48:15.370634 | debian-bookworm | } failure 2025-10-04 17:48:15.374447 | 2025-10-04 17:48:15.374585 | PLAY RECAP 2025-10-04 17:48:15.374728 | debian-bookworm | ok: 4 changed: 3 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2025-10-04 17:48:15.374815 | 2025-10-04 17:48:15.505040 | RUN END RESULT_NORMAL: [untrusted : github.com/osism/container-image-kolla-ansible/playbooks/build.yml@main] 2025-10-04 17:48:15.508047 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-10-04 17:48:16.226558 | 2025-10-04 17:48:16.226798 | PLAY [Base post] 2025-10-04 17:48:16.240568 | 2025-10-04 17:48:16.240696 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-10-04 17:48:17.048071 | debian-bookworm | changed 2025-10-04 17:48:17.056268 | 2025-10-04 17:48:17.056382 | PLAY RECAP 2025-10-04 17:48:17.056464 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-10-04 17:48:17.056556 | 2025-10-04 17:48:17.166311 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-10-04 17:48:17.168921 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2025-10-04 17:48:17.926039 | 2025-10-04 17:48:17.926200 | PLAY [Base post-logs] 2025-10-04 17:48:17.936972 | 2025-10-04 17:48:17.937162 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-10-04 17:48:18.401309 | localhost | changed 2025-10-04 17:48:18.416824 | 2025-10-04 17:48:18.416987 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-10-04 17:48:18.453777 | localhost | ok 2025-10-04 17:48:18.458768 | 2025-10-04 17:48:18.458930 | TASK [Set zuul-log-path fact] 2025-10-04 17:48:18.475182 | localhost | ok 2025-10-04 17:48:18.484858 | 2025-10-04 17:48:18.484969 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-04 17:48:18.510182 | localhost | ok 2025-10-04 17:48:18.514368 | 2025-10-04 17:48:18.514495 | TASK [upload-logs : Create log directories] 2025-10-04 17:48:18.994038 | localhost | changed 2025-10-04 17:48:19.000637 | 2025-10-04 17:48:19.000860 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-10-04 17:48:19.493587 | localhost -> localhost | ok: Runtime: 0:00:00.006830 2025-10-04 17:48:19.497743 | 2025-10-04 17:48:19.497852 | TASK [upload-logs : Upload logs to log server] 2025-10-04 17:48:20.051038 | localhost | Output suppressed because no_log was given 2025-10-04 17:48:20.053410 | 2025-10-04 17:48:20.053533 | LOOP [upload-logs : Compress console log and json output] 2025-10-04 17:48:20.109731 | localhost | skipping: Conditional result was False 2025-10-04 17:48:20.114769 | localhost | skipping: Conditional result was False 2025-10-04 17:48:20.127700 | 2025-10-04 17:48:20.127946 | LOOP [upload-logs : Upload compressed console log and json output] 2025-10-04 17:48:20.172154 | localhost | skipping: Conditional result was False 2025-10-04 17:48:20.172759 | 2025-10-04 17:48:20.176618 | localhost | skipping: Conditional result was False 2025-10-04 17:48:20.189616 | 2025-10-04 17:48:20.189833 | LOOP [upload-logs : Upload console log and json output]