2025-08-24 19:09:31.041397 | Job console starting 2025-08-24 19:09:31.054695 | Updating git repos 2025-08-24 19:09:31.087248 | Cloning repos into workspace 2025-08-24 19:09:31.127878 | Restoring repo states 2025-08-24 19:09:31.137875 | Merging changes 2025-08-24 19:09:31.137892 | Checking out repos 2025-08-24 19:09:31.194215 | Preparing playbooks 2025-08-24 19:09:31.950882 | Running Ansible setup 2025-08-24 19:09:36.074634 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-08-24 19:09:36.894435 | 2025-08-24 19:09:36.894711 | PLAY [Base pre] 2025-08-24 19:09:36.917176 | 2025-08-24 19:09:36.917356 | TASK [Setup log path fact] 2025-08-24 19:09:36.936739 | debian-bookworm | ok 2025-08-24 19:09:36.952653 | 2025-08-24 19:09:36.952800 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-08-24 19:09:36.986604 | debian-bookworm | ok 2025-08-24 19:09:36.996761 | 2025-08-24 19:09:36.996878 | TASK [emit-job-header : Print job information] 2025-08-24 19:09:37.047677 | # Job Information 2025-08-24 19:09:37.047919 | Ansible Version: 2.16.14 2025-08-24 19:09:37.047973 | Job: container-image-kolla-ansible-push-2024.1 2025-08-24 19:09:37.048022 | Pipeline: post 2025-08-24 19:09:37.048060 | Executor: 521e9411259a 2025-08-24 19:09:37.048092 | Triggered by: https://github.com/osism/container-image-kolla-ansible/commit/861f1398f89adbec86c1e5e71e332671d06728a7 2025-08-24 19:09:37.048127 | Event ID: d97852a2-811d-11f0-998e-85c13e3317d5 2025-08-24 19:09:37.056367 | 2025-08-24 19:09:37.056607 | LOOP [emit-job-header : Print node information] 2025-08-24 19:09:37.172578 | debian-bookworm | ok: 2025-08-24 19:09:37.172800 | debian-bookworm | # Node Information 2025-08-24 19:09:37.172834 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-08-24 19:09:37.172859 | debian-bookworm | Hostname: debian 2025-08-24 19:09:37.172881 | debian-bookworm | Username: zuul 2025-08-24 19:09:37.172902 | debian-bookworm | Distro: Debian 12.11 2025-08-24 19:09:37.172924 | debian-bookworm | Provider: regiocloud-a 2025-08-24 19:09:37.172945 | debian-bookworm | Region: 2025-08-24 19:09:37.172966 | debian-bookworm | Label: debian-bookworm 2025-08-24 19:09:37.172986 | debian-bookworm | Product Name: OpenStack Nova 2025-08-24 19:09:37.173005 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe08:de2b 2025-08-24 19:09:37.191327 | 2025-08-24 19:09:37.191545 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-08-24 19:09:37.662939 | debian-bookworm -> localhost | changed 2025-08-24 19:09:37.670486 | 2025-08-24 19:09:37.670609 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-08-24 19:09:38.731998 | debian-bookworm -> localhost | changed 2025-08-24 19:09:38.753222 | 2025-08-24 19:09:38.753401 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-08-24 19:09:39.052799 | debian-bookworm -> localhost | ok 2025-08-24 19:09:39.067340 | 2025-08-24 19:09:39.067539 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-08-24 19:09:39.093844 | debian-bookworm | ok 2025-08-24 19:09:39.115917 | debian-bookworm | included: /var/lib/zuul/builds/19d9295c0d2c40969b13e9447b431a2d/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-08-24 19:09:39.122392 | 2025-08-24 19:09:39.122505 | TASK [add-build-sshkey : Create Temp SSH key] 2025-08-24 19:09:40.060765 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-08-24 19:09:40.061339 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/19d9295c0d2c40969b13e9447b431a2d/work/19d9295c0d2c40969b13e9447b431a2d_id_rsa 2025-08-24 19:09:40.061483 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/19d9295c0d2c40969b13e9447b431a2d/work/19d9295c0d2c40969b13e9447b431a2d_id_rsa.pub 2025-08-24 19:09:40.061566 | debian-bookworm -> localhost | The key fingerprint is: 2025-08-24 19:09:40.061637 | debian-bookworm -> localhost | SHA256:jRnT9E1EWq8/+OLkJwHWIKWu0k6c3C2f1FQXW2L3E38 zuul-build-sshkey 2025-08-24 19:09:40.061730 | debian-bookworm -> localhost | The key's randomart image is: 2025-08-24 19:09:40.061796 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-08-24 19:09:40.061855 | debian-bookworm -> localhost | | o. oBoo| 2025-08-24 19:09:40.061918 | debian-bookworm -> localhost | | +.o * +B| 2025-08-24 19:09:40.061978 | debian-bookworm -> localhost | | o.o = .+E| 2025-08-24 19:09:40.062035 | debian-bookworm -> localhost | | .* o ...+| 2025-08-24 19:09:40.062092 | debian-bookworm -> localhost | | S.o ... | 2025-08-24 19:09:40.062168 | debian-bookworm -> localhost | | + + . o... | 2025-08-24 19:09:40.062227 | debian-bookworm -> localhost | | . B o o +...| 2025-08-24 19:09:40.062285 | debian-bookworm -> localhost | | + + +o...| 2025-08-24 19:09:40.062347 | debian-bookworm -> localhost | | . o.o+. | 2025-08-24 19:09:40.062403 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-08-24 19:09:40.062607 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.424434 2025-08-24 19:09:40.075748 | 2025-08-24 19:09:40.075913 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-08-24 19:09:40.109165 | debian-bookworm | ok 2025-08-24 19:09:40.119306 | debian-bookworm | included: /var/lib/zuul/builds/19d9295c0d2c40969b13e9447b431a2d/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-08-24 19:09:40.128642 | 2025-08-24 19:09:40.128746 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-08-24 19:09:40.152824 | debian-bookworm | skipping: Conditional result was False 2025-08-24 19:09:40.159786 | 2025-08-24 19:09:40.159890 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-08-24 19:09:40.823407 | debian-bookworm | changed 2025-08-24 19:09:40.835366 | 2025-08-24 19:09:40.835568 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-08-24 19:09:41.098082 | debian-bookworm | ok 2025-08-24 19:09:41.106709 | 2025-08-24 19:09:41.106881 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-08-24 19:09:41.795395 | debian-bookworm | changed 2025-08-24 19:09:41.806404 | 2025-08-24 19:09:41.806589 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-08-24 19:09:42.472395 | debian-bookworm | changed 2025-08-24 19:09:42.480654 | 2025-08-24 19:09:42.480843 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-08-24 19:09:42.506291 | debian-bookworm | skipping: Conditional result was False 2025-08-24 19:09:42.516002 | 2025-08-24 19:09:42.516143 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-08-24 19:09:42.999762 | debian-bookworm -> localhost | changed 2025-08-24 19:09:43.011643 | 2025-08-24 19:09:43.011776 | TASK [add-build-sshkey : Add back temp key] 2025-08-24 19:09:43.370953 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/19d9295c0d2c40969b13e9447b431a2d/work/19d9295c0d2c40969b13e9447b431a2d_id_rsa (zuul-build-sshkey) 2025-08-24 19:09:43.371492 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.017962 2025-08-24 19:09:43.387619 | 2025-08-24 19:09:43.387776 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-08-24 19:09:43.818053 | debian-bookworm | ok 2025-08-24 19:09:43.827209 | 2025-08-24 19:09:43.827365 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-08-24 19:09:43.863353 | debian-bookworm | skipping: Conditional result was False 2025-08-24 19:09:43.882806 | 2025-08-24 19:09:43.882961 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-08-24 19:09:44.332474 | debian-bookworm | ok 2025-08-24 19:09:44.346992 | 2025-08-24 19:09:44.347113 | TASK [validate-host : Define zuul_info_dir fact] 2025-08-24 19:09:44.390683 | debian-bookworm | ok 2025-08-24 19:09:44.397515 | 2025-08-24 19:09:44.397637 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-08-24 19:09:44.706770 | debian-bookworm -> localhost | ok 2025-08-24 19:09:44.713721 | 2025-08-24 19:09:44.713830 | TASK [validate-host : Collect information about the host] 2025-08-24 19:09:45.825063 | debian-bookworm | ok 2025-08-24 19:09:45.838062 | 2025-08-24 19:09:45.838179 | TASK [validate-host : Sanitize hostname] 2025-08-24 19:09:45.956159 | debian-bookworm | ok 2025-08-24 19:09:45.996874 | 2025-08-24 19:09:45.997002 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-08-24 19:09:46.580273 | debian-bookworm -> localhost | changed 2025-08-24 19:09:46.586995 | 2025-08-24 19:09:46.587115 | TASK [validate-host : Collect information about zuul worker] 2025-08-24 19:09:47.013252 | debian-bookworm | ok 2025-08-24 19:09:47.018219 | 2025-08-24 19:09:47.018330 | TASK [validate-host : Write out all zuul information for each host] 2025-08-24 19:09:47.609854 | debian-bookworm -> localhost | changed 2025-08-24 19:09:47.620272 | 2025-08-24 19:09:47.620396 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-08-24 19:09:47.898654 | debian-bookworm | ok 2025-08-24 19:09:47.906827 | 2025-08-24 19:09:47.906998 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-08-24 19:09:48.797288 | debian-bookworm | changed: 2025-08-24 19:09:48.797606 | debian-bookworm | cd+++++++++ src/ 2025-08-24 19:09:48.797666 | debian-bookworm | cd+++++++++ src/github.com/ 2025-08-24 19:09:48.797707 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-08-24 19:09:48.797745 | debian-bookworm | cd+++++++++ src/github.com/osism/container-image-kolla-ansible/ 2025-08-24 19:09:48.797780 | debian-bookworm | Containerfile 2025-08-24 19:09:48.798002 | debian-bookworm | 2025-08-24 19:15:42.703879 | debian-bookworm | ERROR 2025-08-24 19:15:42.704055 | debian-bookworm | { 2025-08-24 19:15:42.704091 | debian-bookworm | "delta": "0:00:21.492907", 2025-08-24 19:15:42.704114 | debian-bookworm | "end": "2025-08-24 19:15:42.461630", 2025-08-24 19:15:42.704140 | debian-bookworm | "msg": "non-zero return code", 2025-08-24 19:15:42.704160 | debian-bookworm | "rc": 1, 2025-08-24 19:15:42.704178 | debian-bookworm | "start": "2025-08-24 19:15:20.968723" 2025-08-24 19:15:42.704196 | debian-bookworm | } failure 2025-08-24 19:15:42.705661 | 2025-08-24 19:15:42.705722 | PLAY RECAP 2025-08-24 19:15:42.705869 | debian-bookworm | ok: 4 changed: 3 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2025-08-24 19:15:42.705900 | 2025-08-24 19:15:42.880834 | RUN END RESULT_NORMAL: [untrusted : github.com/osism/container-image-kolla-ansible/playbooks/build.yml@main] 2025-08-24 19:15:42.881912 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-08-24 19:15:43.612574 | 2025-08-24 19:15:43.612704 | PLAY [Base post] 2025-08-24 19:15:43.631073 | 2025-08-24 19:15:43.631192 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-08-24 19:15:44.466917 | debian-bookworm | changed 2025-08-24 19:15:44.481887 | 2025-08-24 19:15:44.481984 | PLAY RECAP 2025-08-24 19:15:44.482048 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-08-24 19:15:44.482110 | 2025-08-24 19:15:44.601801 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-08-24 19:15:44.604935 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2025-08-24 19:15:45.821704 | 2025-08-24 19:15:45.821895 | PLAY [Base post-logs] 2025-08-24 19:15:45.855101 | 2025-08-24 19:15:45.855298 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-08-24 19:15:46.790545 | localhost | changed 2025-08-24 19:15:46.801720 | 2025-08-24 19:15:46.801891 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-08-24 19:15:46.828408 | localhost | ok 2025-08-24 19:15:46.831753 | 2025-08-24 19:15:46.831856 | TASK [Set zuul-log-path fact] 2025-08-24 19:15:46.847626 | localhost | ok 2025-08-24 19:15:46.856414 | 2025-08-24 19:15:46.856538 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-08-24 19:15:46.882887 | localhost | ok 2025-08-24 19:15:46.886918 | 2025-08-24 19:15:46.895682 | TASK [upload-logs : Create log directories] 2025-08-24 19:15:47.837406 | localhost | changed 2025-08-24 19:15:47.840603 | 2025-08-24 19:15:47.840715 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-08-24 19:15:49.130331 | localhost -> localhost | ok: Runtime: 0:00:00.016339 2025-08-24 19:15:49.134011 | 2025-08-24 19:15:49.134107 | TASK [upload-logs : Upload logs to log server] 2025-08-24 19:15:49.736422 | localhost | Output suppressed because no_log was given 2025-08-24 19:15:49.737836 | 2025-08-24 19:15:49.737907 | LOOP [upload-logs : Compress console log and json output] 2025-08-24 19:15:49.790182 | localhost | skipping: Conditional result was False 2025-08-24 19:15:49.806243 | localhost | skipping: Conditional result was False 2025-08-24 19:15:49.813473 | 2025-08-24 19:15:49.813569 | LOOP [upload-logs : Upload compressed console log and json output] 2025-08-24 19:15:49.867990 | localhost | skipping: Conditional result was False 2025-08-24 19:15:49.868237 | 2025-08-24 19:15:49.872326 | localhost | skipping: Conditional result was False 2025-08-24 19:15:49.878557 | 2025-08-24 19:15:49.878654 | LOOP [upload-logs : Upload console log and json output]