2026-05-18 19:49:04.851064 | Job console starting 2026-05-18 19:49:04.889216 | Updating git repos 2026-05-18 19:49:04.922277 | Cloning repos into workspace 2026-05-18 19:49:05.034309 | Restoring repo states 2026-05-18 19:49:05.056211 | Merging changes 2026-05-18 19:49:05.750198 | Checking out repos 2026-05-18 19:49:05.813218 | Preparing playbooks 2026-05-18 19:49:07.071590 | Running Ansible setup 2026-05-18 19:49:11.940424 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-05-18 19:49:13.377117 | 2026-05-18 19:49:13.377232 | PLAY [Base pre] 2026-05-18 19:49:13.420251 | 2026-05-18 19:49:13.420365 | TASK [Setup log path fact] 2026-05-18 19:49:13.447728 | debian-bookworm | ok 2026-05-18 19:49:13.467971 | 2026-05-18 19:49:13.468090 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-18 19:49:13.501340 | debian-bookworm | ok 2026-05-18 19:49:13.514194 | 2026-05-18 19:49:13.514306 | TASK [emit-job-header : Print job information] 2026-05-18 19:49:13.552734 | # Job Information 2026-05-18 19:49:13.552902 | Ansible Version: 2.16.14 2026-05-18 19:49:13.552936 | Job: container-image-cfg-cookiecutter-build 2026-05-18 19:49:13.552970 | Pipeline: check 2026-05-18 19:49:13.552993 | Executor: 521e9411259a 2026-05-18 19:49:13.553014 | Triggered by: https://github.com/osism/cfg-cookiecutter/pull/816 2026-05-18 19:49:13.553035 | Event ID: 3bb7caa0-52f2-11f1-9bba-41bbb5b1c6fc 2026-05-18 19:49:13.558045 | 2026-05-18 19:49:13.558152 | LOOP [emit-job-header : Print node information] 2026-05-18 19:49:13.717231 | debian-bookworm | ok: 2026-05-18 19:49:13.717402 | debian-bookworm | # Node Information 2026-05-18 19:49:13.717438 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-05-18 19:49:13.717464 | debian-bookworm | Hostname: debian 2026-05-18 19:49:13.717487 | debian-bookworm | Username: zuul 2026-05-18 19:49:13.717508 | debian-bookworm | Distro: Debian 12.14 2026-05-18 19:49:13.717531 | debian-bookworm | Provider: regiocloud-a 2026-05-18 19:49:13.717553 | debian-bookworm | Region: 2026-05-18 19:49:13.717574 | debian-bookworm | Label: debian-bookworm 2026-05-18 19:49:13.717595 | debian-bookworm | Product Name: OpenStack Nova 2026-05-18 19:49:13.717614 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe6f:3209 2026-05-18 19:49:13.735741 | 2026-05-18 19:49:13.735851 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-05-18 19:49:14.194541 | debian-bookworm -> localhost | changed 2026-05-18 19:49:14.201389 | 2026-05-18 19:49:14.201494 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-05-18 19:49:15.546574 | debian-bookworm -> localhost | changed 2026-05-18 19:49:15.560056 | 2026-05-18 19:49:15.560163 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-05-18 19:49:15.906422 | debian-bookworm -> localhost | ok 2026-05-18 19:49:15.912572 | 2026-05-18 19:49:15.912711 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-05-18 19:49:15.935369 | debian-bookworm | ok 2026-05-18 19:49:15.964084 | debian-bookworm | included: /var/lib/zuul/builds/bbabc0c6108f4946ae405a6b32093482/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-05-18 19:49:15.976652 | 2026-05-18 19:49:15.976779 | TASK [add-build-sshkey : Create Temp SSH key] 2026-05-18 19:49:21.051342 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-05-18 19:49:21.051535 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/bbabc0c6108f4946ae405a6b32093482/work/bbabc0c6108f4946ae405a6b32093482_id_rsa 2026-05-18 19:49:21.051571 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/bbabc0c6108f4946ae405a6b32093482/work/bbabc0c6108f4946ae405a6b32093482_id_rsa.pub 2026-05-18 19:49:21.051597 | debian-bookworm -> localhost | The key fingerprint is: 2026-05-18 19:49:21.051647 | debian-bookworm -> localhost | SHA256:6BTHDHRMpEuxC5JngGdQma+d3wy+VrL8d/9O9hEbRKI zuul-build-sshkey 2026-05-18 19:49:21.051681 | debian-bookworm -> localhost | The key's randomart image is: 2026-05-18 19:49:21.051703 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-05-18 19:49:21.051725 | debian-bookworm -> localhost | |.+oo .+++ . . | 2026-05-18 19:49:21.051746 | debian-bookworm -> localhost | |. =o O. . o | 2026-05-18 19:49:21.051766 | debian-bookworm -> localhost | | oo.+ = + E . | 2026-05-18 19:49:21.051786 | debian-bookworm -> localhost | | +.o * . | 2026-05-18 19:49:21.051805 | debian-bookworm -> localhost | | o .= S o | 2026-05-18 19:49:21.051829 | debian-bookworm -> localhost | | . ooo . +| 2026-05-18 19:49:21.051876 | debian-bookworm -> localhost | | +.B oo| 2026-05-18 19:49:21.051899 | debian-bookworm -> localhost | | * o . . oo| 2026-05-18 19:49:21.051920 | debian-bookworm -> localhost | | ..o.. . ..o+| 2026-05-18 19:49:21.051940 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-05-18 19:49:21.051993 | debian-bookworm -> localhost | ok: Runtime: 0:00:04.272099 2026-05-18 19:49:21.058421 | 2026-05-18 19:49:21.058516 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-05-18 19:49:21.110578 | debian-bookworm | ok 2026-05-18 19:49:21.156993 | debian-bookworm | included: /var/lib/zuul/builds/bbabc0c6108f4946ae405a6b32093482/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-05-18 19:49:21.176539 | 2026-05-18 19:49:21.176664 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-05-18 19:49:21.224107 | debian-bookworm | skipping: Conditional result was False 2026-05-18 19:49:21.230389 | 2026-05-18 19:49:21.230497 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-05-18 19:49:22.292246 | debian-bookworm | changed 2026-05-18 19:49:22.300512 | 2026-05-18 19:49:22.300631 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-05-18 19:49:22.595900 | debian-bookworm | ok 2026-05-18 19:49:22.644439 | 2026-05-18 19:49:22.644715 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-05-18 19:49:23.465660 | debian-bookworm | changed 2026-05-18 19:49:23.475122 | 2026-05-18 19:49:23.475221 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-05-18 19:49:24.272517 | debian-bookworm | changed 2026-05-18 19:49:24.277100 | 2026-05-18 19:49:24.277264 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-05-18 19:49:24.334339 | debian-bookworm | skipping: Conditional result was False 2026-05-18 19:49:24.340129 | 2026-05-18 19:49:24.340230 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-05-18 19:49:25.351547 | debian-bookworm -> localhost | changed 2026-05-18 19:49:25.360441 | 2026-05-18 19:49:25.360539 | TASK [add-build-sshkey : Add back temp key] 2026-05-18 19:49:26.104298 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/bbabc0c6108f4946ae405a6b32093482/work/bbabc0c6108f4946ae405a6b32093482_id_rsa (zuul-build-sshkey) 2026-05-18 19:49:26.104495 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.008122 2026-05-18 19:49:26.140368 | 2026-05-18 19:49:26.140469 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-05-18 19:49:26.643439 | debian-bookworm | ok 2026-05-18 19:49:26.647741 | 2026-05-18 19:49:26.647827 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-05-18 19:49:26.684652 | debian-bookworm | skipping: Conditional result was False 2026-05-18 19:49:26.693740 | 2026-05-18 19:49:26.693842 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-05-18 19:49:27.268783 | debian-bookworm | ok 2026-05-18 19:49:27.281320 | 2026-05-18 19:49:27.281415 | TASK [validate-host : Define zuul_info_dir fact] 2026-05-18 19:49:27.328381 | debian-bookworm | ok 2026-05-18 19:49:27.333116 | 2026-05-18 19:49:27.333217 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-05-18 19:49:27.880863 | debian-bookworm -> localhost | ok 2026-05-18 19:49:27.886569 | 2026-05-18 19:49:27.886695 | TASK [validate-host : Collect information about the host] 2026-05-18 19:49:29.561589 | debian-bookworm | ok 2026-05-18 19:49:29.577942 | 2026-05-18 19:49:29.578038 | TASK [validate-host : Sanitize hostname] 2026-05-18 19:49:29.841454 | debian-bookworm | ok 2026-05-18 19:49:29.961611 | 2026-05-18 19:49:29.961733 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-05-18 19:49:31.383653 | debian-bookworm -> localhost | changed 2026-05-18 19:49:31.388325 | 2026-05-18 19:49:31.388424 | TASK [validate-host : Collect information about zuul worker] 2026-05-18 19:49:32.033249 | debian-bookworm | ok 2026-05-18 19:49:32.037127 | 2026-05-18 19:49:32.037220 | TASK [validate-host : Write out all zuul information for each host] 2026-05-18 19:49:33.321453 | debian-bookworm -> localhost | changed 2026-05-18 19:49:33.329433 | 2026-05-18 19:49:33.329528 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-05-18 19:49:33.653216 | debian-bookworm | ok 2026-05-18 19:49:33.657590 | 2026-05-18 19:49:33.657692 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-05-18 19:49:35.296245 | debian-bookworm | changed: 2026-05-18 19:49:35.296396 | debian-bookworm | cd+++++++++ src/ 2026-05-18 19:49:35.296425 | debian-bookworm | cd+++++++++ src/github.com/ 2026-05-18 19:49:35.296447 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-05-18 19:49:35.296466 | debian-bookworm | cd+++++++++ src/github.com/osism/cfg-cookiecutter/ 2026-05-18 19:49:35.296483 | debian-bookworm | Containerfile 2026-05-18 19:49:35.296589 | debian-bookworm | localhost | ok: "/var/lib/zuul/builds/bbabc0c6108f4946ae405a6b32093482/work/logs" 2026-05-18 19:51:15.699992 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/bbabc0c6108f4946ae405a6b32093482/work/artifacts" 2026-05-18 19:51:15.980456 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/bbabc0c6108f4946ae405a6b32093482/work/docs" 2026-05-18 19:51:16.000337 | 2026-05-18 19:51:16.000516 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-05-18 19:51:16.827572 | debian-bookworm | changed: 2026-05-18 19:51:16.827882 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-05-18 19:51:16.827930 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-05-18 19:51:16.827964 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-05-18 19:51:16.828019 | debian-bookworm | changed: All items complete 2026-05-18 19:51:16.828050 | 2026-05-18 19:51:17.400396 | debian-bookworm | changed: .d..t...... ./ 2026-05-18 19:51:17.986001 | debian-bookworm | changed: .d..t...... ./ 2026-05-18 19:51:18.003508 | 2026-05-18 19:51:18.003770 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-05-18 19:51:18.552482 | debian-bookworm -> localhost | ok: Item: artifacts Runtime: 0:00:00.006122 2026-05-18 19:51:18.893195 | debian-bookworm -> localhost | ok: Item: docs Runtime: 0:00:00.007160 2026-05-18 19:51:18.913160 | 2026-05-18 19:51:18.913369 | PLAY RECAP 2026-05-18 19:51:18.913441 | debian-bookworm | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-05-18 19:51:18.913471 | 2026-05-18 19:51:19.067574 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-05-18 19:51:19.068619 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-18 19:51:19.822187 | 2026-05-18 19:51:19.822392 | PLAY [Base post] 2026-05-18 19:51:19.837487 | 2026-05-18 19:51:19.837690 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-05-18 19:51:20.646880 | debian-bookworm | changed 2026-05-18 19:51:20.662629 | 2026-05-18 19:51:20.663015 | PLAY RECAP 2026-05-18 19:51:20.663155 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-05-18 19:51:20.663285 | 2026-05-18 19:51:20.833066 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-18 19:51:20.834060 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-05-18 19:51:21.707878 | 2026-05-18 19:51:21.708065 | PLAY [Base post-logs] 2026-05-18 19:51:21.724254 | 2026-05-18 19:51:21.724672 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-05-18 19:51:22.207435 | localhost | changed 2026-05-18 19:51:22.228643 | 2026-05-18 19:51:22.228990 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-05-18 19:51:22.273067 | localhost | ok 2026-05-18 19:51:22.281769 | 2026-05-18 19:51:22.282293 | TASK [Set zuul-log-path fact] 2026-05-18 19:51:22.314970 | localhost | ok 2026-05-18 19:51:22.333733 | 2026-05-18 19:51:22.334261 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-18 19:51:22.377094 | localhost | ok 2026-05-18 19:51:22.384263 | 2026-05-18 19:51:22.384649 | TASK [upload-logs : Create log directories] 2026-05-18 19:51:22.919759 | localhost | changed 2026-05-18 19:51:22.923155 | 2026-05-18 19:51:22.923274 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-05-18 19:51:23.481190 | localhost -> localhost | ok: Runtime: 0:00:00.004901 2026-05-18 19:51:23.501494 | 2026-05-18 19:51:23.501974 | TASK [upload-logs : Upload logs to log server] 2026-05-18 19:51:24.090884 | localhost | Output suppressed because no_log was given 2026-05-18 19:51:24.093961 | 2026-05-18 19:51:24.094121 | LOOP [upload-logs : Compress console log and json output] 2026-05-18 19:51:24.156862 | localhost | skipping: Conditional result was False 2026-05-18 19:51:24.163023 | localhost | skipping: Conditional result was False 2026-05-18 19:51:24.169702 | 2026-05-18 19:51:24.169928 | LOOP [upload-logs : Upload compressed console log and json output] 2026-05-18 19:51:24.217885 | localhost | skipping: Conditional result was False 2026-05-18 19:51:24.218554 | 2026-05-18 19:51:24.221031 | localhost | skipping: Conditional result was False 2026-05-18 19:51:24.235265 | 2026-05-18 19:51:24.235499 | LOOP [upload-logs : Upload console log and json output]