2025-09-24 00:00:04.867415 | Job console starting 2025-09-24 00:00:04.894548 | Updating git repos 2025-09-24 00:00:04.925254 | Cloning repos into workspace 2025-09-24 00:00:04.983301 | Restoring repo states 2025-09-24 00:00:04.997662 | Merging changes 2025-09-24 00:00:04.997680 | Checking out repos 2025-09-24 00:00:05.079688 | Preparing playbooks 2025-09-24 00:00:05.767776 | Running Ansible setup 2025-09-24 00:00:11.038343 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-09-24 00:00:12.396193 | 2025-09-24 00:00:12.396371 | PLAY [Base pre] 2025-09-24 00:00:12.422094 | 2025-09-24 00:00:12.422277 | TASK [Setup log path fact] 2025-09-24 00:00:12.474255 | debian-bookworm | ok 2025-09-24 00:00:12.498031 | 2025-09-24 00:00:12.498204 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-09-24 00:00:12.530664 | debian-bookworm | ok 2025-09-24 00:00:12.555994 | 2025-09-24 00:00:12.556195 | TASK [emit-job-header : Print job information] 2025-09-24 00:00:12.617292 | # Job Information 2025-09-24 00:00:12.617479 | Ansible Version: 2.16.14 2025-09-24 00:00:12.617514 | Job: container-image-kolla-ansible-push-2024.1 2025-09-24 00:00:12.617547 | Pipeline: periodic-midnight 2025-09-24 00:00:12.617571 | Executor: 521e9411259a 2025-09-24 00:00:12.617592 | Triggered by: https://github.com/osism/container-image-kolla-ansible 2025-09-24 00:00:12.617614 | Event ID: 2942683442bf4ee1b37889cf1335ab92 2025-09-24 00:00:12.628053 | 2025-09-24 00:00:12.628203 | LOOP [emit-job-header : Print node information] 2025-09-24 00:00:12.954927 | debian-bookworm | ok: 2025-09-24 00:00:12.955181 | debian-bookworm | # Node Information 2025-09-24 00:00:12.955222 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-09-24 00:00:12.955268 | debian-bookworm | Hostname: debian 2025-09-24 00:00:12.955291 | debian-bookworm | Username: zuul 2025-09-24 00:00:12.955311 | debian-bookworm | Distro: Debian 12.12 2025-09-24 00:00:12.955335 | debian-bookworm | Provider: regiocloud-a 2025-09-24 00:00:12.955356 | debian-bookworm | Region: 2025-09-24 00:00:12.955377 | debian-bookworm | Label: debian-bookworm 2025-09-24 00:00:12.955397 | debian-bookworm | Product Name: OpenStack Nova 2025-09-24 00:00:12.955417 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe51:ee9b 2025-09-24 00:00:12.979369 | 2025-09-24 00:00:12.979521 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-09-24 00:00:14.194033 | debian-bookworm -> localhost | changed 2025-09-24 00:00:14.202726 | 2025-09-24 00:00:14.202949 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-09-24 00:00:16.608504 | debian-bookworm -> localhost | changed 2025-09-24 00:00:16.621600 | 2025-09-24 00:00:16.621689 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-09-24 00:00:17.692888 | debian-bookworm -> localhost | ok 2025-09-24 00:00:17.698184 | 2025-09-24 00:00:17.698271 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-09-24 00:00:17.766055 | debian-bookworm | ok 2025-09-24 00:00:17.822341 | debian-bookworm | included: /var/lib/zuul/builds/e79ceba678d447009bbf5263b8dfcad5/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-09-24 00:00:17.868391 | 2025-09-24 00:00:17.868489 | TASK [add-build-sshkey : Create Temp SSH key] 2025-09-24 00:00:25.557882 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-09-24 00:00:25.558054 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/e79ceba678d447009bbf5263b8dfcad5/work/e79ceba678d447009bbf5263b8dfcad5_id_rsa 2025-09-24 00:00:25.558086 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/e79ceba678d447009bbf5263b8dfcad5/work/e79ceba678d447009bbf5263b8dfcad5_id_rsa.pub 2025-09-24 00:00:25.558107 | debian-bookworm -> localhost | The key fingerprint is: 2025-09-24 00:00:25.558127 | debian-bookworm -> localhost | SHA256:rHSQB/Pcsh9rsj/2ehBpWfWJfowD1yc3496wP0ER7p8 zuul-build-sshkey 2025-09-24 00:00:25.558154 | debian-bookworm -> localhost | The key's randomart image is: 2025-09-24 00:00:25.558173 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-09-24 00:00:25.558190 | debian-bookworm -> localhost | | o ....| 2025-09-24 00:00:25.558207 | debian-bookworm -> localhost | | * . . +o.| 2025-09-24 00:00:25.558223 | debian-bookworm -> localhost | | o = .= o.*=| 2025-09-24 00:00:25.558239 | debian-bookworm -> localhost | | + o= + =++| 2025-09-24 00:00:25.558255 | debian-bookworm -> localhost | | . S... +o= | 2025-09-24 00:00:25.558273 | debian-bookworm -> localhost | | . o ..o +++| 2025-09-24 00:00:25.558290 | debian-bookworm -> localhost | | . . +. .E+| 2025-09-24 00:00:25.558306 | debian-bookworm -> localhost | | +o . o | 2025-09-24 00:00:25.558322 | debian-bookworm -> localhost | | .oo=. o| 2025-09-24 00:00:25.558339 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-09-24 00:00:25.558376 | debian-bookworm -> localhost | ok: Runtime: 0:00:06.457236 2025-09-24 00:00:25.563465 | 2025-09-24 00:00:25.563544 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-09-24 00:00:25.620379 | debian-bookworm | ok 2025-09-24 00:00:25.634133 | debian-bookworm | included: /var/lib/zuul/builds/e79ceba678d447009bbf5263b8dfcad5/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-09-24 00:00:25.656674 | 2025-09-24 00:00:25.656762 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-09-24 00:00:25.694118 | debian-bookworm | skipping: Conditional result was False 2025-09-24 00:00:25.699156 | 2025-09-24 00:00:25.699242 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-09-24 00:00:26.513558 | debian-bookworm | changed 2025-09-24 00:00:26.517848 | 2025-09-24 00:00:26.517917 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-09-24 00:00:26.796603 | debian-bookworm | ok 2025-09-24 00:00:26.801272 | 2025-09-24 00:00:26.801349 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-09-24 00:00:27.536956 | debian-bookworm | changed 2025-09-24 00:00:27.542391 | 2025-09-24 00:00:27.542478 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-09-24 00:00:28.346339 | debian-bookworm | changed 2025-09-24 00:00:28.350658 | 2025-09-24 00:00:28.350732 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-09-24 00:00:28.377137 | debian-bookworm | skipping: Conditional result was False 2025-09-24 00:00:28.382070 | 2025-09-24 00:00:28.382155 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-09-24 00:00:29.310213 | debian-bookworm -> localhost | changed 2025-09-24 00:00:29.322093 | 2025-09-24 00:00:29.322193 | TASK [add-build-sshkey : Add back temp key] 2025-09-24 00:00:30.113553 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/e79ceba678d447009bbf5263b8dfcad5/work/e79ceba678d447009bbf5263b8dfcad5_id_rsa (zuul-build-sshkey) 2025-09-24 00:00:30.113763 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.044065 2025-09-24 00:00:30.121095 | 2025-09-24 00:00:30.121195 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-09-24 00:00:30.654215 | debian-bookworm | ok 2025-09-24 00:00:30.659111 | 2025-09-24 00:00:30.659203 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-09-24 00:00:30.696725 | debian-bookworm | skipping: Conditional result was False 2025-09-24 00:00:30.713674 | 2025-09-24 00:00:30.713782 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-09-24 00:00:31.221005 | debian-bookworm | ok 2025-09-24 00:00:31.246367 | 2025-09-24 00:00:31.246479 | TASK [validate-host : Define zuul_info_dir fact] 2025-09-24 00:00:31.296207 | debian-bookworm | ok 2025-09-24 00:00:31.301307 | 2025-09-24 00:00:31.301408 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-09-24 00:00:31.916084 | debian-bookworm -> localhost | ok 2025-09-24 00:00:31.922905 | 2025-09-24 00:00:31.923013 | TASK [validate-host : Collect information about the host] 2025-09-24 00:00:33.384630 | debian-bookworm | ok 2025-09-24 00:00:33.402348 | 2025-09-24 00:00:33.402452 | TASK [validate-host : Sanitize hostname] 2025-09-24 00:00:33.519903 | debian-bookworm | ok 2025-09-24 00:00:33.643277 | 2025-09-24 00:00:33.643400 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-09-24 00:00:34.797911 | debian-bookworm -> localhost | changed 2025-09-24 00:00:34.803724 | 2025-09-24 00:00:34.803854 | TASK [validate-host : Collect information about zuul worker] 2025-09-24 00:00:35.299911 | debian-bookworm | ok 2025-09-24 00:00:35.304435 | 2025-09-24 00:00:35.304534 | TASK [validate-host : Write out all zuul information for each host] 2025-09-24 00:00:36.700025 | debian-bookworm -> localhost | changed 2025-09-24 00:00:36.708944 | 2025-09-24 00:00:36.709025 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-09-24 00:00:36.984307 | debian-bookworm | ok 2025-09-24 00:00:36.988855 | 2025-09-24 00:00:36.988939 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-09-24 00:00:37.963965 | debian-bookworm | changed: 2025-09-24 00:00:37.964131 | debian-bookworm | cd+++++++++ src/ 2025-09-24 00:00:37.964165 | debian-bookworm | cd+++++++++ src/github.com/ 2025-09-24 00:00:37.964190 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-09-24 00:00:37.964212 | debian-bookworm | cd+++++++++ src/github.com/osism/container-image-kolla-ansible/ 2025-09-24 00:00:37.964232 | debian-bookworm | Containerfile 2025-09-24 00:00:37.964375 | debian-bookworm | 2025-09-24 00:08:52.979973 | debian-bookworm | ERROR 2025-09-24 00:08:52.980423 | debian-bookworm | { 2025-09-24 00:08:52.980531 | debian-bookworm | "delta": "0:00:20.336048", 2025-09-24 00:08:52.980603 | debian-bookworm | "end": "2025-09-24 00:08:52.551835", 2025-09-24 00:08:52.980671 | debian-bookworm | "msg": "non-zero return code", 2025-09-24 00:08:52.980727 | debian-bookworm | "rc": 1, 2025-09-24 00:08:52.980782 | debian-bookworm | "start": "2025-09-24 00:08:32.215787" 2025-09-24 00:08:52.980836 | debian-bookworm | } failure 2025-09-24 00:08:52.986009 | 2025-09-24 00:08:52.986157 | PLAY RECAP 2025-09-24 00:08:52.986260 | debian-bookworm | ok: 4 changed: 3 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2025-09-24 00:08:52.986311 | 2025-09-24 00:08:53.112822 | RUN END RESULT_NORMAL: [untrusted : github.com/osism/container-image-kolla-ansible/playbooks/build.yml@main] 2025-09-24 00:08:53.115341 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-09-24 00:08:53.767294 | 2025-09-24 00:08:53.767412 | PLAY [Base post] 2025-09-24 00:08:53.780363 | 2025-09-24 00:08:53.780466 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-09-24 00:08:54.613093 | debian-bookworm | changed 2025-09-24 00:08:54.621471 | 2025-09-24 00:08:54.621575 | PLAY RECAP 2025-09-24 00:08:54.621643 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-09-24 00:08:54.621717 | 2025-09-24 00:08:54.705621 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-09-24 00:08:54.706539 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2025-09-24 00:08:55.445023 | 2025-09-24 00:08:55.445151 | PLAY [Base post-logs] 2025-09-24 00:08:55.454737 | 2025-09-24 00:08:55.454871 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-09-24 00:08:55.874936 | localhost | changed 2025-09-24 00:08:55.885805 | 2025-09-24 00:08:55.886050 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-09-24 00:08:55.922262 | localhost | ok 2025-09-24 00:08:55.925613 | 2025-09-24 00:08:55.925715 | TASK [Set zuul-log-path fact] 2025-09-24 00:08:55.940824 | localhost | ok 2025-09-24 00:08:55.951224 | 2025-09-24 00:08:55.951347 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-09-24 00:08:55.986501 | localhost | ok 2025-09-24 00:08:55.989567 | 2025-09-24 00:08:55.989671 | TASK [upload-logs : Create log directories] 2025-09-24 00:08:56.520491 | localhost | changed 2025-09-24 00:08:56.526395 | 2025-09-24 00:08:56.526571 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-09-24 00:08:57.021802 | localhost -> localhost | ok: Runtime: 0:00:00.006951 2025-09-24 00:08:57.030230 | 2025-09-24 00:08:57.030397 | TASK [upload-logs : Upload logs to log server] 2025-09-24 00:08:57.597225 | localhost | Output suppressed because no_log was given 2025-09-24 00:08:57.599760 | 2025-09-24 00:08:57.599945 | LOOP [upload-logs : Compress console log and json output] 2025-09-24 00:08:57.663193 | localhost | skipping: Conditional result was False 2025-09-24 00:08:57.667803 | localhost | skipping: Conditional result was False 2025-09-24 00:08:57.680421 | 2025-09-24 00:08:57.680631 | LOOP [upload-logs : Upload compressed console log and json output] 2025-09-24 00:08:57.730337 | localhost | skipping: Conditional result was False 2025-09-24 00:08:57.730977 | 2025-09-24 00:08:57.734637 | localhost | skipping: Conditional result was False 2025-09-24 00:08:57.741361 | 2025-09-24 00:08:57.741568 | LOOP [upload-logs : Upload console log and json output]