2025-09-27 10:28:16.836975 | Job console starting 2025-09-27 10:28:16.853129 | Updating git repos 2025-09-27 10:28:16.876918 | Cloning repos into workspace 2025-09-27 10:28:16.945452 | Restoring repo states 2025-09-27 10:28:16.962595 | Merging changes 2025-09-27 10:28:16.962615 | Checking out repos 2025-09-27 10:28:17.032031 | Preparing playbooks 2025-09-27 10:28:17.705052 | Running Ansible setup 2025-09-27 10:28:21.919553 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-09-27 10:28:22.659681 | 2025-09-27 10:28:22.659839 | PLAY [Base pre] 2025-09-27 10:28:22.678208 | 2025-09-27 10:28:22.678385 | TASK [Setup log path fact] 2025-09-27 10:28:22.707179 | debian-bookworm | ok 2025-09-27 10:28:22.722159 | 2025-09-27 10:28:22.722294 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-09-27 10:28:22.751593 | debian-bookworm | ok 2025-09-27 10:28:22.761476 | 2025-09-27 10:28:22.761596 | TASK [emit-job-header : Print job information] 2025-09-27 10:28:22.800013 | # Job Information 2025-09-27 10:28:22.800186 | Ansible Version: 2.16.14 2025-09-27 10:28:22.800221 | Job: container-image-kolla-ansible-push-2025.1 2025-09-27 10:28:22.800254 | Pipeline: post 2025-09-27 10:28:22.800278 | Executor: 521e9411259a 2025-09-27 10:28:22.800311 | Triggered by: https://github.com/osism/container-image-kolla-ansible/commit/39fdcd5d8e15c9feb3f16fac4290debf58776790 2025-09-27 10:28:22.800335 | Event ID: ab4dbb18-9b8c-11f0-8f3c-03c8c62250a5 2025-09-27 10:28:22.805264 | 2025-09-27 10:28:22.805388 | LOOP [emit-job-header : Print node information] 2025-09-27 10:28:22.908960 | debian-bookworm | ok: 2025-09-27 10:28:22.909163 | debian-bookworm | # Node Information 2025-09-27 10:28:22.909201 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-09-27 10:28:22.909226 | debian-bookworm | Hostname: debian 2025-09-27 10:28:22.909248 | debian-bookworm | Username: zuul 2025-09-27 10:28:22.909268 | debian-bookworm | Distro: Debian 12.12 2025-09-27 10:28:22.909291 | debian-bookworm | Provider: regiocloud-a 2025-09-27 10:28:22.909327 | debian-bookworm | Region: 2025-09-27 10:28:22.909349 | debian-bookworm | Label: debian-bookworm 2025-09-27 10:28:22.909369 | debian-bookworm | Product Name: OpenStack Nova 2025-09-27 10:28:22.909388 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe6c:6efd 2025-09-27 10:28:22.927152 | 2025-09-27 10:28:22.927274 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-09-27 10:28:23.376059 | debian-bookworm -> localhost | changed 2025-09-27 10:28:23.383706 | 2025-09-27 10:28:23.383828 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-09-27 10:28:24.358471 | debian-bookworm -> localhost | changed 2025-09-27 10:28:24.370064 | 2025-09-27 10:28:24.370186 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-09-27 10:28:24.637582 | debian-bookworm -> localhost | ok 2025-09-27 10:28:24.644935 | 2025-09-27 10:28:24.645072 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-09-27 10:28:24.674789 | debian-bookworm | ok 2025-09-27 10:28:24.692851 | debian-bookworm | included: /var/lib/zuul/builds/aa4257bce59444c9b4e3818f22aaf193/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-09-27 10:28:24.699446 | 2025-09-27 10:28:24.699564 | TASK [add-build-sshkey : Create Temp SSH key] 2025-09-27 10:28:25.572630 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-09-27 10:28:25.573179 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/aa4257bce59444c9b4e3818f22aaf193/work/aa4257bce59444c9b4e3818f22aaf193_id_rsa 2025-09-27 10:28:25.573287 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/aa4257bce59444c9b4e3818f22aaf193/work/aa4257bce59444c9b4e3818f22aaf193_id_rsa.pub 2025-09-27 10:28:25.573396 | debian-bookworm -> localhost | The key fingerprint is: 2025-09-27 10:28:25.573458 | debian-bookworm -> localhost | SHA256:AwtJecIGeGgu98wVq1cVbnP9yLUegDItCxuybnq5rjM zuul-build-sshkey 2025-09-27 10:28:25.573588 | debian-bookworm -> localhost | The key's randomart image is: 2025-09-27 10:28:25.573685 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-09-27 10:28:25.573763 | debian-bookworm -> localhost | | o.o.. .. | 2025-09-27 10:28:25.573847 | debian-bookworm -> localhost | |o...=.o .o .. | 2025-09-27 10:28:25.573922 | debian-bookworm -> localhost | |o. .o+.= =+o... .| 2025-09-27 10:28:25.573997 | debian-bookworm -> localhost | |... .=o=.=o ..+.| 2025-09-27 10:28:25.574073 | debian-bookworm -> localhost | |.. + +.oS. oo.| 2025-09-27 10:28:25.574157 | debian-bookworm -> localhost | | * . . . .| 2025-09-27 10:28:25.574236 | debian-bookworm -> localhost | | +. . | 2025-09-27 10:28:25.574342 | debian-bookworm -> localhost | | Eoo | 2025-09-27 10:28:25.574434 | debian-bookworm -> localhost | | o*o. | 2025-09-27 10:28:25.574518 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-09-27 10:28:25.574692 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.355508 2025-09-27 10:28:25.589558 | 2025-09-27 10:28:25.589721 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-09-27 10:28:25.618906 | debian-bookworm | ok 2025-09-27 10:28:25.628263 | debian-bookworm | included: /var/lib/zuul/builds/aa4257bce59444c9b4e3818f22aaf193/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-09-27 10:28:25.637600 | 2025-09-27 10:28:25.637698 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-09-27 10:28:25.661953 | debian-bookworm | skipping: Conditional result was False 2025-09-27 10:28:25.667860 | 2025-09-27 10:28:25.667967 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-09-27 10:28:26.372577 | debian-bookworm | changed 2025-09-27 10:28:26.380052 | 2025-09-27 10:28:26.380167 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-09-27 10:28:26.660747 | debian-bookworm | ok 2025-09-27 10:28:26.666500 | 2025-09-27 10:28:26.666605 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-09-27 10:28:27.311021 | debian-bookworm | changed 2025-09-27 10:28:27.320760 | 2025-09-27 10:28:27.320885 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-09-27 10:28:27.948066 | debian-bookworm | changed 2025-09-27 10:28:27.954394 | 2025-09-27 10:28:27.954511 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-09-27 10:28:27.978589 | debian-bookworm | skipping: Conditional result was False 2025-09-27 10:28:27.984805 | 2025-09-27 10:28:27.984910 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-09-27 10:28:28.432937 | debian-bookworm -> localhost | changed 2025-09-27 10:28:28.456249 | 2025-09-27 10:28:28.456445 | TASK [add-build-sshkey : Add back temp key] 2025-09-27 10:28:28.804950 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/aa4257bce59444c9b4e3818f22aaf193/work/aa4257bce59444c9b4e3818f22aaf193_id_rsa (zuul-build-sshkey) 2025-09-27 10:28:28.805192 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.017647 2025-09-27 10:28:28.813623 | 2025-09-27 10:28:28.813761 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-09-27 10:28:29.222728 | debian-bookworm | ok 2025-09-27 10:28:29.231224 | 2025-09-27 10:28:29.231555 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-09-27 10:28:29.257433 | debian-bookworm | skipping: Conditional result was False 2025-09-27 10:28:29.278553 | 2025-09-27 10:28:29.278677 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-09-27 10:28:29.721119 | debian-bookworm | ok 2025-09-27 10:28:29.751829 | 2025-09-27 10:28:29.752002 | TASK [validate-host : Define zuul_info_dir fact] 2025-09-27 10:28:29.797361 | debian-bookworm | ok 2025-09-27 10:28:29.804645 | 2025-09-27 10:28:29.804766 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-09-27 10:28:30.096687 | debian-bookworm -> localhost | ok 2025-09-27 10:28:30.103484 | 2025-09-27 10:28:30.103600 | TASK [validate-host : Collect information about the host] 2025-09-27 10:28:31.208976 | debian-bookworm | ok 2025-09-27 10:28:31.219233 | 2025-09-27 10:28:31.219361 | TASK [validate-host : Sanitize hostname] 2025-09-27 10:28:31.330419 | debian-bookworm | ok 2025-09-27 10:28:31.368597 | 2025-09-27 10:28:31.368726 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-09-27 10:28:31.931394 | debian-bookworm -> localhost | changed 2025-09-27 10:28:31.937372 | 2025-09-27 10:28:31.937489 | TASK [validate-host : Collect information about zuul worker] 2025-09-27 10:28:32.354904 | debian-bookworm | ok 2025-09-27 10:28:32.362055 | 2025-09-27 10:28:32.362200 | TASK [validate-host : Write out all zuul information for each host] 2025-09-27 10:28:32.953381 | debian-bookworm -> localhost | changed 2025-09-27 10:28:32.963412 | 2025-09-27 10:28:32.963521 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-09-27 10:28:33.232963 | debian-bookworm | ok 2025-09-27 10:28:33.239946 | 2025-09-27 10:28:33.240073 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-09-27 10:28:34.140346 | debian-bookworm | changed: 2025-09-27 10:28:34.140590 | debian-bookworm | cd+++++++++ src/ 2025-09-27 10:28:34.140635 | debian-bookworm | cd+++++++++ src/github.com/ 2025-09-27 10:28:34.140666 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-09-27 10:28:34.140695 | debian-bookworm | cd+++++++++ src/github.com/osism/container-image-kolla-ansible/ 2025-09-27 10:28:34.140721 | debian-bookworm | Containerfile 2025-09-27 10:28:34.140883 | debian-bookworm | 2025-09-27 10:36:49.224073 | debian-bookworm | ERROR 2025-09-27 10:36:49.224529 | debian-bookworm | { 2025-09-27 10:36:49.224628 | debian-bookworm | "delta": "0:00:20.836714", 2025-09-27 10:36:49.224695 | debian-bookworm | "end": "2025-09-27 10:36:48.810574", 2025-09-27 10:36:49.224768 | debian-bookworm | "msg": "non-zero return code", 2025-09-27 10:36:49.224825 | debian-bookworm | "rc": 1, 2025-09-27 10:36:49.224880 | debian-bookworm | "start": "2025-09-27 10:36:27.973860" 2025-09-27 10:36:49.224933 | debian-bookworm | } failure 2025-09-27 10:36:49.228825 | 2025-09-27 10:36:49.229236 | PLAY RECAP 2025-09-27 10:36:49.229490 | debian-bookworm | ok: 4 changed: 3 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2025-09-27 10:36:49.229600 | 2025-09-27 10:36:49.397509 | RUN END RESULT_NORMAL: [untrusted : github.com/osism/container-image-kolla-ansible/playbooks/build.yml@main] 2025-09-27 10:36:49.398908 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-09-27 10:36:50.142997 | 2025-09-27 10:36:50.143145 | PLAY [Base post] 2025-09-27 10:36:50.156858 | 2025-09-27 10:36:50.156981 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-09-27 10:36:51.015682 | debian-bookworm | changed 2025-09-27 10:36:51.025000 | 2025-09-27 10:36:51.025137 | PLAY RECAP 2025-09-27 10:36:51.025215 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-09-27 10:36:51.025339 | 2025-09-27 10:36:51.149402 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-09-27 10:36:51.152309 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2025-09-27 10:36:51.901081 | 2025-09-27 10:36:51.901246 | PLAY [Base post-logs] 2025-09-27 10:36:51.912163 | 2025-09-27 10:36:51.912340 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-09-27 10:36:52.374089 | localhost | changed 2025-09-27 10:36:52.384257 | 2025-09-27 10:36:52.384447 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-09-27 10:36:52.410405 | localhost | ok 2025-09-27 10:36:52.413749 | 2025-09-27 10:36:52.413852 | TASK [Set zuul-log-path fact] 2025-09-27 10:36:52.429077 | localhost | ok 2025-09-27 10:36:52.437585 | 2025-09-27 10:36:52.437693 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-09-27 10:36:52.463198 | localhost | ok 2025-09-27 10:36:52.467600 | 2025-09-27 10:36:52.467731 | TASK [upload-logs : Create log directories] 2025-09-27 10:36:52.975856 | localhost | changed 2025-09-27 10:36:52.980820 | 2025-09-27 10:36:52.980990 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-09-27 10:36:53.482287 | localhost -> localhost | ok: Runtime: 0:00:00.007282 2025-09-27 10:36:53.488417 | 2025-09-27 10:36:53.488567 | TASK [upload-logs : Upload logs to log server] 2025-09-27 10:36:54.031695 | localhost | Output suppressed because no_log was given 2025-09-27 10:36:54.034557 | 2025-09-27 10:36:54.034707 | LOOP [upload-logs : Compress console log and json output] 2025-09-27 10:36:54.088154 | localhost | skipping: Conditional result was False 2025-09-27 10:36:54.093758 | localhost | skipping: Conditional result was False 2025-09-27 10:36:54.107628 | 2025-09-27 10:36:54.107848 | LOOP [upload-logs : Upload compressed console log and json output] 2025-09-27 10:36:54.156778 | localhost | skipping: Conditional result was False 2025-09-27 10:36:54.157456 | 2025-09-27 10:36:54.160675 | localhost | skipping: Conditional result was False 2025-09-27 10:36:54.168128 | 2025-09-27 10:36:54.168335 | LOOP [upload-logs : Upload console log and json output]