2025-10-03 07:48:05.048759 | Job console starting 2025-10-03 07:48:05.058711 | Updating git repos 2025-10-03 07:48:05.089433 | Cloning repos into workspace 2025-10-03 07:48:05.130961 | Restoring repo states 2025-10-03 07:48:05.142350 | Merging changes 2025-10-03 07:48:05.142371 | Checking out repos 2025-10-03 07:48:05.202978 | Preparing playbooks 2025-10-03 07:48:05.814110 | Running Ansible setup 2025-10-03 07:48:09.849820 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-10-03 07:48:10.576686 | 2025-10-03 07:48:10.576845 | PLAY [Base pre] 2025-10-03 07:48:10.592990 | 2025-10-03 07:48:10.593121 | TASK [Setup log path fact] 2025-10-03 07:48:10.620719 | debian-bookworm | ok 2025-10-03 07:48:10.639498 | 2025-10-03 07:48:10.639625 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-03 07:48:10.670944 | debian-bookworm | ok 2025-10-03 07:48:10.681693 | 2025-10-03 07:48:10.681798 | TASK [emit-job-header : Print job information] 2025-10-03 07:48:10.724669 | # Job Information 2025-10-03 07:48:10.724918 | Ansible Version: 2.16.14 2025-10-03 07:48:10.724977 | Job: container-image-kolla-ansible-push-2025.1 2025-10-03 07:48:10.725035 | Pipeline: post 2025-10-03 07:48:10.725075 | Executor: 521e9411259a 2025-10-03 07:48:10.725111 | Triggered by: https://github.com/osism/container-image-kolla-ansible/commit/4cd0bc3621ea1c1365b985a35f05f7450832b4d8 2025-10-03 07:48:10.725150 | Event ID: 49334416-a02d-11f0-856b-b7dbc5f70231 2025-10-03 07:48:10.732589 | 2025-10-03 07:48:10.732726 | LOOP [emit-job-header : Print node information] 2025-10-03 07:48:10.839572 | debian-bookworm | ok: 2025-10-03 07:48:10.839794 | debian-bookworm | # Node Information 2025-10-03 07:48:10.839838 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-10-03 07:48:10.839869 | debian-bookworm | Hostname: debian 2025-10-03 07:48:10.839897 | debian-bookworm | Username: zuul 2025-10-03 07:48:10.839923 | debian-bookworm | Distro: Debian 12.12 2025-10-03 07:48:10.839952 | debian-bookworm | Provider: regiocloud-a 2025-10-03 07:48:10.839979 | debian-bookworm | Region: 2025-10-03 07:48:10.840006 | debian-bookworm | Label: debian-bookworm 2025-10-03 07:48:10.840031 | debian-bookworm | Product Name: OpenStack Nova 2025-10-03 07:48:10.840056 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fee5:fd90 2025-10-03 07:48:10.851232 | 2025-10-03 07:48:10.851350 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-10-03 07:48:11.311578 | debian-bookworm -> localhost | changed 2025-10-03 07:48:11.320558 | 2025-10-03 07:48:11.320765 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-10-03 07:48:12.323712 | debian-bookworm -> localhost | changed 2025-10-03 07:48:12.334959 | 2025-10-03 07:48:12.335076 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-10-03 07:48:12.622318 | debian-bookworm -> localhost | ok 2025-10-03 07:48:12.632617 | 2025-10-03 07:48:12.632788 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-10-03 07:48:12.654875 | debian-bookworm | ok 2025-10-03 07:48:12.673534 | debian-bookworm | included: /var/lib/zuul/builds/c9549ba9f97945d5bedf4dc2dce0699c/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.679755 | 2025-10-03 07:48:12.679857 | TASK [add-build-sshkey : Create Temp SSH key] 2025-10-03 07:48:13.978863 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-10-03 07:48:13.979268 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/c9549ba9f97945d5bedf4dc2dce0699c/work/c9549ba9f97945d5bedf4dc2dce0699c_id_rsa 2025-10-03 07:48:13.979348 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/c9549ba9f97945d5bedf4dc2dce0699c/work/c9549ba9f97945d5bedf4dc2dce0699c_id_rsa.pub 2025-10-03 07:48:13.979404 | debian-bookworm -> localhost | The key fingerprint is: 2025-10-03 07:48:13.979454 | debian-bookworm -> localhost | SHA256:AhY4kzBJqk/MhTMj8B8f/9C15uOSYrc3LUZmdppyIa0 zuul-build-sshkey 2025-10-03 07:48:13.979517 | debian-bookworm -> localhost | The key's randomart image is: 2025-10-03 07:48:13.979566 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-10-03 07:48:13.979610 | debian-bookworm -> localhost | |=+ o. | 2025-10-03 07:48:13.979671 | debian-bookworm -> localhost | |+o=. . | 2025-10-03 07:48:13.979717 | debian-bookworm -> localhost | |o *o= . . | 2025-10-03 07:48:13.979757 | debian-bookworm -> localhost | |.+ B + o . . . | 2025-10-03 07:48:13.979797 | debian-bookworm -> localhost | |. + . o S ..o | 2025-10-03 07:48:13.979845 | debian-bookworm -> localhost | | o . o.oB . | 2025-10-03 07:48:13.979888 | debian-bookworm -> localhost | | . .Oo* | 2025-10-03 07:48:13.979931 | debian-bookworm -> localhost | | o E.O.. | 2025-10-03 07:48:13.979975 | debian-bookworm -> localhost | | . o.B.o | 2025-10-03 07:48:13.980017 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-10-03 07:48:13.980120 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.826236 2025-10-03 07:48:13.991214 | 2025-10-03 07:48:13.991352 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-10-03 07:48:14.020245 | debian-bookworm | ok 2025-10-03 07:48:14.029776 | debian-bookworm | included: /var/lib/zuul/builds/c9549ba9f97945d5bedf4dc2dce0699c/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-10-03 07:48:14.038810 | 2025-10-03 07:48:14.038935 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-10-03 07:48:14.062515 | debian-bookworm | skipping: Conditional result was False 2025-10-03 07:48:14.068767 | 2025-10-03 07:48:14.068888 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-10-03 07:48:14.713740 | debian-bookworm | changed 2025-10-03 07:48:14.720974 | 2025-10-03 07:48:14.721098 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-10-03 07:48:14.977083 | debian-bookworm | ok 2025-10-03 07:48:14.985141 | 2025-10-03 07:48:14.985267 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-10-03 07:48:15.665153 | debian-bookworm | changed 2025-10-03 07:48:15.674644 | 2025-10-03 07:48:15.674813 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-10-03 07:48:16.313873 | debian-bookworm | changed 2025-10-03 07:48:16.320422 | 2025-10-03 07:48:16.320535 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-10-03 07:48:16.343916 | debian-bookworm | skipping: Conditional result was False 2025-10-03 07:48:16.349899 | 2025-10-03 07:48:16.350005 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-10-03 07:48:16.791689 | debian-bookworm -> localhost | changed 2025-10-03 07:48:16.809390 | 2025-10-03 07:48:16.809519 | TASK [add-build-sshkey : Add back temp key] 2025-10-03 07:48:17.137053 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/c9549ba9f97945d5bedf4dc2dce0699c/work/c9549ba9f97945d5bedf4dc2dce0699c_id_rsa (zuul-build-sshkey) 2025-10-03 07:48:17.137291 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.011313 2025-10-03 07:48:17.145514 | 2025-10-03 07:48:17.145617 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-10-03 07:48:17.556233 | debian-bookworm | ok 2025-10-03 07:48:17.564228 | 2025-10-03 07:48:17.564359 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-10-03 07:48:17.598681 | debian-bookworm | skipping: Conditional result was False 2025-10-03 07:48:17.619523 | 2025-10-03 07:48:17.619679 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-10-03 07:48:18.043101 | debian-bookworm | ok 2025-10-03 07:48:18.057966 | 2025-10-03 07:48:18.058093 | TASK [validate-host : Define zuul_info_dir fact] 2025-10-03 07:48:18.097967 | debian-bookworm | ok 2025-10-03 07:48:18.103821 | 2025-10-03 07:48:18.103926 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-10-03 07:48:18.391167 | debian-bookworm -> localhost | ok 2025-10-03 07:48:18.397807 | 2025-10-03 07:48:18.397918 | TASK [validate-host : Collect information about the host] 2025-10-03 07:48:19.458062 | debian-bookworm | ok 2025-10-03 07:48:19.471621 | 2025-10-03 07:48:19.471753 | TASK [validate-host : Sanitize hostname] 2025-10-03 07:48:19.580792 | debian-bookworm | ok 2025-10-03 07:48:19.622219 | 2025-10-03 07:48:19.622354 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-10-03 07:48:20.214575 | debian-bookworm -> localhost | changed 2025-10-03 07:48:20.226715 | 2025-10-03 07:48:20.226970 | TASK [validate-host : Collect information about zuul worker] 2025-10-03 07:48:20.647054 | debian-bookworm | ok 2025-10-03 07:48:20.655262 | 2025-10-03 07:48:20.655416 | TASK [validate-host : Write out all zuul information for each host] 2025-10-03 07:48:21.215848 | debian-bookworm -> localhost | changed 2025-10-03 07:48:21.226100 | 2025-10-03 07:48:21.226219 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-10-03 07:48:21.483743 | debian-bookworm | ok 2025-10-03 07:48:21.492320 | 2025-10-03 07:48:21.492439 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-10-03 07:48:22.398863 | debian-bookworm | changed: 2025-10-03 07:48:22.399151 | debian-bookworm | cd+++++++++ src/ 2025-10-03 07:48:22.399208 | debian-bookworm | cd+++++++++ src/github.com/ 2025-10-03 07:48:22.399249 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-10-03 07:48:22.399285 | debian-bookworm | cd+++++++++ src/github.com/osism/container-image-kolla-ansible/ 2025-10-03 07:48:22.399319 | debian-bookworm | Containerfile 2025-10-03 07:48:22.399530 | debian-bookworm | 2025-10-03 08:00:22.559675 | debian-bookworm | ERROR 2025-10-03 08:00:22.560017 | debian-bookworm | { 2025-10-03 08:00:22.560060 | debian-bookworm | "delta": "0:00:20.309222", 2025-10-03 08:00:22.560085 | debian-bookworm | "end": "2025-10-03 08:00:22.124997", 2025-10-03 08:00:22.560110 | debian-bookworm | "msg": "non-zero return code", 2025-10-03 08:00:22.560130 | debian-bookworm | "rc": 1, 2025-10-03 08:00:22.560162 | debian-bookworm | "start": "2025-10-03 08:00:01.815775" 2025-10-03 08:00:22.560182 | debian-bookworm | } failure 2025-10-03 08:00:22.563750 | 2025-10-03 08:00:22.563839 | PLAY RECAP 2025-10-03 08:00:22.563899 | debian-bookworm | ok: 4 changed: 3 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2025-10-03 08:00:22.563925 | 2025-10-03 08:00:22.741611 | RUN END RESULT_NORMAL: [untrusted : github.com/osism/container-image-kolla-ansible/playbooks/build.yml@main] 2025-10-03 08:00:22.742692 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-10-03 08:00:23.489026 | 2025-10-03 08:00:23.489179 | PLAY [Base post] 2025-10-03 08:00:23.502656 | 2025-10-03 08:00:23.502800 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-10-03 08:00:24.332870 | debian-bookworm | changed 2025-10-03 08:00:24.342073 | 2025-10-03 08:00:24.342219 | PLAY RECAP 2025-10-03 08:00:24.342301 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-10-03 08:00:24.342378 | 2025-10-03 08:00:24.471487 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-10-03 08:00:24.473614 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2025-10-03 08:00:25.229696 | 2025-10-03 08:00:25.229869 | PLAY [Base post-logs] 2025-10-03 08:00:25.241116 | 2025-10-03 08:00:25.241259 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-10-03 08:00:25.683688 | localhost | changed 2025-10-03 08:00:25.697155 | 2025-10-03 08:00:25.697309 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-10-03 08:00:25.722941 | localhost | ok 2025-10-03 08:00:25.725984 | 2025-10-03 08:00:25.726080 | TASK [Set zuul-log-path fact] 2025-10-03 08:00:25.740453 | localhost | ok 2025-10-03 08:00:25.748193 | 2025-10-03 08:00:25.748300 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-03 08:00:25.773524 | localhost | ok 2025-10-03 08:00:25.776648 | 2025-10-03 08:00:25.776769 | TASK [upload-logs : Create log directories] 2025-10-03 08:00:26.263997 | localhost | changed 2025-10-03 08:00:26.270528 | 2025-10-03 08:00:26.270701 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-10-03 08:00:26.742943 | localhost -> localhost | ok: Runtime: 0:00:00.006745 2025-10-03 08:00:26.749440 | 2025-10-03 08:00:26.749582 | TASK [upload-logs : Upload logs to log server] 2025-10-03 08:00:27.322796 | localhost | Output suppressed because no_log was given 2025-10-03 08:00:27.325030 | 2025-10-03 08:00:27.325148 | LOOP [upload-logs : Compress console log and json output] 2025-10-03 08:00:27.380635 | localhost | skipping: Conditional result was False 2025-10-03 08:00:27.386514 | localhost | skipping: Conditional result was False 2025-10-03 08:00:27.398471 | 2025-10-03 08:00:27.398595 | LOOP [upload-logs : Upload compressed console log and json output] 2025-10-03 08:00:27.443364 | localhost | skipping: Conditional result was False 2025-10-03 08:00:27.444053 | 2025-10-03 08:00:27.448148 | localhost | skipping: Conditional result was False 2025-10-03 08:00:27.457316 | 2025-10-03 08:00:27.457432 | LOOP [upload-logs : Upload console log and json output]