2026-05-09 21:53:01.636138 | Job console starting 2026-05-09 21:53:01.703597 | Updating git repos 2026-05-09 21:53:01.774225 | Cloning repos into workspace 2026-05-09 21:53:01.950321 | Restoring repo states 2026-05-09 21:53:01.998950 | Merging changes 2026-05-09 21:53:02.892707 | Checking out repos 2026-05-09 21:53:03.036589 | Preparing playbooks 2026-05-09 21:53:06.754907 | Running Ansible setup 2026-05-09 21:53:12.857857 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-05-09 21:53:14.249786 | 2026-05-09 21:53:14.249927 | PLAY [Base pre] 2026-05-09 21:53:14.357245 | 2026-05-09 21:53:14.357409 | TASK [Setup log path fact] 2026-05-09 21:53:14.408483 | debian-bookworm | ok 2026-05-09 21:53:14.541848 | 2026-05-09 21:53:14.542017 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-09 21:53:14.626193 | debian-bookworm | ok 2026-05-09 21:53:14.648175 | 2026-05-09 21:53:14.648317 | TASK [emit-job-header : Print job information] 2026-05-09 21:53:14.729783 | # Job Information 2026-05-09 21:53:14.729954 | Ansible Version: 2.16.14 2026-05-09 21:53:14.729989 | Job: container-image-cfg-cookiecutter-build 2026-05-09 21:53:14.730022 | Pipeline: check 2026-05-09 21:53:14.730045 | Executor: 521e9411259a 2026-05-09 21:53:14.730066 | Triggered by: https://github.com/osism/cfg-cookiecutter/pull/839 2026-05-09 21:53:14.730089 | Event ID: 6b62e1c0-4bf1-11f1-89ec-fa79072c1636 2026-05-09 21:53:14.734900 | 2026-05-09 21:53:14.735003 | LOOP [emit-job-header : Print node information] 2026-05-09 21:53:15.126248 | debian-bookworm | ok: 2026-05-09 21:53:15.126464 | debian-bookworm | # Node Information 2026-05-09 21:53:15.126499 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-05-09 21:53:15.126526 | debian-bookworm | Hostname: debian 2026-05-09 21:53:15.126547 | debian-bookworm | Username: zuul 2026-05-09 21:53:15.126567 | debian-bookworm | Distro: Debian 12.13 2026-05-09 21:53:15.126590 | debian-bookworm | Provider: regiocloud-a 2026-05-09 21:53:15.126610 | debian-bookworm | Region: 2026-05-09 21:53:15.126631 | debian-bookworm | Label: debian-bookworm 2026-05-09 21:53:15.126651 | debian-bookworm | Product Name: OpenStack Nova 2026-05-09 21:53:15.126670 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe92:6bdc 2026-05-09 21:53:15.145186 | 2026-05-09 21:53:15.145316 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-05-09 21:53:17.132684 | debian-bookworm -> localhost | changed 2026-05-09 21:53:17.140937 | 2026-05-09 21:53:17.141091 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-05-09 21:53:19.826546 | debian-bookworm -> localhost | changed 2026-05-09 21:53:19.836982 | 2026-05-09 21:53:19.837097 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-05-09 21:53:20.917315 | debian-bookworm -> localhost | ok 2026-05-09 21:53:20.924234 | 2026-05-09 21:53:20.924370 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-05-09 21:53:20.993720 | debian-bookworm | ok 2026-05-09 21:53:21.044737 | debian-bookworm | included: /var/lib/zuul/builds/075f65b2447441b28084389e608e115a/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-05-09 21:53:21.087843 | 2026-05-09 21:53:21.087966 | TASK [add-build-sshkey : Create Temp SSH key] 2026-05-09 21:53:24.173063 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-05-09 21:53:24.173235 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/075f65b2447441b28084389e608e115a/work/075f65b2447441b28084389e608e115a_id_rsa 2026-05-09 21:53:24.173289 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/075f65b2447441b28084389e608e115a/work/075f65b2447441b28084389e608e115a_id_rsa.pub 2026-05-09 21:53:24.173314 | debian-bookworm -> localhost | The key fingerprint is: 2026-05-09 21:53:24.173334 | debian-bookworm -> localhost | SHA256:ob+cpSx+SePHYMOrMF/Lq9dKbzOhPnXoRN8tbEVeWHQ zuul-build-sshkey 2026-05-09 21:53:24.173360 | debian-bookworm -> localhost | The key's randomart image is: 2026-05-09 21:53:24.173378 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-05-09 21:53:24.173395 | debian-bookworm -> localhost | | +E| 2026-05-09 21:53:24.173413 | debian-bookworm -> localhost | | . +| 2026-05-09 21:53:24.173429 | debian-bookworm -> localhost | | . o.| 2026-05-09 21:53:24.173446 | debian-bookworm -> localhost | | . .. o| 2026-05-09 21:53:24.173462 | debian-bookworm -> localhost | | ..S. o o o | 2026-05-09 21:53:24.173485 | debian-bookworm -> localhost | | .B = o = .| 2026-05-09 21:53:24.173501 | debian-bookworm -> localhost | | o ++&.o . . | 2026-05-09 21:53:24.173517 | debian-bookworm -> localhost | | +.*OBO | 2026-05-09 21:53:24.173534 | debian-bookworm -> localhost | | .=B%*.o | 2026-05-09 21:53:24.173550 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-05-09 21:53:24.173601 | debian-bookworm -> localhost | ok: Runtime: 0:00:01.849938 2026-05-09 21:53:24.178697 | 2026-05-09 21:53:24.178779 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-05-09 21:53:24.205980 | debian-bookworm | ok 2026-05-09 21:53:24.229348 | debian-bookworm | included: /var/lib/zuul/builds/075f65b2447441b28084389e608e115a/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-05-09 21:53:24.276052 | 2026-05-09 21:53:24.276153 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-05-09 21:53:24.304093 | debian-bookworm | skipping: Conditional result was False 2026-05-09 21:53:24.308943 | 2026-05-09 21:53:24.309028 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-05-09 21:53:24.943969 | debian-bookworm | changed 2026-05-09 21:53:24.948897 | 2026-05-09 21:53:24.948966 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-05-09 21:53:25.224764 | debian-bookworm | ok 2026-05-09 21:53:25.229230 | 2026-05-09 21:53:25.229321 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-05-09 21:53:26.075406 | debian-bookworm | changed 2026-05-09 21:53:26.091603 | 2026-05-09 21:53:26.098318 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-05-09 21:53:26.847405 | debian-bookworm | changed 2026-05-09 21:53:26.852046 | 2026-05-09 21:53:26.852122 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-05-09 21:53:26.907961 | debian-bookworm | skipping: Conditional result was False 2026-05-09 21:53:26.913790 | 2026-05-09 21:53:26.913896 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-05-09 21:53:27.664153 | debian-bookworm -> localhost | changed 2026-05-09 21:53:27.679221 | 2026-05-09 21:53:27.679344 | TASK [add-build-sshkey : Add back temp key] 2026-05-09 21:53:28.212130 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/075f65b2447441b28084389e608e115a/work/075f65b2447441b28084389e608e115a_id_rsa (zuul-build-sshkey) 2026-05-09 21:53:28.212364 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.029286 2026-05-09 21:53:28.221812 | 2026-05-09 21:53:28.221983 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-05-09 21:53:28.647215 | debian-bookworm | ok 2026-05-09 21:53:28.652384 | 2026-05-09 21:53:28.652477 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-05-09 21:53:28.675537 | debian-bookworm | skipping: Conditional result was False 2026-05-09 21:53:28.685823 | 2026-05-09 21:53:28.685917 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-05-09 21:53:29.260614 | debian-bookworm | ok 2026-05-09 21:53:29.294679 | 2026-05-09 21:53:29.294793 | TASK [validate-host : Define zuul_info_dir fact] 2026-05-09 21:53:29.398497 | debian-bookworm | ok 2026-05-09 21:53:29.403728 | 2026-05-09 21:53:29.403826 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-05-09 21:53:29.908817 | debian-bookworm -> localhost | ok 2026-05-09 21:53:29.915347 | 2026-05-09 21:53:29.915444 | TASK [validate-host : Collect information about the host] 2026-05-09 21:53:31.344376 | debian-bookworm | ok 2026-05-09 21:53:31.384084 | 2026-05-09 21:53:31.384203 | TASK [validate-host : Sanitize hostname] 2026-05-09 21:53:31.561851 | debian-bookworm | ok 2026-05-09 21:53:31.628444 | 2026-05-09 21:53:31.628556 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-05-09 21:53:33.389758 | debian-bookworm -> localhost | changed 2026-05-09 21:53:33.396056 | 2026-05-09 21:53:33.396213 | TASK [validate-host : Collect information about zuul worker] 2026-05-09 21:53:33.843149 | debian-bookworm | ok 2026-05-09 21:53:33.849864 | 2026-05-09 21:53:33.851405 | TASK [validate-host : Write out all zuul information for each host] 2026-05-09 21:53:35.016745 | debian-bookworm -> localhost | changed 2026-05-09 21:53:35.026996 | 2026-05-09 21:53:35.027123 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-05-09 21:53:35.342816 | debian-bookworm | ok 2026-05-09 21:53:35.355810 | 2026-05-09 21:53:35.355922 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-05-09 21:53:37.606127 | debian-bookworm | changed: 2026-05-09 21:53:37.606351 | debian-bookworm | cd+++++++++ src/ 2026-05-09 21:53:37.606388 | debian-bookworm | cd+++++++++ src/github.com/ 2026-05-09 21:53:37.606413 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-05-09 21:53:37.606435 | debian-bookworm | cd+++++++++ src/github.com/osism/cfg-cookiecutter/ 2026-05-09 21:53:37.606456 | debian-bookworm | Containerfile 2026-05-09 21:53:37.606588 | debian-bookworm | localhost | ok: "/var/lib/zuul/builds/075f65b2447441b28084389e608e115a/work/logs" 2026-05-09 21:55:16.765746 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/075f65b2447441b28084389e608e115a/work/artifacts" 2026-05-09 21:55:17.067433 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/075f65b2447441b28084389e608e115a/work/docs" 2026-05-09 21:55:17.088819 | 2026-05-09 21:55:17.089042 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-05-09 21:55:17.913502 | debian-bookworm | changed: 2026-05-09 21:55:17.913746 | debian-bookworm | .d..t...... ./ 2026-05-09 21:55:17.913782 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-05-09 21:55:17.913813 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-05-09 21:55:17.913839 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-05-09 21:55:17.913881 | debian-bookworm | changed: All items complete 2026-05-09 21:55:17.913908 | 2026-05-09 21:55:18.503009 | debian-bookworm | changed: .d..t...... ./ 2026-05-09 21:55:19.100533 | debian-bookworm | changed: .d..t...... ./ 2026-05-09 21:55:19.123562 | 2026-05-09 21:55:19.123720 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-05-09 21:55:19.642174 | debian-bookworm -> localhost | ok: Item: artifacts Runtime: 0:00:00.009556 2026-05-09 21:55:19.929740 | debian-bookworm -> localhost | ok: Item: docs Runtime: 0:00:00.010742 2026-05-09 21:55:19.944344 | 2026-05-09 21:55:19.944477 | PLAY RECAP 2026-05-09 21:55:19.944546 | debian-bookworm | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-05-09 21:55:19.944581 | 2026-05-09 21:55:20.090122 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-05-09 21:55:20.094246 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-09 21:55:20.999856 | 2026-05-09 21:55:21.000030 | PLAY [Base post] 2026-05-09 21:55:21.015355 | 2026-05-09 21:55:21.015533 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-05-09 21:55:21.801844 | debian-bookworm | changed 2026-05-09 21:55:21.808609 | 2026-05-09 21:55:21.808737 | PLAY RECAP 2026-05-09 21:55:21.808804 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-05-09 21:55:21.808870 | 2026-05-09 21:55:21.979892 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-09 21:55:21.980871 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-05-09 21:55:22.965845 | 2026-05-09 21:55:22.966021 | PLAY [Base post-logs] 2026-05-09 21:55:22.977521 | 2026-05-09 21:55:22.977664 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-05-09 21:55:23.508323 | localhost | changed 2026-05-09 21:55:23.525488 | 2026-05-09 21:55:23.525667 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-05-09 21:55:23.561413 | localhost | ok 2026-05-09 21:55:23.564882 | 2026-05-09 21:55:23.564991 | TASK [Set zuul-log-path fact] 2026-05-09 21:55:23.580524 | localhost | ok 2026-05-09 21:55:23.596184 | 2026-05-09 21:55:23.596467 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-09 21:55:23.624159 | localhost | ok 2026-05-09 21:55:23.627728 | 2026-05-09 21:55:23.627855 | TASK [upload-logs : Create log directories] 2026-05-09 21:55:24.328298 | localhost | changed 2026-05-09 21:55:24.331267 | 2026-05-09 21:55:24.331398 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-05-09 21:55:25.055708 | localhost -> localhost | ok: Runtime: 0:00:00.020050 2026-05-09 21:55:25.060158 | 2026-05-09 21:55:25.060301 | TASK [upload-logs : Upload logs to log server] 2026-05-09 21:55:25.776432 | localhost | Output suppressed because no_log was given 2026-05-09 21:55:25.779071 | 2026-05-09 21:55:25.779181 | LOOP [upload-logs : Compress console log and json output] 2026-05-09 21:55:25.827230 | localhost | skipping: Conditional result was False 2026-05-09 21:55:25.847035 | localhost | skipping: Conditional result was False 2026-05-09 21:55:25.856572 | 2026-05-09 21:55:25.856706 | LOOP [upload-logs : Upload compressed console log and json output] 2026-05-09 21:55:25.920020 | localhost | skipping: Conditional result was False 2026-05-09 21:55:25.920357 | 2026-05-09 21:55:25.925402 | localhost | skipping: Conditional result was False 2026-05-09 21:55:25.934177 | 2026-05-09 21:55:25.934357 | LOOP [upload-logs : Upload console log and json output]