2026-10-03 10:01:04.320290 | Job console starting 2026-10-03 10:01:04.336239 | Updating git repos 2026-10-03 10:01:04.376874 | Cloning repos into workspace 2026-10-03 10:01:04.469840 | Restoring repo states 2026-10-03 10:01:04.484353 | Merging changes 2026-10-03 10:01:05.055771 | Checking out repos 2026-10-03 10:01:05.125393 | Preparing playbooks 2026-10-03 10:01:06.343367 | Running Ansible setup 2026-10-03 10:01:10.898160 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-10-03 10:01:11.708277 | 2026-10-03 10:01:11.708398 | PLAY [Base pre] 2026-10-03 10:01:11.724009 | 2026-10-03 10:01:11.724110 | TASK [Setup log path fact] 2026-10-03 10:01:11.762270 | debian-bookworm | ok 2026-10-03 10:01:11.779076 | 2026-10-03 10:01:11.779191 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-10-03 10:01:11.816582 | debian-bookworm | ok 2026-10-03 10:01:11.824562 | 2026-10-03 10:01:11.824662 | TASK [emit-job-header : Print job information] 2026-10-03 10:01:11.851691 | # Job Information 2026-10-03 10:01:11.851822 | Ansible Version: 2.16.14 2026-10-03 10:01:11.851849 | Job: container-image-cfg-cookiecutter-build 2026-10-03 10:01:11.851876 | Pipeline: check 2026-10-03 10:01:11.851894 | Executor: 521e9411259a 2026-10-03 10:01:11.851912 | Triggered by: https://github.com/osism/cfg-cookiecutter/pull/884 2026-10-03 10:01:11.851929 | Event ID: 30489b50-bf11-11f1-95f2-5f8d9e700a89 2026-10-03 10:01:11.855876 | 2026-10-03 10:01:11.855951 | LOOP [emit-job-header : Print node information] 2026-10-03 10:01:11.968015 | debian-bookworm | ok: 2026-10-03 10:01:11.968202 | debian-bookworm | # Node Information 2026-10-03 10:01:11.968268 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-10-03 10:01:11.968296 | debian-bookworm | Hostname: debian 2026-10-03 10:01:11.968319 | debian-bookworm | Username: zuul 2026-10-03 10:01:11.968340 | debian-bookworm | Distro: Debian 12.15 2026-10-03 10:01:11.968364 | debian-bookworm | Provider: regiocloud-a 2026-10-03 10:01:11.968384 | debian-bookworm | Region: 2026-10-03 10:01:11.968406 | debian-bookworm | Label: debian-bookworm 2026-10-03 10:01:11.968427 | debian-bookworm | Product Name: OpenStack Nova 2026-10-03 10:01:11.968446 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe15:2e7d 2026-10-03 10:01:11.986358 | 2026-10-03 10:01:11.986452 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-10-03 10:01:12.551040 | debian-bookworm -> localhost | changed 2026-10-03 10:01:12.557642 | 2026-10-03 10:01:12.557728 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-10-03 10:01:14.469327 | debian-bookworm -> localhost | changed 2026-10-03 10:01:14.477940 | 2026-10-03 10:01:14.478029 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-10-03 10:01:14.964746 | debian-bookworm -> localhost | ok 2026-10-03 10:01:14.969682 | 2026-10-03 10:01:14.969766 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-10-03 10:01:15.018102 | debian-bookworm | ok 2026-10-03 10:01:15.051332 | debian-bookworm | included: /var/lib/zuul/builds/489dfd2504d34167b6a6897f701c8e30/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-10-03 10:01:15.056186 | 2026-10-03 10:01:15.056265 | TASK [add-build-sshkey : Create Temp SSH key] 2026-10-03 10:01:15.983816 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-10-03 10:01:15.984005 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/489dfd2504d34167b6a6897f701c8e30/work/489dfd2504d34167b6a6897f701c8e30_id_rsa 2026-10-03 10:01:15.984038 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/489dfd2504d34167b6a6897f701c8e30/work/489dfd2504d34167b6a6897f701c8e30_id_rsa.pub 2026-10-03 10:01:15.984059 | debian-bookworm -> localhost | The key fingerprint is: 2026-10-03 10:01:15.984080 | debian-bookworm -> localhost | SHA256:NASNQsgIJ/zjEz0CoKbwc92eNwvgY0+2hWMy8i2ZwNs zuul-build-sshkey 2026-10-03 10:01:15.984107 | debian-bookworm -> localhost | The key's randomart image is: 2026-10-03 10:01:15.984126 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-10-03 10:01:15.984143 | debian-bookworm -> localhost | |Bo.o. .+. | 2026-10-03 10:01:15.984160 | debian-bookworm -> localhost | |o=o . ... | 2026-10-03 10:01:15.984177 | debian-bookworm -> localhost | |o.o .. o | 2026-10-03 10:01:15.984193 | debian-bookworm -> localhost | |+. = + o . | 2026-10-03 10:01:15.984209 | debian-bookworm -> localhost | |. +.= + S | 2026-10-03 10:01:15.984229 | debian-bookworm -> localhost | | =o. o o | 2026-10-03 10:01:15.984245 | debian-bookworm -> localhost | | o+*oX + | 2026-10-03 10:01:15.984260 | debian-bookworm -> localhost | | .+EX * o | 2026-10-03 10:01:15.984276 | debian-bookworm -> localhost | | ..+ . | 2026-10-03 10:01:15.984292 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-10-03 10:01:15.984336 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.286781 2026-10-03 10:01:15.989938 | 2026-10-03 10:01:15.990019 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-10-03 10:01:16.005980 | debian-bookworm | ok 2026-10-03 10:01:16.013939 | debian-bookworm | included: /var/lib/zuul/builds/489dfd2504d34167b6a6897f701c8e30/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-10-03 10:01:16.020464 | 2026-10-03 10:01:16.020532 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-10-03 10:01:16.032915 | debian-bookworm | skipping: Conditional result was False 2026-10-03 10:01:16.037396 | 2026-10-03 10:01:16.037471 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-10-03 10:01:16.726533 | debian-bookworm | changed 2026-10-03 10:01:16.732529 | 2026-10-03 10:01:16.732622 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-10-03 10:01:16.994071 | debian-bookworm | ok 2026-10-03 10:01:16.998757 | 2026-10-03 10:01:16.998845 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-10-03 10:01:17.683946 | debian-bookworm | changed 2026-10-03 10:01:17.688155 | 2026-10-03 10:01:17.688232 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-10-03 10:01:18.533375 | debian-bookworm | changed 2026-10-03 10:01:18.538637 | 2026-10-03 10:01:18.538721 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-10-03 10:01:18.561182 | debian-bookworm | skipping: Conditional result was False 2026-10-03 10:01:18.566036 | 2026-10-03 10:01:18.566115 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-10-03 10:01:19.175852 | debian-bookworm -> localhost | changed 2026-10-03 10:01:19.184414 | 2026-10-03 10:01:19.184500 | TASK [add-build-sshkey : Add back temp key] 2026-10-03 10:01:19.557982 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/489dfd2504d34167b6a6897f701c8e30/work/489dfd2504d34167b6a6897f701c8e30_id_rsa (zuul-build-sshkey) 2026-10-03 10:01:19.558166 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.028426 2026-10-03 10:01:19.564650 | 2026-10-03 10:01:19.564726 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-10-03 10:01:20.030652 | debian-bookworm | ok 2026-10-03 10:01:20.035777 | 2026-10-03 10:01:20.035872 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-10-03 10:01:20.058929 | debian-bookworm | skipping: Conditional result was False 2026-10-03 10:01:20.069147 | 2026-10-03 10:01:20.069248 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-10-03 10:01:20.620864 | debian-bookworm | ok 2026-10-03 10:01:20.637038 | 2026-10-03 10:01:20.637142 | TASK [validate-host : Define zuul_info_dir fact] 2026-10-03 10:01:20.690723 | debian-bookworm | ok 2026-10-03 10:01:20.698142 | 2026-10-03 10:01:20.698260 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-10-03 10:01:20.985145 | debian-bookworm -> localhost | ok 2026-10-03 10:01:20.992100 | 2026-10-03 10:01:20.992192 | TASK [validate-host : Collect information about the host] 2026-10-03 10:01:22.080434 | debian-bookworm | ok 2026-10-03 10:01:22.099148 | 2026-10-03 10:01:22.099252 | TASK [validate-host : Sanitize hostname] 2026-10-03 10:01:22.251195 | debian-bookworm | ok 2026-10-03 10:01:22.261945 | 2026-10-03 10:01:22.262052 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-10-03 10:01:22.861792 | debian-bookworm -> localhost | changed 2026-10-03 10:01:22.869006 | 2026-10-03 10:01:22.869086 | TASK [validate-host : Collect information about zuul worker] 2026-10-03 10:01:23.349960 | debian-bookworm | ok 2026-10-03 10:01:23.356430 | 2026-10-03 10:01:23.356703 | TASK [validate-host : Write out all zuul information for each host] 2026-10-03 10:01:24.224324 | debian-bookworm -> localhost | changed 2026-10-03 10:01:24.233148 | 2026-10-03 10:01:24.233235 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-10-03 10:01:24.539430 | debian-bookworm | ok 2026-10-03 10:01:24.544820 | 2026-10-03 10:01:24.544915 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-10-03 10:01:25.474365 | debian-bookworm | changed: 2026-10-03 10:01:25.474556 | debian-bookworm | cd+++++++++ src/ 2026-10-03 10:01:25.476085 | debian-bookworm | cd+++++++++ src/github.com/ 2026-10-03 10:01:25.476139 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-10-03 10:01:25.476193 | debian-bookworm | cd+++++++++ src/github.com/osism/cfg-cookiecutter/ 2026-10-03 10:01:25.476216 | debian-bookworm | Containerfile 2026-10-03 10:01:25.476350 | debian-bookworm | localhost | ok: "/var/lib/zuul/builds/489dfd2504d34167b6a6897f701c8e30/work/logs" 2026-10-03 10:03:50.649937 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/489dfd2504d34167b6a6897f701c8e30/work/artifacts" 2026-10-03 10:03:50.925984 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/489dfd2504d34167b6a6897f701c8e30/work/docs" 2026-10-03 10:03:50.943173 | 2026-10-03 10:03:50.943314 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-10-03 10:03:51.728364 | debian-bookworm | changed: 2026-10-03 10:03:51.728738 | debian-bookworm | .d..t...... ./ 2026-10-03 10:03:51.728805 | debian-bookworm | >f+++++++++ net-debug.post.txt 2026-10-03 10:03:51.728848 | debian-bookworm | >f+++++++++ net-debug.pre.txt 2026-10-03 10:03:51.728898 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-10-03 10:03:51.728935 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-10-03 10:03:51.728968 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-10-03 10:03:51.729023 | debian-bookworm | changed: All items complete 2026-10-03 10:03:51.729328 | 2026-10-03 10:03:52.317872 | debian-bookworm | changed: .d..t...... ./ 2026-10-03 10:03:52.945756 | debian-bookworm | changed: .d..t...... ./ 2026-10-03 10:03:52.968793 | 2026-10-03 10:03:52.968934 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-10-03 10:03:53.315384 | debian-bookworm -> localhost | ok: Item: artifacts Runtime: 0:00:00.008771 2026-10-03 10:03:53.601023 | debian-bookworm -> localhost | ok: Item: docs Runtime: 0:00:00.008582 2026-10-03 10:03:53.615477 | 2026-10-03 10:03:53.615597 | PLAY RECAP 2026-10-03 10:03:53.615694 | debian-bookworm | ok: 8 changed: 3 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-10-03 10:03:53.615737 | 2026-10-03 10:03:53.748189 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-10-03 10:03:53.750745 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-10-03 10:03:54.462754 | 2026-10-03 10:03:54.462904 | PLAY [Base post] 2026-10-03 10:03:54.476109 | 2026-10-03 10:03:54.476232 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-10-03 10:03:55.335342 | debian-bookworm | changed 2026-10-03 10:03:55.341845 | 2026-10-03 10:03:55.342014 | PLAY RECAP 2026-10-03 10:03:55.342227 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-10-03 10:03:55.342438 | 2026-10-03 10:03:55.462946 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-10-03 10:03:55.465242 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-10-03 10:03:56.279138 | 2026-10-03 10:03:56.279306 | PLAY [Base post-logs] 2026-10-03 10:03:56.291228 | 2026-10-03 10:03:56.291368 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-10-03 10:03:56.773916 | localhost | changed 2026-10-03 10:03:56.784677 | 2026-10-03 10:03:56.784833 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-10-03 10:03:56.810423 | localhost | ok 2026-10-03 10:03:56.813426 | 2026-10-03 10:03:56.813520 | TASK [Set zuul-log-path fact] 2026-10-03 10:03:56.828826 | localhost | ok 2026-10-03 10:03:56.836587 | 2026-10-03 10:03:56.836741 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-10-03 10:03:56.862510 | localhost | ok 2026-10-03 10:03:56.865523 | 2026-10-03 10:03:56.865668 | TASK [upload-logs : Create log directories] 2026-10-03 10:03:57.344544 | localhost | changed 2026-10-03 10:03:57.351341 | 2026-10-03 10:03:57.351514 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-10-03 10:03:57.875228 | localhost -> localhost | ok: Runtime: 0:00:00.005088 2026-10-03 10:03:57.879451 | 2026-10-03 10:03:57.879567 | TASK [upload-logs : Upload logs to log server] 2026-10-03 10:03:58.416858 | localhost | Output suppressed because no_log was given 2026-10-03 10:03:58.418864 | 2026-10-03 10:03:58.418979 | LOOP [upload-logs : Compress console log and json output] 2026-10-03 10:03:58.475261 | localhost | skipping: Conditional result was False 2026-10-03 10:03:58.481256 | localhost | skipping: Conditional result was False 2026-10-03 10:03:58.489231 | 2026-10-03 10:03:58.489487 | LOOP [upload-logs : Upload compressed console log and json output] 2026-10-03 10:03:58.554959 | localhost | skipping: Conditional result was False 2026-10-03 10:03:58.555257 | 2026-10-03 10:03:58.564636 | localhost | skipping: Conditional result was False 2026-10-03 10:03:58.569364 | 2026-10-03 10:03:58.569514 | LOOP [upload-logs : Upload console log and json output]