2025-09-08 00:04:46.587084 | Job console starting 2025-09-08 00:04:46.600379 | Updating git repos 2025-09-08 00:04:46.625302 | Cloning repos into workspace 2025-09-08 00:04:46.679820 | Restoring repo states 2025-09-08 00:04:46.690893 | Merging changes 2025-09-08 00:04:46.690924 | Checking out repos 2025-09-08 00:04:46.740727 | Preparing playbooks 2025-09-08 00:04:47.419568 | Running Ansible setup 2025-09-08 00:04:51.879196 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-09-08 00:04:52.648404 | 2025-09-08 00:04:52.648659 | PLAY [Base pre] 2025-09-08 00:04:52.666490 | 2025-09-08 00:04:52.666662 | TASK [Setup log path fact] 2025-09-08 00:04:52.696544 | debian-bookworm | ok 2025-09-08 00:04:52.711399 | 2025-09-08 00:04:52.711549 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-09-08 00:04:52.756608 | debian-bookworm | ok 2025-09-08 00:04:52.769226 | 2025-09-08 00:04:52.769344 | TASK [emit-job-header : Print job information] 2025-09-08 00:04:52.825209 | # Job Information 2025-09-08 00:04:52.825478 | Ansible Version: 2.16.14 2025-09-08 00:04:52.825558 | Job: container-image-kolla-ansible-push-2024.2 2025-09-08 00:04:52.825618 | Pipeline: periodic-midnight 2025-09-08 00:04:52.825661 | Executor: 521e9411259a 2025-09-08 00:04:52.825699 | Triggered by: https://github.com/osism/container-image-kolla-ansible 2025-09-08 00:04:52.825741 | Event ID: 0830184aec09421ea08e0053388356a9 2025-09-08 00:04:52.834031 | 2025-09-08 00:04:52.834180 | LOOP [emit-job-header : Print node information] 2025-09-08 00:04:52.954055 | debian-bookworm | ok: 2025-09-08 00:04:52.954342 | debian-bookworm | # Node Information 2025-09-08 00:04:52.954404 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-09-08 00:04:52.954450 | debian-bookworm | Hostname: debian 2025-09-08 00:04:52.954489 | debian-bookworm | Username: zuul 2025-09-08 00:04:52.954543 | debian-bookworm | Distro: Debian 12.12 2025-09-08 00:04:52.954589 | debian-bookworm | Provider: regiocloud-a 2025-09-08 00:04:52.954625 | debian-bookworm | Region: 2025-09-08 00:04:52.954663 | debian-bookworm | Label: debian-bookworm 2025-09-08 00:04:52.954698 | debian-bookworm | Product Name: OpenStack Nova 2025-09-08 00:04:52.954733 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe04:171f 2025-09-08 00:04:52.980569 | 2025-09-08 00:04:52.980720 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-09-08 00:04:53.472982 | debian-bookworm -> localhost | changed 2025-09-08 00:04:53.488188 | 2025-09-08 00:04:53.488349 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-09-08 00:04:54.566682 | debian-bookworm -> localhost | changed 2025-09-08 00:04:54.578009 | 2025-09-08 00:04:54.578135 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-09-08 00:04:54.886641 | debian-bookworm -> localhost | ok 2025-09-08 00:04:54.894269 | 2025-09-08 00:04:54.894401 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-09-08 00:04:54.925474 | debian-bookworm | ok 2025-09-08 00:04:54.943562 | debian-bookworm | included: /var/lib/zuul/builds/4efdd2a0862b436bb16ffd3e9afc587f/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-09-08 00:04:54.949831 | 2025-09-08 00:04:54.949937 | TASK [add-build-sshkey : Create Temp SSH key] 2025-09-08 00:04:56.217023 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-09-08 00:04:56.217333 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/4efdd2a0862b436bb16ffd3e9afc587f/work/4efdd2a0862b436bb16ffd3e9afc587f_id_rsa 2025-09-08 00:04:56.217392 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/4efdd2a0862b436bb16ffd3e9afc587f/work/4efdd2a0862b436bb16ffd3e9afc587f_id_rsa.pub 2025-09-08 00:04:56.217434 | debian-bookworm -> localhost | The key fingerprint is: 2025-09-08 00:04:56.217471 | debian-bookworm -> localhost | SHA256:eRuKtmADgVAJdyd9uJn7pOxDR/6asHOPseAdm6i5T60 zuul-build-sshkey 2025-09-08 00:04:56.217570 | debian-bookworm -> localhost | The key's randomart image is: 2025-09-08 00:04:56.217611 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-09-08 00:04:56.217644 | debian-bookworm -> localhost | |ooo.o... | 2025-09-08 00:04:56.217677 | debian-bookworm -> localhost | |.o.. oo . | 2025-09-08 00:04:56.217709 | debian-bookworm -> localhost | |. . = | 2025-09-08 00:04:56.217740 | debian-bookworm -> localhost | | . + .. | 2025-09-08 00:04:56.217772 | debian-bookworm -> localhost | | . +S o | 2025-09-08 00:04:56.217813 | debian-bookworm -> localhost | | . oo+o o | 2025-09-08 00:04:56.217845 | debian-bookworm -> localhost | | +o*==.. | 2025-09-08 00:04:56.217876 | debian-bookworm -> localhost | | . B=OoO. | 2025-09-08 00:04:56.217908 | debian-bookworm -> localhost | | +=E+Oo. | 2025-09-08 00:04:56.217938 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-09-08 00:04:56.218013 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.743957 2025-09-08 00:04:56.226700 | 2025-09-08 00:04:56.226821 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-09-08 00:04:56.256165 | debian-bookworm | ok 2025-09-08 00:04:56.265919 | debian-bookworm | included: /var/lib/zuul/builds/4efdd2a0862b436bb16ffd3e9afc587f/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-09-08 00:04:56.275365 | 2025-09-08 00:04:56.275468 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-09-08 00:04:56.299278 | debian-bookworm | skipping: Conditional result was False 2025-09-08 00:04:56.314463 | 2025-09-08 00:04:56.314635 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-09-08 00:04:57.021004 | debian-bookworm | changed 2025-09-08 00:04:57.030079 | 2025-09-08 00:04:57.030211 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-09-08 00:04:57.309692 | debian-bookworm | ok 2025-09-08 00:04:57.317955 | 2025-09-08 00:04:57.318099 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-09-08 00:04:58.042230 | debian-bookworm | changed 2025-09-08 00:04:58.052510 | 2025-09-08 00:04:58.052663 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-09-08 00:04:58.816775 | debian-bookworm | changed 2025-09-08 00:04:58.824150 | 2025-09-08 00:04:58.824268 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-09-08 00:04:58.859245 | debian-bookworm | skipping: Conditional result was False 2025-09-08 00:04:58.871094 | 2025-09-08 00:04:58.871249 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-09-08 00:04:59.325399 | debian-bookworm -> localhost | changed 2025-09-08 00:04:59.346305 | 2025-09-08 00:04:59.346451 | TASK [add-build-sshkey : Add back temp key] 2025-09-08 00:04:59.704910 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/4efdd2a0862b436bb16ffd3e9afc587f/work/4efdd2a0862b436bb16ffd3e9afc587f_id_rsa (zuul-build-sshkey) 2025-09-08 00:04:59.705334 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.019090 2025-09-08 00:04:59.719861 | 2025-09-08 00:04:59.720033 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-09-08 00:05:00.155002 | debian-bookworm | ok 2025-09-08 00:05:00.164111 | 2025-09-08 00:05:00.164274 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-09-08 00:05:00.199551 | debian-bookworm | skipping: Conditional result was False 2025-09-08 00:05:00.217886 | 2025-09-08 00:05:00.218037 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-09-08 00:05:00.661537 | debian-bookworm | ok 2025-09-08 00:05:00.676869 | 2025-09-08 00:05:00.677038 | TASK [validate-host : Define zuul_info_dir fact] 2025-09-08 00:05:00.720195 | debian-bookworm | ok 2025-09-08 00:05:00.728211 | 2025-09-08 00:05:00.728342 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-09-08 00:05:01.037313 | debian-bookworm -> localhost | ok 2025-09-08 00:05:01.052175 | 2025-09-08 00:05:01.052345 | TASK [validate-host : Collect information about the host] 2025-09-08 00:05:02.239861 | debian-bookworm | ok 2025-09-08 00:05:02.253760 | 2025-09-08 00:05:02.253882 | TASK [validate-host : Sanitize hostname] 2025-09-08 00:05:02.371364 | debian-bookworm | ok 2025-09-08 00:05:02.411861 | 2025-09-08 00:05:02.412145 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-09-08 00:05:03.004342 | debian-bookworm -> localhost | changed 2025-09-08 00:05:03.010900 | 2025-09-08 00:05:03.011098 | TASK [validate-host : Collect information about zuul worker] 2025-09-08 00:05:03.449676 | debian-bookworm | ok 2025-09-08 00:05:03.457465 | 2025-09-08 00:05:03.457649 | TASK [validate-host : Write out all zuul information for each host] 2025-09-08 00:05:04.015075 | debian-bookworm -> localhost | changed 2025-09-08 00:05:04.033955 | 2025-09-08 00:05:04.034110 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-09-08 00:05:04.345208 | debian-bookworm | ok 2025-09-08 00:05:04.354168 | 2025-09-08 00:05:04.354299 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-09-08 00:05:05.236782 | debian-bookworm | changed: 2025-09-08 00:05:05.237336 | debian-bookworm | cd+++++++++ src/ 2025-09-08 00:05:05.237437 | debian-bookworm | cd+++++++++ src/github.com/ 2025-09-08 00:05:05.237508 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-09-08 00:05:05.237601 | debian-bookworm | cd+++++++++ src/github.com/osism/container-image-kolla-ansible/ 2025-09-08 00:05:05.237661 | debian-bookworm | Containerfile 2025-09-08 00:05:05.238028 | debian-bookworm | 2025-09-08 00:18:23.839286 | debian-bookworm | ERROR 2025-09-08 00:18:23.839862 | debian-bookworm | { 2025-09-08 00:18:23.840362 | debian-bookworm | "delta": "0:00:19.805739", 2025-09-08 00:18:23.840486 | debian-bookworm | "end": "2025-09-08 00:18:23.348124", 2025-09-08 00:18:23.840558 | debian-bookworm | "msg": "non-zero return code", 2025-09-08 00:18:23.840618 | debian-bookworm | "rc": 1, 2025-09-08 00:18:23.840672 | debian-bookworm | "start": "2025-09-08 00:18:03.542385" 2025-09-08 00:18:23.840723 | debian-bookworm | } failure 2025-09-08 00:18:23.844342 | 2025-09-08 00:18:23.844536 | PLAY RECAP 2025-09-08 00:18:23.844660 | debian-bookworm | ok: 4 changed: 3 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2025-09-08 00:18:23.844722 | 2025-09-08 00:18:23.974659 | RUN END RESULT_NORMAL: [untrusted : github.com/osism/container-image-kolla-ansible/playbooks/build.yml@main] 2025-09-08 00:18:23.977574 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-09-08 00:18:24.674765 | 2025-09-08 00:18:24.674937 | PLAY [Base post] 2025-09-08 00:18:24.688539 | 2025-09-08 00:18:24.688670 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-09-08 00:18:25.534458 | debian-bookworm | changed 2025-09-08 00:18:25.547478 | 2025-09-08 00:18:25.547663 | PLAY RECAP 2025-09-08 00:18:25.547786 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-09-08 00:18:25.547914 | 2025-09-08 00:18:25.668176 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-09-08 00:18:25.670509 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2025-09-08 00:18:26.450189 | 2025-09-08 00:18:26.450349 | PLAY [Base post-logs] 2025-09-08 00:18:26.461055 | 2025-09-08 00:18:26.461181 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-09-08 00:18:26.914340 | localhost | changed 2025-09-08 00:18:26.929096 | 2025-09-08 00:18:26.929254 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-09-08 00:18:26.966045 | localhost | ok 2025-09-08 00:18:26.971490 | 2025-09-08 00:18:26.971645 | TASK [Set zuul-log-path fact] 2025-09-08 00:18:26.989180 | localhost | ok 2025-09-08 00:18:27.002794 | 2025-09-08 00:18:27.002999 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-09-08 00:18:27.039829 | localhost | ok 2025-09-08 00:18:27.045605 | 2025-09-08 00:18:27.045776 | TASK [upload-logs : Create log directories] 2025-09-08 00:18:27.564736 | localhost | changed 2025-09-08 00:18:27.570057 | 2025-09-08 00:18:27.570229 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-09-08 00:18:28.065846 | localhost -> localhost | ok: Runtime: 0:00:00.007248 2025-09-08 00:18:28.075036 | 2025-09-08 00:18:28.075231 | TASK [upload-logs : Upload logs to log server] 2025-09-08 00:18:28.670779 | localhost | Output suppressed because no_log was given 2025-09-08 00:18:28.672722 | 2025-09-08 00:18:28.672828 | LOOP [upload-logs : Compress console log and json output] 2025-09-08 00:18:28.737727 | localhost | skipping: Conditional result was False 2025-09-08 00:18:28.743661 | localhost | skipping: Conditional result was False 2025-09-08 00:18:28.753701 | 2025-09-08 00:18:28.753880 | LOOP [upload-logs : Upload compressed console log and json output] 2025-09-08 00:18:28.810581 | localhost | skipping: Conditional result was False 2025-09-08 00:18:28.811456 | 2025-09-08 00:18:28.814241 | localhost | skipping: Conditional result was False 2025-09-08 00:18:28.827325 | 2025-09-08 00:18:28.827598 | LOOP [upload-logs : Upload console log and json output]