2025-10-01 00:00:05.344793 | Job console starting 2025-10-01 00:00:05.376371 | Updating git repos 2025-10-01 00:00:05.577579 | Cloning repos into workspace 2025-10-01 00:00:05.672994 | Restoring repo states 2025-10-01 00:00:05.685371 | Merging changes 2025-10-01 00:00:05.685388 | Checking out repos 2025-10-01 00:00:05.789402 | Preparing playbooks 2025-10-01 00:00:06.576287 | Running Ansible setup 2025-10-01 00:00:10.752014 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-10-01 00:00:11.819663 | 2025-10-01 00:00:11.819854 | PLAY [Base pre] 2025-10-01 00:00:11.861443 | 2025-10-01 00:00:11.861570 | TASK [Setup log path fact] 2025-10-01 00:00:11.902564 | debian-bookworm | ok 2025-10-01 00:00:11.963209 | 2025-10-01 00:00:11.963374 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-01 00:00:11.993654 | debian-bookworm | ok 2025-10-01 00:00:12.009803 | 2025-10-01 00:00:12.009913 | TASK [emit-job-header : Print job information] 2025-10-01 00:00:12.096829 | # Job Information 2025-10-01 00:00:12.096997 | Ansible Version: 2.16.14 2025-10-01 00:00:12.097031 | Job: container-image-kolla-ansible-push-2024.1 2025-10-01 00:00:12.097064 | Pipeline: periodic-midnight 2025-10-01 00:00:12.097086 | Executor: 521e9411259a 2025-10-01 00:00:12.097106 | Triggered by: https://github.com/osism/container-image-kolla-ansible 2025-10-01 00:00:12.097128 | Event ID: 56bec85a7bbf4a999816b31e91898c0b 2025-10-01 00:00:12.104469 | 2025-10-01 00:00:12.104580 | LOOP [emit-job-header : Print node information] 2025-10-01 00:00:12.312281 | debian-bookworm | ok: 2025-10-01 00:00:12.312436 | debian-bookworm | # Node Information 2025-10-01 00:00:12.312470 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-10-01 00:00:12.312495 | debian-bookworm | Hostname: debian 2025-10-01 00:00:12.312516 | debian-bookworm | Username: zuul 2025-10-01 00:00:12.312536 | debian-bookworm | Distro: Debian 12.12 2025-10-01 00:00:12.312559 | debian-bookworm | Provider: regiocloud-a 2025-10-01 00:00:12.312580 | debian-bookworm | Region: 2025-10-01 00:00:12.312601 | debian-bookworm | Label: debian-bookworm 2025-10-01 00:00:12.312620 | debian-bookworm | Product Name: OpenStack Nova 2025-10-01 00:00:12.312639 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe67:4e00 2025-10-01 00:00:12.334208 | 2025-10-01 00:00:12.334325 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-10-01 00:00:13.357467 | debian-bookworm -> localhost | changed 2025-10-01 00:00:13.363282 | 2025-10-01 00:00:13.366067 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-10-01 00:00:16.048071 | debian-bookworm -> localhost | changed 2025-10-01 00:00:16.056965 | 2025-10-01 00:00:16.057055 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-10-01 00:00:16.728665 | debian-bookworm -> localhost | ok 2025-10-01 00:00:16.733661 | 2025-10-01 00:00:16.733755 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-10-01 00:00:16.760507 | debian-bookworm | ok 2025-10-01 00:00:16.799707 | debian-bookworm | included: /var/lib/zuul/builds/b324bba8faa74a7ea145a806d90365dd/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-10-01 00:00:16.823964 | 2025-10-01 00:00:16.824059 | TASK [add-build-sshkey : Create Temp SSH key] 2025-10-01 00:00:20.241023 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-10-01 00:00:20.241947 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/b324bba8faa74a7ea145a806d90365dd/work/b324bba8faa74a7ea145a806d90365dd_id_rsa 2025-10-01 00:00:20.242006 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/b324bba8faa74a7ea145a806d90365dd/work/b324bba8faa74a7ea145a806d90365dd_id_rsa.pub 2025-10-01 00:00:20.242030 | debian-bookworm -> localhost | The key fingerprint is: 2025-10-01 00:00:20.242050 | debian-bookworm -> localhost | SHA256:R8L5kGI8BZy78rYbeK/sP3ZMo56sIakLwtqxNJadYws zuul-build-sshkey 2025-10-01 00:00:20.242075 | debian-bookworm -> localhost | The key's randomart image is: 2025-10-01 00:00:20.242093 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-10-01 00:00:20.242110 | debian-bookworm -> localhost | | ..o. | 2025-10-01 00:00:20.242127 | debian-bookworm -> localhost | | .oo o | 2025-10-01 00:00:20.242144 | debian-bookworm -> localhost | | =.* . | 2025-10-01 00:00:20.242160 | debian-bookworm -> localhost | | ..o = | 2025-10-01 00:00:20.242175 | debian-bookworm -> localhost | | .S o | 2025-10-01 00:00:20.242198 | debian-bookworm -> localhost | |. o.+. .o | 2025-10-01 00:00:20.242214 | debian-bookworm -> localhost | |o.E Oo+ + . | 2025-10-01 00:00:20.242230 | debian-bookworm -> localhost | |o= B *o=+.o | 2025-10-01 00:00:20.242247 | debian-bookworm -> localhost | |. =..oBO*o | 2025-10-01 00:00:20.242263 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-10-01 00:00:20.242309 | debian-bookworm -> localhost | ok: Runtime: 0:00:02.437260 2025-10-01 00:00:20.247459 | 2025-10-01 00:00:20.247532 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-10-01 00:00:20.305329 | debian-bookworm | ok 2025-10-01 00:00:20.328532 | debian-bookworm | included: /var/lib/zuul/builds/b324bba8faa74a7ea145a806d90365dd/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-10-01 00:00:20.352380 | 2025-10-01 00:00:20.352476 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-10-01 00:00:20.385312 | debian-bookworm | skipping: Conditional result was False 2025-10-01 00:00:20.390754 | 2025-10-01 00:00:20.390925 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-10-01 00:00:21.408653 | debian-bookworm | changed 2025-10-01 00:00:21.412950 | 2025-10-01 00:00:21.413022 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-10-01 00:00:21.680656 | debian-bookworm | ok 2025-10-01 00:00:21.685200 | 2025-10-01 00:00:21.685278 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-10-01 00:00:22.428973 | debian-bookworm | changed 2025-10-01 00:00:22.434362 | 2025-10-01 00:00:22.434445 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-10-01 00:00:23.188128 | debian-bookworm | changed 2025-10-01 00:00:23.193309 | 2025-10-01 00:00:23.193389 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-10-01 00:00:23.232069 | debian-bookworm | skipping: Conditional result was False 2025-10-01 00:00:23.236997 | 2025-10-01 00:00:23.237082 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-10-01 00:00:24.101603 | debian-bookworm -> localhost | changed 2025-10-01 00:00:24.117461 | 2025-10-01 00:00:24.117557 | TASK [add-build-sshkey : Add back temp key] 2025-10-01 00:00:25.070197 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/b324bba8faa74a7ea145a806d90365dd/work/b324bba8faa74a7ea145a806d90365dd_id_rsa (zuul-build-sshkey) 2025-10-01 00:00:25.070394 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.022691 2025-10-01 00:00:25.076568 | 2025-10-01 00:00:25.076649 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-10-01 00:00:25.799355 | debian-bookworm | ok 2025-10-01 00:00:25.803733 | 2025-10-01 00:00:25.803826 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-10-01 00:00:25.848086 | debian-bookworm | skipping: Conditional result was False 2025-10-01 00:00:25.857439 | 2025-10-01 00:00:25.857541 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-10-01 00:00:26.443373 | debian-bookworm | ok 2025-10-01 00:00:26.466465 | 2025-10-01 00:00:26.466570 | TASK [validate-host : Define zuul_info_dir fact] 2025-10-01 00:00:26.523932 | debian-bookworm | ok 2025-10-01 00:00:26.528227 | 2025-10-01 00:00:26.528309 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-10-01 00:00:27.201643 | debian-bookworm -> localhost | ok 2025-10-01 00:00:27.207059 | 2025-10-01 00:00:27.207157 | TASK [validate-host : Collect information about the host] 2025-10-01 00:00:28.729356 | debian-bookworm | ok 2025-10-01 00:00:28.751402 | 2025-10-01 00:00:28.751500 | TASK [validate-host : Sanitize hostname] 2025-10-01 00:00:28.937017 | debian-bookworm | ok 2025-10-01 00:00:29.039080 | 2025-10-01 00:00:29.039171 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-10-01 00:00:30.267377 | debian-bookworm -> localhost | changed 2025-10-01 00:00:30.272056 | 2025-10-01 00:00:30.272135 | TASK [validate-host : Collect information about zuul worker] 2025-10-01 00:00:30.777266 | debian-bookworm | ok 2025-10-01 00:00:30.781964 | 2025-10-01 00:00:30.782053 | TASK [validate-host : Write out all zuul information for each host] 2025-10-01 00:00:31.860064 | debian-bookworm -> localhost | changed 2025-10-01 00:00:31.869205 | 2025-10-01 00:00:31.869299 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-10-01 00:00:32.188435 | debian-bookworm | ok 2025-10-01 00:00:32.199491 | 2025-10-01 00:00:32.199570 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-10-01 00:00:33.761280 | debian-bookworm | changed: 2025-10-01 00:00:33.761445 | debian-bookworm | cd+++++++++ src/ 2025-10-01 00:00:33.761479 | debian-bookworm | cd+++++++++ src/github.com/ 2025-10-01 00:00:33.761503 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-10-01 00:00:33.761525 | debian-bookworm | cd+++++++++ src/github.com/osism/container-image-kolla-ansible/ 2025-10-01 00:00:33.761545 | debian-bookworm | Containerfile 2025-10-01 00:00:33.761671 | debian-bookworm | 2025-10-01 00:11:45.514423 | debian-bookworm | ERROR 2025-10-01 00:11:45.514998 | debian-bookworm | { 2025-10-01 00:11:45.515117 | debian-bookworm | "delta": "0:00:20.508116", 2025-10-01 00:11:45.515189 | debian-bookworm | "end": "2025-10-01 00:11:45.275275", 2025-10-01 00:11:45.515289 | debian-bookworm | "msg": "non-zero return code", 2025-10-01 00:11:45.515348 | debian-bookworm | "rc": 1, 2025-10-01 00:11:45.515400 | debian-bookworm | "start": "2025-10-01 00:11:24.767159" 2025-10-01 00:11:45.515450 | debian-bookworm | } failure 2025-10-01 00:11:45.519487 | 2025-10-01 00:11:45.519640 | PLAY RECAP 2025-10-01 00:11:45.519760 | debian-bookworm | ok: 4 changed: 3 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2025-10-01 00:11:45.519865 | 2025-10-01 00:11:45.655116 | RUN END RESULT_NORMAL: [untrusted : github.com/osism/container-image-kolla-ansible/playbooks/build.yml@main] 2025-10-01 00:11:45.657837 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-10-01 00:11:46.401618 | 2025-10-01 00:11:46.401764 | PLAY [Base post] 2025-10-01 00:11:46.415764 | 2025-10-01 00:11:46.415910 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-10-01 00:11:47.266023 | debian-bookworm | changed 2025-10-01 00:11:47.272395 | 2025-10-01 00:11:47.272502 | PLAY RECAP 2025-10-01 00:11:47.272566 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-10-01 00:11:47.272630 | 2025-10-01 00:11:47.383897 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-10-01 00:11:47.386407 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2025-10-01 00:11:48.181886 | 2025-10-01 00:11:48.182055 | PLAY [Base post-logs] 2025-10-01 00:11:48.193052 | 2025-10-01 00:11:48.193193 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-10-01 00:11:48.649340 | localhost | changed 2025-10-01 00:11:48.666357 | 2025-10-01 00:11:48.666551 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-10-01 00:11:48.695014 | localhost | ok 2025-10-01 00:11:48.702440 | 2025-10-01 00:11:48.702611 | TASK [Set zuul-log-path fact] 2025-10-01 00:11:48.720400 | localhost | ok 2025-10-01 00:11:48.733640 | 2025-10-01 00:11:48.733791 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-01 00:11:48.770705 | localhost | ok 2025-10-01 00:11:48.776735 | 2025-10-01 00:11:48.776933 | TASK [upload-logs : Create log directories] 2025-10-01 00:11:49.317489 | localhost | changed 2025-10-01 00:11:49.322606 | 2025-10-01 00:11:49.322762 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-10-01 00:11:49.825183 | localhost -> localhost | ok: Runtime: 0:00:00.006782 2025-10-01 00:11:49.829726 | 2025-10-01 00:11:49.829870 | TASK [upload-logs : Upload logs to log server] 2025-10-01 00:11:50.426539 | localhost | Output suppressed because no_log was given 2025-10-01 00:11:50.428942 | 2025-10-01 00:11:50.429066 | LOOP [upload-logs : Compress console log and json output] 2025-10-01 00:11:50.520531 | localhost | skipping: Conditional result was False 2025-10-01 00:11:50.527644 | localhost | skipping: Conditional result was False 2025-10-01 00:11:50.533691 | 2025-10-01 00:11:50.533833 | LOOP [upload-logs : Upload compressed console log and json output] 2025-10-01 00:11:50.577623 | localhost | skipping: Conditional result was False 2025-10-01 00:11:50.577940 | 2025-10-01 00:11:50.587218 | localhost | skipping: Conditional result was False 2025-10-01 00:11:50.595411 | 2025-10-01 00:11:50.595621 | LOOP [upload-logs : Upload console log and json output]