2025-09-12 00:04:47.199882 | Job console starting 2025-09-12 00:04:47.214718 | Updating git repos 2025-09-12 00:04:47.245821 | Cloning repos into workspace 2025-09-12 00:04:47.281187 | Restoring repo states 2025-09-12 00:04:47.291605 | Merging changes 2025-09-12 00:04:47.291624 | Checking out repos 2025-09-12 00:04:47.337969 | Preparing playbooks 2025-09-12 00:04:47.964739 | Running Ansible setup 2025-09-12 00:04:52.164018 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-09-12 00:04:52.903778 | 2025-09-12 00:04:52.903933 | PLAY [Base pre] 2025-09-12 00:04:52.920222 | 2025-09-12 00:04:52.920346 | TASK [Setup log path fact] 2025-09-12 00:04:52.949338 | debian-bookworm | ok 2025-09-12 00:04:52.964179 | 2025-09-12 00:04:52.964298 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-09-12 00:04:53.005719 | debian-bookworm | ok 2025-09-12 00:04:53.016041 | 2025-09-12 00:04:53.016180 | TASK [emit-job-header : Print job information] 2025-09-12 00:04:53.056224 | # Job Information 2025-09-12 00:04:53.056429 | Ansible Version: 2.16.14 2025-09-12 00:04:53.056468 | Job: container-image-kolla-ansible-push-2024.1 2025-09-12 00:04:53.056507 | Pipeline: periodic-midnight 2025-09-12 00:04:53.056534 | Executor: 521e9411259a 2025-09-12 00:04:53.056559 | Triggered by: https://github.com/osism/container-image-kolla-ansible 2025-09-12 00:04:53.056584 | Event ID: b17a3f91eb4d4eb7a6d5eb5863bf3dc7 2025-09-12 00:04:53.061940 | 2025-09-12 00:04:53.062050 | LOOP [emit-job-header : Print node information] 2025-09-12 00:04:53.172264 | debian-bookworm | ok: 2025-09-12 00:04:53.172580 | debian-bookworm | # Node Information 2025-09-12 00:04:53.172652 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-09-12 00:04:53.172705 | debian-bookworm | Hostname: debian 2025-09-12 00:04:53.172750 | debian-bookworm | Username: zuul 2025-09-12 00:04:53.172792 | debian-bookworm | Distro: Debian 12.12 2025-09-12 00:04:53.172840 | debian-bookworm | Provider: regiocloud-a 2025-09-12 00:04:53.172884 | debian-bookworm | Region: 2025-09-12 00:04:53.172927 | debian-bookworm | Label: debian-bookworm 2025-09-12 00:04:53.172968 | debian-bookworm | Product Name: OpenStack Nova 2025-09-12 00:04:53.173008 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fefe:1304 2025-09-12 00:04:53.201348 | 2025-09-12 00:04:53.201492 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-09-12 00:04:53.721950 | debian-bookworm -> localhost | changed 2025-09-12 00:04:53.738753 | 2025-09-12 00:04:53.738921 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-09-12 00:04:54.785859 | debian-bookworm -> localhost | changed 2025-09-12 00:04:54.797449 | 2025-09-12 00:04:54.797567 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-09-12 00:04:55.075373 | debian-bookworm -> localhost | ok 2025-09-12 00:04:55.087867 | 2025-09-12 00:04:55.088038 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-09-12 00:04:55.117549 | debian-bookworm | ok 2025-09-12 00:04:55.135827 | debian-bookworm | included: /var/lib/zuul/builds/614bd0521bc0429490db3929572c4ebc/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-09-12 00:04:55.142056 | 2025-09-12 00:04:55.142190 | TASK [add-build-sshkey : Create Temp SSH key] 2025-09-12 00:04:56.638940 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-09-12 00:04:56.639596 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/614bd0521bc0429490db3929572c4ebc/work/614bd0521bc0429490db3929572c4ebc_id_rsa 2025-09-12 00:04:56.639717 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/614bd0521bc0429490db3929572c4ebc/work/614bd0521bc0429490db3929572c4ebc_id_rsa.pub 2025-09-12 00:04:56.639795 | debian-bookworm -> localhost | The key fingerprint is: 2025-09-12 00:04:56.639868 | debian-bookworm -> localhost | SHA256:nOSDciScq4AY/DT1gVu+Ehuz5NwwzWS24nlmODhPmYQ zuul-build-sshkey 2025-09-12 00:04:56.639956 | debian-bookworm -> localhost | The key's randomart image is: 2025-09-12 00:04:56.640021 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-09-12 00:04:56.640083 | debian-bookworm -> localhost | | ... | 2025-09-12 00:04:56.640165 | debian-bookworm -> localhost | |. ..o.=. | 2025-09-12 00:04:56.640223 | debian-bookworm -> localhost | |.. o= @.o | 2025-09-12 00:04:56.640280 | debian-bookworm -> localhost | |o.oE.# X . | 2025-09-12 00:04:56.640338 | debian-bookworm -> localhost | |+ .X ^ S | 2025-09-12 00:04:56.640406 | debian-bookworm -> localhost | | . + ^ * . | 2025-09-12 00:04:56.640466 | debian-bookworm -> localhost | | . + * | 2025-09-12 00:04:56.640525 | debian-bookworm -> localhost | | . | 2025-09-12 00:04:56.640584 | debian-bookworm -> localhost | | | 2025-09-12 00:04:56.640642 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-09-12 00:04:56.640788 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.958240 2025-09-12 00:04:56.654532 | 2025-09-12 00:04:56.654677 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-09-12 00:04:56.691597 | debian-bookworm | ok 2025-09-12 00:04:56.705794 | debian-bookworm | included: /var/lib/zuul/builds/614bd0521bc0429490db3929572c4ebc/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-09-12 00:04:56.715756 | 2025-09-12 00:04:56.715868 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-09-12 00:04:56.739965 | debian-bookworm | skipping: Conditional result was False 2025-09-12 00:04:56.750318 | 2025-09-12 00:04:56.750469 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-09-12 00:04:57.356627 | debian-bookworm | changed 2025-09-12 00:04:57.366781 | 2025-09-12 00:04:57.366957 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-09-12 00:04:57.617430 | debian-bookworm | ok 2025-09-12 00:04:57.627494 | 2025-09-12 00:04:57.627649 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-09-12 00:04:58.306524 | debian-bookworm | changed 2025-09-12 00:04:58.314066 | 2025-09-12 00:04:58.314200 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-09-12 00:04:58.989017 | debian-bookworm | changed 2025-09-12 00:04:58.998243 | 2025-09-12 00:04:58.998389 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-09-12 00:04:59.023901 | debian-bookworm | skipping: Conditional result was False 2025-09-12 00:04:59.036510 | 2025-09-12 00:04:59.036687 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-09-12 00:04:59.477993 | debian-bookworm -> localhost | changed 2025-09-12 00:04:59.488663 | 2025-09-12 00:04:59.488768 | TASK [add-build-sshkey : Add back temp key] 2025-09-12 00:04:59.806314 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/614bd0521bc0429490db3929572c4ebc/work/614bd0521bc0429490db3929572c4ebc_id_rsa (zuul-build-sshkey) 2025-09-12 00:04:59.806944 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.010317 2025-09-12 00:04:59.822772 | 2025-09-12 00:04:59.822952 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-09-12 00:05:00.256034 | debian-bookworm | ok 2025-09-12 00:05:00.264974 | 2025-09-12 00:05:00.265123 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-09-12 00:05:00.300621 | debian-bookworm | skipping: Conditional result was False 2025-09-12 00:05:00.321242 | 2025-09-12 00:05:00.321378 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-09-12 00:05:00.760785 | debian-bookworm | ok 2025-09-12 00:05:00.776438 | 2025-09-12 00:05:00.776559 | TASK [validate-host : Define zuul_info_dir fact] 2025-09-12 00:05:00.812407 | debian-bookworm | ok 2025-09-12 00:05:00.820082 | 2025-09-12 00:05:00.820246 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-09-12 00:05:01.098046 | debian-bookworm -> localhost | ok 2025-09-12 00:05:01.110667 | 2025-09-12 00:05:01.110811 | TASK [validate-host : Collect information about the host] 2025-09-12 00:05:02.134003 | debian-bookworm | ok 2025-09-12 00:05:02.148787 | 2025-09-12 00:05:02.148917 | TASK [validate-host : Sanitize hostname] 2025-09-12 00:05:02.267184 | debian-bookworm | ok 2025-09-12 00:05:02.302092 | 2025-09-12 00:05:02.302210 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-09-12 00:05:02.871471 | debian-bookworm -> localhost | changed 2025-09-12 00:05:02.884900 | 2025-09-12 00:05:02.885076 | TASK [validate-host : Collect information about zuul worker] 2025-09-12 00:05:03.277636 | debian-bookworm | ok 2025-09-12 00:05:03.294757 | 2025-09-12 00:05:03.294940 | TASK [validate-host : Write out all zuul information for each host] 2025-09-12 00:05:03.871294 | debian-bookworm -> localhost | changed 2025-09-12 00:05:03.889187 | 2025-09-12 00:05:03.889319 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-09-12 00:05:04.160385 | debian-bookworm | ok 2025-09-12 00:05:04.169366 | 2025-09-12 00:05:04.169495 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-09-12 00:05:05.037353 | debian-bookworm | changed: 2025-09-12 00:05:05.037656 | debian-bookworm | cd+++++++++ src/ 2025-09-12 00:05:05.037710 | debian-bookworm | cd+++++++++ src/github.com/ 2025-09-12 00:05:05.037745 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-09-12 00:05:05.037777 | debian-bookworm | cd+++++++++ src/github.com/osism/container-image-kolla-ansible/ 2025-09-12 00:05:05.037808 | debian-bookworm | Containerfile 2025-09-12 00:05:05.037994 | debian-bookworm | 2025-09-12 00:12:57.302605 | debian-bookworm | ERROR 2025-09-12 00:12:57.303155 | debian-bookworm | { 2025-09-12 00:12:57.303272 | debian-bookworm | "delta": "0:00:18.762683", 2025-09-12 00:12:57.303343 | debian-bookworm | "end": "2025-09-12 00:12:56.796154", 2025-09-12 00:12:57.303412 | debian-bookworm | "msg": "non-zero return code", 2025-09-12 00:12:57.303494 | debian-bookworm | "rc": 1, 2025-09-12 00:12:57.303620 | debian-bookworm | "start": "2025-09-12 00:12:38.033471" 2025-09-12 00:12:57.303680 | debian-bookworm | } failure 2025-09-12 00:12:57.310254 | 2025-09-12 00:12:57.310423 | PLAY RECAP 2025-09-12 00:12:57.310528 | debian-bookworm | ok: 4 changed: 3 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2025-09-12 00:12:57.310615 | 2025-09-12 00:12:57.459398 | RUN END RESULT_NORMAL: [untrusted : github.com/osism/container-image-kolla-ansible/playbooks/build.yml@main] 2025-09-12 00:12:57.460481 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-09-12 00:12:58.264180 | 2025-09-12 00:12:58.264355 | PLAY [Base post] 2025-09-12 00:12:58.278665 | 2025-09-12 00:12:58.278805 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-09-12 00:12:59.095166 | debian-bookworm | changed 2025-09-12 00:12:59.104598 | 2025-09-12 00:12:59.104740 | PLAY RECAP 2025-09-12 00:12:59.104818 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-09-12 00:12:59.104896 | 2025-09-12 00:12:59.237809 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-09-12 00:12:59.239775 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2025-09-12 00:13:00.031888 | 2025-09-12 00:13:00.032054 | PLAY [Base post-logs] 2025-09-12 00:13:00.042916 | 2025-09-12 00:13:00.043052 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-09-12 00:13:00.505105 | localhost | changed 2025-09-12 00:13:00.515373 | 2025-09-12 00:13:00.515518 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-09-12 00:13:00.553826 | localhost | ok 2025-09-12 00:13:00.560967 | 2025-09-12 00:13:00.561304 | TASK [Set zuul-log-path fact] 2025-09-12 00:13:00.590260 | localhost | ok 2025-09-12 00:13:00.605336 | 2025-09-12 00:13:00.605504 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-09-12 00:13:00.643973 | localhost | ok 2025-09-12 00:13:00.650901 | 2025-09-12 00:13:00.651112 | TASK [upload-logs : Create log directories] 2025-09-12 00:13:01.186116 | localhost | changed 2025-09-12 00:13:01.189179 | 2025-09-12 00:13:01.189287 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-09-12 00:13:01.670717 | localhost -> localhost | ok: Runtime: 0:00:00.007802 2025-09-12 00:13:01.679506 | 2025-09-12 00:13:01.679695 | TASK [upload-logs : Upload logs to log server] 2025-09-12 00:13:02.233852 | localhost | Output suppressed because no_log was given 2025-09-12 00:13:02.235915 | 2025-09-12 00:13:02.236023 | LOOP [upload-logs : Compress console log and json output] 2025-09-12 00:13:02.283204 | localhost | skipping: Conditional result was False 2025-09-12 00:13:02.289865 | localhost | skipping: Conditional result was False 2025-09-12 00:13:02.293844 | 2025-09-12 00:13:02.293947 | LOOP [upload-logs : Upload compressed console log and json output] 2025-09-12 00:13:02.351162 | localhost | skipping: Conditional result was False 2025-09-12 00:13:02.351852 | 2025-09-12 00:13:02.354766 | localhost | skipping: Conditional result was False 2025-09-12 00:13:02.362941 | 2025-09-12 00:13:02.363137 | LOOP [upload-logs : Upload console log and json output]