2025-10-06 00:00:12.618128 | Job console starting 2025-10-06 00:00:12.633578 | Updating git repos 2025-10-06 00:00:12.905347 | Cloning repos into workspace 2025-10-06 00:00:13.024374 | Restoring repo states 2025-10-06 00:00:13.057744 | Merging changes 2025-10-06 00:00:13.057761 | Checking out repos 2025-10-06 00:00:13.192482 | Preparing playbooks 2025-10-06 00:00:14.670936 | Running Ansible setup 2025-10-06 00:00:22.156383 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-10-06 00:00:23.284800 | 2025-10-06 00:00:23.284911 | PLAY [Base pre] 2025-10-06 00:00:23.324902 | 2025-10-06 00:00:23.325018 | TASK [Setup log path fact] 2025-10-06 00:00:23.352566 | debian-bookworm | ok 2025-10-06 00:00:23.376034 | 2025-10-06 00:00:23.376153 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-06 00:00:23.427808 | debian-bookworm | ok 2025-10-06 00:00:23.436978 | 2025-10-06 00:00:23.437062 | TASK [emit-job-header : Print job information] 2025-10-06 00:00:23.489061 | # Job Information 2025-10-06 00:00:23.489190 | Ansible Version: 2.16.14 2025-10-06 00:00:23.489218 | Job: container-image-kolla-ansible-push-2024.2 2025-10-06 00:00:23.489246 | Pipeline: periodic-midnight 2025-10-06 00:00:23.489265 | Executor: 521e9411259a 2025-10-06 00:00:23.489282 | Triggered by: https://github.com/osism/container-image-kolla-ansible 2025-10-06 00:00:23.489301 | Event ID: 5b739c599ca34c508209dcb76e82cc95 2025-10-06 00:00:23.493206 | 2025-10-06 00:00:23.493282 | LOOP [emit-job-header : Print node information] 2025-10-06 00:00:23.648469 | debian-bookworm | ok: 2025-10-06 00:00:23.648682 | debian-bookworm | # Node Information 2025-10-06 00:00:23.648715 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-10-06 00:00:23.648736 | debian-bookworm | Hostname: debian 2025-10-06 00:00:23.648754 | debian-bookworm | Username: zuul 2025-10-06 00:00:23.648771 | debian-bookworm | Distro: Debian 12.12 2025-10-06 00:00:23.648791 | debian-bookworm | Provider: regiocloud-a 2025-10-06 00:00:23.648808 | debian-bookworm | Region: 2025-10-06 00:00:23.648826 | debian-bookworm | Label: debian-bookworm 2025-10-06 00:00:23.648843 | debian-bookworm | Product Name: OpenStack Nova 2025-10-06 00:00:23.648860 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe35:9d6f 2025-10-06 00:00:23.667034 | 2025-10-06 00:00:23.667126 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-10-06 00:00:24.723869 | debian-bookworm -> localhost | changed 2025-10-06 00:00:24.729768 | 2025-10-06 00:00:24.729867 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-10-06 00:00:26.829883 | debian-bookworm -> localhost | changed 2025-10-06 00:00:26.838769 | 2025-10-06 00:00:26.838876 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-10-06 00:00:27.489272 | debian-bookworm -> localhost | ok 2025-10-06 00:00:27.494140 | 2025-10-06 00:00:27.494226 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-10-06 00:00:27.541546 | debian-bookworm | ok 2025-10-06 00:00:27.580182 | debian-bookworm | included: /var/lib/zuul/builds/aa23a0912a4b40019561f08b01511769/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-10-06 00:00:27.598876 | 2025-10-06 00:00:27.598967 | TASK [add-build-sshkey : Create Temp SSH key] 2025-10-06 00:00:31.332920 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-10-06 00:00:31.333093 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/aa23a0912a4b40019561f08b01511769/work/aa23a0912a4b40019561f08b01511769_id_rsa 2025-10-06 00:00:31.333130 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/aa23a0912a4b40019561f08b01511769/work/aa23a0912a4b40019561f08b01511769_id_rsa.pub 2025-10-06 00:00:31.333152 | debian-bookworm -> localhost | The key fingerprint is: 2025-10-06 00:00:31.333171 | debian-bookworm -> localhost | SHA256:kxPbHTipBelaeUclC4KjjSMGBPzZzDHyDo0O2V0986c zuul-build-sshkey 2025-10-06 00:00:31.333198 | debian-bookworm -> localhost | The key's randomart image is: 2025-10-06 00:00:31.333216 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-10-06 00:00:31.333233 | debian-bookworm -> localhost | |=. .+.. ... | 2025-10-06 00:00:31.333251 | debian-bookworm -> localhost | |.. . oo..* +.o | 2025-10-06 00:00:31.333268 | debian-bookworm -> localhost | | .+ @++o..O.o | 2025-10-06 00:00:31.333284 | debian-bookworm -> localhost | | oo*+B. +B.+.o | 2025-10-06 00:00:31.333299 | debian-bookworm -> localhost | | .o.o. oS...+ | 2025-10-06 00:00:31.333321 | debian-bookworm -> localhost | | . .. o E | 2025-10-06 00:00:31.333337 | debian-bookworm -> localhost | | | 2025-10-06 00:00:31.333353 | debian-bookworm -> localhost | | | 2025-10-06 00:00:31.333369 | debian-bookworm -> localhost | | | 2025-10-06 00:00:31.333386 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-10-06 00:00:31.333429 | debian-bookworm -> localhost | ok: Runtime: 0:00:02.792025 2025-10-06 00:00:31.339134 | 2025-10-06 00:00:31.339212 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-10-06 00:00:31.401957 | debian-bookworm | ok 2025-10-06 00:00:31.409700 | debian-bookworm | included: /var/lib/zuul/builds/aa23a0912a4b40019561f08b01511769/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-10-06 00:00:31.427962 | 2025-10-06 00:00:31.428051 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-10-06 00:00:31.464402 | debian-bookworm | skipping: Conditional result was False 2025-10-06 00:00:31.469194 | 2025-10-06 00:00:31.469273 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-10-06 00:00:32.105304 | debian-bookworm | changed 2025-10-06 00:00:32.109751 | 2025-10-06 00:00:32.109834 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-10-06 00:00:32.387132 | debian-bookworm | ok 2025-10-06 00:00:32.392232 | 2025-10-06 00:00:32.392310 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-10-06 00:00:33.106101 | debian-bookworm | changed 2025-10-06 00:00:33.111461 | 2025-10-06 00:00:33.111552 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-10-06 00:00:33.841199 | debian-bookworm | changed 2025-10-06 00:00:33.848170 | 2025-10-06 00:00:33.848271 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-10-06 00:00:33.895411 | debian-bookworm | skipping: Conditional result was False 2025-10-06 00:00:33.901368 | 2025-10-06 00:00:33.901464 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-10-06 00:00:35.052859 | debian-bookworm -> localhost | changed 2025-10-06 00:00:35.063120 | 2025-10-06 00:00:35.088214 | TASK [add-build-sshkey : Add back temp key] 2025-10-06 00:00:35.687616 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/aa23a0912a4b40019561f08b01511769/work/aa23a0912a4b40019561f08b01511769_id_rsa (zuul-build-sshkey) 2025-10-06 00:00:35.687820 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.030337 2025-10-06 00:00:35.695383 | 2025-10-06 00:00:35.695475 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-10-06 00:00:36.313270 | debian-bookworm | ok 2025-10-06 00:00:36.317354 | 2025-10-06 00:00:36.317427 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-10-06 00:00:36.361151 | debian-bookworm | skipping: Conditional result was False 2025-10-06 00:00:36.369875 | 2025-10-06 00:00:36.369964 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-10-06 00:00:37.018304 | debian-bookworm | ok 2025-10-06 00:00:37.034171 | 2025-10-06 00:00:37.034261 | TASK [validate-host : Define zuul_info_dir fact] 2025-10-06 00:00:37.091386 | debian-bookworm | ok 2025-10-06 00:00:37.095593 | 2025-10-06 00:00:37.095671 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-10-06 00:00:37.759015 | debian-bookworm -> localhost | ok 2025-10-06 00:00:37.768643 | 2025-10-06 00:00:37.772431 | TASK [validate-host : Collect information about the host] 2025-10-06 00:00:38.945951 | debian-bookworm | ok 2025-10-06 00:00:38.958172 | 2025-10-06 00:00:38.958257 | TASK [validate-host : Sanitize hostname] 2025-10-06 00:00:39.046326 | debian-bookworm | ok 2025-10-06 00:00:39.106016 | 2025-10-06 00:00:39.106111 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-10-06 00:00:39.571070 | debian-bookworm -> localhost | changed 2025-10-06 00:00:39.575796 | 2025-10-06 00:00:39.575880 | TASK [validate-host : Collect information about zuul worker] 2025-10-06 00:00:40.118097 | debian-bookworm | ok 2025-10-06 00:00:40.123181 | 2025-10-06 00:00:40.123265 | TASK [validate-host : Write out all zuul information for each host] 2025-10-06 00:00:40.738805 | debian-bookworm -> localhost | changed 2025-10-06 00:00:40.746621 | 2025-10-06 00:00:40.746707 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-10-06 00:00:41.035283 | debian-bookworm | ok 2025-10-06 00:00:41.039631 | 2025-10-06 00:00:41.039710 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-10-06 00:00:42.061465 | debian-bookworm | changed: 2025-10-06 00:00:42.061661 | debian-bookworm | cd+++++++++ src/ 2025-10-06 00:00:42.061697 | debian-bookworm | cd+++++++++ src/github.com/ 2025-10-06 00:00:42.061722 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-10-06 00:00:42.061744 | debian-bookworm | cd+++++++++ src/github.com/osism/container-image-kolla-ansible/ 2025-10-06 00:00:42.061764 | debian-bookworm | Containerfile 2025-10-06 00:00:42.061894 | debian-bookworm | 2025-10-06 00:17:03.621871 | debian-bookworm | ERROR 2025-10-06 00:17:03.622325 | debian-bookworm | { 2025-10-06 00:17:03.622431 | debian-bookworm | "delta": "0:00:20.825539", 2025-10-06 00:17:03.622536 | debian-bookworm | "end": "2025-10-06 00:17:03.180982", 2025-10-06 00:17:03.622821 | debian-bookworm | "msg": "non-zero return code", 2025-10-06 00:17:03.622931 | debian-bookworm | "rc": 1, 2025-10-06 00:17:03.622991 | debian-bookworm | "start": "2025-10-06 00:16:42.355443" 2025-10-06 00:17:03.623046 | debian-bookworm | } failure 2025-10-06 00:17:03.631376 | 2025-10-06 00:17:03.631529 | PLAY RECAP 2025-10-06 00:17:03.631631 | debian-bookworm | ok: 4 changed: 3 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2025-10-06 00:17:03.631685 | 2025-10-06 00:17:03.783182 | RUN END RESULT_NORMAL: [untrusted : github.com/osism/container-image-kolla-ansible/playbooks/build.yml@main] 2025-10-06 00:17:03.785923 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-10-06 00:17:04.511646 | 2025-10-06 00:17:04.511793 | PLAY [Base post] 2025-10-06 00:17:04.525156 | 2025-10-06 00:17:04.525281 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-10-06 00:17:05.373599 | debian-bookworm | changed 2025-10-06 00:17:05.382102 | 2025-10-06 00:17:05.382225 | PLAY RECAP 2025-10-06 00:17:05.382297 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-10-06 00:17:05.382371 | 2025-10-06 00:17:05.497596 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-10-06 00:17:05.498644 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2025-10-06 00:17:06.284068 | 2025-10-06 00:17:06.284241 | PLAY [Base post-logs] 2025-10-06 00:17:06.295432 | 2025-10-06 00:17:06.295588 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-10-06 00:17:06.771820 | localhost | changed 2025-10-06 00:17:06.788574 | 2025-10-06 00:17:06.788764 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-10-06 00:17:06.815271 | localhost | ok 2025-10-06 00:17:06.818530 | 2025-10-06 00:17:06.818629 | TASK [Set zuul-log-path fact] 2025-10-06 00:17:06.833216 | localhost | ok 2025-10-06 00:17:06.841127 | 2025-10-06 00:17:06.841231 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-06 00:17:06.865942 | localhost | ok 2025-10-06 00:17:06.869996 | 2025-10-06 00:17:06.870159 | TASK [upload-logs : Create log directories] 2025-10-06 00:17:07.358083 | localhost | changed 2025-10-06 00:17:07.361006 | 2025-10-06 00:17:07.361121 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-10-06 00:17:07.835094 | localhost -> localhost | ok: Runtime: 0:00:00.006708 2025-10-06 00:17:07.844058 | 2025-10-06 00:17:07.844250 | TASK [upload-logs : Upload logs to log server] 2025-10-06 00:17:08.377509 | localhost | Output suppressed because no_log was given 2025-10-06 00:17:08.382395 | 2025-10-06 00:17:08.382633 | LOOP [upload-logs : Compress console log and json output] 2025-10-06 00:17:08.431838 | localhost | skipping: Conditional result was False 2025-10-06 00:17:08.436722 | localhost | skipping: Conditional result was False 2025-10-06 00:17:08.448769 | 2025-10-06 00:17:08.448983 | LOOP [upload-logs : Upload compressed console log and json output] 2025-10-06 00:17:08.494206 | localhost | skipping: Conditional result was False 2025-10-06 00:17:08.494795 | 2025-10-06 00:17:08.498279 | localhost | skipping: Conditional result was False 2025-10-06 00:17:08.511922 | 2025-10-06 00:17:08.512157 | LOOP [upload-logs : Upload console log and json output]