2025-09-23 12:25:57.737595 | Job console starting 2025-09-23 12:25:57.764822 | Updating git repos 2025-09-23 12:25:57.825516 | Cloning repos into workspace 2025-09-23 12:25:57.911350 | Restoring repo states 2025-09-23 12:25:57.930413 | Merging changes 2025-09-23 12:25:57.930449 | Checking out repos 2025-09-23 12:25:58.024932 | Preparing playbooks 2025-09-23 12:25:58.780583 | Running Ansible setup 2025-09-23 12:26:02.910744 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-09-23 12:26:03.650378 | 2025-09-23 12:26:03.650546 | PLAY [Base pre] 2025-09-23 12:26:03.666934 | 2025-09-23 12:26:03.667112 | TASK [Setup log path fact] 2025-09-23 12:26:03.686976 | debian-bookworm | ok 2025-09-23 12:26:03.703915 | 2025-09-23 12:26:03.704073 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-09-23 12:26:03.733436 | debian-bookworm | ok 2025-09-23 12:26:03.744228 | 2025-09-23 12:26:03.744344 | TASK [emit-job-header : Print job information] 2025-09-23 12:26:03.782763 | # Job Information 2025-09-23 12:26:03.782992 | Ansible Version: 2.16.14 2025-09-23 12:26:03.783066 | Job: container-image-kolla-ansible-push-2024.1 2025-09-23 12:26:03.783103 | Pipeline: post 2025-09-23 12:26:03.783127 | Executor: 521e9411259a 2025-09-23 12:26:03.783148 | Triggered by: https://github.com/osism/container-image-kolla-ansible/commit/0a1ecf1e78d29dc7764e06184ae6e2670d6d6e34 2025-09-23 12:26:03.783171 | Event ID: 727f6442-9878-11f0-8da8-f500392d0af1 2025-09-23 12:26:03.789154 | 2025-09-23 12:26:03.789271 | LOOP [emit-job-header : Print node information] 2025-09-23 12:26:03.892221 | debian-bookworm | ok: 2025-09-23 12:26:03.892467 | debian-bookworm | # Node Information 2025-09-23 12:26:03.892502 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-09-23 12:26:03.892527 | debian-bookworm | Hostname: debian 2025-09-23 12:26:03.892548 | debian-bookworm | Username: zuul 2025-09-23 12:26:03.892569 | debian-bookworm | Distro: Debian 12.12 2025-09-23 12:26:03.892592 | debian-bookworm | Provider: regiocloud-a 2025-09-23 12:26:03.892613 | debian-bookworm | Region: 2025-09-23 12:26:03.892634 | debian-bookworm | Label: debian-bookworm 2025-09-23 12:26:03.892654 | debian-bookworm | Product Name: OpenStack Nova 2025-09-23 12:26:03.892673 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:feaf:48c6 2025-09-23 12:26:03.907918 | 2025-09-23 12:26:03.908096 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-09-23 12:26:04.379141 | debian-bookworm -> localhost | changed 2025-09-23 12:26:04.386590 | 2025-09-23 12:26:04.386714 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-09-23 12:26:05.403869 | debian-bookworm -> localhost | changed 2025-09-23 12:26:05.415116 | 2025-09-23 12:26:05.415229 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-09-23 12:26:05.687558 | debian-bookworm -> localhost | ok 2025-09-23 12:26:05.693897 | 2025-09-23 12:26:05.694017 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-09-23 12:26:05.723851 | debian-bookworm | ok 2025-09-23 12:26:05.742241 | debian-bookworm | included: /var/lib/zuul/builds/c9f3156fd4b34e04bb2e3ee0dd070c5a/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-09-23 12:26:05.748734 | 2025-09-23 12:26:05.748839 | TASK [add-build-sshkey : Create Temp SSH key] 2025-09-23 12:26:08.969614 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-09-23 12:26:08.969872 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/c9f3156fd4b34e04bb2e3ee0dd070c5a/work/c9f3156fd4b34e04bb2e3ee0dd070c5a_id_rsa 2025-09-23 12:26:08.969913 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/c9f3156fd4b34e04bb2e3ee0dd070c5a/work/c9f3156fd4b34e04bb2e3ee0dd070c5a_id_rsa.pub 2025-09-23 12:26:08.969941 | debian-bookworm -> localhost | The key fingerprint is: 2025-09-23 12:26:08.969965 | debian-bookworm -> localhost | SHA256:ZuSo6IkTRUMcoSB7Qs8OfwLZR0coUre1zUX54yw9mCE zuul-build-sshkey 2025-09-23 12:26:08.970000 | debian-bookworm -> localhost | The key's randomart image is: 2025-09-23 12:26:08.970042 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-09-23 12:26:08.970065 | debian-bookworm -> localhost | |o+=+ oo+ .o. | 2025-09-23 12:26:08.970087 | debian-bookworm -> localhost | |++O.o.+ + .. | 2025-09-23 12:26:08.970107 | debian-bookworm -> localhost | |+=o=.o ..o . | 2025-09-23 12:26:08.970127 | debian-bookworm -> localhost | | o* . + E . o | 2025-09-23 12:26:08.970146 | debian-bookworm -> localhost | | . + .. S . B . | 2025-09-23 12:26:08.970171 | debian-bookworm -> localhost | |. .o. o + = | 2025-09-23 12:26:08.970191 | debian-bookworm -> localhost | | .. . . . | 2025-09-23 12:26:08.970210 | debian-bookworm -> localhost | |.o . | 2025-09-23 12:26:08.970231 | debian-bookworm -> localhost | |..o | 2025-09-23 12:26:08.970251 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-09-23 12:26:08.970306 | debian-bookworm -> localhost | ok: Runtime: 0:00:02.731714 2025-09-23 12:26:08.977059 | 2025-09-23 12:26:08.977173 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-09-23 12:26:09.005683 | debian-bookworm | ok 2025-09-23 12:26:09.015373 | debian-bookworm | included: /var/lib/zuul/builds/c9f3156fd4b34e04bb2e3ee0dd070c5a/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-09-23 12:26:09.024711 | 2025-09-23 12:26:09.024817 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-09-23 12:26:09.058994 | debian-bookworm | skipping: Conditional result was False 2025-09-23 12:26:09.065372 | 2025-09-23 12:26:09.065494 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-09-23 12:26:09.721427 | debian-bookworm | changed 2025-09-23 12:26:09.727116 | 2025-09-23 12:26:09.727244 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-09-23 12:26:09.989102 | debian-bookworm | ok 2025-09-23 12:26:09.995211 | 2025-09-23 12:26:09.995323 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-09-23 12:26:10.651262 | debian-bookworm | changed 2025-09-23 12:26:10.661447 | 2025-09-23 12:26:10.661586 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-09-23 12:26:11.368181 | debian-bookworm | changed 2025-09-23 12:26:11.373804 | 2025-09-23 12:26:11.373911 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-09-23 12:26:11.397363 | debian-bookworm | skipping: Conditional result was False 2025-09-23 12:26:11.403489 | 2025-09-23 12:26:11.403595 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-09-23 12:26:11.832009 | debian-bookworm -> localhost | changed 2025-09-23 12:26:11.855334 | 2025-09-23 12:26:11.855535 | TASK [add-build-sshkey : Add back temp key] 2025-09-23 12:26:12.196191 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/c9f3156fd4b34e04bb2e3ee0dd070c5a/work/c9f3156fd4b34e04bb2e3ee0dd070c5a_id_rsa (zuul-build-sshkey) 2025-09-23 12:26:12.196445 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.023597 2025-09-23 12:26:12.204780 | 2025-09-23 12:26:12.204892 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-09-23 12:26:12.615304 | debian-bookworm | ok 2025-09-23 12:26:12.622950 | 2025-09-23 12:26:12.623123 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-09-23 12:26:12.667718 | debian-bookworm | skipping: Conditional result was False 2025-09-23 12:26:12.684268 | 2025-09-23 12:26:12.684398 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-09-23 12:26:13.137490 | debian-bookworm | ok 2025-09-23 12:26:13.155889 | 2025-09-23 12:26:13.156163 | TASK [validate-host : Define zuul_info_dir fact] 2025-09-23 12:26:13.205004 | debian-bookworm | ok 2025-09-23 12:26:13.213219 | 2025-09-23 12:26:13.213353 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-09-23 12:26:13.540868 | debian-bookworm -> localhost | ok 2025-09-23 12:26:13.547765 | 2025-09-23 12:26:13.547875 | TASK [validate-host : Collect information about the host] 2025-09-23 12:26:14.634194 | debian-bookworm | ok 2025-09-23 12:26:14.646561 | 2025-09-23 12:26:14.646670 | TASK [validate-host : Sanitize hostname] 2025-09-23 12:26:14.752291 | debian-bookworm | ok 2025-09-23 12:26:14.815158 | 2025-09-23 12:26:14.815297 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-09-23 12:26:15.396086 | debian-bookworm -> localhost | changed 2025-09-23 12:26:15.417825 | 2025-09-23 12:26:15.417961 | TASK [validate-host : Collect information about zuul worker] 2025-09-23 12:26:15.831966 | debian-bookworm | ok 2025-09-23 12:26:15.837226 | 2025-09-23 12:26:15.837350 | TASK [validate-host : Write out all zuul information for each host] 2025-09-23 12:26:16.411698 | debian-bookworm -> localhost | changed 2025-09-23 12:26:16.430427 | 2025-09-23 12:26:16.430577 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-09-23 12:26:16.740902 | debian-bookworm | ok 2025-09-23 12:26:16.750781 | 2025-09-23 12:26:16.750949 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-09-23 12:26:17.577826 | debian-bookworm | changed: 2025-09-23 12:26:17.578098 | debian-bookworm | cd+++++++++ src/ 2025-09-23 12:26:17.578139 | debian-bookworm | cd+++++++++ src/github.com/ 2025-09-23 12:26:17.578164 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-09-23 12:26:17.578186 | debian-bookworm | cd+++++++++ src/github.com/osism/container-image-kolla-ansible/ 2025-09-23 12:26:17.578207 | debian-bookworm | Containerfile 2025-09-23 12:26:17.578340 | debian-bookworm | 2025-09-23 12:32:13.561170 | debian-bookworm | ERROR 2025-09-23 12:32:13.561563 | debian-bookworm | { 2025-09-23 12:32:13.561654 | debian-bookworm | "delta": "0:00:20.581166", 2025-09-23 12:32:13.561714 | debian-bookworm | "end": "2025-09-23 12:32:13.389490", 2025-09-23 12:32:13.561779 | debian-bookworm | "msg": "non-zero return code", 2025-09-23 12:32:13.561830 | debian-bookworm | "rc": 1, 2025-09-23 12:32:13.561877 | debian-bookworm | "start": "2025-09-23 12:31:52.808324" 2025-09-23 12:32:13.561922 | debian-bookworm | } failure 2025-09-23 12:32:13.566322 | 2025-09-23 12:32:13.566494 | PLAY RECAP 2025-09-23 12:32:13.566648 | debian-bookworm | ok: 4 changed: 3 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2025-09-23 12:32:13.566724 | 2025-09-23 12:32:13.708404 | RUN END RESULT_NORMAL: [untrusted : github.com/osism/container-image-kolla-ansible/playbooks/build.yml@main] 2025-09-23 12:32:13.711016 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-09-23 12:32:14.454493 | 2025-09-23 12:32:14.454649 | PLAY [Base post] 2025-09-23 12:32:14.468457 | 2025-09-23 12:32:14.468584 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-09-23 12:32:15.304356 | debian-bookworm | changed 2025-09-23 12:32:15.314511 | 2025-09-23 12:32:15.314623 | PLAY RECAP 2025-09-23 12:32:15.314690 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-09-23 12:32:15.314758 | 2025-09-23 12:32:15.425491 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-09-23 12:32:15.426544 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2025-09-23 12:32:16.187848 | 2025-09-23 12:32:16.188005 | PLAY [Base post-logs] 2025-09-23 12:32:16.198588 | 2025-09-23 12:32:16.198706 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-09-23 12:32:16.652714 | localhost | changed 2025-09-23 12:32:16.668989 | 2025-09-23 12:32:16.669169 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-09-23 12:32:16.707783 | localhost | ok 2025-09-23 12:32:16.715095 | 2025-09-23 12:32:16.715320 | TASK [Set zuul-log-path fact] 2025-09-23 12:32:16.733467 | localhost | ok 2025-09-23 12:32:16.745635 | 2025-09-23 12:32:16.745763 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-09-23 12:32:16.782110 | localhost | ok 2025-09-23 12:32:16.788348 | 2025-09-23 12:32:16.788522 | TASK [upload-logs : Create log directories] 2025-09-23 12:32:17.293560 | localhost | changed 2025-09-23 12:32:17.297926 | 2025-09-23 12:32:17.298068 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-09-23 12:32:17.788603 | localhost -> localhost | ok: Runtime: 0:00:00.006631 2025-09-23 12:32:17.792887 | 2025-09-23 12:32:17.793010 | TASK [upload-logs : Upload logs to log server] 2025-09-23 12:32:18.322113 | localhost | Output suppressed because no_log was given 2025-09-23 12:32:18.325660 | 2025-09-23 12:32:18.325826 | LOOP [upload-logs : Compress console log and json output] 2025-09-23 12:32:18.381928 | localhost | skipping: Conditional result was False 2025-09-23 12:32:18.387014 | localhost | skipping: Conditional result was False 2025-09-23 12:32:18.399459 | 2025-09-23 12:32:18.399680 | LOOP [upload-logs : Upload compressed console log and json output] 2025-09-23 12:32:18.445804 | localhost | skipping: Conditional result was False 2025-09-23 12:32:18.446506 | 2025-09-23 12:32:18.449958 | localhost | skipping: Conditional result was False 2025-09-23 12:32:18.463399 | 2025-09-23 12:32:18.463635 | LOOP [upload-logs : Upload console log and json output]