2025-09-23 12:25:57.805585 | Job console starting 2025-09-23 12:25:57.830328 | Updating git repos 2025-09-23 12:25:57.865324 | Cloning repos into workspace 2025-09-23 12:25:57.970179 | Restoring repo states 2025-09-23 12:25:57.983577 | Merging changes 2025-09-23 12:25:57.983597 | Checking out repos 2025-09-23 12:25:58.048585 | Preparing playbooks 2025-09-23 12:25:58.813314 | Running Ansible setup 2025-09-23 12:26:02.967742 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-09-23 12:26:03.751539 | 2025-09-23 12:26:03.751693 | PLAY [Base pre] 2025-09-23 12:26:03.768249 | 2025-09-23 12:26:03.768398 | TASK [Setup log path fact] 2025-09-23 12:26:03.787587 | debian-bookworm | ok 2025-09-23 12:26:03.803734 | 2025-09-23 12:26:03.803877 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-09-23 12:26:03.833750 | debian-bookworm | ok 2025-09-23 12:26:03.843661 | 2025-09-23 12:26:03.843774 | TASK [emit-job-header : Print job information] 2025-09-23 12:26:03.883941 | # Job Information 2025-09-23 12:26:03.884148 | Ansible Version: 2.16.14 2025-09-23 12:26:03.884195 | Job: container-image-kolla-ansible-push-2024.2 2025-09-23 12:26:03.884239 | Pipeline: post 2025-09-23 12:26:03.884269 | Executor: 521e9411259a 2025-09-23 12:26:03.884296 | Triggered by: https://github.com/osism/container-image-kolla-ansible/commit/0a1ecf1e78d29dc7764e06184ae6e2670d6d6e34 2025-09-23 12:26:03.884325 | Event ID: 727f6442-9878-11f0-8da8-f500392d0af1 2025-09-23 12:26:03.890397 | 2025-09-23 12:26:03.890515 | LOOP [emit-job-header : Print node information] 2025-09-23 12:26:03.999806 | debian-bookworm | ok: 2025-09-23 12:26:03.999997 | debian-bookworm | # Node Information 2025-09-23 12:26:04.000047 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-09-23 12:26:04.000074 | debian-bookworm | Hostname: debian 2025-09-23 12:26:04.000095 | debian-bookworm | Username: zuul 2025-09-23 12:26:04.000115 | debian-bookworm | Distro: Debian 12.12 2025-09-23 12:26:04.000139 | debian-bookworm | Provider: regiocloud-a 2025-09-23 12:26:04.000160 | debian-bookworm | Region: 2025-09-23 12:26:04.000181 | debian-bookworm | Label: debian-bookworm 2025-09-23 12:26:04.000200 | debian-bookworm | Product Name: OpenStack Nova 2025-09-23 12:26:04.000220 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe02:3194 2025-09-23 12:26:04.014730 | 2025-09-23 12:26:04.014877 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-09-23 12:26:04.453418 | debian-bookworm -> localhost | changed 2025-09-23 12:26:04.460946 | 2025-09-23 12:26:04.461095 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-09-23 12:26:05.468155 | debian-bookworm -> localhost | changed 2025-09-23 12:26:05.479448 | 2025-09-23 12:26:05.479566 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-09-23 12:26:05.765898 | debian-bookworm -> localhost | ok 2025-09-23 12:26:05.773221 | 2025-09-23 12:26:05.773351 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-09-23 12:26:05.801977 | debian-bookworm | ok 2025-09-23 12:26:05.824236 | debian-bookworm | included: /var/lib/zuul/builds/c8d526cd2ab443e0a3408650861a97d7/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.830535 | 2025-09-23 12:26:05.830640 | TASK [add-build-sshkey : Create Temp SSH key] 2025-09-23 12:26:07.226559 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-09-23 12:26:07.226787 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/c8d526cd2ab443e0a3408650861a97d7/work/c8d526cd2ab443e0a3408650861a97d7_id_rsa 2025-09-23 12:26:07.226826 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/c8d526cd2ab443e0a3408650861a97d7/work/c8d526cd2ab443e0a3408650861a97d7_id_rsa.pub 2025-09-23 12:26:07.226871 | debian-bookworm -> localhost | The key fingerprint is: 2025-09-23 12:26:07.226896 | debian-bookworm -> localhost | SHA256:6DXCs69bJ92rk7ntpiMFk/nbjO7n127Iuhq8i5Y3yqc zuul-build-sshkey 2025-09-23 12:26:07.226937 | debian-bookworm -> localhost | The key's randomart image is: 2025-09-23 12:26:07.226959 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-09-23 12:26:07.226980 | debian-bookworm -> localhost | | | 2025-09-23 12:26:07.227001 | debian-bookworm -> localhost | | | 2025-09-23 12:26:07.227020 | debian-bookworm -> localhost | | o | 2025-09-23 12:26:07.227057 | debian-bookworm -> localhost | | . .= | 2025-09-23 12:26:07.227078 | debian-bookworm -> localhost | | = S+ | 2025-09-23 12:26:07.227101 | debian-bookworm -> localhost | | . = +o. | 2025-09-23 12:26:07.227121 | debian-bookworm -> localhost | | o oo=B.. o | 2025-09-23 12:26:07.227141 | debian-bookworm -> localhost | | +++@+=.+ o| 2025-09-23 12:26:07.227162 | debian-bookworm -> localhost | | o+EOX#B+ o.| 2025-09-23 12:26:07.227182 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-09-23 12:26:07.227233 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.899268 2025-09-23 12:26:07.233820 | 2025-09-23 12:26:07.233933 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-09-23 12:26:07.256947 | debian-bookworm | ok 2025-09-23 12:26:07.266507 | debian-bookworm | included: /var/lib/zuul/builds/c8d526cd2ab443e0a3408650861a97d7/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-09-23 12:26:07.275607 | 2025-09-23 12:26:07.275703 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-09-23 12:26:07.289498 | debian-bookworm | skipping: Conditional result was False 2025-09-23 12:26:07.297995 | 2025-09-23 12:26:07.298147 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-09-23 12:26:07.947112 | debian-bookworm | changed 2025-09-23 12:26:07.956156 | 2025-09-23 12:26:07.956280 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-09-23 12:26:08.269513 | debian-bookworm | ok 2025-09-23 12:26:08.278095 | 2025-09-23 12:26:08.278235 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-09-23 12:26:08.926309 | debian-bookworm | changed 2025-09-23 12:26:08.937335 | 2025-09-23 12:26:08.937480 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-09-23 12:26:09.599315 | debian-bookworm | changed 2025-09-23 12:26:09.605895 | 2025-09-23 12:26:09.606007 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-09-23 12:26:09.630072 | debian-bookworm | skipping: Conditional result was False 2025-09-23 12:26:09.637233 | 2025-09-23 12:26:09.637356 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-09-23 12:26:10.089172 | debian-bookworm -> localhost | changed 2025-09-23 12:26:10.101383 | 2025-09-23 12:26:10.101498 | TASK [add-build-sshkey : Add back temp key] 2025-09-23 12:26:10.428254 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/c8d526cd2ab443e0a3408650861a97d7/work/c8d526cd2ab443e0a3408650861a97d7_id_rsa (zuul-build-sshkey) 2025-09-23 12:26:10.428547 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.017449 2025-09-23 12:26:10.436765 | 2025-09-23 12:26:10.436872 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-09-23 12:26:10.835622 | debian-bookworm | ok 2025-09-23 12:26:10.842682 | 2025-09-23 12:26:10.842812 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-09-23 12:26:10.877521 | debian-bookworm | skipping: Conditional result was False 2025-09-23 12:26:10.899750 | 2025-09-23 12:26:10.899911 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-09-23 12:26:11.355704 | debian-bookworm | ok 2025-09-23 12:26:11.380863 | 2025-09-23 12:26:11.380981 | TASK [validate-host : Define zuul_info_dir fact] 2025-09-23 12:26:11.421176 | debian-bookworm | ok 2025-09-23 12:26:11.426313 | 2025-09-23 12:26:11.426417 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-09-23 12:26:11.704410 | debian-bookworm -> localhost | ok 2025-09-23 12:26:11.714372 | 2025-09-23 12:26:11.714498 | TASK [validate-host : Collect information about the host] 2025-09-23 12:26:12.823584 | debian-bookworm | ok 2025-09-23 12:26:12.837210 | 2025-09-23 12:26:12.837349 | TASK [validate-host : Sanitize hostname] 2025-09-23 12:26:12.937589 | debian-bookworm | ok 2025-09-23 12:26:12.974647 | 2025-09-23 12:26:12.974774 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-09-23 12:26:13.585797 | debian-bookworm -> localhost | changed 2025-09-23 12:26:13.591891 | 2025-09-23 12:26:13.592010 | TASK [validate-host : Collect information about zuul worker] 2025-09-23 12:26:14.013067 | debian-bookworm | ok 2025-09-23 12:26:14.019946 | 2025-09-23 12:26:14.020095 | TASK [validate-host : Write out all zuul information for each host] 2025-09-23 12:26:14.572161 | debian-bookworm -> localhost | changed 2025-09-23 12:26:14.582763 | 2025-09-23 12:26:14.582890 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-09-23 12:26:14.878255 | debian-bookworm | ok 2025-09-23 12:26:14.885643 | 2025-09-23 12:26:14.885760 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-09-23 12:26:15.741533 | debian-bookworm | changed: 2025-09-23 12:26:15.741822 | debian-bookworm | cd+++++++++ src/ 2025-09-23 12:26:15.741881 | debian-bookworm | cd+++++++++ src/github.com/ 2025-09-23 12:26:15.741921 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-09-23 12:26:15.741957 | debian-bookworm | cd+++++++++ src/github.com/osism/container-image-kolla-ansible/ 2025-09-23 12:26:15.741989 | debian-bookworm | Containerfile 2025-09-23 12:26:15.742224 | debian-bookworm | 2025-09-23 12:36:18.751994 | debian-bookworm | ERROR 2025-09-23 12:36:18.752405 | debian-bookworm | { 2025-09-23 12:36:18.752496 | debian-bookworm | "delta": "0:00:20.401833", 2025-09-23 12:36:18.752563 | debian-bookworm | "end": "2025-09-23 12:36:18.415020", 2025-09-23 12:36:18.752635 | debian-bookworm | "msg": "non-zero return code", 2025-09-23 12:36:18.752691 | debian-bookworm | "rc": 1, 2025-09-23 12:36:18.752743 | debian-bookworm | "start": "2025-09-23 12:35:58.013187" 2025-09-23 12:36:18.752795 | debian-bookworm | } failure 2025-09-23 12:36:18.757601 | 2025-09-23 12:36:18.757767 | PLAY RECAP 2025-09-23 12:36:18.758299 | debian-bookworm | ok: 4 changed: 3 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2025-09-23 12:36:18.758565 | 2025-09-23 12:36:18.908231 | RUN END RESULT_NORMAL: [untrusted : github.com/osism/container-image-kolla-ansible/playbooks/build.yml@main] 2025-09-23 12:36:18.910266 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-09-23 12:36:19.631992 | 2025-09-23 12:36:19.632155 | PLAY [Base post] 2025-09-23 12:36:19.645907 | 2025-09-23 12:36:19.646041 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-09-23 12:36:20.536797 | debian-bookworm | changed 2025-09-23 12:36:20.546528 | 2025-09-23 12:36:20.546660 | PLAY RECAP 2025-09-23 12:36:20.546744 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-09-23 12:36:20.546825 | 2025-09-23 12:36:20.669258 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-09-23 12:36:20.671841 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2025-09-23 12:36:21.451386 | 2025-09-23 12:36:21.451548 | PLAY [Base post-logs] 2025-09-23 12:36:21.462658 | 2025-09-23 12:36:21.462790 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-09-23 12:36:21.936222 | localhost | changed 2025-09-23 12:36:21.950590 | 2025-09-23 12:36:21.950792 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-09-23 12:36:21.987831 | localhost | ok 2025-09-23 12:36:21.993082 | 2025-09-23 12:36:21.993218 | TASK [Set zuul-log-path fact] 2025-09-23 12:36:22.009442 | localhost | ok 2025-09-23 12:36:22.020672 | 2025-09-23 12:36:22.020802 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-09-23 12:36:22.058324 | localhost | ok 2025-09-23 12:36:22.065325 | 2025-09-23 12:36:22.065493 | TASK [upload-logs : Create log directories] 2025-09-23 12:36:22.559404 | localhost | changed 2025-09-23 12:36:22.564712 | 2025-09-23 12:36:22.564866 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-09-23 12:36:23.065231 | localhost -> localhost | ok: Runtime: 0:00:00.006923 2025-09-23 12:36:23.074863 | 2025-09-23 12:36:23.075073 | TASK [upload-logs : Upload logs to log server] 2025-09-23 12:36:23.634054 | localhost | Output suppressed because no_log was given 2025-09-23 12:36:23.636808 | 2025-09-23 12:36:23.637010 | LOOP [upload-logs : Compress console log and json output] 2025-09-23 12:36:23.695674 | localhost | skipping: Conditional result was False 2025-09-23 12:36:23.700550 | localhost | skipping: Conditional result was False 2025-09-23 12:36:23.710129 | 2025-09-23 12:36:23.710281 | LOOP [upload-logs : Upload compressed console log and json output] 2025-09-23 12:36:23.766189 | localhost | skipping: Conditional result was False 2025-09-23 12:36:23.766827 | 2025-09-23 12:36:23.770226 | localhost | skipping: Conditional result was False 2025-09-23 12:36:23.784415 | 2025-09-23 12:36:23.784666 | LOOP [upload-logs : Upload console log and json output]