2025-09-16 00:00:12.403296 | Job console starting 2025-09-16 00:00:12.472358 | Updating git repos 2025-09-16 00:00:12.539002 | Cloning repos into workspace 2025-09-16 00:00:12.645883 | Restoring repo states 2025-09-16 00:00:12.688971 | Merging changes 2025-09-16 00:00:12.688989 | Checking out repos 2025-09-16 00:00:12.784124 | Preparing playbooks 2025-09-16 00:00:13.865225 | Running Ansible setup 2025-09-16 00:00:20.291915 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-09-16 00:00:22.127889 | 2025-09-16 00:00:22.128001 | PLAY [Base pre] 2025-09-16 00:00:22.183234 | 2025-09-16 00:00:22.183366 | TASK [Setup log path fact] 2025-09-16 00:00:22.211914 | debian-bookworm | ok 2025-09-16 00:00:22.250828 | 2025-09-16 00:00:22.250964 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-09-16 00:00:22.305962 | debian-bookworm | ok 2025-09-16 00:00:22.328717 | 2025-09-16 00:00:22.328822 | TASK [emit-job-header : Print job information] 2025-09-16 00:00:22.390476 | # Job Information 2025-09-16 00:00:22.390613 | Ansible Version: 2.16.14 2025-09-16 00:00:22.390642 | Job: container-image-kolla-ansible-push-2024.2 2025-09-16 00:00:22.390670 | Pipeline: periodic-midnight 2025-09-16 00:00:22.390689 | Executor: 521e9411259a 2025-09-16 00:00:22.390706 | Triggered by: https://github.com/osism/container-image-kolla-ansible 2025-09-16 00:00:22.390723 | Event ID: 692372c6b5e8432c827a49cede439329 2025-09-16 00:00:22.394611 | 2025-09-16 00:00:22.394687 | LOOP [emit-job-header : Print node information] 2025-09-16 00:00:22.638395 | debian-bookworm | ok: 2025-09-16 00:00:22.638560 | debian-bookworm | # Node Information 2025-09-16 00:00:22.638587 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-09-16 00:00:22.638608 | debian-bookworm | Hostname: debian 2025-09-16 00:00:22.638625 | debian-bookworm | Username: zuul 2025-09-16 00:00:22.638643 | debian-bookworm | Distro: Debian 12.12 2025-09-16 00:00:22.638663 | debian-bookworm | Provider: regiocloud-a 2025-09-16 00:00:22.638680 | debian-bookworm | Region: 2025-09-16 00:00:22.638698 | debian-bookworm | Label: debian-bookworm 2025-09-16 00:00:22.638714 | debian-bookworm | Product Name: OpenStack Nova 2025-09-16 00:00:22.638731 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fefc:d531 2025-09-16 00:00:22.655516 | 2025-09-16 00:00:22.655616 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-09-16 00:00:23.951170 | debian-bookworm -> localhost | changed 2025-09-16 00:00:23.956904 | 2025-09-16 00:00:23.956999 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-09-16 00:00:26.487708 | debian-bookworm -> localhost | changed 2025-09-16 00:00:26.496487 | 2025-09-16 00:00:26.496576 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-09-16 00:00:27.148814 | debian-bookworm -> localhost | ok 2025-09-16 00:00:27.153843 | 2025-09-16 00:00:27.153938 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-09-16 00:00:27.221185 | debian-bookworm | ok 2025-09-16 00:00:27.251080 | debian-bookworm | included: /var/lib/zuul/builds/deae25ae579b4bd38a266142f7084168/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-09-16 00:00:27.266711 | 2025-09-16 00:00:27.266807 | TASK [add-build-sshkey : Create Temp SSH key] 2025-09-16 00:00:30.678521 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-09-16 00:00:30.678715 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/deae25ae579b4bd38a266142f7084168/work/deae25ae579b4bd38a266142f7084168_id_rsa 2025-09-16 00:00:30.678751 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/deae25ae579b4bd38a266142f7084168/work/deae25ae579b4bd38a266142f7084168_id_rsa.pub 2025-09-16 00:00:30.678773 | debian-bookworm -> localhost | The key fingerprint is: 2025-09-16 00:00:30.678793 | debian-bookworm -> localhost | SHA256:+enXP39b8Sue0Z0NmpevyubNtZpxbU6iENbG4Yk/hxM zuul-build-sshkey 2025-09-16 00:00:30.678821 | debian-bookworm -> localhost | The key's randomart image is: 2025-09-16 00:00:30.678871 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-09-16 00:00:30.678890 | debian-bookworm -> localhost | | | 2025-09-16 00:00:30.678908 | debian-bookworm -> localhost | | | 2025-09-16 00:00:30.678926 | debian-bookworm -> localhost | | . | 2025-09-16 00:00:30.678942 | debian-bookworm -> localhost | | . = o | 2025-09-16 00:00:30.678959 | debian-bookworm -> localhost | | S + E .. | 2025-09-16 00:00:30.678981 | debian-bookworm -> localhost | | o = =.oB| 2025-09-16 00:00:30.678999 | debian-bookworm -> localhost | | + B+=+X| 2025-09-16 00:00:30.679017 | debian-bookworm -> localhost | | . ooXBO=| 2025-09-16 00:00:30.679034 | debian-bookworm -> localhost | | .+****X| 2025-09-16 00:00:30.679051 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-09-16 00:00:30.679099 | debian-bookworm -> localhost | ok: Runtime: 0:00:02.289866 2025-09-16 00:00:30.684469 | 2025-09-16 00:00:30.684548 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-09-16 00:00:30.711826 | debian-bookworm | ok 2025-09-16 00:00:30.722656 | debian-bookworm | included: /var/lib/zuul/builds/deae25ae579b4bd38a266142f7084168/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-09-16 00:00:30.768507 | 2025-09-16 00:00:30.768600 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-09-16 00:00:30.826145 | debian-bookworm | skipping: Conditional result was False 2025-09-16 00:00:30.831058 | 2025-09-16 00:00:30.831147 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-09-16 00:00:31.690681 | debian-bookworm | changed 2025-09-16 00:00:31.695152 | 2025-09-16 00:00:31.695232 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-09-16 00:00:31.940289 | debian-bookworm | ok 2025-09-16 00:00:31.944860 | 2025-09-16 00:00:31.944936 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-09-16 00:00:32.695139 | debian-bookworm | changed 2025-09-16 00:00:32.700624 | 2025-09-16 00:00:32.700703 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-09-16 00:00:33.418585 | debian-bookworm | changed 2025-09-16 00:00:33.422977 | 2025-09-16 00:00:33.423052 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-09-16 00:00:33.452772 | debian-bookworm | skipping: Conditional result was False 2025-09-16 00:00:33.458396 | 2025-09-16 00:00:33.458493 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-09-16 00:00:34.325837 | debian-bookworm -> localhost | changed 2025-09-16 00:00:34.334380 | 2025-09-16 00:00:34.334469 | TASK [add-build-sshkey : Add back temp key] 2025-09-16 00:00:34.978392 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/deae25ae579b4bd38a266142f7084168/work/deae25ae579b4bd38a266142f7084168_id_rsa (zuul-build-sshkey) 2025-09-16 00:00:34.978576 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.036921 2025-09-16 00:00:34.985026 | 2025-09-16 00:00:34.985112 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-09-16 00:00:35.388847 | debian-bookworm | ok 2025-09-16 00:00:35.394466 | 2025-09-16 00:00:35.394555 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-09-16 00:00:35.437792 | debian-bookworm | skipping: Conditional result was False 2025-09-16 00:00:35.447455 | 2025-09-16 00:00:35.447546 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-09-16 00:00:35.829302 | debian-bookworm | ok 2025-09-16 00:00:35.839691 | 2025-09-16 00:00:35.839780 | TASK [validate-host : Define zuul_info_dir fact] 2025-09-16 00:00:35.866494 | debian-bookworm | ok 2025-09-16 00:00:35.871190 | 2025-09-16 00:00:35.871269 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-09-16 00:00:36.146912 | debian-bookworm -> localhost | ok 2025-09-16 00:00:36.152712 | 2025-09-16 00:00:36.152797 | TASK [validate-host : Collect information about the host] 2025-09-16 00:00:37.375303 | debian-bookworm | ok 2025-09-16 00:00:37.410792 | 2025-09-16 00:00:37.410919 | TASK [validate-host : Sanitize hostname] 2025-09-16 00:00:37.629015 | debian-bookworm | ok 2025-09-16 00:00:37.709782 | 2025-09-16 00:00:37.710306 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-09-16 00:00:38.918124 | debian-bookworm -> localhost | changed 2025-09-16 00:00:38.922790 | 2025-09-16 00:00:38.922898 | TASK [validate-host : Collect information about zuul worker] 2025-09-16 00:00:39.558785 | debian-bookworm | ok 2025-09-16 00:00:39.562652 | 2025-09-16 00:00:39.562728 | TASK [validate-host : Write out all zuul information for each host] 2025-09-16 00:00:40.681338 | debian-bookworm -> localhost | changed 2025-09-16 00:00:40.692885 | 2025-09-16 00:00:40.692978 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-09-16 00:00:41.054927 | debian-bookworm | ok 2025-09-16 00:00:41.065123 | 2025-09-16 00:00:41.065240 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-09-16 00:00:42.022577 | debian-bookworm | changed: 2025-09-16 00:00:42.022751 | debian-bookworm | cd+++++++++ src/ 2025-09-16 00:00:42.022784 | debian-bookworm | cd+++++++++ src/github.com/ 2025-09-16 00:00:42.022808 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-09-16 00:00:42.022868 | debian-bookworm | cd+++++++++ src/github.com/osism/container-image-kolla-ansible/ 2025-09-16 00:00:42.022894 | debian-bookworm | Containerfile 2025-09-16 00:00:42.023027 | debian-bookworm | 2025-09-16 00:15:24.963836 | debian-bookworm | ERROR 2025-09-16 00:15:24.964126 | debian-bookworm | { 2025-09-16 00:15:24.964190 | debian-bookworm | "delta": "0:00:21.739132", 2025-09-16 00:15:24.964233 | debian-bookworm | "end": "2025-09-16 00:15:24.910954", 2025-09-16 00:15:24.964301 | debian-bookworm | "msg": "non-zero return code", 2025-09-16 00:15:24.964336 | debian-bookworm | "rc": 1, 2025-09-16 00:15:24.964370 | debian-bookworm | "start": "2025-09-16 00:15:03.171822" 2025-09-16 00:15:24.964401 | debian-bookworm | } failure 2025-09-16 00:15:24.967144 | 2025-09-16 00:15:24.967316 | PLAY RECAP 2025-09-16 00:15:24.967404 | debian-bookworm | ok: 4 changed: 3 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2025-09-16 00:15:24.967444 | 2025-09-16 00:15:25.122830 | RUN END RESULT_NORMAL: [untrusted : github.com/osism/container-image-kolla-ansible/playbooks/build.yml@main] 2025-09-16 00:15:25.124822 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-09-16 00:15:25.854708 | 2025-09-16 00:15:25.854888 | PLAY [Base post] 2025-09-16 00:15:25.868351 | 2025-09-16 00:15:25.868475 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-09-16 00:15:26.716540 | debian-bookworm | changed 2025-09-16 00:15:26.727563 | 2025-09-16 00:15:26.727706 | PLAY RECAP 2025-09-16 00:15:26.727796 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-09-16 00:15:26.727882 | 2025-09-16 00:15:26.851538 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-09-16 00:15:26.853988 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2025-09-16 00:15:27.644398 | 2025-09-16 00:15:27.644568 | PLAY [Base post-logs] 2025-09-16 00:15:27.655634 | 2025-09-16 00:15:27.655774 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-09-16 00:15:28.129122 | localhost | changed 2025-09-16 00:15:28.141397 | 2025-09-16 00:15:28.141555 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-09-16 00:15:28.168337 | localhost | ok 2025-09-16 00:15:28.174206 | 2025-09-16 00:15:28.174434 | TASK [Set zuul-log-path fact] 2025-09-16 00:15:28.202396 | localhost | ok 2025-09-16 00:15:28.216847 | 2025-09-16 00:15:28.217011 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-09-16 00:15:28.255021 | localhost | ok 2025-09-16 00:15:28.260918 | 2025-09-16 00:15:28.261066 | TASK [upload-logs : Create log directories] 2025-09-16 00:15:28.755571 | localhost | changed 2025-09-16 00:15:28.758991 | 2025-09-16 00:15:28.759106 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-09-16 00:15:29.248913 | localhost -> localhost | ok: Runtime: 0:00:00.008336 2025-09-16 00:15:29.253217 | 2025-09-16 00:15:29.253355 | TASK [upload-logs : Upload logs to log server] 2025-09-16 00:15:29.817726 | localhost | Output suppressed because no_log was given 2025-09-16 00:15:29.821837 | 2025-09-16 00:15:29.822023 | LOOP [upload-logs : Compress console log and json output] 2025-09-16 00:15:29.909322 | localhost | skipping: Conditional result was False 2025-09-16 00:15:29.925596 | localhost | skipping: Conditional result was False 2025-09-16 00:15:29.937722 | 2025-09-16 00:15:29.937945 | LOOP [upload-logs : Upload compressed console log and json output] 2025-09-16 00:15:29.993198 | localhost | skipping: Conditional result was False 2025-09-16 00:15:29.993858 | 2025-09-16 00:15:29.997322 | localhost | skipping: Conditional result was False 2025-09-16 00:15:30.013441 | 2025-09-16 00:15:30.013636 | LOOP [upload-logs : Upload console log and json output]