2025-09-20 09:17:51.916554 | Job console starting 2025-09-20 09:17:51.941611 | Updating git repos 2025-09-20 09:17:51.971012 | Cloning repos into workspace 2025-09-20 09:17:52.022933 | Restoring repo states 2025-09-20 09:17:52.040469 | Merging changes 2025-09-20 09:17:52.040489 | Checking out repos 2025-09-20 09:17:52.117645 | Preparing playbooks 2025-09-20 09:17:52.789304 | Running Ansible setup 2025-09-20 09:17:57.211263 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-09-20 09:17:57.983122 | 2025-09-20 09:17:57.983279 | PLAY [Base pre] 2025-09-20 09:17:58.000808 | 2025-09-20 09:17:58.000948 | TASK [Setup log path fact] 2025-09-20 09:17:58.019665 | debian-bookworm | ok 2025-09-20 09:17:58.035477 | 2025-09-20 09:17:58.035615 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-09-20 09:17:58.080666 | debian-bookworm | ok 2025-09-20 09:17:58.093944 | 2025-09-20 09:17:58.094128 | TASK [emit-job-header : Print job information] 2025-09-20 09:17:58.150655 | # Job Information 2025-09-20 09:17:58.150982 | Ansible Version: 2.16.14 2025-09-20 09:17:58.151045 | Job: container-image-kolla-ansible-push-2024.2 2025-09-20 09:17:58.151105 | Pipeline: post 2025-09-20 09:17:58.151147 | Executor: 521e9411259a 2025-09-20 09:17:58.151185 | Triggered by: https://github.com/osism/container-image-kolla-ansible/commit/9ea270718776797ddc6fbc89df8aa60bb908f900 2025-09-20 09:17:58.151224 | Event ID: ac757420-9602-11f0-8cca-30e416142cf2 2025-09-20 09:17:58.158771 | 2025-09-20 09:17:58.158988 | LOOP [emit-job-header : Print node information] 2025-09-20 09:17:58.270590 | debian-bookworm | ok: 2025-09-20 09:17:58.270886 | debian-bookworm | # Node Information 2025-09-20 09:17:58.270929 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-09-20 09:17:58.270954 | debian-bookworm | Hostname: debian 2025-09-20 09:17:58.270975 | debian-bookworm | Username: zuul 2025-09-20 09:17:58.270995 | debian-bookworm | Distro: Debian 12.12 2025-09-20 09:17:58.271018 | debian-bookworm | Provider: regiocloud-a 2025-09-20 09:17:58.271039 | debian-bookworm | Region: 2025-09-20 09:17:58.271060 | debian-bookworm | Label: debian-bookworm 2025-09-20 09:17:58.271081 | debian-bookworm | Product Name: OpenStack Nova 2025-09-20 09:17:58.271100 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe38:5495 2025-09-20 09:17:58.287931 | 2025-09-20 09:17:58.288070 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-09-20 09:17:58.757813 | debian-bookworm -> localhost | changed 2025-09-20 09:17:58.765488 | 2025-09-20 09:17:58.765620 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-09-20 09:17:59.922740 | debian-bookworm -> localhost | changed 2025-09-20 09:17:59.937022 | 2025-09-20 09:17:59.937154 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-09-20 09:18:00.246023 | debian-bookworm -> localhost | ok 2025-09-20 09:18:00.255648 | 2025-09-20 09:18:00.255790 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-09-20 09:18:00.285631 | debian-bookworm | ok 2025-09-20 09:18:00.305306 | debian-bookworm | included: /var/lib/zuul/builds/162682b193414eedb4414d1b151c664c/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-09-20 09:18:00.311653 | 2025-09-20 09:18:00.311767 | TASK [add-build-sshkey : Create Temp SSH key] 2025-09-20 09:18:01.465871 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-09-20 09:18:01.466054 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/162682b193414eedb4414d1b151c664c/work/162682b193414eedb4414d1b151c664c_id_rsa 2025-09-20 09:18:01.466092 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/162682b193414eedb4414d1b151c664c/work/162682b193414eedb4414d1b151c664c_id_rsa.pub 2025-09-20 09:18:01.466119 | debian-bookworm -> localhost | The key fingerprint is: 2025-09-20 09:18:01.466144 | debian-bookworm -> localhost | SHA256:C46qoosXmqru0b3KPcETWF2mjS/yOtAObXu9VPxqEuA zuul-build-sshkey 2025-09-20 09:18:01.466175 | debian-bookworm -> localhost | The key's randomart image is: 2025-09-20 09:18:01.466198 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-09-20 09:18:01.466218 | debian-bookworm -> localhost | | . .o | 2025-09-20 09:18:01.466240 | debian-bookworm -> localhost | | . .= | 2025-09-20 09:18:01.466261 | debian-bookworm -> localhost | | o o . | 2025-09-20 09:18:01.466280 | debian-bookworm -> localhost | | . . .. . | 2025-09-20 09:18:01.466300 | debian-bookworm -> localhost | | +.+.S. o | 2025-09-20 09:18:01.466325 | debian-bookworm -> localhost | | oo.XoE.o. . | 2025-09-20 09:18:01.466345 | debian-bookworm -> localhost | | + o*.=.o.. . | 2025-09-20 09:18:01.466365 | debian-bookworm -> localhost | |= +..=o..o .. | 2025-09-20 09:18:01.466386 | debian-bookworm -> localhost | |#*.o.++ .+. | 2025-09-20 09:18:01.466417 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-09-20 09:18:01.466469 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.519101 2025-09-20 09:18:01.473904 | 2025-09-20 09:18:01.473986 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-09-20 09:18:01.501533 | debian-bookworm | ok 2025-09-20 09:18:01.510158 | debian-bookworm | included: /var/lib/zuul/builds/162682b193414eedb4414d1b151c664c/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-09-20 09:18:01.519707 | 2025-09-20 09:18:01.519783 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-09-20 09:18:01.542755 | debian-bookworm | skipping: Conditional result was False 2025-09-20 09:18:01.549109 | 2025-09-20 09:18:01.549215 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-09-20 09:18:02.152734 | debian-bookworm | changed 2025-09-20 09:18:02.160547 | 2025-09-20 09:18:02.160647 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-09-20 09:18:02.434614 | debian-bookworm | ok 2025-09-20 09:18:02.442792 | 2025-09-20 09:18:02.442916 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-09-20 09:18:03.126687 | debian-bookworm | changed 2025-09-20 09:18:03.135344 | 2025-09-20 09:18:03.135481 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-09-20 09:18:03.807812 | debian-bookworm | changed 2025-09-20 09:18:03.813656 | 2025-09-20 09:18:03.813765 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-09-20 09:18:03.848510 | debian-bookworm | skipping: Conditional result was False 2025-09-20 09:18:03.864343 | 2025-09-20 09:18:03.864511 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-09-20 09:18:04.325732 | debian-bookworm -> localhost | changed 2025-09-20 09:18:04.336988 | 2025-09-20 09:18:04.337114 | TASK [add-build-sshkey : Add back temp key] 2025-09-20 09:18:04.686316 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/162682b193414eedb4414d1b151c664c/work/162682b193414eedb4414d1b151c664c_id_rsa (zuul-build-sshkey) 2025-09-20 09:18:04.686826 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.018744 2025-09-20 09:18:04.704599 | 2025-09-20 09:18:04.704765 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-09-20 09:18:05.118468 | debian-bookworm | ok 2025-09-20 09:18:05.129018 | 2025-09-20 09:18:05.129167 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-09-20 09:18:05.153210 | debian-bookworm | skipping: Conditional result was False 2025-09-20 09:18:05.167287 | 2025-09-20 09:18:05.167435 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-09-20 09:18:05.594736 | debian-bookworm | ok 2025-09-20 09:18:05.608137 | 2025-09-20 09:18:05.608256 | TASK [validate-host : Define zuul_info_dir fact] 2025-09-20 09:18:05.652625 | debian-bookworm | ok 2025-09-20 09:18:05.660118 | 2025-09-20 09:18:05.660243 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-09-20 09:18:05.988174 | debian-bookworm -> localhost | ok 2025-09-20 09:18:06.002276 | 2025-09-20 09:18:06.002447 | TASK [validate-host : Collect information about the host] 2025-09-20 09:18:07.100035 | debian-bookworm | ok 2025-09-20 09:18:07.110890 | 2025-09-20 09:18:07.111002 | TASK [validate-host : Sanitize hostname] 2025-09-20 09:18:07.248330 | debian-bookworm | ok 2025-09-20 09:18:07.310797 | 2025-09-20 09:18:07.311662 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-09-20 09:18:07.928947 | debian-bookworm -> localhost | changed 2025-09-20 09:18:07.935631 | 2025-09-20 09:18:07.935747 | TASK [validate-host : Collect information about zuul worker] 2025-09-20 09:18:08.357281 | debian-bookworm | ok 2025-09-20 09:18:08.362300 | 2025-09-20 09:18:08.362429 | TASK [validate-host : Write out all zuul information for each host] 2025-09-20 09:18:08.933249 | debian-bookworm -> localhost | changed 2025-09-20 09:18:08.944832 | 2025-09-20 09:18:08.944942 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-09-20 09:18:09.213044 | debian-bookworm | ok 2025-09-20 09:18:09.222015 | 2025-09-20 09:18:09.222139 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-09-20 09:18:10.476606 | debian-bookworm | changed: 2025-09-20 09:18:10.476831 | debian-bookworm | cd+++++++++ src/ 2025-09-20 09:18:10.476870 | debian-bookworm | cd+++++++++ src/github.com/ 2025-09-20 09:18:10.476896 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-09-20 09:18:10.476920 | debian-bookworm | cd+++++++++ src/github.com/osism/container-image-kolla-ansible/ 2025-09-20 09:18:10.476943 | debian-bookworm | Containerfile 2025-09-20 09:18:10.477087 | debian-bookworm | 2025-09-20 09:27:10.801284 | debian-bookworm | ERROR 2025-09-20 09:27:10.801528 | debian-bookworm | { 2025-09-20 09:27:10.801573 | debian-bookworm | "delta": "0:00:20.387625", 2025-09-20 09:27:10.801598 | debian-bookworm | "end": "2025-09-20 09:27:10.435754", 2025-09-20 09:27:10.801622 | debian-bookworm | "msg": "non-zero return code", 2025-09-20 09:27:10.801642 | debian-bookworm | "rc": 1, 2025-09-20 09:27:10.801661 | debian-bookworm | "start": "2025-09-20 09:26:50.048129" 2025-09-20 09:27:10.801679 | debian-bookworm | } failure 2025-09-20 09:27:10.803716 | 2025-09-20 09:27:10.803804 | PLAY RECAP 2025-09-20 09:27:10.803861 | debian-bookworm | ok: 4 changed: 3 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2025-09-20 09:27:10.803898 | 2025-09-20 09:27:10.986522 | RUN END RESULT_NORMAL: [untrusted : github.com/osism/container-image-kolla-ansible/playbooks/build.yml@main] 2025-09-20 09:27:10.988972 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-09-20 09:27:11.772137 | 2025-09-20 09:27:11.772339 | PLAY [Base post] 2025-09-20 09:27:11.786500 | 2025-09-20 09:27:11.786644 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-09-20 09:27:12.621192 | debian-bookworm | changed 2025-09-20 09:27:12.627631 | 2025-09-20 09:27:12.627735 | PLAY RECAP 2025-09-20 09:27:12.627797 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-09-20 09:27:12.627859 | 2025-09-20 09:27:12.760414 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-09-20 09:27:12.762957 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2025-09-20 09:27:13.549588 | 2025-09-20 09:27:13.549757 | PLAY [Base post-logs] 2025-09-20 09:27:13.560855 | 2025-09-20 09:27:13.560986 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-09-20 09:27:14.009572 | localhost | changed 2025-09-20 09:27:14.025483 | 2025-09-20 09:27:14.025670 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-09-20 09:27:14.065471 | localhost | ok 2025-09-20 09:27:14.072613 | 2025-09-20 09:27:14.072783 | TASK [Set zuul-log-path fact] 2025-09-20 09:27:14.101870 | localhost | ok 2025-09-20 09:27:14.116436 | 2025-09-20 09:27:14.116583 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-09-20 09:27:14.154586 | localhost | ok 2025-09-20 09:27:14.161734 | 2025-09-20 09:27:14.161905 | TASK [upload-logs : Create log directories] 2025-09-20 09:27:14.686567 | localhost | changed 2025-09-20 09:27:14.689534 | 2025-09-20 09:27:14.689699 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-09-20 09:27:15.199604 | localhost -> localhost | ok: Runtime: 0:00:00.007255 2025-09-20 09:27:15.203875 | 2025-09-20 09:27:15.203994 | TASK [upload-logs : Upload logs to log server] 2025-09-20 09:27:15.767024 | localhost | Output suppressed because no_log was given 2025-09-20 09:27:15.769360 | 2025-09-20 09:27:15.769488 | LOOP [upload-logs : Compress console log and json output] 2025-09-20 09:27:15.823822 | localhost | skipping: Conditional result was False 2025-09-20 09:27:15.828817 | localhost | skipping: Conditional result was False 2025-09-20 09:27:15.840819 | 2025-09-20 09:27:15.841026 | LOOP [upload-logs : Upload compressed console log and json output] 2025-09-20 09:27:15.888931 | localhost | skipping: Conditional result was False 2025-09-20 09:27:15.889608 | 2025-09-20 09:27:15.892998 | localhost | skipping: Conditional result was False 2025-09-20 09:27:15.907536 | 2025-09-20 09:27:15.907763 | LOOP [upload-logs : Upload console log and json output]