2025-09-23 12:36:37.226705 | Job console starting 2025-09-23 12:36:37.245896 | Updating git repos 2025-09-23 12:36:37.284486 | Cloning repos into workspace 2025-09-23 12:36:37.333649 | Restoring repo states 2025-09-23 12:36:37.353794 | Merging changes 2025-09-23 12:36:37.354508 | Checking out repos 2025-09-23 12:36:37.453744 | Preparing playbooks 2025-09-23 12:36:38.112122 | Running Ansible setup 2025-09-23 12:36:42.205635 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-09-23 12:36:42.998271 | 2025-09-23 12:36:42.998434 | PLAY [Base pre] 2025-09-23 12:36:43.024004 | 2025-09-23 12:36:43.024227 | TASK [Setup log path fact] 2025-09-23 12:36:43.065138 | debian-bookworm | ok 2025-09-23 12:36:43.083512 | 2025-09-23 12:36:43.083720 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-09-23 12:36:43.113592 | debian-bookworm | ok 2025-09-23 12:36:43.123767 | 2025-09-23 12:36:43.123904 | TASK [emit-job-header : Print job information] 2025-09-23 12:36:43.162969 | # Job Information 2025-09-23 12:36:43.163145 | Ansible Version: 2.16.14 2025-09-23 12:36:43.163180 | Job: container-image-kolla-ansible-push-2024.1 2025-09-23 12:36:43.163213 | Pipeline: post 2025-09-23 12:36:43.163237 | Executor: 521e9411259a 2025-09-23 12:36:43.163258 | Triggered by: https://github.com/osism/container-image-kolla-ansible/commit/35b1b8b0b81f999f6a67d1d2a50bcbd8cafb1b84 2025-09-23 12:36:43.163281 | Event ID: ef3d80e4-9879-11f0-81c5-e52ab470d7f5 2025-09-23 12:36:43.168256 | 2025-09-23 12:36:43.168364 | LOOP [emit-job-header : Print node information] 2025-09-23 12:36:43.270980 | debian-bookworm | ok: 2025-09-23 12:36:43.271175 | debian-bookworm | # Node Information 2025-09-23 12:36:43.271211 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-09-23 12:36:43.271237 | debian-bookworm | Hostname: debian 2025-09-23 12:36:43.271260 | debian-bookworm | Username: zuul 2025-09-23 12:36:43.271282 | debian-bookworm | Distro: Debian 12.12 2025-09-23 12:36:43.271306 | debian-bookworm | Provider: regiocloud-a 2025-09-23 12:36:43.271328 | debian-bookworm | Region: 2025-09-23 12:36:43.271349 | debian-bookworm | Label: debian-bookworm 2025-09-23 12:36:43.271369 | debian-bookworm | Product Name: OpenStack Nova 2025-09-23 12:36:43.271389 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe27:909 2025-09-23 12:36:43.283295 | 2025-09-23 12:36:43.283411 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-09-23 12:36:43.741873 | debian-bookworm -> localhost | changed 2025-09-23 12:36:43.749384 | 2025-09-23 12:36:43.749498 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-09-23 12:36:44.788006 | debian-bookworm -> localhost | changed 2025-09-23 12:36:44.810014 | 2025-09-23 12:36:44.810176 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-09-23 12:36:45.079350 | debian-bookworm -> localhost | ok 2025-09-23 12:36:45.085897 | 2025-09-23 12:36:45.086019 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-09-23 12:36:45.121895 | debian-bookworm | ok 2025-09-23 12:36:45.151842 | debian-bookworm | included: /var/lib/zuul/builds/cac54ac9970945039b9fb38c370d5d50/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-09-23 12:36:45.159055 | 2025-09-23 12:36:45.159201 | TASK [add-build-sshkey : Create Temp SSH key] 2025-09-23 12:36:46.370889 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-09-23 12:36:46.371135 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/cac54ac9970945039b9fb38c370d5d50/work/cac54ac9970945039b9fb38c370d5d50_id_rsa 2025-09-23 12:36:46.371177 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/cac54ac9970945039b9fb38c370d5d50/work/cac54ac9970945039b9fb38c370d5d50_id_rsa.pub 2025-09-23 12:36:46.371205 | debian-bookworm -> localhost | The key fingerprint is: 2025-09-23 12:36:46.371230 | debian-bookworm -> localhost | SHA256:FDL8rg7rdkInBH6lAxud0rv4tjkn7v2nob76Da9POE8 zuul-build-sshkey 2025-09-23 12:36:46.371265 | debian-bookworm -> localhost | The key's randomart image is: 2025-09-23 12:36:46.371288 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-09-23 12:36:46.371310 | debian-bookworm -> localhost | | o oo . | 2025-09-23 12:36:46.371333 | debian-bookworm -> localhost | | = + oo . | 2025-09-23 12:36:46.371354 | debian-bookworm -> localhost | | . * + .. | 2025-09-23 12:36:46.371374 | debian-bookworm -> localhost | | o * .. | 2025-09-23 12:36:46.371394 | debian-bookworm -> localhost | | + o .S | 2025-09-23 12:36:46.371420 | debian-bookworm -> localhost | | . + ... | 2025-09-23 12:36:46.371443 | debian-bookworm -> localhost | | o.o+.E | 2025-09-23 12:36:46.371464 | debian-bookworm -> localhost | | B=+X .. | 2025-09-23 12:36:46.371486 | debian-bookworm -> localhost | | *O%O=Bo | 2025-09-23 12:36:46.371507 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-09-23 12:36:46.371571 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.716228 2025-09-23 12:36:46.378252 | 2025-09-23 12:36:46.378412 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-09-23 12:36:46.397716 | debian-bookworm | ok 2025-09-23 12:36:46.407069 | debian-bookworm | included: /var/lib/zuul/builds/cac54ac9970945039b9fb38c370d5d50/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-09-23 12:36:46.416239 | 2025-09-23 12:36:46.416340 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-09-23 12:36:46.440114 | debian-bookworm | skipping: Conditional result was False 2025-09-23 12:36:46.455489 | 2025-09-23 12:36:46.455686 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-09-23 12:36:47.070438 | debian-bookworm | changed 2025-09-23 12:36:47.079366 | 2025-09-23 12:36:47.079502 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-09-23 12:36:47.361715 | debian-bookworm | ok 2025-09-23 12:36:47.370022 | 2025-09-23 12:36:47.370155 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-09-23 12:36:48.069535 | debian-bookworm | changed 2025-09-23 12:36:48.079060 | 2025-09-23 12:36:48.079194 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-09-23 12:36:48.738547 | debian-bookworm | changed 2025-09-23 12:36:48.748249 | 2025-09-23 12:36:48.748396 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-09-23 12:36:48.772605 | debian-bookworm | skipping: Conditional result was False 2025-09-23 12:36:48.781331 | 2025-09-23 12:36:48.781479 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-09-23 12:36:49.223768 | debian-bookworm -> localhost | changed 2025-09-23 12:36:49.242078 | 2025-09-23 12:36:49.242212 | TASK [add-build-sshkey : Add back temp key] 2025-09-23 12:36:49.569927 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/cac54ac9970945039b9fb38c370d5d50/work/cac54ac9970945039b9fb38c370d5d50_id_rsa (zuul-build-sshkey) 2025-09-23 12:36:49.570224 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.011412 2025-09-23 12:36:49.579297 | 2025-09-23 12:36:49.579416 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-09-23 12:36:49.969962 | debian-bookworm | ok 2025-09-23 12:36:49.978232 | 2025-09-23 12:36:49.978424 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-09-23 12:36:50.013999 | debian-bookworm | skipping: Conditional result was False 2025-09-23 12:36:50.034960 | 2025-09-23 12:36:50.035147 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-09-23 12:36:50.468657 | debian-bookworm | ok 2025-09-23 12:36:50.484184 | 2025-09-23 12:36:50.484314 | TASK [validate-host : Define zuul_info_dir fact] 2025-09-23 12:36:50.518442 | debian-bookworm | ok 2025-09-23 12:36:50.526671 | 2025-09-23 12:36:50.526805 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-09-23 12:36:50.823817 | debian-bookworm -> localhost | ok 2025-09-23 12:36:50.830496 | 2025-09-23 12:36:50.830612 | TASK [validate-host : Collect information about the host] 2025-09-23 12:36:51.940614 | debian-bookworm | ok 2025-09-23 12:36:51.953832 | 2025-09-23 12:36:51.953999 | TASK [validate-host : Sanitize hostname] 2025-09-23 12:36:52.069956 | debian-bookworm | ok 2025-09-23 12:36:52.120358 | 2025-09-23 12:36:52.120484 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-09-23 12:36:52.659871 | debian-bookworm -> localhost | changed 2025-09-23 12:36:52.665833 | 2025-09-23 12:36:52.665969 | TASK [validate-host : Collect information about zuul worker] 2025-09-23 12:36:53.075403 | debian-bookworm | ok 2025-09-23 12:36:53.083337 | 2025-09-23 12:36:53.083492 | TASK [validate-host : Write out all zuul information for each host] 2025-09-23 12:36:53.615223 | debian-bookworm -> localhost | changed 2025-09-23 12:36:53.625639 | 2025-09-23 12:36:53.625748 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-09-23 12:36:53.905482 | debian-bookworm | ok 2025-09-23 12:36:53.914774 | 2025-09-23 12:36:53.914985 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-09-23 12:36:54.777017 | debian-bookworm | changed: 2025-09-23 12:36:54.777225 | debian-bookworm | cd+++++++++ src/ 2025-09-23 12:36:54.777261 | debian-bookworm | cd+++++++++ src/github.com/ 2025-09-23 12:36:54.777286 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-09-23 12:36:54.777308 | debian-bookworm | cd+++++++++ src/github.com/osism/container-image-kolla-ansible/ 2025-09-23 12:36:54.777328 | debian-bookworm | Containerfile 2025-09-23 12:36:54.777457 | debian-bookworm | 2025-09-23 12:47:02.713457 | debian-bookworm | ERROR 2025-09-23 12:47:02.713924 | debian-bookworm | { 2025-09-23 12:47:02.714035 | debian-bookworm | "delta": "0:00:20.476393", 2025-09-23 12:47:02.714103 | debian-bookworm | "end": "2025-09-23 12:47:02.409562", 2025-09-23 12:47:02.714175 | debian-bookworm | "msg": "non-zero return code", 2025-09-23 12:47:02.714233 | debian-bookworm | "rc": 1, 2025-09-23 12:47:02.714287 | debian-bookworm | "start": "2025-09-23 12:46:41.933169" 2025-09-23 12:47:02.714347 | debian-bookworm | } failure 2025-09-23 12:47:02.718118 | 2025-09-23 12:47:02.718254 | PLAY RECAP 2025-09-23 12:47:02.718360 | debian-bookworm | ok: 4 changed: 3 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2025-09-23 12:47:02.718410 | 2025-09-23 12:47:02.851483 | RUN END RESULT_NORMAL: [untrusted : github.com/osism/container-image-kolla-ansible/playbooks/build.yml@main] 2025-09-23 12:47:02.853869 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-09-23 12:47:03.593951 | 2025-09-23 12:47:03.594099 | PLAY [Base post] 2025-09-23 12:47:03.607395 | 2025-09-23 12:47:03.607521 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-09-23 12:47:04.445915 | debian-bookworm | changed 2025-09-23 12:47:04.455090 | 2025-09-23 12:47:04.455216 | PLAY RECAP 2025-09-23 12:47:04.455295 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-09-23 12:47:04.455371 | 2025-09-23 12:47:04.572598 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-09-23 12:47:04.573663 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2025-09-23 12:47:05.338048 | 2025-09-23 12:47:05.338216 | PLAY [Base post-logs] 2025-09-23 12:47:05.349215 | 2025-09-23 12:47:05.349412 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-09-23 12:47:05.814281 | localhost | changed 2025-09-23 12:47:05.831029 | 2025-09-23 12:47:05.831206 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-09-23 12:47:05.870293 | localhost | ok 2025-09-23 12:47:05.876936 | 2025-09-23 12:47:05.877101 | TASK [Set zuul-log-path fact] 2025-09-23 12:47:05.894353 | localhost | ok 2025-09-23 12:47:05.906180 | 2025-09-23 12:47:05.906300 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-09-23 12:47:05.943645 | localhost | ok 2025-09-23 12:47:05.950828 | 2025-09-23 12:47:05.951034 | TASK [upload-logs : Create log directories] 2025-09-23 12:47:06.420369 | localhost | changed 2025-09-23 12:47:06.425246 | 2025-09-23 12:47:06.425393 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-09-23 12:47:06.906052 | localhost -> localhost | ok: Runtime: 0:00:00.006813 2025-09-23 12:47:06.910207 | 2025-09-23 12:47:06.910329 | TASK [upload-logs : Upload logs to log server] 2025-09-23 12:47:07.519660 | localhost | Output suppressed because no_log was given 2025-09-23 12:47:07.524065 | 2025-09-23 12:47:07.524270 | LOOP [upload-logs : Compress console log and json output] 2025-09-23 12:47:07.581802 | localhost | skipping: Conditional result was False 2025-09-23 12:47:07.586929 | localhost | skipping: Conditional result was False 2025-09-23 12:47:07.599593 | 2025-09-23 12:47:07.599856 | LOOP [upload-logs : Upload compressed console log and json output] 2025-09-23 12:47:07.656319 | localhost | skipping: Conditional result was False 2025-09-23 12:47:07.656797 | 2025-09-23 12:47:07.660709 | localhost | skipping: Conditional result was False 2025-09-23 12:47:07.671935 | 2025-09-23 12:47:07.672111 | LOOP [upload-logs : Upload console log and json output]