2025-10-30 02:39:51.555376 | Job console starting 2025-10-30 02:39:51.626868 | Updating git repos 2025-10-30 02:39:51.663081 | Cloning repos into workspace 2025-10-30 02:39:51.772798 | Restoring repo states 2025-10-30 02:39:51.793605 | Merging changes 2025-10-30 02:39:52.465301 | Checking out repos 2025-10-30 02:39:52.575552 | Preparing playbooks 2025-10-30 02:39:54.346792 | Running Ansible setup 2025-10-30 02:39:59.960454 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-10-30 02:40:01.787884 | 2025-10-30 02:40:01.788011 | PLAY [Base pre] 2025-10-30 02:40:01.819384 | 2025-10-30 02:40:01.819517 | TASK [Setup log path fact] 2025-10-30 02:40:01.851743 | debian-bookworm | ok 2025-10-30 02:40:01.888640 | 2025-10-30 02:40:01.888770 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-30 02:40:01.961145 | debian-bookworm | ok 2025-10-30 02:40:01.992944 | 2025-10-30 02:40:01.993058 | TASK [emit-job-header : Print job information] 2025-10-30 02:40:02.081472 | # Job Information 2025-10-30 02:40:02.081644 | Ansible Version: 2.16.14 2025-10-30 02:40:02.081679 | Job: container-image-cfg-cookiecutter-build 2025-10-30 02:40:02.081710 | Pipeline: check 2025-10-30 02:40:02.081733 | Executor: 521e9411259a 2025-10-30 02:40:02.081753 | Triggered by: https://github.com/osism/cfg-cookiecutter/pull/793 2025-10-30 02:40:02.081776 | Event ID: afe3ca70-b539-11f0-81cc-a683d83162ac 2025-10-30 02:40:02.086488 | 2025-10-30 02:40:02.086589 | LOOP [emit-job-header : Print node information] 2025-10-30 02:40:02.294666 | debian-bookworm | ok: 2025-10-30 02:40:02.294875 | debian-bookworm | # Node Information 2025-10-30 02:40:02.294914 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-10-30 02:40:02.294939 | debian-bookworm | Hostname: debian 2025-10-30 02:40:02.294961 | debian-bookworm | Username: zuul 2025-10-30 02:40:02.294981 | debian-bookworm | Distro: Debian 12.12 2025-10-30 02:40:02.295006 | debian-bookworm | Provider: regiocloud-a 2025-10-30 02:40:02.295026 | debian-bookworm | Region: 2025-10-30 02:40:02.295047 | debian-bookworm | Label: debian-bookworm 2025-10-30 02:40:02.295066 | debian-bookworm | Product Name: OpenStack Nova 2025-10-30 02:40:02.295086 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe30:435f 2025-10-30 02:40:02.313498 | 2025-10-30 02:40:02.313623 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-10-30 02:40:03.255207 | debian-bookworm -> localhost | changed 2025-10-30 02:40:03.270050 | 2025-10-30 02:40:03.270166 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-10-30 02:40:05.301231 | debian-bookworm -> localhost | changed 2025-10-30 02:40:05.315395 | 2025-10-30 02:40:05.315520 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-10-30 02:40:06.067046 | debian-bookworm -> localhost | ok 2025-10-30 02:40:06.073050 | 2025-10-30 02:40:06.073154 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-10-30 02:40:06.110864 | debian-bookworm | ok 2025-10-30 02:40:06.166368 | debian-bookworm | included: /var/lib/zuul/builds/c491373dff21468a81074f7144c6282d/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-10-30 02:40:06.192701 | 2025-10-30 02:40:06.192860 | TASK [add-build-sshkey : Create Temp SSH key] 2025-10-30 02:40:07.894415 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-10-30 02:40:07.894668 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/c491373dff21468a81074f7144c6282d/work/c491373dff21468a81074f7144c6282d_id_rsa 2025-10-30 02:40:07.894709 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/c491373dff21468a81074f7144c6282d/work/c491373dff21468a81074f7144c6282d_id_rsa.pub 2025-10-30 02:40:07.894736 | debian-bookworm -> localhost | The key fingerprint is: 2025-10-30 02:40:07.894762 | debian-bookworm -> localhost | SHA256:sS4MFYWB8uFWhYfWziviCuRgXnKV8BepcXD9H63Y030 zuul-build-sshkey 2025-10-30 02:40:07.894792 | debian-bookworm -> localhost | The key's randomart image is: 2025-10-30 02:40:07.894814 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-10-30 02:40:07.894861 | debian-bookworm -> localhost | | . o+@= | 2025-10-30 02:40:07.894885 | debian-bookworm -> localhost | | . =.O++. | 2025-10-30 02:40:07.894906 | debian-bookworm -> localhost | | + B==. . . | 2025-10-30 02:40:07.894926 | debian-bookworm -> localhost | | =o. oo . . . | 2025-10-30 02:40:07.894947 | debian-bookworm -> localhost | |.+ +. S. + + .| 2025-10-30 02:40:07.894968 | debian-bookworm -> localhost | |* + .o... . = .E| 2025-10-30 02:40:07.894988 | debian-bookworm -> localhost | |.o . .o.. . .| 2025-10-30 02:40:07.895008 | debian-bookworm -> localhost | | . . . | 2025-10-30 02:40:07.895028 | debian-bookworm -> localhost | | .. | 2025-10-30 02:40:07.895047 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-10-30 02:40:07.895097 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.757922 2025-10-30 02:40:07.901050 | 2025-10-30 02:40:07.901148 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-10-30 02:40:07.920379 | debian-bookworm | ok 2025-10-30 02:40:07.931339 | debian-bookworm | included: /var/lib/zuul/builds/c491373dff21468a81074f7144c6282d/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-10-30 02:40:07.940212 | 2025-10-30 02:40:07.940319 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-10-30 02:40:07.975771 | debian-bookworm | skipping: Conditional result was False 2025-10-30 02:40:07.981376 | 2025-10-30 02:40:07.981467 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-10-30 02:40:08.626765 | debian-bookworm | changed 2025-10-30 02:40:08.631559 | 2025-10-30 02:40:08.631634 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-10-30 02:40:08.900108 | debian-bookworm | ok 2025-10-30 02:40:08.910240 | 2025-10-30 02:40:08.910352 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-10-30 02:40:09.557153 | debian-bookworm | changed 2025-10-30 02:40:09.562570 | 2025-10-30 02:40:09.562651 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-10-30 02:40:10.261908 | debian-bookworm | changed 2025-10-30 02:40:10.269049 | 2025-10-30 02:40:10.269149 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-10-30 02:40:10.308867 | debian-bookworm | skipping: Conditional result was False 2025-10-30 02:40:10.316064 | 2025-10-30 02:40:10.316197 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-10-30 02:40:11.316927 | debian-bookworm -> localhost | changed 2025-10-30 02:40:11.327342 | 2025-10-30 02:40:11.327441 | TASK [add-build-sshkey : Add back temp key] 2025-10-30 02:40:11.823174 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/c491373dff21468a81074f7144c6282d/work/c491373dff21468a81074f7144c6282d_id_rsa (zuul-build-sshkey) 2025-10-30 02:40:11.823363 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.014178 2025-10-30 02:40:11.829541 | 2025-10-30 02:40:11.829629 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-10-30 02:40:12.251951 | debian-bookworm | ok 2025-10-30 02:40:12.256064 | 2025-10-30 02:40:12.256145 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-10-30 02:40:12.301355 | debian-bookworm | skipping: Conditional result was False 2025-10-30 02:40:12.310400 | 2025-10-30 02:40:12.310488 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-10-30 02:40:12.747695 | debian-bookworm | ok 2025-10-30 02:40:12.767096 | 2025-10-30 02:40:12.767195 | TASK [validate-host : Define zuul_info_dir fact] 2025-10-30 02:40:12.804349 | debian-bookworm | ok 2025-10-30 02:40:12.808557 | 2025-10-30 02:40:12.808639 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-10-30 02:40:13.340115 | debian-bookworm -> localhost | ok 2025-10-30 02:40:13.345168 | 2025-10-30 02:40:13.345251 | TASK [validate-host : Collect information about the host] 2025-10-30 02:40:14.402067 | debian-bookworm | ok 2025-10-30 02:40:14.431021 | 2025-10-30 02:40:14.431190 | TASK [validate-host : Sanitize hostname] 2025-10-30 02:40:14.590603 | debian-bookworm | ok 2025-10-30 02:40:14.661305 | 2025-10-30 02:40:14.661408 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-10-30 02:40:15.569006 | debian-bookworm -> localhost | changed 2025-10-30 02:40:15.574356 | 2025-10-30 02:40:15.574449 | TASK [validate-host : Collect information about zuul worker] 2025-10-30 02:40:15.978285 | debian-bookworm | ok 2025-10-30 02:40:15.982755 | 2025-10-30 02:40:15.982933 | TASK [validate-host : Write out all zuul information for each host] 2025-10-30 02:40:16.984450 | debian-bookworm -> localhost | changed 2025-10-30 02:40:17.004207 | 2025-10-30 02:40:17.004312 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-10-30 02:40:17.289339 | debian-bookworm | ok 2025-10-30 02:40:17.304361 | 2025-10-30 02:40:17.304458 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-10-30 02:40:18.681024 | debian-bookworm | changed: 2025-10-30 02:40:18.681188 | debian-bookworm | cd+++++++++ src/ 2025-10-30 02:40:18.681222 | debian-bookworm | cd+++++++++ src/github.com/ 2025-10-30 02:40:18.681246 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-10-30 02:40:18.681268 | debian-bookworm | cd+++++++++ src/github.com/osism/cfg-cookiecutter/ 2025-10-30 02:40:18.681289 | debian-bookworm | Containerfile 2025-10-30 02:40:18.681418 | debian-bookworm | localhost | ok: Runtime: 0:00:00.007502 2025-10-30 02:41:38.106807 | 2025-10-30 02:41:38.106964 | TASK [upload-logs : Upload logs to log server] 2025-10-30 02:41:38.662863 | localhost | Output suppressed because no_log was given 2025-10-30 02:41:38.666930 | 2025-10-30 02:41:38.667108 | LOOP [upload-logs : Compress console log and json output] 2025-10-30 02:41:38.727943 | localhost | skipping: Conditional result was False 2025-10-30 02:41:38.736092 | localhost | skipping: Conditional result was False 2025-10-30 02:41:38.749957 | 2025-10-30 02:41:38.750210 | LOOP [upload-logs : Upload compressed console log and json output] 2025-10-30 02:41:38.805974 | localhost | skipping: Conditional result was False 2025-10-30 02:41:38.806637 | 2025-10-30 02:41:38.810141 | localhost | skipping: Conditional result was False 2025-10-30 02:41:38.823736 | 2025-10-30 02:41:38.823985 | LOOP [upload-logs : Upload console log and json output]