2025-09-09 00:00:08.291396 | Job console starting 2025-09-09 00:00:08.320749 | Updating git repos 2025-09-09 00:00:08.346478 | Cloning repos into workspace 2025-09-09 00:00:08.393253 | Restoring repo states 2025-09-09 00:00:08.411803 | Merging changes 2025-09-09 00:00:08.411817 | Checking out repos 2025-09-09 00:00:08.537559 | Preparing playbooks 2025-09-09 00:00:09.224177 | Running Ansible setup 2025-09-09 00:00:14.524691 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-09-09 00:00:16.303158 | 2025-09-09 00:00:16.303294 | PLAY [Base pre] 2025-09-09 00:00:16.327131 | 2025-09-09 00:00:16.327520 | TASK [Setup log path fact] 2025-09-09 00:00:16.356869 | debian-bookworm | ok 2025-09-09 00:00:16.384882 | 2025-09-09 00:00:16.385053 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-09-09 00:00:16.428338 | debian-bookworm | ok 2025-09-09 00:00:16.440818 | 2025-09-09 00:00:16.440931 | TASK [emit-job-header : Print job information] 2025-09-09 00:00:16.551636 | # Job Information 2025-09-09 00:00:16.551798 | Ansible Version: 2.16.14 2025-09-09 00:00:16.551833 | Job: container-image-kolla-ansible-push-2024.1 2025-09-09 00:00:16.551866 | Pipeline: periodic-midnight 2025-09-09 00:00:16.551889 | Executor: 521e9411259a 2025-09-09 00:00:16.551909 | Triggered by: https://github.com/osism/container-image-kolla-ansible 2025-09-09 00:00:16.551931 | Event ID: 7641473b89084f39929d02d94729e2ef 2025-09-09 00:00:16.558782 | 2025-09-09 00:00:16.560837 | LOOP [emit-job-header : Print node information] 2025-09-09 00:00:16.765230 | debian-bookworm | ok: 2025-09-09 00:00:16.765400 | debian-bookworm | # Node Information 2025-09-09 00:00:16.765435 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-09-09 00:00:16.765459 | debian-bookworm | Hostname: debian 2025-09-09 00:00:16.765480 | debian-bookworm | Username: zuul 2025-09-09 00:00:16.765501 | debian-bookworm | Distro: Debian 12.12 2025-09-09 00:00:16.765538 | debian-bookworm | Provider: regiocloud-a 2025-09-09 00:00:16.765560 | debian-bookworm | Region: 2025-09-09 00:00:16.765581 | debian-bookworm | Label: debian-bookworm 2025-09-09 00:00:16.765608 | debian-bookworm | Product Name: OpenStack Nova 2025-09-09 00:00:16.765634 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe7d:6a8d 2025-09-09 00:00:16.783133 | 2025-09-09 00:00:16.783362 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-09-09 00:00:18.029898 | debian-bookworm -> localhost | changed 2025-09-09 00:00:18.038483 | 2025-09-09 00:00:18.038593 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-09-09 00:00:21.399791 | debian-bookworm -> localhost | changed 2025-09-09 00:00:21.408558 | 2025-09-09 00:00:21.408643 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-09-09 00:00:22.317798 | debian-bookworm -> localhost | ok 2025-09-09 00:00:22.322685 | 2025-09-09 00:00:22.322772 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-09-09 00:00:22.342686 | debian-bookworm | ok 2025-09-09 00:00:22.393504 | debian-bookworm | included: /var/lib/zuul/builds/404f3863a82b467d9bdc988278e81cd9/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-09-09 00:00:22.408838 | 2025-09-09 00:00:22.408931 | TASK [add-build-sshkey : Create Temp SSH key] 2025-09-09 00:00:24.451735 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-09-09 00:00:24.451904 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/404f3863a82b467d9bdc988278e81cd9/work/404f3863a82b467d9bdc988278e81cd9_id_rsa 2025-09-09 00:00:24.451936 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/404f3863a82b467d9bdc988278e81cd9/work/404f3863a82b467d9bdc988278e81cd9_id_rsa.pub 2025-09-09 00:00:24.451959 | debian-bookworm -> localhost | The key fingerprint is: 2025-09-09 00:00:24.451979 | debian-bookworm -> localhost | SHA256:IKZfS8gF5UpM0yxDsYmeLujv8lfwOK+sipJP97jdUSc zuul-build-sshkey 2025-09-09 00:00:24.452007 | debian-bookworm -> localhost | The key's randomart image is: 2025-09-09 00:00:24.452027 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-09-09 00:00:24.452045 | debian-bookworm -> localhost | | .*=. | 2025-09-09 00:00:24.452065 | debian-bookworm -> localhost | | +o*o | 2025-09-09 00:00:24.452097 | debian-bookworm -> localhost | | . Bo+ | 2025-09-09 00:00:24.452118 | debian-bookworm -> localhost | | . * * . | 2025-09-09 00:00:24.452136 | debian-bookworm -> localhost | | + + * S E . | 2025-09-09 00:00:24.452158 | debian-bookworm -> localhost | |.. . = + . o | 2025-09-09 00:00:24.452177 | debian-bookworm -> localhost | |o.o o = . | 2025-09-09 00:00:24.452194 | debian-bookworm -> localhost | |=+ ..= o . | 2025-09-09 00:00:24.452214 | debian-bookworm -> localhost | |o+B==++ . | 2025-09-09 00:00:24.452233 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-09-09 00:00:24.452275 | debian-bookworm -> localhost | ok: Runtime: 0:00:01.122877 2025-09-09 00:00:24.457975 | 2025-09-09 00:00:24.458059 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-09-09 00:00:24.494447 | debian-bookworm | ok 2025-09-09 00:00:24.501815 | debian-bookworm | included: /var/lib/zuul/builds/404f3863a82b467d9bdc988278e81cd9/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-09-09 00:00:24.519934 | 2025-09-09 00:00:24.520016 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-09-09 00:00:24.572793 | debian-bookworm | skipping: Conditional result was False 2025-09-09 00:00:24.577672 | 2025-09-09 00:00:24.577765 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-09-09 00:00:25.576922 | debian-bookworm | changed 2025-09-09 00:00:25.581261 | 2025-09-09 00:00:25.581342 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-09-09 00:00:25.904882 | debian-bookworm | ok 2025-09-09 00:00:25.910480 | 2025-09-09 00:00:25.910560 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-09-09 00:00:26.683154 | debian-bookworm | changed 2025-09-09 00:00:26.688486 | 2025-09-09 00:00:26.688573 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-09-09 00:00:27.435938 | debian-bookworm | changed 2025-09-09 00:00:27.440308 | 2025-09-09 00:00:27.440383 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-09-09 00:00:27.491600 | debian-bookworm | skipping: Conditional result was False 2025-09-09 00:00:27.496602 | 2025-09-09 00:00:27.496680 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-09-09 00:00:28.495388 | debian-bookworm -> localhost | changed 2025-09-09 00:00:28.503941 | 2025-09-09 00:00:28.504033 | TASK [add-build-sshkey : Add back temp key] 2025-09-09 00:00:29.314590 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/404f3863a82b467d9bdc988278e81cd9/work/404f3863a82b467d9bdc988278e81cd9_id_rsa (zuul-build-sshkey) 2025-09-09 00:00:29.314765 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.021031 2025-09-09 00:00:29.321140 | 2025-09-09 00:00:29.321228 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-09-09 00:00:29.931873 | debian-bookworm | ok 2025-09-09 00:00:29.935889 | 2025-09-09 00:00:29.935964 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-09-09 00:00:29.983434 | debian-bookworm | skipping: Conditional result was False 2025-09-09 00:00:29.993318 | 2025-09-09 00:00:29.993409 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-09-09 00:00:30.574819 | debian-bookworm | ok 2025-09-09 00:00:30.592848 | 2025-09-09 00:00:30.592947 | TASK [validate-host : Define zuul_info_dir fact] 2025-09-09 00:00:30.629754 | debian-bookworm | ok 2025-09-09 00:00:30.634467 | 2025-09-09 00:00:30.634547 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-09-09 00:00:31.144207 | debian-bookworm -> localhost | ok 2025-09-09 00:00:31.149579 | 2025-09-09 00:00:31.149692 | TASK [validate-host : Collect information about the host] 2025-09-09 00:00:32.624532 | debian-bookworm | ok 2025-09-09 00:00:32.635386 | 2025-09-09 00:00:32.635470 | TASK [validate-host : Sanitize hostname] 2025-09-09 00:00:32.893729 | debian-bookworm | ok 2025-09-09 00:00:32.977912 | 2025-09-09 00:00:32.978016 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-09-09 00:00:34.299587 | debian-bookworm -> localhost | changed 2025-09-09 00:00:34.304080 | 2025-09-09 00:00:34.304181 | TASK [validate-host : Collect information about zuul worker] 2025-09-09 00:00:34.946593 | debian-bookworm | ok 2025-09-09 00:00:34.951944 | 2025-09-09 00:00:34.952030 | TASK [validate-host : Write out all zuul information for each host] 2025-09-09 00:00:35.996637 | debian-bookworm -> localhost | changed 2025-09-09 00:00:36.004388 | 2025-09-09 00:00:36.004475 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-09-09 00:00:36.322068 | debian-bookworm | ok 2025-09-09 00:00:36.326513 | 2025-09-09 00:00:36.326593 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-09-09 00:00:37.735322 | debian-bookworm | changed: 2025-09-09 00:00:37.735488 | debian-bookworm | cd+++++++++ src/ 2025-09-09 00:00:37.735521 | debian-bookworm | cd+++++++++ src/github.com/ 2025-09-09 00:00:37.735545 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-09-09 00:00:37.735566 | debian-bookworm | cd+++++++++ src/github.com/osism/container-image-kolla-ansible/ 2025-09-09 00:00:37.735586 | debian-bookworm | Containerfile 2025-09-09 00:00:37.735717 | debian-bookworm | 2025-09-09 00:14:03.034820 | debian-bookworm | ERROR 2025-09-09 00:14:03.035158 | debian-bookworm | { 2025-09-09 00:14:03.035363 | debian-bookworm | "delta": "0:00:19.750504", 2025-09-09 00:14:03.035408 | debian-bookworm | "end": "2025-09-09 00:14:02.976243", 2025-09-09 00:14:03.035452 | debian-bookworm | "msg": "non-zero return code", 2025-09-09 00:14:03.035489 | debian-bookworm | "rc": 1, 2025-09-09 00:14:03.035522 | debian-bookworm | "start": "2025-09-09 00:13:43.225739" 2025-09-09 00:14:03.035576 | debian-bookworm | } failure 2025-09-09 00:14:03.037743 | 2025-09-09 00:14:03.037837 | PLAY RECAP 2025-09-09 00:14:03.037915 | debian-bookworm | ok: 4 changed: 3 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2025-09-09 00:14:03.037955 | 2025-09-09 00:14:03.170125 | RUN END RESULT_NORMAL: [untrusted : github.com/osism/container-image-kolla-ansible/playbooks/build.yml@main] 2025-09-09 00:14:03.172828 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-09-09 00:14:03.907595 | 2025-09-09 00:14:03.907757 | PLAY [Base post] 2025-09-09 00:14:03.921786 | 2025-09-09 00:14:03.921922 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-09-09 00:14:04.760818 | debian-bookworm | changed 2025-09-09 00:14:04.769909 | 2025-09-09 00:14:04.770032 | PLAY RECAP 2025-09-09 00:14:04.770107 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-09-09 00:14:04.770267 | 2025-09-09 00:14:04.885483 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-09-09 00:14:04.888810 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2025-09-09 00:14:05.687417 | 2025-09-09 00:14:05.687580 | PLAY [Base post-logs] 2025-09-09 00:14:05.699693 | 2025-09-09 00:14:05.699836 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-09-09 00:14:06.154265 | localhost | changed 2025-09-09 00:14:06.172294 | 2025-09-09 00:14:06.172460 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-09-09 00:14:06.201509 | localhost | ok 2025-09-09 00:14:06.209965 | 2025-09-09 00:14:06.210235 | TASK [Set zuul-log-path fact] 2025-09-09 00:14:06.240113 | localhost | ok 2025-09-09 00:14:06.255507 | 2025-09-09 00:14:06.255652 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-09-09 00:14:06.292274 | localhost | ok 2025-09-09 00:14:06.300500 | 2025-09-09 00:14:06.300716 | TASK [upload-logs : Create log directories] 2025-09-09 00:14:06.809927 | localhost | changed 2025-09-09 00:14:06.814957 | 2025-09-09 00:14:06.815118 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-09-09 00:14:07.309395 | localhost -> localhost | ok: Runtime: 0:00:00.006998 2025-09-09 00:14:07.318636 | 2025-09-09 00:14:07.318813 | TASK [upload-logs : Upload logs to log server] 2025-09-09 00:14:07.874983 | localhost | Output suppressed because no_log was given 2025-09-09 00:14:07.878997 | 2025-09-09 00:14:07.879198 | LOOP [upload-logs : Compress console log and json output] 2025-09-09 00:14:07.935825 | localhost | skipping: Conditional result was False 2025-09-09 00:14:07.940818 | localhost | skipping: Conditional result was False 2025-09-09 00:14:07.952587 | 2025-09-09 00:14:07.952829 | LOOP [upload-logs : Upload compressed console log and json output] 2025-09-09 00:14:07.999997 | localhost | skipping: Conditional result was False 2025-09-09 00:14:08.000694 | 2025-09-09 00:14:08.004127 | localhost | skipping: Conditional result was False 2025-09-09 00:14:08.018664 | 2025-09-09 00:14:08.018943 | LOOP [upload-logs : Upload console log and json output]