2025-10-03 07:48:04.994066 | Job console starting 2025-10-03 07:48:05.008678 | Updating git repos 2025-10-03 07:48:05.038904 | Cloning repos into workspace 2025-10-03 07:48:05.100464 | Restoring repo states 2025-10-03 07:48:05.111746 | Merging changes 2025-10-03 07:48:05.111762 | Checking out repos 2025-10-03 07:48:05.170303 | Preparing playbooks 2025-10-03 07:48:05.813296 | Running Ansible setup 2025-10-03 07:48:09.883245 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-10-03 07:48:10.593234 | 2025-10-03 07:48:10.593384 | PLAY [Base pre] 2025-10-03 07:48:10.614585 | 2025-10-03 07:48:10.614787 | TASK [Setup log path fact] 2025-10-03 07:48:10.635640 | debian-bookworm | ok 2025-10-03 07:48:10.650529 | 2025-10-03 07:48:10.650702 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-03 07:48:10.690504 | debian-bookworm | ok 2025-10-03 07:48:10.700743 | 2025-10-03 07:48:10.700848 | TASK [emit-job-header : Print job information] 2025-10-03 07:48:10.757454 | # Job Information 2025-10-03 07:48:10.757782 | Ansible Version: 2.16.14 2025-10-03 07:48:10.757848 | Job: container-image-kolla-ansible-push-2024.1 2025-10-03 07:48:10.757906 | Pipeline: post 2025-10-03 07:48:10.757944 | Executor: 521e9411259a 2025-10-03 07:48:10.757977 | Triggered by: https://github.com/osism/container-image-kolla-ansible/commit/4cd0bc3621ea1c1365b985a35f05f7450832b4d8 2025-10-03 07:48:10.758013 | Event ID: 49334416-a02d-11f0-856b-b7dbc5f70231 2025-10-03 07:48:10.765534 | 2025-10-03 07:48:10.765701 | LOOP [emit-job-header : Print node information] 2025-10-03 07:48:10.890893 | debian-bookworm | ok: 2025-10-03 07:48:10.891158 | debian-bookworm | # Node Information 2025-10-03 07:48:10.891197 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-10-03 07:48:10.891222 | debian-bookworm | Hostname: debian 2025-10-03 07:48:10.891244 | debian-bookworm | Username: zuul 2025-10-03 07:48:10.891265 | debian-bookworm | Distro: Debian 12.12 2025-10-03 07:48:10.891357 | debian-bookworm | Provider: regiocloud-a 2025-10-03 07:48:10.891388 | debian-bookworm | Region: 2025-10-03 07:48:10.891417 | debian-bookworm | Label: debian-bookworm 2025-10-03 07:48:10.891445 | debian-bookworm | Product Name: OpenStack Nova 2025-10-03 07:48:10.891471 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe95:6e18 2025-10-03 07:48:10.911546 | 2025-10-03 07:48:10.911712 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-10-03 07:48:11.363407 | debian-bookworm -> localhost | changed 2025-10-03 07:48:11.372079 | 2025-10-03 07:48:11.372211 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-10-03 07:48:12.441358 | debian-bookworm -> localhost | changed 2025-10-03 07:48:12.452562 | 2025-10-03 07:48:12.452697 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-10-03 07:48:12.729539 | debian-bookworm -> localhost | ok 2025-10-03 07:48:12.735983 | 2025-10-03 07:48:12.736107 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-10-03 07:48:12.764379 | debian-bookworm | ok 2025-10-03 07:48:12.781826 | debian-bookworm | included: /var/lib/zuul/builds/3756666b394a46a1968e8816f36a0866/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-10-03 07:48:12.787957 | 2025-10-03 07:48:12.788057 | TASK [add-build-sshkey : Create Temp SSH key] 2025-10-03 07:48:13.828938 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-10-03 07:48:13.829436 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/3756666b394a46a1968e8816f36a0866/work/3756666b394a46a1968e8816f36a0866_id_rsa 2025-10-03 07:48:13.829551 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/3756666b394a46a1968e8816f36a0866/work/3756666b394a46a1968e8816f36a0866_id_rsa.pub 2025-10-03 07:48:13.829631 | debian-bookworm -> localhost | The key fingerprint is: 2025-10-03 07:48:13.829725 | debian-bookworm -> localhost | SHA256:TrQ2vifQiTb0T1XqNyVRHKeCSj9btEoG/2b/H5gAFDM zuul-build-sshkey 2025-10-03 07:48:13.829816 | debian-bookworm -> localhost | The key's randomart image is: 2025-10-03 07:48:13.829880 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-10-03 07:48:13.829942 | debian-bookworm -> localhost | | E. ++| 2025-10-03 07:48:13.830005 | debian-bookworm -> localhost | | . o. o.o| 2025-10-03 07:48:13.830062 | debian-bookworm -> localhost | | +.. oo.. | 2025-10-03 07:48:13.830120 | debian-bookworm -> localhost | | .o *..oo. .| 2025-10-03 07:48:13.830177 | debian-bookworm -> localhost | | . +S.*+o o | 2025-10-03 07:48:13.830249 | debian-bookworm -> localhost | | ==++.*o = | 2025-10-03 07:48:13.830308 | debian-bookworm -> localhost | | . oooo ++ o | 2025-10-03 07:48:13.830362 | debian-bookworm -> localhost | | ..oo . .| 2025-10-03 07:48:13.830421 | debian-bookworm -> localhost | | .o ..+| 2025-10-03 07:48:13.830479 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-10-03 07:48:13.830622 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.568042 2025-10-03 07:48:13.845044 | 2025-10-03 07:48:13.845188 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-10-03 07:48:13.881475 | debian-bookworm | ok 2025-10-03 07:48:13.895719 | debian-bookworm | included: /var/lib/zuul/builds/3756666b394a46a1968e8816f36a0866/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-10-03 07:48:13.905780 | 2025-10-03 07:48:13.905883 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-10-03 07:48:13.929698 | debian-bookworm | skipping: Conditional result was False 2025-10-03 07:48:13.945406 | 2025-10-03 07:48:13.945544 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-10-03 07:48:14.595162 | debian-bookworm | changed 2025-10-03 07:48:14.602915 | 2025-10-03 07:48:14.603039 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-10-03 07:48:14.865800 | debian-bookworm | ok 2025-10-03 07:48:14.874208 | 2025-10-03 07:48:14.874330 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-10-03 07:48:15.542361 | debian-bookworm | changed 2025-10-03 07:48:15.554154 | 2025-10-03 07:48:15.554309 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-10-03 07:48:16.252616 | debian-bookworm | changed 2025-10-03 07:48:16.262488 | 2025-10-03 07:48:16.262633 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-10-03 07:48:16.287376 | debian-bookworm | skipping: Conditional result was False 2025-10-03 07:48:16.301089 | 2025-10-03 07:48:16.301223 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-10-03 07:48:16.740785 | debian-bookworm -> localhost | changed 2025-10-03 07:48:16.761717 | 2025-10-03 07:48:16.761854 | TASK [add-build-sshkey : Add back temp key] 2025-10-03 07:48:17.091698 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/3756666b394a46a1968e8816f36a0866/work/3756666b394a46a1968e8816f36a0866_id_rsa (zuul-build-sshkey) 2025-10-03 07:48:17.092405 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.017941 2025-10-03 07:48:17.107539 | 2025-10-03 07:48:17.107741 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-10-03 07:48:17.499231 | debian-bookworm | ok 2025-10-03 07:48:17.508127 | 2025-10-03 07:48:17.508276 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-10-03 07:48:17.546521 | debian-bookworm | skipping: Conditional result was False 2025-10-03 07:48:17.567115 | 2025-10-03 07:48:17.567250 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-10-03 07:48:18.017898 | debian-bookworm | ok 2025-10-03 07:48:18.044238 | 2025-10-03 07:48:18.044467 | TASK [validate-host : Define zuul_info_dir fact] 2025-10-03 07:48:18.094397 | debian-bookworm | ok 2025-10-03 07:48:18.104219 | 2025-10-03 07:48:18.104368 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-10-03 07:48:18.394628 | debian-bookworm -> localhost | ok 2025-10-03 07:48:18.406650 | 2025-10-03 07:48:18.406821 | TASK [validate-host : Collect information about the host] 2025-10-03 07:48:19.559556 | debian-bookworm | ok 2025-10-03 07:48:19.572138 | 2025-10-03 07:48:19.572258 | TASK [validate-host : Sanitize hostname] 2025-10-03 07:48:19.676384 | debian-bookworm | ok 2025-10-03 07:48:19.717684 | 2025-10-03 07:48:19.717810 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-10-03 07:48:20.267981 | debian-bookworm -> localhost | changed 2025-10-03 07:48:20.274087 | 2025-10-03 07:48:20.274208 | TASK [validate-host : Collect information about zuul worker] 2025-10-03 07:48:20.709384 | debian-bookworm | ok 2025-10-03 07:48:20.714137 | 2025-10-03 07:48:20.714244 | TASK [validate-host : Write out all zuul information for each host] 2025-10-03 07:48:21.246457 | debian-bookworm -> localhost | changed 2025-10-03 07:48:21.264900 | 2025-10-03 07:48:21.265038 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-10-03 07:48:21.536381 | debian-bookworm | ok 2025-10-03 07:48:21.542175 | 2025-10-03 07:48:21.542280 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-10-03 07:48:22.414436 | debian-bookworm | changed: 2025-10-03 07:48:22.414648 | debian-bookworm | cd+++++++++ src/ 2025-10-03 07:48:22.414734 | debian-bookworm | cd+++++++++ src/github.com/ 2025-10-03 07:48:22.414760 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-10-03 07:48:22.414784 | debian-bookworm | cd+++++++++ src/github.com/osism/container-image-kolla-ansible/ 2025-10-03 07:48:22.414806 | debian-bookworm | Containerfile 2025-10-03 07:48:22.414973 | debian-bookworm | 2025-10-03 07:58:14.876615 | debian-bookworm | ERROR 2025-10-03 07:58:14.876940 | debian-bookworm | { 2025-10-03 07:58:14.877014 | debian-bookworm | "delta": "0:00:20.215130", 2025-10-03 07:58:14.877054 | debian-bookworm | "end": "2025-10-03 07:58:14.824203", 2025-10-03 07:58:14.877096 | debian-bookworm | "msg": "non-zero return code", 2025-10-03 07:58:14.877128 | debian-bookworm | "rc": 1, 2025-10-03 07:58:14.877159 | debian-bookworm | "start": "2025-10-03 07:57:54.609073" 2025-10-03 07:58:14.877189 | debian-bookworm | } failure 2025-10-03 07:58:14.879314 | 2025-10-03 07:58:14.879414 | PLAY RECAP 2025-10-03 07:58:14.879500 | debian-bookworm | ok: 4 changed: 3 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2025-10-03 07:58:14.879543 | 2025-10-03 07:58:15.063334 | RUN END RESULT_NORMAL: [untrusted : github.com/osism/container-image-kolla-ansible/playbooks/build.yml@main] 2025-10-03 07:58:15.067259 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-10-03 07:58:15.796639 | 2025-10-03 07:58:15.796832 | PLAY [Base post] 2025-10-03 07:58:15.810481 | 2025-10-03 07:58:15.810611 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-10-03 07:58:16.620960 | debian-bookworm | changed 2025-10-03 07:58:16.630880 | 2025-10-03 07:58:16.631008 | PLAY RECAP 2025-10-03 07:58:16.631086 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-10-03 07:58:16.631164 | 2025-10-03 07:58:16.744157 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-10-03 07:58:16.746659 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2025-10-03 07:58:17.511792 | 2025-10-03 07:58:17.511952 | PLAY [Base post-logs] 2025-10-03 07:58:17.522682 | 2025-10-03 07:58:17.522858 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-10-03 07:58:17.977270 | localhost | changed 2025-10-03 07:58:17.992891 | 2025-10-03 07:58:17.993067 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-10-03 07:58:18.031363 | localhost | ok 2025-10-03 07:58:18.037001 | 2025-10-03 07:58:18.037154 | TASK [Set zuul-log-path fact] 2025-10-03 07:58:18.056904 | localhost | ok 2025-10-03 07:58:18.071483 | 2025-10-03 07:58:18.071624 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-03 07:58:18.099152 | localhost | ok 2025-10-03 07:58:18.106947 | 2025-10-03 07:58:18.107120 | TASK [upload-logs : Create log directories] 2025-10-03 07:58:18.581897 | localhost | changed 2025-10-03 07:58:18.584979 | 2025-10-03 07:58:18.585097 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-10-03 07:58:19.076745 | localhost -> localhost | ok: Runtime: 0:00:00.006969 2025-10-03 07:58:19.084528 | 2025-10-03 07:58:19.084696 | TASK [upload-logs : Upload logs to log server] 2025-10-03 07:58:19.617796 | localhost | Output suppressed because no_log was given 2025-10-03 07:58:19.620129 | 2025-10-03 07:58:19.620234 | LOOP [upload-logs : Compress console log and json output] 2025-10-03 07:58:19.673343 | localhost | skipping: Conditional result was False 2025-10-03 07:58:19.678570 | localhost | skipping: Conditional result was False 2025-10-03 07:58:19.690800 | 2025-10-03 07:58:19.691042 | LOOP [upload-logs : Upload compressed console log and json output] 2025-10-03 07:58:19.736882 | localhost | skipping: Conditional result was False 2025-10-03 07:58:19.737431 | 2025-10-03 07:58:19.741091 | localhost | skipping: Conditional result was False 2025-10-03 07:58:19.753674 | 2025-10-03 07:58:19.753899 | LOOP [upload-logs : Upload console log and json output]