2025-09-20 09:26:18.222159 | Job console starting 2025-09-20 09:26:18.235200 | Updating git repos 2025-09-20 09:26:18.264663 | Cloning repos into workspace 2025-09-20 09:26:18.314124 | Restoring repo states 2025-09-20 09:26:18.327427 | Merging changes 2025-09-20 09:26:18.327456 | Checking out repos 2025-09-20 09:26:18.384031 | Preparing playbooks 2025-09-20 09:26:19.004241 | Running Ansible setup 2025-09-20 09:26:23.279072 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-09-20 09:26:24.037369 | 2025-09-20 09:26:24.037533 | PLAY [Base pre] 2025-09-20 09:26:24.056016 | 2025-09-20 09:26:24.056168 | TASK [Setup log path fact] 2025-09-20 09:26:24.087240 | debian-bookworm | ok 2025-09-20 09:26:24.105306 | 2025-09-20 09:26:24.105481 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-09-20 09:26:24.136986 | debian-bookworm | ok 2025-09-20 09:26:24.147687 | 2025-09-20 09:26:24.147821 | TASK [emit-job-header : Print job information] 2025-09-20 09:26:24.200363 | # Job Information 2025-09-20 09:26:24.200612 | Ansible Version: 2.16.14 2025-09-20 09:26:24.200666 | Job: container-image-kolla-ansible-push-2024.1 2025-09-20 09:26:24.200718 | Pipeline: post 2025-09-20 09:26:24.200755 | Executor: 521e9411259a 2025-09-20 09:26:24.200787 | Triggered by: https://github.com/osism/container-image-kolla-ansible/commit/5288a1582401fd4f31aa53fb42873b2ee3aa7189 2025-09-20 09:26:24.200821 | Event ID: b2c18cb0-9602-11f0-9b4b-856f3d4a1e6d 2025-09-20 09:26:24.207573 | 2025-09-20 09:26:24.207703 | LOOP [emit-job-header : Print node information] 2025-09-20 09:26:24.317181 | debian-bookworm | ok: 2025-09-20 09:26:24.317550 | debian-bookworm | # Node Information 2025-09-20 09:26:24.317599 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-09-20 09:26:24.317625 | debian-bookworm | Hostname: debian 2025-09-20 09:26:24.317647 | debian-bookworm | Username: zuul 2025-09-20 09:26:24.317668 | debian-bookworm | Distro: Debian 12.12 2025-09-20 09:26:24.317691 | debian-bookworm | Provider: regiocloud-a 2025-09-20 09:26:24.317712 | debian-bookworm | Region: 2025-09-20 09:26:24.317733 | debian-bookworm | Label: debian-bookworm 2025-09-20 09:26:24.317754 | debian-bookworm | Product Name: OpenStack Nova 2025-09-20 09:26:24.317773 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe68:76a7 2025-09-20 09:26:24.336774 | 2025-09-20 09:26:24.336900 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-09-20 09:26:24.817275 | debian-bookworm -> localhost | changed 2025-09-20 09:26:24.824852 | 2025-09-20 09:26:24.824972 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-09-20 09:26:25.864450 | debian-bookworm -> localhost | changed 2025-09-20 09:26:25.882579 | 2025-09-20 09:26:25.882724 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-09-20 09:26:26.171263 | debian-bookworm -> localhost | ok 2025-09-20 09:26:26.185505 | 2025-09-20 09:26:26.185686 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-09-20 09:26:26.220058 | debian-bookworm | ok 2025-09-20 09:26:26.239768 | debian-bookworm | included: /var/lib/zuul/builds/03e13ab9dfde48428d7b7863e5266c2f/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-09-20 09:26:26.245799 | 2025-09-20 09:26:26.245893 | TASK [add-build-sshkey : Create Temp SSH key] 2025-09-20 09:26:27.741539 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-09-20 09:26:27.742028 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/03e13ab9dfde48428d7b7863e5266c2f/work/03e13ab9dfde48428d7b7863e5266c2f_id_rsa 2025-09-20 09:26:27.742234 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/03e13ab9dfde48428d7b7863e5266c2f/work/03e13ab9dfde48428d7b7863e5266c2f_id_rsa.pub 2025-09-20 09:26:27.742348 | debian-bookworm -> localhost | The key fingerprint is: 2025-09-20 09:26:27.742424 | debian-bookworm -> localhost | SHA256:qghBxFPmpbv0Pi96cN7a3s/Ymi+SMytB0ZjXQIzkaEo zuul-build-sshkey 2025-09-20 09:26:27.742510 | debian-bookworm -> localhost | The key's randomart image is: 2025-09-20 09:26:27.742574 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-09-20 09:26:27.742635 | debian-bookworm -> localhost | |...o.oBoo | 2025-09-20 09:26:27.742695 | debian-bookworm -> localhost | |.oo == + . | 2025-09-20 09:26:27.742750 | debian-bookworm -> localhost | | E.= .o | 2025-09-20 09:26:27.742804 | debian-bookworm -> localhost | |o o .. | 2025-09-20 09:26:27.742900 | debian-bookworm -> localhost | |.. o. S | 2025-09-20 09:26:27.742970 | debian-bookworm -> localhost | | ...oo . | 2025-09-20 09:26:27.743029 | debian-bookworm -> localhost | |. .+.+ . | 2025-09-20 09:26:27.743086 | debian-bookworm -> localhost | | . ..B.*..= | 2025-09-20 09:26:27.743143 | debian-bookworm -> localhost | | ..+oB===== | 2025-09-20 09:26:27.743200 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-09-20 09:26:27.743357 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.977839 2025-09-20 09:26:27.758181 | 2025-09-20 09:26:27.758347 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-09-20 09:26:27.797607 | debian-bookworm | ok 2025-09-20 09:26:27.812464 | debian-bookworm | included: /var/lib/zuul/builds/03e13ab9dfde48428d7b7863e5266c2f/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-09-20 09:26:27.822516 | 2025-09-20 09:26:27.822616 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-09-20 09:26:27.846245 | debian-bookworm | skipping: Conditional result was False 2025-09-20 09:26:27.853803 | 2025-09-20 09:26:27.853913 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-09-20 09:26:28.489850 | debian-bookworm | changed 2025-09-20 09:26:28.495560 | 2025-09-20 09:26:28.495665 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-09-20 09:26:28.757143 | debian-bookworm | ok 2025-09-20 09:26:28.763116 | 2025-09-20 09:26:28.763218 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-09-20 09:26:29.409562 | debian-bookworm | changed 2025-09-20 09:26:29.417018 | 2025-09-20 09:26:29.417133 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-09-20 09:26:30.092646 | debian-bookworm | changed 2025-09-20 09:26:30.101257 | 2025-09-20 09:26:30.101410 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-09-20 09:26:30.125838 | debian-bookworm | skipping: Conditional result was False 2025-09-20 09:26:30.131893 | 2025-09-20 09:26:30.132002 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-09-20 09:26:30.570268 | debian-bookworm -> localhost | changed 2025-09-20 09:26:30.582120 | 2025-09-20 09:26:30.582745 | TASK [add-build-sshkey : Add back temp key] 2025-09-20 09:26:30.921495 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/03e13ab9dfde48428d7b7863e5266c2f/work/03e13ab9dfde48428d7b7863e5266c2f_id_rsa (zuul-build-sshkey) 2025-09-20 09:26:30.921769 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.020646 2025-09-20 09:26:30.930521 | 2025-09-20 09:26:30.930638 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-09-20 09:26:31.354662 | debian-bookworm | ok 2025-09-20 09:26:31.363987 | 2025-09-20 09:26:31.364129 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-09-20 09:26:31.388411 | debian-bookworm | skipping: Conditional result was False 2025-09-20 09:26:31.405717 | 2025-09-20 09:26:31.405838 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-09-20 09:26:31.839656 | debian-bookworm | ok 2025-09-20 09:26:31.854434 | 2025-09-20 09:26:31.854553 | TASK [validate-host : Define zuul_info_dir fact] 2025-09-20 09:26:31.900346 | debian-bookworm | ok 2025-09-20 09:26:31.908158 | 2025-09-20 09:26:31.908290 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-09-20 09:26:32.226129 | debian-bookworm -> localhost | ok 2025-09-20 09:26:32.233027 | 2025-09-20 09:26:32.233139 | TASK [validate-host : Collect information about the host] 2025-09-20 09:26:33.334537 | debian-bookworm | ok 2025-09-20 09:26:33.346882 | 2025-09-20 09:26:33.347003 | TASK [validate-host : Sanitize hostname] 2025-09-20 09:26:33.465150 | debian-bookworm | ok 2025-09-20 09:26:33.507551 | 2025-09-20 09:26:33.507675 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-09-20 09:26:34.051645 | debian-bookworm -> localhost | changed 2025-09-20 09:26:34.057610 | 2025-09-20 09:26:34.057726 | TASK [validate-host : Collect information about zuul worker] 2025-09-20 09:26:34.483366 | debian-bookworm | ok 2025-09-20 09:26:34.489922 | 2025-09-20 09:26:34.490055 | TASK [validate-host : Write out all zuul information for each host] 2025-09-20 09:26:35.061182 | debian-bookworm -> localhost | changed 2025-09-20 09:26:35.071261 | 2025-09-20 09:26:35.071449 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-09-20 09:26:35.340555 | debian-bookworm | ok 2025-09-20 09:26:35.349064 | 2025-09-20 09:26:35.349196 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-09-20 09:26:36.197366 | debian-bookworm | changed: 2025-09-20 09:26:36.197573 | debian-bookworm | cd+++++++++ src/ 2025-09-20 09:26:36.197608 | debian-bookworm | cd+++++++++ src/github.com/ 2025-09-20 09:26:36.197633 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-09-20 09:26:36.197656 | debian-bookworm | cd+++++++++ src/github.com/osism/container-image-kolla-ansible/ 2025-09-20 09:26:36.197676 | debian-bookworm | Containerfile 2025-09-20 09:26:36.197803 | debian-bookworm | 2025-09-20 09:39:03.116562 | debian-bookworm | ERROR 2025-09-20 09:39:03.118273 | debian-bookworm | { 2025-09-20 09:39:03.118423 | debian-bookworm | "delta": "0:00:21.653049", 2025-09-20 09:39:03.118499 | debian-bookworm | "end": "2025-09-20 09:39:02.985090", 2025-09-20 09:39:03.118597 | debian-bookworm | "msg": "non-zero return code", 2025-09-20 09:39:03.118658 | debian-bookworm | "rc": 1, 2025-09-20 09:39:03.118711 | debian-bookworm | "start": "2025-09-20 09:38:41.332041" 2025-09-20 09:39:03.118761 | debian-bookworm | } failure 2025-09-20 09:39:03.123708 | 2025-09-20 09:39:03.123869 | PLAY RECAP 2025-09-20 09:39:03.123997 | debian-bookworm | ok: 4 changed: 3 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2025-09-20 09:39:03.124057 | 2025-09-20 09:39:03.283104 | RUN END RESULT_NORMAL: [untrusted : github.com/osism/container-image-kolla-ansible/playbooks/build.yml@main] 2025-09-20 09:39:03.284219 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-09-20 09:39:03.987350 | 2025-09-20 09:39:03.987505 | PLAY [Base post] 2025-09-20 09:39:04.000974 | 2025-09-20 09:39:04.001103 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-09-20 09:39:04.829812 | debian-bookworm | changed 2025-09-20 09:39:04.839734 | 2025-09-20 09:39:04.839872 | PLAY RECAP 2025-09-20 09:39:04.839957 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-09-20 09:39:04.840036 | 2025-09-20 09:39:04.948932 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-09-20 09:39:04.952072 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2025-09-20 09:39:05.725351 | 2025-09-20 09:39:05.725514 | PLAY [Base post-logs] 2025-09-20 09:39:05.736332 | 2025-09-20 09:39:05.736465 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-09-20 09:39:06.200816 | localhost | changed 2025-09-20 09:39:06.217308 | 2025-09-20 09:39:06.217487 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-09-20 09:39:06.255306 | localhost | ok 2025-09-20 09:39:06.261202 | 2025-09-20 09:39:06.261348 | TASK [Set zuul-log-path fact] 2025-09-20 09:39:06.278187 | localhost | ok 2025-09-20 09:39:06.287639 | 2025-09-20 09:39:06.287753 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-09-20 09:39:06.313479 | localhost | ok 2025-09-20 09:39:06.318609 | 2025-09-20 09:39:06.318761 | TASK [upload-logs : Create log directories] 2025-09-20 09:39:06.803687 | localhost | changed 2025-09-20 09:39:06.806558 | 2025-09-20 09:39:06.806662 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-09-20 09:39:07.291360 | localhost -> localhost | ok: Runtime: 0:00:00.006552 2025-09-20 09:39:07.300880 | 2025-09-20 09:39:07.301156 | TASK [upload-logs : Upload logs to log server] 2025-09-20 09:39:07.849787 | localhost | Output suppressed because no_log was given 2025-09-20 09:39:07.854109 | 2025-09-20 09:39:07.854405 | LOOP [upload-logs : Compress console log and json output] 2025-09-20 09:39:07.907858 | localhost | skipping: Conditional result was False 2025-09-20 09:39:07.912872 | localhost | skipping: Conditional result was False 2025-09-20 09:39:07.927283 | 2025-09-20 09:39:07.927502 | LOOP [upload-logs : Upload compressed console log and json output] 2025-09-20 09:39:07.975892 | localhost | skipping: Conditional result was False 2025-09-20 09:39:07.976598 | 2025-09-20 09:39:07.978404 | localhost | skipping: Conditional result was False 2025-09-20 09:39:07.985307 | 2025-09-20 09:39:07.985502 | LOOP [upload-logs : Upload console log and json output]