2025-09-28 12:07:51.481409 | Job console starting 2025-09-28 12:07:51.512217 | Updating git repos 2025-09-28 12:07:51.540552 | Cloning repos into workspace 2025-09-28 12:07:51.587694 | Restoring repo states 2025-09-28 12:07:51.598948 | Merging changes 2025-09-28 12:07:51.598963 | Checking out repos 2025-09-28 12:07:51.680994 | Preparing playbooks 2025-09-28 12:07:52.199985 | Running Ansible setup 2025-09-28 12:07:56.355957 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-09-28 12:07:57.094639 | 2025-09-28 12:07:57.094801 | PLAY [Base pre] 2025-09-28 12:07:57.111106 | 2025-09-28 12:07:57.111242 | TASK [Setup log path fact] 2025-09-28 12:07:57.140466 | debian-bookworm | ok 2025-09-28 12:07:57.156228 | 2025-09-28 12:07:57.156367 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-09-28 12:07:57.185874 | debian-bookworm | ok 2025-09-28 12:07:57.195947 | 2025-09-28 12:07:57.196068 | TASK [emit-job-header : Print job information] 2025-09-28 12:07:57.235425 | # Job Information 2025-09-28 12:07:57.235604 | Ansible Version: 2.16.14 2025-09-28 12:07:57.235641 | Job: container-image-kolla-ansible-push-2024.2 2025-09-28 12:07:57.235675 | Pipeline: post 2025-09-28 12:07:57.235698 | Executor: 521e9411259a 2025-09-28 12:07:57.235719 | Triggered by: https://github.com/osism/container-image-kolla-ansible/commit/7721685a520a230598fb6d88e8c5a4ca9f0180bd 2025-09-28 12:07:57.235741 | Event ID: bde4d612-9c63-11f0-9692-31c526073aba 2025-09-28 12:07:57.240720 | 2025-09-28 12:07:57.240824 | LOOP [emit-job-header : Print node information] 2025-09-28 12:07:57.353495 | debian-bookworm | ok: 2025-09-28 12:07:57.353745 | debian-bookworm | # Node Information 2025-09-28 12:07:57.353798 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-09-28 12:07:57.353832 | debian-bookworm | Hostname: debian 2025-09-28 12:07:57.353862 | debian-bookworm | Username: zuul 2025-09-28 12:07:57.353889 | debian-bookworm | Distro: Debian 12.12 2025-09-28 12:07:57.353920 | debian-bookworm | Provider: regiocloud-a 2025-09-28 12:07:57.353948 | debian-bookworm | Region: 2025-09-28 12:07:57.353976 | debian-bookworm | Label: debian-bookworm 2025-09-28 12:07:57.354003 | debian-bookworm | Product Name: OpenStack Nova 2025-09-28 12:07:57.354029 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe23:5dc5 2025-09-28 12:07:57.368757 | 2025-09-28 12:07:57.368881 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-09-28 12:07:57.847943 | debian-bookworm -> localhost | changed 2025-09-28 12:07:57.855510 | 2025-09-28 12:07:57.855633 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-09-28 12:07:58.884304 | debian-bookworm -> localhost | changed 2025-09-28 12:07:58.895743 | 2025-09-28 12:07:58.895872 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-09-28 12:07:59.185108 | debian-bookworm -> localhost | ok 2025-09-28 12:07:59.191720 | 2025-09-28 12:07:59.191842 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-09-28 12:07:59.220793 | debian-bookworm | ok 2025-09-28 12:07:59.238979 | debian-bookworm | included: /var/lib/zuul/builds/9c866c7ab56c46509a02c0f1d6b781bd/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-09-28 12:07:59.245305 | 2025-09-28 12:07:59.245467 | TASK [add-build-sshkey : Create Temp SSH key] 2025-09-28 12:08:01.039001 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-09-28 12:08:01.039230 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/9c866c7ab56c46509a02c0f1d6b781bd/work/9c866c7ab56c46509a02c0f1d6b781bd_id_rsa 2025-09-28 12:08:01.039268 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/9c866c7ab56c46509a02c0f1d6b781bd/work/9c866c7ab56c46509a02c0f1d6b781bd_id_rsa.pub 2025-09-28 12:08:01.039294 | debian-bookworm -> localhost | The key fingerprint is: 2025-09-28 12:08:01.039318 | debian-bookworm -> localhost | SHA256:MdRx0hrtvyj/sghcFPG6+hUm0v+3gno9S08jaHGqt4A zuul-build-sshkey 2025-09-28 12:08:01.039352 | debian-bookworm -> localhost | The key's randomart image is: 2025-09-28 12:08:01.039394 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-09-28 12:08:01.039417 | debian-bookworm -> localhost | | ..==. | 2025-09-28 12:08:01.039438 | debian-bookworm -> localhost | | . o=o | 2025-09-28 12:08:01.039458 | debian-bookworm -> localhost | | o .+. | 2025-09-28 12:08:01.039478 | debian-bookworm -> localhost | | =... | 2025-09-28 12:08:01.039497 | debian-bookworm -> localhost | | S =.oo | 2025-09-28 12:08:01.039522 | debian-bookworm -> localhost | | ..o ==.. | 2025-09-28 12:08:01.039542 | debian-bookworm -> localhost | | Eo..+=+ + | 2025-09-28 12:08:01.039561 | debian-bookworm -> localhost | | o=*+== o| 2025-09-28 12:08:01.039582 | debian-bookworm -> localhost | | .+*++=*+.| 2025-09-28 12:08:01.039601 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-09-28 12:08:01.039649 | debian-bookworm -> localhost | ok: Runtime: 0:00:01.334984 2025-09-28 12:08:01.046241 | 2025-09-28 12:08:01.046388 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-09-28 12:08:01.065647 | debian-bookworm | ok 2025-09-28 12:08:01.075208 | debian-bookworm | included: /var/lib/zuul/builds/9c866c7ab56c46509a02c0f1d6b781bd/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-09-28 12:08:01.084386 | 2025-09-28 12:08:01.084489 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-09-28 12:08:01.108236 | debian-bookworm | skipping: Conditional result was False 2025-09-28 12:08:01.116225 | 2025-09-28 12:08:01.116354 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-09-28 12:08:01.740731 | debian-bookworm | changed 2025-09-28 12:08:01.749440 | 2025-09-28 12:08:01.749562 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-09-28 12:08:02.004341 | debian-bookworm | ok 2025-09-28 12:08:02.011180 | 2025-09-28 12:08:02.011293 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-09-28 12:08:02.653292 | debian-bookworm | changed 2025-09-28 12:08:02.662306 | 2025-09-28 12:08:02.662454 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-09-28 12:08:03.288987 | debian-bookworm | changed 2025-09-28 12:08:03.299415 | 2025-09-28 12:08:03.299678 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-09-28 12:08:03.325772 | debian-bookworm | skipping: Conditional result was False 2025-09-28 12:08:03.332330 | 2025-09-28 12:08:03.332494 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-09-28 12:08:03.770537 | debian-bookworm -> localhost | changed 2025-09-28 12:08:03.783043 | 2025-09-28 12:08:03.783198 | TASK [add-build-sshkey : Add back temp key] 2025-09-28 12:08:04.121639 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/9c866c7ab56c46509a02c0f1d6b781bd/work/9c866c7ab56c46509a02c0f1d6b781bd_id_rsa (zuul-build-sshkey) 2025-09-28 12:08:04.121887 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.020937 2025-09-28 12:08:04.130347 | 2025-09-28 12:08:04.130521 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-09-28 12:08:04.524597 | debian-bookworm | ok 2025-09-28 12:08:04.529974 | 2025-09-28 12:08:04.530084 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-09-28 12:08:04.565430 | debian-bookworm | skipping: Conditional result was False 2025-09-28 12:08:04.576743 | 2025-09-28 12:08:04.576860 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-09-28 12:08:05.020771 | debian-bookworm | ok 2025-09-28 12:08:05.033547 | 2025-09-28 12:08:05.033706 | TASK [validate-host : Define zuul_info_dir fact] 2025-09-28 12:08:05.074396 | debian-bookworm | ok 2025-09-28 12:08:05.080039 | 2025-09-28 12:08:05.080156 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-09-28 12:08:05.409138 | debian-bookworm -> localhost | ok 2025-09-28 12:08:05.423934 | 2025-09-28 12:08:05.424095 | TASK [validate-host : Collect information about the host] 2025-09-28 12:08:06.533298 | debian-bookworm | ok 2025-09-28 12:08:06.552954 | 2025-09-28 12:08:06.553108 | TASK [validate-host : Sanitize hostname] 2025-09-28 12:08:06.675313 | debian-bookworm | ok 2025-09-28 12:08:06.721495 | 2025-09-28 12:08:06.721641 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-09-28 12:08:07.292405 | debian-bookworm -> localhost | changed 2025-09-28 12:08:07.298803 | 2025-09-28 12:08:07.299103 | TASK [validate-host : Collect information about zuul worker] 2025-09-28 12:08:07.707264 | debian-bookworm | ok 2025-09-28 12:08:07.714406 | 2025-09-28 12:08:07.714538 | TASK [validate-host : Write out all zuul information for each host] 2025-09-28 12:08:08.248015 | debian-bookworm -> localhost | changed 2025-09-28 12:08:08.263981 | 2025-09-28 12:08:08.264137 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-09-28 12:08:08.542739 | debian-bookworm | ok 2025-09-28 12:08:08.552035 | 2025-09-28 12:08:08.552167 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-09-28 12:08:09.435654 | debian-bookworm | changed: 2025-09-28 12:08:09.435869 | debian-bookworm | cd+++++++++ src/ 2025-09-28 12:08:09.435905 | debian-bookworm | cd+++++++++ src/github.com/ 2025-09-28 12:08:09.435930 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-09-28 12:08:09.435952 | debian-bookworm | cd+++++++++ src/github.com/osism/container-image-kolla-ansible/ 2025-09-28 12:08:09.435972 | debian-bookworm | Containerfile 2025-09-28 12:08:09.436102 | debian-bookworm | 2025-09-28 12:21:05.482117 | debian-bookworm | ERROR 2025-09-28 12:21:05.482593 | debian-bookworm | { 2025-09-28 12:21:05.482708 | debian-bookworm | "delta": "0:00:20.954135", 2025-09-28 12:21:05.482781 | debian-bookworm | "end": "2025-09-28 12:21:05.149375", 2025-09-28 12:21:05.482897 | debian-bookworm | "msg": "non-zero return code", 2025-09-28 12:21:05.482961 | debian-bookworm | "rc": 1, 2025-09-28 12:21:05.483019 | debian-bookworm | "start": "2025-09-28 12:20:44.195240" 2025-09-28 12:21:05.483074 | debian-bookworm | } failure 2025-09-28 12:21:05.489025 | 2025-09-28 12:21:05.489159 | PLAY RECAP 2025-09-28 12:21:05.489305 | debian-bookworm | ok: 4 changed: 3 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2025-09-28 12:21:05.489369 | 2025-09-28 12:21:05.667718 | RUN END RESULT_NORMAL: [untrusted : github.com/osism/container-image-kolla-ansible/playbooks/build.yml@main] 2025-09-28 12:21:05.670637 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-09-28 12:21:06.391019 | 2025-09-28 12:21:06.391165 | PLAY [Base post] 2025-09-28 12:21:06.404700 | 2025-09-28 12:21:06.404820 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-09-28 12:21:07.243214 | debian-bookworm | changed 2025-09-28 12:21:07.253297 | 2025-09-28 12:21:07.253435 | PLAY RECAP 2025-09-28 12:21:07.253519 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-09-28 12:21:07.253598 | 2025-09-28 12:21:07.377837 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-09-28 12:21:07.378915 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2025-09-28 12:21:08.116019 | 2025-09-28 12:21:08.116174 | PLAY [Base post-logs] 2025-09-28 12:21:08.126568 | 2025-09-28 12:21:08.126690 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-09-28 12:21:08.600781 | localhost | changed 2025-09-28 12:21:08.618210 | 2025-09-28 12:21:08.618434 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-09-28 12:21:08.657368 | localhost | ok 2025-09-28 12:21:08.664740 | 2025-09-28 12:21:08.664917 | TASK [Set zuul-log-path fact] 2025-09-28 12:21:08.682620 | localhost | ok 2025-09-28 12:21:08.693695 | 2025-09-28 12:21:08.693814 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-09-28 12:21:08.719316 | localhost | ok 2025-09-28 12:21:08.723478 | 2025-09-28 12:21:08.723603 | TASK [upload-logs : Create log directories] 2025-09-28 12:21:09.199189 | localhost | changed 2025-09-28 12:21:09.202679 | 2025-09-28 12:21:09.202803 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-09-28 12:21:09.684156 | localhost -> localhost | ok: Runtime: 0:00:00.006613 2025-09-28 12:21:09.694135 | 2025-09-28 12:21:09.694343 | TASK [upload-logs : Upload logs to log server] 2025-09-28 12:21:10.252080 | localhost | Output suppressed because no_log was given 2025-09-28 12:21:10.256085 | 2025-09-28 12:21:10.256297 | LOOP [upload-logs : Compress console log and json output] 2025-09-28 12:21:10.313744 | localhost | skipping: Conditional result was False 2025-09-28 12:21:10.318565 | localhost | skipping: Conditional result was False 2025-09-28 12:21:10.331774 | 2025-09-28 12:21:10.332008 | LOOP [upload-logs : Upload compressed console log and json output] 2025-09-28 12:21:10.376853 | localhost | skipping: Conditional result was False 2025-09-28 12:21:10.377480 | 2025-09-28 12:21:10.380685 | localhost | skipping: Conditional result was False 2025-09-28 12:21:10.395024 | 2025-09-28 12:21:10.395260 | LOOP [upload-logs : Upload console log and json output]