2025-09-23 18:34:48.918431 | Job console starting 2025-09-23 18:34:48.948866 | Updating git repos 2025-09-23 18:34:48.979424 | Cloning repos into workspace 2025-09-23 18:34:49.033018 | Restoring repo states 2025-09-23 18:34:49.045882 | Merging changes 2025-09-23 18:34:49.045899 | Checking out repos 2025-09-23 18:34:49.100503 | Preparing playbooks 2025-09-23 18:34:49.792946 | Running Ansible setup 2025-09-23 18:34:53.870597 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-09-23 18:34:54.626126 | 2025-09-23 18:34:54.626277 | PLAY [Base pre] 2025-09-23 18:34:54.642536 | 2025-09-23 18:34:54.642669 | TASK [Setup log path fact] 2025-09-23 18:34:54.672643 | debian-bookworm | ok 2025-09-23 18:34:54.688232 | 2025-09-23 18:34:54.688358 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-09-23 18:34:54.728925 | debian-bookworm | ok 2025-09-23 18:34:54.740209 | 2025-09-23 18:34:54.740320 | TASK [emit-job-header : Print job information] 2025-09-23 18:34:54.788918 | # Job Information 2025-09-23 18:34:54.789103 | Ansible Version: 2.16.14 2025-09-23 18:34:54.789139 | Job: container-image-kolla-ansible-push-2025.1 2025-09-23 18:34:54.789173 | Pipeline: post 2025-09-23 18:34:54.789196 | Executor: 521e9411259a 2025-09-23 18:34:54.789217 | Triggered by: https://github.com/osism/container-image-kolla-ansible/commit/4d60c31224e121f77bcf4d4394f6dc512a0872fc 2025-09-23 18:34:54.789239 | Event ID: f7913c2c-98ab-11f0-8d48-452dc257cab3 2025-09-23 18:34:54.794180 | 2025-09-23 18:34:54.794291 | LOOP [emit-job-header : Print node information] 2025-09-23 18:34:54.904335 | debian-bookworm | ok: 2025-09-23 18:34:54.904609 | debian-bookworm | # Node Information 2025-09-23 18:34:54.904649 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-09-23 18:34:54.904674 | debian-bookworm | Hostname: debian 2025-09-23 18:34:54.904695 | debian-bookworm | Username: zuul 2025-09-23 18:34:54.904715 | debian-bookworm | Distro: Debian 12.12 2025-09-23 18:34:54.904739 | debian-bookworm | Provider: regiocloud-a 2025-09-23 18:34:54.904759 | debian-bookworm | Region: 2025-09-23 18:34:54.904781 | debian-bookworm | Label: debian-bookworm 2025-09-23 18:34:54.904800 | debian-bookworm | Product Name: OpenStack Nova 2025-09-23 18:34:54.904819 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe5d:978a 2025-09-23 18:34:54.934177 | 2025-09-23 18:34:54.934337 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-09-23 18:34:55.416815 | debian-bookworm -> localhost | changed 2025-09-23 18:34:55.431364 | 2025-09-23 18:34:55.431545 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-09-23 18:34:56.468723 | debian-bookworm -> localhost | changed 2025-09-23 18:34:56.481071 | 2025-09-23 18:34:56.481206 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-09-23 18:34:56.750561 | debian-bookworm -> localhost | ok 2025-09-23 18:34:56.760071 | 2025-09-23 18:34:56.760223 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-09-23 18:34:56.794099 | debian-bookworm | ok 2025-09-23 18:34:56.815851 | debian-bookworm | included: /var/lib/zuul/builds/0fdf43857125454aade867d6190be5c0/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-09-23 18:34:56.822267 | 2025-09-23 18:34:56.822370 | TASK [add-build-sshkey : Create Temp SSH key] 2025-09-23 18:34:58.400001 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-09-23 18:34:58.401876 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/0fdf43857125454aade867d6190be5c0/work/0fdf43857125454aade867d6190be5c0_id_rsa 2025-09-23 18:34:58.402038 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/0fdf43857125454aade867d6190be5c0/work/0fdf43857125454aade867d6190be5c0_id_rsa.pub 2025-09-23 18:34:58.402121 | debian-bookworm -> localhost | The key fingerprint is: 2025-09-23 18:34:58.402192 | debian-bookworm -> localhost | SHA256:K78RJ2lgHuRWQuN2Q/KZ1buzCnaumHIa8Go6BKMM4G8 zuul-build-sshkey 2025-09-23 18:34:58.402284 | debian-bookworm -> localhost | The key's randomart image is: 2025-09-23 18:34:58.402351 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-09-23 18:34:58.402416 | debian-bookworm -> localhost | | .* o .. | 2025-09-23 18:34:58.402480 | debian-bookworm -> localhost | |. + B + . | 2025-09-23 18:34:58.402541 | debian-bookworm -> localhost | |o O * . | 2025-09-23 18:34:58.402601 | debian-bookworm -> localhost | |+. = + o . | 2025-09-23 18:34:58.402660 | debian-bookworm -> localhost | |=.o . S . . | 2025-09-23 18:34:58.402742 | debian-bookworm -> localhost | |.o E . = o | 2025-09-23 18:34:58.402804 | debian-bookworm -> localhost | |. . o . = . o | 2025-09-23 18:34:58.402920 | debian-bookworm -> localhost | |. .....* = . | 2025-09-23 18:34:58.403059 | debian-bookworm -> localhost | |.+. .+o +oo. | 2025-09-23 18:34:58.403124 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-09-23 18:34:58.403263 | debian-bookworm -> localhost | ok: Runtime: 0:00:01.037212 2025-09-23 18:34:58.415444 | 2025-09-23 18:34:58.415597 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-09-23 18:34:58.450032 | debian-bookworm | ok 2025-09-23 18:34:58.462130 | debian-bookworm | included: /var/lib/zuul/builds/0fdf43857125454aade867d6190be5c0/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-09-23 18:34:58.471341 | 2025-09-23 18:34:58.471441 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-09-23 18:34:58.495391 | debian-bookworm | skipping: Conditional result was False 2025-09-23 18:34:58.509039 | 2025-09-23 18:34:58.509197 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-09-23 18:34:59.177208 | debian-bookworm | changed 2025-09-23 18:34:59.182816 | 2025-09-23 18:34:59.182988 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-09-23 18:34:59.451293 | debian-bookworm | ok 2025-09-23 18:34:59.459732 | 2025-09-23 18:34:59.459858 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-09-23 18:35:00.130780 | debian-bookworm | changed 2025-09-23 18:35:00.139470 | 2025-09-23 18:35:00.139590 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-09-23 18:35:00.812710 | debian-bookworm | changed 2025-09-23 18:35:00.818566 | 2025-09-23 18:35:00.818680 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-09-23 18:35:00.842620 | debian-bookworm | skipping: Conditional result was False 2025-09-23 18:35:00.849758 | 2025-09-23 18:35:00.849874 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-09-23 18:35:01.313318 | debian-bookworm -> localhost | changed 2025-09-23 18:35:01.335875 | 2025-09-23 18:35:01.336060 | TASK [add-build-sshkey : Add back temp key] 2025-09-23 18:35:01.684266 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/0fdf43857125454aade867d6190be5c0/work/0fdf43857125454aade867d6190be5c0_id_rsa (zuul-build-sshkey) 2025-09-23 18:35:01.684754 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.021886 2025-09-23 18:35:01.701584 | 2025-09-23 18:35:01.701725 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-09-23 18:35:02.115627 | debian-bookworm | ok 2025-09-23 18:35:02.124776 | 2025-09-23 18:35:02.124932 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-09-23 18:35:02.159656 | debian-bookworm | skipping: Conditional result was False 2025-09-23 18:35:02.178231 | 2025-09-23 18:35:02.178361 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-09-23 18:35:02.611696 | debian-bookworm | ok 2025-09-23 18:35:02.627342 | 2025-09-23 18:35:02.627505 | TASK [validate-host : Define zuul_info_dir fact] 2025-09-23 18:35:02.672210 | debian-bookworm | ok 2025-09-23 18:35:02.680508 | 2025-09-23 18:35:02.680645 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-09-23 18:35:02.999372 | debian-bookworm -> localhost | ok 2025-09-23 18:35:03.013393 | 2025-09-23 18:35:03.013560 | TASK [validate-host : Collect information about the host] 2025-09-23 18:35:04.175186 | debian-bookworm | ok 2025-09-23 18:35:04.185692 | 2025-09-23 18:35:04.185821 | TASK [validate-host : Sanitize hostname] 2025-09-23 18:35:04.300901 | debian-bookworm | ok 2025-09-23 18:35:04.348851 | 2025-09-23 18:35:04.349016 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-09-23 18:35:04.912753 | debian-bookworm -> localhost | changed 2025-09-23 18:35:04.923506 | 2025-09-23 18:35:04.923664 | TASK [validate-host : Collect information about zuul worker] 2025-09-23 18:35:05.326193 | debian-bookworm | ok 2025-09-23 18:35:05.331001 | 2025-09-23 18:35:05.331113 | TASK [validate-host : Write out all zuul information for each host] 2025-09-23 18:35:05.843691 | debian-bookworm -> localhost | changed 2025-09-23 18:35:05.853561 | 2025-09-23 18:35:05.853680 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-09-23 18:35:06.130246 | debian-bookworm | ok 2025-09-23 18:35:06.140270 | 2025-09-23 18:35:06.140414 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-09-23 18:35:06.990825 | debian-bookworm | changed: 2025-09-23 18:35:06.991115 | debian-bookworm | cd+++++++++ src/ 2025-09-23 18:35:06.991159 | debian-bookworm | cd+++++++++ src/github.com/ 2025-09-23 18:35:06.991191 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-09-23 18:35:06.991220 | debian-bookworm | cd+++++++++ src/github.com/osism/container-image-kolla-ansible/ 2025-09-23 18:35:06.991248 | debian-bookworm | Containerfile 2025-09-23 18:35:06.991418 | debian-bookworm | 2025-09-23 18:45:22.465087 | debian-bookworm | ERROR 2025-09-23 18:45:22.465503 | debian-bookworm | { 2025-09-23 18:45:22.465607 | debian-bookworm | "delta": "0:00:22.074930", 2025-09-23 18:45:22.465674 | debian-bookworm | "end": "2025-09-23 18:45:22.215411", 2025-09-23 18:45:22.465757 | debian-bookworm | "msg": "non-zero return code", 2025-09-23 18:45:22.465814 | debian-bookworm | "rc": 1, 2025-09-23 18:45:22.465862 | debian-bookworm | "start": "2025-09-23 18:45:00.140481" 2025-09-23 18:45:22.465906 | debian-bookworm | } failure 2025-09-23 18:45:22.470002 | 2025-09-23 18:45:22.470328 | PLAY RECAP 2025-09-23 18:45:22.470488 | debian-bookworm | ok: 4 changed: 3 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2025-09-23 18:45:22.470565 | 2025-09-23 18:45:22.611911 | RUN END RESULT_NORMAL: [untrusted : github.com/osism/container-image-kolla-ansible/playbooks/build.yml@main] 2025-09-23 18:45:22.616384 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-09-23 18:45:23.363600 | 2025-09-23 18:45:23.363751 | PLAY [Base post] 2025-09-23 18:45:23.377505 | 2025-09-23 18:45:23.377638 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-09-23 18:45:24.199298 | debian-bookworm | changed 2025-09-23 18:45:24.206177 | 2025-09-23 18:45:24.206293 | PLAY RECAP 2025-09-23 18:45:24.206370 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-09-23 18:45:24.206432 | 2025-09-23 18:45:24.325755 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-09-23 18:45:24.328315 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2025-09-23 18:45:25.137613 | 2025-09-23 18:45:25.137778 | PLAY [Base post-logs] 2025-09-23 18:45:25.148670 | 2025-09-23 18:45:25.148801 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-09-23 18:45:25.614385 | localhost | changed 2025-09-23 18:45:25.630328 | 2025-09-23 18:45:25.630494 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-09-23 18:45:25.667954 | localhost | ok 2025-09-23 18:45:25.674274 | 2025-09-23 18:45:25.674422 | TASK [Set zuul-log-path fact] 2025-09-23 18:45:25.691112 | localhost | ok 2025-09-23 18:45:25.703158 | 2025-09-23 18:45:25.703286 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-09-23 18:45:25.740571 | localhost | ok 2025-09-23 18:45:25.747171 | 2025-09-23 18:45:25.747338 | TASK [upload-logs : Create log directories] 2025-09-23 18:45:26.240941 | localhost | changed 2025-09-23 18:45:26.243836 | 2025-09-23 18:45:26.243943 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-09-23 18:45:26.723449 | localhost -> localhost | ok: Runtime: 0:00:00.005930 2025-09-23 18:45:26.731406 | 2025-09-23 18:45:26.731593 | TASK [upload-logs : Upload logs to log server] 2025-09-23 18:45:27.308353 | localhost | Output suppressed because no_log was given 2025-09-23 18:45:27.311990 | 2025-09-23 18:45:27.312184 | LOOP [upload-logs : Compress console log and json output] 2025-09-23 18:45:27.378500 | localhost | skipping: Conditional result was False 2025-09-23 18:45:27.383554 | localhost | skipping: Conditional result was False 2025-09-23 18:45:27.392673 | 2025-09-23 18:45:27.392781 | LOOP [upload-logs : Upload compressed console log and json output] 2025-09-23 18:45:27.452552 | localhost | skipping: Conditional result was False 2025-09-23 18:45:27.453199 | 2025-09-23 18:45:27.456745 | localhost | skipping: Conditional result was False 2025-09-23 18:45:27.469856 | 2025-09-23 18:45:27.469972 | LOOP [upload-logs : Upload console log and json output]