2025-10-31 00:12:15.773233 | Job console starting 2025-10-31 00:12:15.779033 | Updating git repos 2025-10-31 00:12:15.807331 | Cloning repos into workspace 2025-10-31 00:12:15.909876 | Restoring repo states 2025-10-31 00:12:15.927021 | Merging changes 2025-10-31 00:12:16.616902 | Checking out repos 2025-10-31 00:12:17.629957 | Preparing playbooks 2025-10-31 00:12:18.766765 | Running Ansible setup 2025-10-31 00:12:24.477687 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-10-31 00:12:25.363033 | 2025-10-31 00:12:25.363142 | PLAY [Base pre] 2025-10-31 00:12:25.390706 | 2025-10-31 00:12:25.390822 | TASK [Setup log path fact] 2025-10-31 00:12:25.418222 | debian-bookworm | ok 2025-10-31 00:12:25.430235 | 2025-10-31 00:12:25.430351 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-31 00:12:25.457844 | debian-bookworm | ok 2025-10-31 00:12:25.465702 | 2025-10-31 00:12:25.465800 | TASK [emit-job-header : Print job information] 2025-10-31 00:12:25.545395 | # Job Information 2025-10-31 00:12:25.545532 | Ansible Version: 2.16.14 2025-10-31 00:12:25.545560 | Job: container-image-cfg-cookiecutter-build 2025-10-31 00:12:25.545587 | Pipeline: check 2025-10-31 00:12:25.545607 | Executor: 521e9411259a 2025-10-31 00:12:25.545625 | Triggered by: https://github.com/osism/cfg-cookiecutter/pull/794 2025-10-31 00:12:25.545643 | Event ID: 356aa1f0-b5ee-11f0-93ab-e1a047b3a0a4 2025-10-31 00:12:25.550015 | 2025-10-31 00:12:25.550100 | LOOP [emit-job-header : Print node information] 2025-10-31 00:12:25.822026 | debian-bookworm | ok: 2025-10-31 00:12:25.822209 | debian-bookworm | # Node Information 2025-10-31 00:12:25.822241 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-10-31 00:12:25.822262 | debian-bookworm | Hostname: debian 2025-10-31 00:12:25.822281 | debian-bookworm | Username: zuul 2025-10-31 00:12:25.822297 | debian-bookworm | Distro: Debian 12.12 2025-10-31 00:12:25.822321 | debian-bookworm | Provider: regiocloud-a 2025-10-31 00:12:25.822338 | debian-bookworm | Region: 2025-10-31 00:12:25.822356 | debian-bookworm | Label: debian-bookworm 2025-10-31 00:12:25.822372 | debian-bookworm | Product Name: OpenStack Nova 2025-10-31 00:12:25.822388 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe61:c537 2025-10-31 00:12:25.833890 | 2025-10-31 00:12:25.833981 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-10-31 00:12:26.619779 | debian-bookworm -> localhost | changed 2025-10-31 00:12:26.629930 | 2025-10-31 00:12:26.630024 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-10-31 00:12:28.105876 | debian-bookworm -> localhost | changed 2025-10-31 00:12:28.118073 | 2025-10-31 00:12:28.118184 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-10-31 00:12:28.485735 | debian-bookworm -> localhost | ok 2025-10-31 00:12:28.490512 | 2025-10-31 00:12:28.490598 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-10-31 00:12:28.515764 | debian-bookworm | ok 2025-10-31 00:12:28.529104 | debian-bookworm | included: /var/lib/zuul/builds/0eac492a74214e73aaa54b8d2930cd89/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-10-31 00:12:28.533970 | 2025-10-31 00:12:28.534042 | TASK [add-build-sshkey : Create Temp SSH key] 2025-10-31 00:12:30.071286 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-10-31 00:12:30.071443 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/0eac492a74214e73aaa54b8d2930cd89/work/0eac492a74214e73aaa54b8d2930cd89_id_rsa 2025-10-31 00:12:30.071475 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/0eac492a74214e73aaa54b8d2930cd89/work/0eac492a74214e73aaa54b8d2930cd89_id_rsa.pub 2025-10-31 00:12:30.071497 | debian-bookworm -> localhost | The key fingerprint is: 2025-10-31 00:12:30.071517 | debian-bookworm -> localhost | SHA256:VBqhcgH/WgNKJu6ezT0OOKd8lMhDCpAxERMr3mUH+t8 zuul-build-sshkey 2025-10-31 00:12:30.071544 | debian-bookworm -> localhost | The key's randomart image is: 2025-10-31 00:12:30.071563 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-10-31 00:12:30.071580 | debian-bookworm -> localhost | |B* .o. o.. | 2025-10-31 00:12:30.071598 | debian-bookworm -> localhost | |o+ ...o + | 2025-10-31 00:12:30.071614 | debian-bookworm -> localhost | |+ ..+o=.o | 2025-10-31 00:12:30.071631 | debian-bookworm -> localhost | |+.o+++.+ | 2025-10-31 00:12:30.071695 | debian-bookworm -> localhost | |.=.o.o S | 2025-10-31 00:12:30.071721 | debian-bookworm -> localhost | |..+.o .o.. | 2025-10-31 00:12:30.071740 | debian-bookworm -> localhost | | +oo .. E | 2025-10-31 00:12:30.071756 | debian-bookworm -> localhost | | o B.o. | 2025-10-31 00:12:30.071773 | debian-bookworm -> localhost | | =.o.o. | 2025-10-31 00:12:30.071790 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-10-31 00:12:30.071838 | debian-bookworm -> localhost | ok: Runtime: 0:00:01.052280 2025-10-31 00:12:30.076860 | 2025-10-31 00:12:30.076940 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-10-31 00:12:30.113272 | debian-bookworm | ok 2025-10-31 00:12:30.120607 | debian-bookworm | included: /var/lib/zuul/builds/0eac492a74214e73aaa54b8d2930cd89/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-10-31 00:12:30.153489 | 2025-10-31 00:12:30.153583 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-10-31 00:12:30.191421 | debian-bookworm | skipping: Conditional result was False 2025-10-31 00:12:30.196125 | 2025-10-31 00:12:30.196205 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-10-31 00:12:30.906767 | debian-bookworm | changed 2025-10-31 00:12:30.914076 | 2025-10-31 00:12:30.914157 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-10-31 00:12:31.144314 | debian-bookworm | ok 2025-10-31 00:12:31.149320 | 2025-10-31 00:12:31.149405 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-10-31 00:12:31.817530 | debian-bookworm | changed 2025-10-31 00:12:31.824578 | 2025-10-31 00:12:31.824672 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-10-31 00:12:32.554262 | debian-bookworm | changed 2025-10-31 00:12:32.558694 | 2025-10-31 00:12:32.558779 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-10-31 00:12:32.582050 | debian-bookworm | skipping: Conditional result was False 2025-10-31 00:12:32.586795 | 2025-10-31 00:12:32.586926 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-10-31 00:12:33.382893 | debian-bookworm -> localhost | changed 2025-10-31 00:12:33.391392 | 2025-10-31 00:12:33.391474 | TASK [add-build-sshkey : Add back temp key] 2025-10-31 00:12:33.974759 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/0eac492a74214e73aaa54b8d2930cd89/work/0eac492a74214e73aaa54b8d2930cd89_id_rsa (zuul-build-sshkey) 2025-10-31 00:12:33.975006 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.008686 2025-10-31 00:12:33.981757 | 2025-10-31 00:12:33.981841 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-10-31 00:12:34.414994 | debian-bookworm | ok 2025-10-31 00:12:34.420397 | 2025-10-31 00:12:34.420491 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-10-31 00:12:34.468713 | debian-bookworm | skipping: Conditional result was False 2025-10-31 00:12:34.479031 | 2025-10-31 00:12:34.479130 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-10-31 00:12:34.939156 | debian-bookworm | ok 2025-10-31 00:12:34.955301 | 2025-10-31 00:12:34.955398 | TASK [validate-host : Define zuul_info_dir fact] 2025-10-31 00:12:34.982939 | debian-bookworm | ok 2025-10-31 00:12:34.987907 | 2025-10-31 00:12:34.987997 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-10-31 00:12:35.455272 | debian-bookworm -> localhost | ok 2025-10-31 00:12:35.461228 | 2025-10-31 00:12:35.461553 | TASK [validate-host : Collect information about the host] 2025-10-31 00:12:36.503905 | debian-bookworm | ok 2025-10-31 00:12:36.524027 | 2025-10-31 00:12:36.524134 | TASK [validate-host : Sanitize hostname] 2025-10-31 00:12:36.675759 | debian-bookworm | ok 2025-10-31 00:12:36.737298 | 2025-10-31 00:12:36.737405 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-10-31 00:12:37.280163 | debian-bookworm -> localhost | changed 2025-10-31 00:12:37.285570 | 2025-10-31 00:12:37.285671 | TASK [validate-host : Collect information about zuul worker] 2025-10-31 00:12:37.715742 | debian-bookworm | ok 2025-10-31 00:12:37.722046 | 2025-10-31 00:12:37.722153 | TASK [validate-host : Write out all zuul information for each host] 2025-10-31 00:12:38.275293 | debian-bookworm -> localhost | changed 2025-10-31 00:12:38.284124 | 2025-10-31 00:12:38.284218 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-10-31 00:12:38.543946 | debian-bookworm | ok 2025-10-31 00:12:38.552163 | 2025-10-31 00:12:38.552274 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-10-31 00:12:39.318037 | debian-bookworm | changed: 2025-10-31 00:12:39.318233 | debian-bookworm | cd+++++++++ src/ 2025-10-31 00:12:39.318273 | debian-bookworm | cd+++++++++ src/github.com/ 2025-10-31 00:12:39.318303 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-10-31 00:12:39.318330 | debian-bookworm | cd+++++++++ src/github.com/osism/cfg-cookiecutter/ 2025-10-31 00:12:39.318354 | debian-bookworm | Containerfile 2025-10-31 00:12:39.318511 | debian-bookworm | localhost | ok: Runtime: 0:00:00.007131 2025-10-31 00:14:03.722530 | 2025-10-31 00:14:03.722751 | TASK [upload-logs : Upload logs to log server] 2025-10-31 00:14:04.288077 | localhost | Output suppressed because no_log was given 2025-10-31 00:14:04.291488 | 2025-10-31 00:14:04.291737 | LOOP [upload-logs : Compress console log and json output] 2025-10-31 00:14:04.348234 | localhost | skipping: Conditional result was False 2025-10-31 00:14:04.352571 | localhost | skipping: Conditional result was False 2025-10-31 00:14:04.364263 | 2025-10-31 00:14:04.364422 | LOOP [upload-logs : Upload compressed console log and json output] 2025-10-31 00:14:04.420331 | localhost | skipping: Conditional result was False 2025-10-31 00:14:04.420981 | 2025-10-31 00:14:04.424584 | localhost | skipping: Conditional result was False 2025-10-31 00:14:04.437400 | 2025-10-31 00:14:04.437609 | LOOP [upload-logs : Upload console log and json output]