2025-09-25 00:00:04.963836 | Job console starting 2025-09-25 00:00:04.981895 | Updating git repos 2025-09-25 00:00:05.006342 | Cloning repos into workspace 2025-09-25 00:00:05.060931 | Restoring repo states 2025-09-25 00:00:05.070484 | Merging changes 2025-09-25 00:00:05.070503 | Checking out repos 2025-09-25 00:00:05.160435 | Preparing playbooks 2025-09-25 00:00:05.991509 | Running Ansible setup 2025-09-25 00:00:11.925344 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-09-25 00:00:13.835890 | 2025-09-25 00:00:13.836076 | PLAY [Base pre] 2025-09-25 00:00:13.857298 | 2025-09-25 00:00:13.857459 | TASK [Setup log path fact] 2025-09-25 00:00:13.897408 | debian-bookworm | ok 2025-09-25 00:00:13.932008 | 2025-09-25 00:00:13.932214 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-09-25 00:00:13.970998 | debian-bookworm | ok 2025-09-25 00:00:13.990474 | 2025-09-25 00:00:13.990610 | TASK [emit-job-header : Print job information] 2025-09-25 00:00:14.067026 | # Job Information 2025-09-25 00:00:14.067257 | Ansible Version: 2.16.14 2025-09-25 00:00:14.067298 | Job: container-image-kolla-ansible-push-2025.1 2025-09-25 00:00:14.067331 | Pipeline: periodic-midnight 2025-09-25 00:00:14.067354 | Executor: 521e9411259a 2025-09-25 00:00:14.067375 | Triggered by: https://github.com/osism/container-image-kolla-ansible 2025-09-25 00:00:14.067397 | Event ID: 671c595cd58543148fb7d0920b29ef47 2025-09-25 00:00:14.072561 | 2025-09-25 00:00:14.072679 | LOOP [emit-job-header : Print node information] 2025-09-25 00:00:14.393091 | debian-bookworm | ok: 2025-09-25 00:00:14.393362 | debian-bookworm | # Node Information 2025-09-25 00:00:14.393402 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-09-25 00:00:14.393429 | debian-bookworm | Hostname: debian 2025-09-25 00:00:14.393452 | debian-bookworm | Username: zuul 2025-09-25 00:00:14.393473 | debian-bookworm | Distro: Debian 12.12 2025-09-25 00:00:14.393497 | debian-bookworm | Provider: regiocloud-a 2025-09-25 00:00:14.393518 | debian-bookworm | Region: 2025-09-25 00:00:14.393538 | debian-bookworm | Label: debian-bookworm 2025-09-25 00:00:14.393558 | debian-bookworm | Product Name: OpenStack Nova 2025-09-25 00:00:14.393577 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe1f:2121 2025-09-25 00:00:14.411992 | 2025-09-25 00:00:14.412142 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-09-25 00:00:16.061441 | debian-bookworm -> localhost | changed 2025-09-25 00:00:16.068562 | 2025-09-25 00:00:16.068664 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-09-25 00:00:19.035506 | debian-bookworm -> localhost | changed 2025-09-25 00:00:19.044197 | 2025-09-25 00:00:19.044287 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-09-25 00:00:19.766721 | debian-bookworm -> localhost | ok 2025-09-25 00:00:19.771697 | 2025-09-25 00:00:19.771784 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-09-25 00:00:19.808249 | debian-bookworm | ok 2025-09-25 00:00:19.840902 | debian-bookworm | included: /var/lib/zuul/builds/3a4a32a318064b0bb18b8a476dc5ad0e/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-09-25 00:00:19.868705 | 2025-09-25 00:00:19.868798 | TASK [add-build-sshkey : Create Temp SSH key] 2025-09-25 00:00:24.600527 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-09-25 00:00:24.600684 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/3a4a32a318064b0bb18b8a476dc5ad0e/work/3a4a32a318064b0bb18b8a476dc5ad0e_id_rsa 2025-09-25 00:00:24.600716 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/3a4a32a318064b0bb18b8a476dc5ad0e/work/3a4a32a318064b0bb18b8a476dc5ad0e_id_rsa.pub 2025-09-25 00:00:24.600738 | debian-bookworm -> localhost | The key fingerprint is: 2025-09-25 00:00:24.600757 | debian-bookworm -> localhost | SHA256:Cpe0T95QnGMElI2mVutQUWbhm1EWQDJiN01cDkhASVs zuul-build-sshkey 2025-09-25 00:00:24.600785 | debian-bookworm -> localhost | The key's randomart image is: 2025-09-25 00:00:24.600804 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-09-25 00:00:24.600822 | debian-bookworm -> localhost | | o*=E&@+=. | 2025-09-25 00:00:24.600840 | debian-bookworm -> localhost | | ..=BX+* | 2025-09-25 00:00:24.600856 | debian-bookworm -> localhost | | o= .O . | 2025-09-25 00:00:24.600872 | debian-bookworm -> localhost | | .+o.o = | 2025-09-25 00:00:24.600888 | debian-bookworm -> localhost | | ..+oS o | 2025-09-25 00:00:24.600910 | debian-bookworm -> localhost | | o =.o | 2025-09-25 00:00:24.600927 | debian-bookworm -> localhost | | . o . | 2025-09-25 00:00:24.600943 | debian-bookworm -> localhost | | | 2025-09-25 00:00:24.600961 | debian-bookworm -> localhost | | | 2025-09-25 00:00:24.600977 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-09-25 00:00:24.601019 | debian-bookworm -> localhost | ok: Runtime: 0:00:03.446585 2025-09-25 00:00:24.610257 | 2025-09-25 00:00:24.610337 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-09-25 00:00:24.665380 | debian-bookworm | ok 2025-09-25 00:00:24.688580 | debian-bookworm | included: /var/lib/zuul/builds/3a4a32a318064b0bb18b8a476dc5ad0e/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-09-25 00:00:24.716397 | 2025-09-25 00:00:24.716524 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-09-25 00:00:24.749348 | debian-bookworm | skipping: Conditional result was False 2025-09-25 00:00:24.755526 | 2025-09-25 00:00:24.755609 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-09-25 00:00:25.705028 | debian-bookworm | changed 2025-09-25 00:00:25.713198 | 2025-09-25 00:00:25.713279 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-09-25 00:00:26.030955 | debian-bookworm | ok 2025-09-25 00:00:26.038445 | 2025-09-25 00:00:26.038530 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-09-25 00:00:26.810949 | debian-bookworm | changed 2025-09-25 00:00:26.816688 | 2025-09-25 00:00:26.816770 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-09-25 00:00:27.571539 | debian-bookworm | changed 2025-09-25 00:00:27.575874 | 2025-09-25 00:00:27.575947 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-09-25 00:00:27.634713 | debian-bookworm | skipping: Conditional result was False 2025-09-25 00:00:27.640249 | 2025-09-25 00:00:27.640335 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-09-25 00:00:28.510440 | debian-bookworm -> localhost | changed 2025-09-25 00:00:28.519401 | 2025-09-25 00:00:28.519488 | TASK [add-build-sshkey : Add back temp key] 2025-09-25 00:00:29.103961 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/3a4a32a318064b0bb18b8a476dc5ad0e/work/3a4a32a318064b0bb18b8a476dc5ad0e_id_rsa (zuul-build-sshkey) 2025-09-25 00:00:29.104141 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.033129 2025-09-25 00:00:29.110365 | 2025-09-25 00:00:29.110451 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-09-25 00:00:29.695913 | debian-bookworm | ok 2025-09-25 00:00:29.699975 | 2025-09-25 00:00:29.700054 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-09-25 00:00:29.722876 | debian-bookworm | skipping: Conditional result was False 2025-09-25 00:00:29.731465 | 2025-09-25 00:00:29.731553 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-09-25 00:00:30.267636 | debian-bookworm | ok 2025-09-25 00:00:30.283029 | 2025-09-25 00:00:30.283123 | TASK [validate-host : Define zuul_info_dir fact] 2025-09-25 00:00:30.348158 | debian-bookworm | ok 2025-09-25 00:00:30.352209 | 2025-09-25 00:00:30.352283 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-09-25 00:00:31.196619 | debian-bookworm -> localhost | ok 2025-09-25 00:00:31.201769 | 2025-09-25 00:00:31.201854 | TASK [validate-host : Collect information about the host] 2025-09-25 00:00:32.592111 | debian-bookworm | ok 2025-09-25 00:00:32.604093 | 2025-09-25 00:00:32.604189 | TASK [validate-host : Sanitize hostname] 2025-09-25 00:00:32.754452 | debian-bookworm | ok 2025-09-25 00:00:32.812743 | 2025-09-25 00:00:32.812837 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-09-25 00:00:33.892570 | debian-bookworm -> localhost | changed 2025-09-25 00:00:33.897491 | 2025-09-25 00:00:33.897571 | TASK [validate-host : Collect information about zuul worker] 2025-09-25 00:00:34.481336 | debian-bookworm | ok 2025-09-25 00:00:34.485027 | 2025-09-25 00:00:34.485105 | TASK [validate-host : Write out all zuul information for each host] 2025-09-25 00:00:35.589498 | debian-bookworm -> localhost | changed 2025-09-25 00:00:35.596994 | 2025-09-25 00:00:35.597073 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-09-25 00:00:35.965840 | debian-bookworm | ok 2025-09-25 00:00:35.970301 | 2025-09-25 00:00:35.970379 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-09-25 00:00:37.290846 | debian-bookworm | changed: 2025-09-25 00:00:37.291016 | debian-bookworm | cd+++++++++ src/ 2025-09-25 00:00:37.291049 | debian-bookworm | cd+++++++++ src/github.com/ 2025-09-25 00:00:37.291073 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-09-25 00:00:37.291094 | debian-bookworm | cd+++++++++ src/github.com/osism/container-image-kolla-ansible/ 2025-09-25 00:00:37.291114 | debian-bookworm | Containerfile 2025-09-25 00:00:37.291257 | debian-bookworm | 2025-09-25 00:14:50.954575 | debian-bookworm | ERROR 2025-09-25 00:14:50.955033 | debian-bookworm | { 2025-09-25 00:14:50.955128 | debian-bookworm | "delta": "0:00:20.054011", 2025-09-25 00:14:50.955186 | debian-bookworm | "end": "2025-09-25 00:14:50.760708", 2025-09-25 00:14:50.955245 | debian-bookworm | "msg": "non-zero return code", 2025-09-25 00:14:50.955296 | debian-bookworm | "rc": 1, 2025-09-25 00:14:50.955344 | debian-bookworm | "start": "2025-09-25 00:14:30.706697" 2025-09-25 00:14:50.955411 | debian-bookworm | } failure 2025-09-25 00:14:50.959370 | 2025-09-25 00:14:50.959515 | PLAY RECAP 2025-09-25 00:14:50.959635 | debian-bookworm | ok: 4 changed: 3 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2025-09-25 00:14:50.959698 | 2025-09-25 00:14:51.103580 | RUN END RESULT_NORMAL: [untrusted : github.com/osism/container-image-kolla-ansible/playbooks/build.yml@main] 2025-09-25 00:14:51.105838 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-09-25 00:14:51.819049 | 2025-09-25 00:14:51.819216 | PLAY [Base post] 2025-09-25 00:14:51.833338 | 2025-09-25 00:14:51.833491 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-09-25 00:14:52.658859 | debian-bookworm | changed 2025-09-25 00:14:52.669849 | 2025-09-25 00:14:52.669993 | PLAY RECAP 2025-09-25 00:14:52.670078 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-09-25 00:14:52.670158 | 2025-09-25 00:14:52.814888 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-09-25 00:14:52.817396 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2025-09-25 00:14:53.600956 | 2025-09-25 00:14:53.601135 | PLAY [Base post-logs] 2025-09-25 00:14:53.612217 | 2025-09-25 00:14:53.612365 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-09-25 00:14:54.064722 | localhost | changed 2025-09-25 00:14:54.074863 | 2025-09-25 00:14:54.075006 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-09-25 00:14:54.114100 | localhost | ok 2025-09-25 00:14:54.121158 | 2025-09-25 00:14:54.121332 | TASK [Set zuul-log-path fact] 2025-09-25 00:14:54.140936 | localhost | ok 2025-09-25 00:14:54.155339 | 2025-09-25 00:14:54.155490 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-09-25 00:14:54.193903 | localhost | ok 2025-09-25 00:14:54.201407 | 2025-09-25 00:14:54.201588 | TASK [upload-logs : Create log directories] 2025-09-25 00:14:54.676978 | localhost | changed 2025-09-25 00:14:54.679971 | 2025-09-25 00:14:54.680082 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-09-25 00:14:55.182394 | localhost -> localhost | ok: Runtime: 0:00:00.007026 2025-09-25 00:14:55.192147 | 2025-09-25 00:14:55.192329 | TASK [upload-logs : Upload logs to log server] 2025-09-25 00:14:55.746137 | localhost | Output suppressed because no_log was given 2025-09-25 00:14:55.748175 | 2025-09-25 00:14:55.748285 | LOOP [upload-logs : Compress console log and json output] 2025-09-25 00:14:55.803400 | localhost | skipping: Conditional result was False 2025-09-25 00:14:55.808603 | localhost | skipping: Conditional result was False 2025-09-25 00:14:55.821943 | 2025-09-25 00:14:55.822170 | LOOP [upload-logs : Upload compressed console log and json output] 2025-09-25 00:14:55.881280 | localhost | skipping: Conditional result was False 2025-09-25 00:14:55.881858 | 2025-09-25 00:14:55.885950 | localhost | skipping: Conditional result was False 2025-09-25 00:14:55.890783 | 2025-09-25 00:14:55.890930 | LOOP [upload-logs : Upload console log and json output]