2025-09-28 17:53:48.913271 | Job console starting 2025-09-28 17:53:48.931373 | Updating git repos 2025-09-28 17:53:48.969673 | Cloning repos into workspace 2025-09-28 17:53:49.022223 | Restoring repo states 2025-09-28 17:53:49.039795 | Merging changes 2025-09-28 17:53:49.039815 | Checking out repos 2025-09-28 17:53:49.121431 | Preparing playbooks 2025-09-28 17:53:49.865638 | Running Ansible setup 2025-09-28 17:53:54.096665 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-09-28 17:53:54.820592 | 2025-09-28 17:53:54.820749 | PLAY [Base pre] 2025-09-28 17:53:54.837047 | 2025-09-28 17:53:54.837184 | TASK [Setup log path fact] 2025-09-28 17:53:54.865959 | debian-bookworm | ok 2025-09-28 17:53:54.880828 | 2025-09-28 17:53:54.880995 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-09-28 17:53:54.920245 | debian-bookworm | ok 2025-09-28 17:53:54.930005 | 2025-09-28 17:53:54.930114 | TASK [emit-job-header : Print job information] 2025-09-28 17:53:54.968139 | # Job Information 2025-09-28 17:53:54.968314 | Ansible Version: 2.16.14 2025-09-28 17:53:54.968350 | Job: container-image-kolla-ansible-push-2024.1 2025-09-28 17:53:54.968384 | Pipeline: post 2025-09-28 17:53:54.968408 | Executor: 521e9411259a 2025-09-28 17:53:54.968429 | Triggered by: https://github.com/osism/container-image-kolla-ansible/commit/4f66e44310da471afaae88ab9dcd92351fa0554d 2025-09-28 17:53:54.968452 | Event ID: 1308da64-9c94-11f0-8c1d-f1cd489e6031 2025-09-28 17:53:54.973334 | 2025-09-28 17:53:54.973443 | LOOP [emit-job-header : Print node information] 2025-09-28 17:53:55.082382 | debian-bookworm | ok: 2025-09-28 17:53:55.082615 | debian-bookworm | # Node Information 2025-09-28 17:53:55.082651 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-09-28 17:53:55.082676 | debian-bookworm | Hostname: debian 2025-09-28 17:53:55.082698 | debian-bookworm | Username: zuul 2025-09-28 17:53:55.082719 | debian-bookworm | Distro: Debian 12.12 2025-09-28 17:53:55.082743 | debian-bookworm | Provider: regiocloud-a 2025-09-28 17:53:55.082763 | debian-bookworm | Region: 2025-09-28 17:53:55.082784 | debian-bookworm | Label: debian-bookworm 2025-09-28 17:53:55.082803 | debian-bookworm | Product Name: OpenStack Nova 2025-09-28 17:53:55.082823 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fee3:5836 2025-09-28 17:53:55.108875 | 2025-09-28 17:53:55.109046 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-09-28 17:53:55.561064 | debian-bookworm -> localhost | changed 2025-09-28 17:53:55.568756 | 2025-09-28 17:53:55.568885 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-09-28 17:53:56.544138 | debian-bookworm -> localhost | changed 2025-09-28 17:53:56.556586 | 2025-09-28 17:53:56.556716 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-09-28 17:53:56.826202 | debian-bookworm -> localhost | ok 2025-09-28 17:53:56.832622 | 2025-09-28 17:53:56.832749 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-09-28 17:53:56.862294 | debian-bookworm | ok 2025-09-28 17:53:56.897142 | debian-bookworm | included: /var/lib/zuul/builds/bdeef6b5ac0348838ccf6ab9bd825680/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-09-28 17:53:56.903465 | 2025-09-28 17:53:56.903572 | TASK [add-build-sshkey : Create Temp SSH key] 2025-09-28 17:53:58.510109 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-09-28 17:53:58.510380 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/bdeef6b5ac0348838ccf6ab9bd825680/work/bdeef6b5ac0348838ccf6ab9bd825680_id_rsa 2025-09-28 17:53:58.510426 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/bdeef6b5ac0348838ccf6ab9bd825680/work/bdeef6b5ac0348838ccf6ab9bd825680_id_rsa.pub 2025-09-28 17:53:58.510456 | debian-bookworm -> localhost | The key fingerprint is: 2025-09-28 17:53:58.510483 | debian-bookworm -> localhost | SHA256:Pv7nWedJpmbkOCUmoUyJ2ctTmA5SIJXo4dfPHkuskhs zuul-build-sshkey 2025-09-28 17:53:58.510520 | debian-bookworm -> localhost | The key's randomart image is: 2025-09-28 17:53:58.510547 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-09-28 17:53:58.510570 | debian-bookworm -> localhost | | .ooo | 2025-09-28 17:53:58.510595 | debian-bookworm -> localhost | | o.. . | 2025-09-28 17:53:58.510617 | debian-bookworm -> localhost | |o . o + + | 2025-09-28 17:53:58.510639 | debian-bookworm -> localhost | | o o = * o | 2025-09-28 17:53:58.510662 | debian-bookworm -> localhost | | . . X S . | 2025-09-28 17:53:58.510689 | debian-bookworm -> localhost | | # . o o | 2025-09-28 17:53:58.510712 | debian-bookworm -> localhost | | E. + * o * .o.| 2025-09-28 17:53:58.510733 | debian-bookworm -> localhost | | o.. + . o.*+o.| 2025-09-28 17:53:58.510755 | debian-bookworm -> localhost | | .o ...o*. ..| 2025-09-28 17:53:58.510778 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-09-28 17:53:58.510871 | debian-bookworm -> localhost | ok: Runtime: 0:00:01.145919 2025-09-28 17:53:58.517600 | 2025-09-28 17:53:58.517709 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-09-28 17:53:58.545775 | debian-bookworm | ok 2025-09-28 17:53:58.555399 | debian-bookworm | included: /var/lib/zuul/builds/bdeef6b5ac0348838ccf6ab9bd825680/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-09-28 17:53:58.564473 | 2025-09-28 17:53:58.564569 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-09-28 17:53:58.587727 | debian-bookworm | skipping: Conditional result was False 2025-09-28 17:53:58.594033 | 2025-09-28 17:53:58.594140 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-09-28 17:53:59.285044 | debian-bookworm | changed 2025-09-28 17:53:59.293154 | 2025-09-28 17:53:59.293282 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-09-28 17:53:59.549464 | debian-bookworm | ok 2025-09-28 17:53:59.557521 | 2025-09-28 17:53:59.557644 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-09-28 17:54:00.207239 | debian-bookworm | changed 2025-09-28 17:54:00.218745 | 2025-09-28 17:54:00.218946 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-09-28 17:54:00.852257 | debian-bookworm | changed 2025-09-28 17:54:00.859663 | 2025-09-28 17:54:00.859787 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-09-28 17:54:00.883724 | debian-bookworm | skipping: Conditional result was False 2025-09-28 17:54:00.890524 | 2025-09-28 17:54:00.890633 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-09-28 17:54:01.355928 | debian-bookworm -> localhost | changed 2025-09-28 17:54:01.367252 | 2025-09-28 17:54:01.367377 | TASK [add-build-sshkey : Add back temp key] 2025-09-28 17:54:01.704593 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/bdeef6b5ac0348838ccf6ab9bd825680/work/bdeef6b5ac0348838ccf6ab9bd825680_id_rsa (zuul-build-sshkey) 2025-09-28 17:54:01.704861 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.017336 2025-09-28 17:54:01.713389 | 2025-09-28 17:54:01.713504 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-09-28 17:54:02.137163 | debian-bookworm | ok 2025-09-28 17:54:02.163154 | 2025-09-28 17:54:02.163297 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-09-28 17:54:02.197822 | debian-bookworm | skipping: Conditional result was False 2025-09-28 17:54:02.217144 | 2025-09-28 17:54:02.217270 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-09-28 17:54:02.694063 | debian-bookworm | ok 2025-09-28 17:54:02.754723 | 2025-09-28 17:54:02.755997 | TASK [validate-host : Define zuul_info_dir fact] 2025-09-28 17:54:02.814463 | debian-bookworm | ok 2025-09-28 17:54:02.819649 | 2025-09-28 17:54:02.819763 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-09-28 17:54:03.097569 | debian-bookworm -> localhost | ok 2025-09-28 17:54:03.104285 | 2025-09-28 17:54:03.104393 | TASK [validate-host : Collect information about the host] 2025-09-28 17:54:04.195090 | debian-bookworm | ok 2025-09-28 17:54:04.209835 | 2025-09-28 17:54:04.210015 | TASK [validate-host : Sanitize hostname] 2025-09-28 17:54:04.321917 | debian-bookworm | ok 2025-09-28 17:54:04.360596 | 2025-09-28 17:54:04.360720 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-09-28 17:54:05.097451 | debian-bookworm -> localhost | changed 2025-09-28 17:54:05.104651 | 2025-09-28 17:54:05.104772 | TASK [validate-host : Collect information about zuul worker] 2025-09-28 17:54:05.568211 | debian-bookworm | ok 2025-09-28 17:54:05.579518 | 2025-09-28 17:54:05.579779 | TASK [validate-host : Write out all zuul information for each host] 2025-09-28 17:54:06.138452 | debian-bookworm -> localhost | changed 2025-09-28 17:54:06.150249 | 2025-09-28 17:54:06.150442 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-09-28 17:54:06.420399 | debian-bookworm | ok 2025-09-28 17:54:06.427543 | 2025-09-28 17:54:06.427652 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-09-28 17:54:07.428530 | debian-bookworm | changed: 2025-09-28 17:54:07.428964 | debian-bookworm | cd+++++++++ src/ 2025-09-28 17:54:07.429021 | debian-bookworm | cd+++++++++ src/github.com/ 2025-09-28 17:54:07.429048 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-09-28 17:54:07.429071 | debian-bookworm | cd+++++++++ src/github.com/osism/container-image-kolla-ansible/ 2025-09-28 17:54:07.429130 | debian-bookworm | Containerfile 2025-09-28 17:54:07.429327 | debian-bookworm | 2025-09-28 18:08:14.773422 | debian-bookworm | ERROR 2025-09-28 18:08:14.773901 | debian-bookworm | { 2025-09-28 18:08:14.774005 | debian-bookworm | "delta": "0:00:20.924926", 2025-09-28 18:08:14.774074 | debian-bookworm | "end": "2025-09-28 18:08:14.425589", 2025-09-28 18:08:14.774143 | debian-bookworm | "msg": "non-zero return code", 2025-09-28 18:08:14.774199 | debian-bookworm | "rc": 1, 2025-09-28 18:08:14.774252 | debian-bookworm | "start": "2025-09-28 18:07:53.500663" 2025-09-28 18:08:14.774303 | debian-bookworm | } failure 2025-09-28 18:08:14.779747 | 2025-09-28 18:08:14.780013 | PLAY RECAP 2025-09-28 18:08:14.780176 | debian-bookworm | ok: 4 changed: 3 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2025-09-28 18:08:14.780260 | 2025-09-28 18:08:14.913182 | RUN END RESULT_NORMAL: [untrusted : github.com/osism/container-image-kolla-ansible/playbooks/build.yml@main] 2025-09-28 18:08:14.916028 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-09-28 18:08:15.654681 | 2025-09-28 18:08:15.654887 | PLAY [Base post] 2025-09-28 18:08:15.668785 | 2025-09-28 18:08:15.668960 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-09-28 18:08:16.497106 | debian-bookworm | changed 2025-09-28 18:08:16.506128 | 2025-09-28 18:08:16.506261 | PLAY RECAP 2025-09-28 18:08:16.506345 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-09-28 18:08:16.506423 | 2025-09-28 18:08:16.622515 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-09-28 18:08:16.625102 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2025-09-28 18:08:17.409597 | 2025-09-28 18:08:17.409765 | PLAY [Base post-logs] 2025-09-28 18:08:17.421644 | 2025-09-28 18:08:17.421785 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-09-28 18:08:17.877478 | localhost | changed 2025-09-28 18:08:17.895670 | 2025-09-28 18:08:17.895838 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-09-28 18:08:17.934667 | localhost | ok 2025-09-28 18:08:17.941906 | 2025-09-28 18:08:17.942069 | TASK [Set zuul-log-path fact] 2025-09-28 18:08:17.960409 | localhost | ok 2025-09-28 18:08:17.973139 | 2025-09-28 18:08:17.973278 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-09-28 18:08:17.999526 | localhost | ok 2025-09-28 18:08:18.004450 | 2025-09-28 18:08:18.004619 | TASK [upload-logs : Create log directories] 2025-09-28 18:08:18.477956 | localhost | changed 2025-09-28 18:08:18.481190 | 2025-09-28 18:08:18.481310 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-09-28 18:08:18.980784 | localhost -> localhost | ok: Runtime: 0:00:00.006602 2025-09-28 18:08:18.984911 | 2025-09-28 18:08:18.985033 | TASK [upload-logs : Upload logs to log server] 2025-09-28 18:08:19.558651 | localhost | Output suppressed because no_log was given 2025-09-28 18:08:19.561284 | 2025-09-28 18:08:19.561413 | LOOP [upload-logs : Compress console log and json output] 2025-09-28 18:08:19.606650 | localhost | skipping: Conditional result was False 2025-09-28 18:08:19.612722 | localhost | skipping: Conditional result was False 2025-09-28 18:08:19.623990 | 2025-09-28 18:08:19.624215 | LOOP [upload-logs : Upload compressed console log and json output] 2025-09-28 18:08:19.670602 | localhost | skipping: Conditional result was False 2025-09-28 18:08:19.671362 | 2025-09-28 18:08:19.674792 | localhost | skipping: Conditional result was False 2025-09-28 18:08:19.688444 | 2025-09-28 18:08:19.688666 | LOOP [upload-logs : Upload console log and json output]