2025-09-17 00:00:04.972494 | Job console starting 2025-09-17 00:00:05.014400 | Updating git repos 2025-09-17 00:00:05.055671 | Cloning repos into workspace 2025-09-17 00:00:05.120884 | Restoring repo states 2025-09-17 00:00:05.139681 | Merging changes 2025-09-17 00:00:05.139714 | Checking out repos 2025-09-17 00:00:05.221287 | Preparing playbooks 2025-09-17 00:00:05.771571 | Running Ansible setup 2025-09-17 00:00:10.966985 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-09-17 00:00:11.874674 | 2025-09-17 00:00:11.874812 | PLAY [Base pre] 2025-09-17 00:00:11.889855 | 2025-09-17 00:00:11.889974 | TASK [Setup log path fact] 2025-09-17 00:00:11.918488 | debian-bookworm | ok 2025-09-17 00:00:11.944864 | 2025-09-17 00:00:11.945005 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-09-17 00:00:11.998948 | debian-bookworm | ok 2025-09-17 00:00:12.020041 | 2025-09-17 00:00:12.020152 | TASK [emit-job-header : Print job information] 2025-09-17 00:00:12.068309 | # Job Information 2025-09-17 00:00:12.068541 | Ansible Version: 2.16.14 2025-09-17 00:00:12.068578 | Job: container-image-kolla-ansible-push-2024.1 2025-09-17 00:00:12.068617 | Pipeline: periodic-midnight 2025-09-17 00:00:12.068641 | Executor: 521e9411259a 2025-09-17 00:00:12.068663 | Triggered by: https://github.com/osism/container-image-kolla-ansible 2025-09-17 00:00:12.068686 | Event ID: 6b25731be4494f149c235f009b6f3f74 2025-09-17 00:00:12.073770 | 2025-09-17 00:00:12.073876 | LOOP [emit-job-header : Print node information] 2025-09-17 00:00:12.347003 | debian-bookworm | ok: 2025-09-17 00:00:12.347269 | debian-bookworm | # Node Information 2025-09-17 00:00:12.347311 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-09-17 00:00:12.347337 | debian-bookworm | Hostname: debian 2025-09-17 00:00:12.347359 | debian-bookworm | Username: zuul 2025-09-17 00:00:12.347381 | debian-bookworm | Distro: Debian 12.12 2025-09-17 00:00:12.347404 | debian-bookworm | Provider: regiocloud-a 2025-09-17 00:00:12.347426 | debian-bookworm | Region: 2025-09-17 00:00:12.347447 | debian-bookworm | Label: debian-bookworm 2025-09-17 00:00:12.347467 | debian-bookworm | Product Name: OpenStack Nova 2025-09-17 00:00:12.347510 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe6a:d551 2025-09-17 00:00:12.379160 | 2025-09-17 00:00:12.379296 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-09-17 00:00:13.157010 | debian-bookworm -> localhost | changed 2025-09-17 00:00:13.162722 | 2025-09-17 00:00:13.162805 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-09-17 00:00:15.049312 | debian-bookworm -> localhost | changed 2025-09-17 00:00:15.058375 | 2025-09-17 00:00:15.058473 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-09-17 00:00:15.872892 | debian-bookworm -> localhost | ok 2025-09-17 00:00:15.879041 | 2025-09-17 00:00:15.879132 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-09-17 00:00:15.937695 | debian-bookworm | ok 2025-09-17 00:00:16.000635 | debian-bookworm | included: /var/lib/zuul/builds/d930d4c3729e4e7cbe185a7c4605cd47/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-09-17 00:00:16.012811 | 2025-09-17 00:00:16.012899 | TASK [add-build-sshkey : Create Temp SSH key] 2025-09-17 00:00:18.791059 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-09-17 00:00:18.792030 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/d930d4c3729e4e7cbe185a7c4605cd47/work/d930d4c3729e4e7cbe185a7c4605cd47_id_rsa 2025-09-17 00:00:18.792081 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/d930d4c3729e4e7cbe185a7c4605cd47/work/d930d4c3729e4e7cbe185a7c4605cd47_id_rsa.pub 2025-09-17 00:00:18.792105 | debian-bookworm -> localhost | The key fingerprint is: 2025-09-17 00:00:18.792125 | debian-bookworm -> localhost | SHA256:vPbmnC2e/vHmD/qA59MJqxUE0WIm9tPhKL3xmW4Kbpw zuul-build-sshkey 2025-09-17 00:00:18.792152 | debian-bookworm -> localhost | The key's randomart image is: 2025-09-17 00:00:18.792171 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-09-17 00:00:18.792189 | debian-bookworm -> localhost | | oo | 2025-09-17 00:00:18.792208 | debian-bookworm -> localhost | | o +.o | 2025-09-17 00:00:18.792225 | debian-bookworm -> localhost | | . * =.. | 2025-09-17 00:00:18.792242 | debian-bookworm -> localhost | | .. *.o | 2025-09-17 00:00:18.792258 | debian-bookworm -> localhost | | S. =.o | 2025-09-17 00:00:18.792279 | debian-bookworm -> localhost | | ...=. | 2025-09-17 00:00:18.792297 | debian-bookworm -> localhost | | .+...=+.. | 2025-09-17 00:00:18.792314 | debian-bookworm -> localhost | | oE+oB==+. | 2025-09-17 00:00:18.792331 | debian-bookworm -> localhost | | ..=@*==+..| 2025-09-17 00:00:18.792348 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-09-17 00:00:18.792395 | debian-bookworm -> localhost | ok: Runtime: 0:00:01.418121 2025-09-17 00:00:18.799800 | 2025-09-17 00:00:18.799888 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-09-17 00:00:18.871023 | debian-bookworm | ok 2025-09-17 00:00:18.900352 | debian-bookworm | included: /var/lib/zuul/builds/d930d4c3729e4e7cbe185a7c4605cd47/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-09-17 00:00:18.934593 | 2025-09-17 00:00:18.934689 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-09-17 00:00:18.994573 | debian-bookworm | skipping: Conditional result was False 2025-09-17 00:00:18.999623 | 2025-09-17 00:00:18.999710 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-09-17 00:00:19.997012 | debian-bookworm | changed 2025-09-17 00:00:20.001774 | 2025-09-17 00:00:20.001851 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-09-17 00:00:20.325319 | debian-bookworm | ok 2025-09-17 00:00:20.330254 | 2025-09-17 00:00:20.330337 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-09-17 00:00:21.110203 | debian-bookworm | changed 2025-09-17 00:00:21.116595 | 2025-09-17 00:00:21.116687 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-09-17 00:00:21.917104 | debian-bookworm | changed 2025-09-17 00:00:21.921626 | 2025-09-17 00:00:21.921705 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-09-17 00:00:21.955146 | debian-bookworm | skipping: Conditional result was False 2025-09-17 00:00:21.960791 | 2025-09-17 00:00:21.960889 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-09-17 00:00:23.081017 | debian-bookworm -> localhost | changed 2025-09-17 00:00:23.089712 | 2025-09-17 00:00:23.089796 | TASK [add-build-sshkey : Add back temp key] 2025-09-17 00:00:24.154397 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/d930d4c3729e4e7cbe185a7c4605cd47/work/d930d4c3729e4e7cbe185a7c4605cd47_id_rsa (zuul-build-sshkey) 2025-09-17 00:00:24.154599 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.035009 2025-09-17 00:00:24.161169 | 2025-09-17 00:00:24.161246 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-09-17 00:00:24.591427 | debian-bookworm | ok 2025-09-17 00:00:24.595608 | 2025-09-17 00:00:24.595680 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-09-17 00:00:24.634454 | debian-bookworm | skipping: Conditional result was False 2025-09-17 00:00:24.655453 | 2025-09-17 00:00:24.655561 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-09-17 00:00:25.199026 | debian-bookworm | ok 2025-09-17 00:00:25.212547 | 2025-09-17 00:00:25.212642 | TASK [validate-host : Define zuul_info_dir fact] 2025-09-17 00:00:25.239624 | debian-bookworm | ok 2025-09-17 00:00:25.244371 | 2025-09-17 00:00:25.244456 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-09-17 00:00:25.950143 | debian-bookworm -> localhost | ok 2025-09-17 00:00:25.955838 | 2025-09-17 00:00:25.955928 | TASK [validate-host : Collect information about the host] 2025-09-17 00:00:27.446591 | debian-bookworm | ok 2025-09-17 00:00:27.468356 | 2025-09-17 00:00:27.468448 | TASK [validate-host : Sanitize hostname] 2025-09-17 00:00:27.629329 | debian-bookworm | ok 2025-09-17 00:00:27.692940 | 2025-09-17 00:00:27.693038 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-09-17 00:00:28.901400 | debian-bookworm -> localhost | changed 2025-09-17 00:00:28.905968 | 2025-09-17 00:00:28.906047 | TASK [validate-host : Collect information about zuul worker] 2025-09-17 00:00:29.487481 | debian-bookworm | ok 2025-09-17 00:00:29.491144 | 2025-09-17 00:00:29.491216 | TASK [validate-host : Write out all zuul information for each host] 2025-09-17 00:00:30.605284 | debian-bookworm -> localhost | changed 2025-09-17 00:00:30.612997 | 2025-09-17 00:00:30.613079 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-09-17 00:00:30.896149 | debian-bookworm | ok 2025-09-17 00:00:30.901639 | 2025-09-17 00:00:30.901735 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-09-17 00:00:32.302544 | debian-bookworm | changed: 2025-09-17 00:00:32.302729 | debian-bookworm | cd+++++++++ src/ 2025-09-17 00:00:32.302764 | debian-bookworm | cd+++++++++ src/github.com/ 2025-09-17 00:00:32.302790 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-09-17 00:00:32.302812 | debian-bookworm | cd+++++++++ src/github.com/osism/container-image-kolla-ansible/ 2025-09-17 00:00:32.302854 | debian-bookworm | Containerfile 2025-09-17 00:00:32.302991 | debian-bookworm | 2025-09-17 00:13:13.688546 | debian-bookworm | ERROR 2025-09-17 00:13:13.689003 | debian-bookworm | { 2025-09-17 00:13:13.689116 | debian-bookworm | "delta": "0:00:21.361746", 2025-09-17 00:13:13.689187 | debian-bookworm | "end": "2025-09-17 00:13:13.285564", 2025-09-17 00:13:13.689257 | debian-bookworm | "msg": "non-zero return code", 2025-09-17 00:13:13.689315 | debian-bookworm | "rc": 1, 2025-09-17 00:13:13.689371 | debian-bookworm | "start": "2025-09-17 00:12:51.923818" 2025-09-17 00:13:13.689424 | debian-bookworm | } failure 2025-09-17 00:13:13.698260 | 2025-09-17 00:13:13.698756 | PLAY RECAP 2025-09-17 00:13:13.698918 | debian-bookworm | ok: 4 changed: 3 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2025-09-17 00:13:13.699316 | 2025-09-17 00:13:13.869952 | RUN END RESULT_NORMAL: [untrusted : github.com/osism/container-image-kolla-ansible/playbooks/build.yml@main] 2025-09-17 00:13:13.871105 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-09-17 00:13:14.631935 | 2025-09-17 00:13:14.632092 | PLAY [Base post] 2025-09-17 00:13:14.645796 | 2025-09-17 00:13:14.645923 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-09-17 00:13:15.461023 | debian-bookworm | changed 2025-09-17 00:13:15.471927 | 2025-09-17 00:13:15.472064 | PLAY RECAP 2025-09-17 00:13:15.472154 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-09-17 00:13:15.472242 | 2025-09-17 00:13:15.591855 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-09-17 00:13:15.594050 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2025-09-17 00:13:16.429728 | 2025-09-17 00:13:16.429891 | PLAY [Base post-logs] 2025-09-17 00:13:16.440657 | 2025-09-17 00:13:16.440782 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-09-17 00:13:16.914531 | localhost | changed 2025-09-17 00:13:16.924929 | 2025-09-17 00:13:16.925090 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-09-17 00:13:16.964128 | localhost | ok 2025-09-17 00:13:16.968110 | 2025-09-17 00:13:16.968226 | TASK [Set zuul-log-path fact] 2025-09-17 00:13:16.993578 | localhost | ok 2025-09-17 00:13:17.001536 | 2025-09-17 00:13:17.001644 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-09-17 00:13:17.028229 | localhost | ok 2025-09-17 00:13:17.032411 | 2025-09-17 00:13:17.032570 | TASK [upload-logs : Create log directories] 2025-09-17 00:13:17.517146 | localhost | changed 2025-09-17 00:13:17.522781 | 2025-09-17 00:13:17.522988 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-09-17 00:13:18.035926 | localhost -> localhost | ok: Runtime: 0:00:00.006992 2025-09-17 00:13:18.045167 | 2025-09-17 00:13:18.045351 | TASK [upload-logs : Upload logs to log server] 2025-09-17 00:13:18.622822 | localhost | Output suppressed because no_log was given 2025-09-17 00:13:18.627448 | 2025-09-17 00:13:18.627692 | LOOP [upload-logs : Compress console log and json output] 2025-09-17 00:13:18.684419 | localhost | skipping: Conditional result was False 2025-09-17 00:13:18.689411 | localhost | skipping: Conditional result was False 2025-09-17 00:13:18.701287 | 2025-09-17 00:13:18.701599 | LOOP [upload-logs : Upload compressed console log and json output] 2025-09-17 00:13:18.748692 | localhost | skipping: Conditional result was False 2025-09-17 00:13:18.749288 | 2025-09-17 00:13:18.753118 | localhost | skipping: Conditional result was False 2025-09-17 00:13:18.767091 | 2025-09-17 00:13:18.767340 | LOOP [upload-logs : Upload console log and json output]