2025-10-18 19:06:53.263803 | Job console starting 2025-10-18 19:06:53.288298 | Updating git repos 2025-10-18 19:06:53.326196 | Cloning repos into workspace 2025-10-18 19:06:53.382786 | Restoring repo states 2025-10-18 19:06:53.390961 | Merging changes 2025-10-18 19:06:53.841221 | Checking out repos 2025-10-18 19:06:53.874276 | Preparing playbooks 2025-10-18 19:06:54.565119 | Running Ansible setup 2025-10-18 19:06:58.970460 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-10-18 19:06:59.791940 | 2025-10-18 19:06:59.792100 | PLAY [Base pre] 2025-10-18 19:06:59.817214 | 2025-10-18 19:06:59.817360 | TASK [Setup log path fact] 2025-10-18 19:06:59.847971 | debian-bookworm | ok 2025-10-18 19:06:59.865312 | 2025-10-18 19:06:59.865467 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-18 19:06:59.895287 | debian-bookworm | ok 2025-10-18 19:06:59.905123 | 2025-10-18 19:06:59.905251 | TASK [emit-job-header : Print job information] 2025-10-18 19:06:59.944076 | # Job Information 2025-10-18 19:06:59.944278 | Ansible Version: 2.16.14 2025-10-18 19:06:59.944322 | Job: python-black 2025-10-18 19:06:59.944354 | Pipeline: check 2025-10-18 19:06:59.944377 | Executor: 521e9411259a 2025-10-18 19:06:59.944397 | Triggered by: https://github.com/osism/node-image/pull/116 2025-10-18 19:06:59.944419 | Event ID: 96fc2530-ac55-11f0-9f6b-6b029eefcfd2 2025-10-18 19:06:59.949623 | 2025-10-18 19:06:59.949742 | LOOP [emit-job-header : Print node information] 2025-10-18 19:07:00.059459 | debian-bookworm | ok: 2025-10-18 19:07:00.059657 | debian-bookworm | # Node Information 2025-10-18 19:07:00.059692 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-10-18 19:07:00.059718 | debian-bookworm | Hostname: debian 2025-10-18 19:07:00.059740 | debian-bookworm | Username: zuul 2025-10-18 19:07:00.059760 | debian-bookworm | Distro: Debian 12.12 2025-10-18 19:07:00.059784 | debian-bookworm | Provider: regiocloud-a 2025-10-18 19:07:00.059805 | debian-bookworm | Region: 2025-10-18 19:07:00.059827 | debian-bookworm | Label: debian-bookworm 2025-10-18 19:07:00.059847 | debian-bookworm | Product Name: OpenStack Nova 2025-10-18 19:07:00.059867 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe52:8696 2025-10-18 19:07:00.078070 | 2025-10-18 19:07:00.078207 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-10-18 19:07:00.547816 | debian-bookworm -> localhost | changed 2025-10-18 19:07:00.555307 | 2025-10-18 19:07:00.555438 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-10-18 19:07:01.602099 | debian-bookworm -> localhost | changed 2025-10-18 19:07:01.613097 | 2025-10-18 19:07:01.613217 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-10-18 19:07:01.892308 | debian-bookworm -> localhost | ok 2025-10-18 19:07:01.900748 | 2025-10-18 19:07:01.900880 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-10-18 19:07:01.923745 | debian-bookworm | ok 2025-10-18 19:07:01.941127 | debian-bookworm | included: /var/lib/zuul/builds/a72d7dfba62e4c189d49fcf043b08fa1/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-10-18 19:07:01.947395 | 2025-10-18 19:07:01.947510 | TASK [add-build-sshkey : Create Temp SSH key] 2025-10-18 19:07:03.783632 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-10-18 19:07:03.783919 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/a72d7dfba62e4c189d49fcf043b08fa1/work/a72d7dfba62e4c189d49fcf043b08fa1_id_rsa 2025-10-18 19:07:03.783968 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/a72d7dfba62e4c189d49fcf043b08fa1/work/a72d7dfba62e4c189d49fcf043b08fa1_id_rsa.pub 2025-10-18 19:07:03.783996 | debian-bookworm -> localhost | The key fingerprint is: 2025-10-18 19:07:03.784022 | debian-bookworm -> localhost | SHA256:5W/rgslgZALXwPyxdOp3DRSAZPW7I5yrrH7nkB+4fyU zuul-build-sshkey 2025-10-18 19:07:03.784046 | debian-bookworm -> localhost | The key's randomart image is: 2025-10-18 19:07:03.784078 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-10-18 19:07:03.784102 | debian-bookworm -> localhost | | o.+ooo... | 2025-10-18 19:07:03.784124 | debian-bookworm -> localhost | | . +.= ... | 2025-10-18 19:07:03.784145 | debian-bookworm -> localhost | | o o = .o | 2025-10-18 19:07:03.784166 | debian-bookworm -> localhost | | . * o.. | 2025-10-18 19:07:03.784186 | debian-bookworm -> localhost | | = S oo | 2025-10-18 19:07:03.784215 | debian-bookworm -> localhost | | ++..Eoo | 2025-10-18 19:07:03.784235 | debian-bookworm -> localhost | | .++=+ooo | 2025-10-18 19:07:03.784255 | debian-bookworm -> localhost | | ..+=+oo . | 2025-10-18 19:07:03.784276 | debian-bookworm -> localhost | | .oo+==. oo | 2025-10-18 19:07:03.784297 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-10-18 19:07:03.784354 | debian-bookworm -> localhost | ok: Runtime: 0:00:01.296082 2025-10-18 19:07:03.791208 | 2025-10-18 19:07:03.791331 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-10-18 19:07:03.820984 | debian-bookworm | ok 2025-10-18 19:07:03.831148 | debian-bookworm | included: /var/lib/zuul/builds/a72d7dfba62e4c189d49fcf043b08fa1/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-10-18 19:07:03.840254 | 2025-10-18 19:07:03.840355 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-10-18 19:07:03.867232 | debian-bookworm | skipping: Conditional result was False 2025-10-18 19:07:03.876045 | 2025-10-18 19:07:03.876176 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-10-18 19:07:04.527149 | debian-bookworm | changed 2025-10-18 19:07:04.535641 | 2025-10-18 19:07:04.535799 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-10-18 19:07:04.816387 | debian-bookworm | ok 2025-10-18 19:07:04.824592 | 2025-10-18 19:07:04.824735 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-10-18 19:07:05.511352 | debian-bookworm | changed 2025-10-18 19:07:05.524164 | 2025-10-18 19:07:05.524302 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-10-18 19:07:06.216851 | debian-bookworm | changed 2025-10-18 19:07:06.222531 | 2025-10-18 19:07:06.222646 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-10-18 19:07:06.246781 | debian-bookworm | skipping: Conditional result was False 2025-10-18 19:07:06.252847 | 2025-10-18 19:07:06.253001 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-10-18 19:07:06.693105 | debian-bookworm -> localhost | changed 2025-10-18 19:07:06.704732 | 2025-10-18 19:07:06.704854 | TASK [add-build-sshkey : Add back temp key] 2025-10-18 19:07:07.024406 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/a72d7dfba62e4c189d49fcf043b08fa1/work/a72d7dfba62e4c189d49fcf043b08fa1_id_rsa (zuul-build-sshkey) 2025-10-18 19:07:07.024651 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.017168 2025-10-18 19:07:07.034247 | 2025-10-18 19:07:07.034382 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-10-18 19:07:07.445650 | debian-bookworm | ok 2025-10-18 19:07:07.452981 | 2025-10-18 19:07:07.453111 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-10-18 19:07:07.476804 | debian-bookworm | skipping: Conditional result was False 2025-10-18 19:07:07.487416 | 2025-10-18 19:07:07.487535 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-10-18 19:07:07.933365 | debian-bookworm | ok 2025-10-18 19:07:07.946885 | 2025-10-18 19:07:07.947060 | TASK [validate-host : Define zuul_info_dir fact] 2025-10-18 19:07:07.979510 | debian-bookworm | ok 2025-10-18 19:07:07.985732 | 2025-10-18 19:07:07.985844 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-10-18 19:07:08.252772 | debian-bookworm -> localhost | ok 2025-10-18 19:07:08.259387 | 2025-10-18 19:07:08.259493 | TASK [validate-host : Collect information about the host] 2025-10-18 19:07:09.476760 | debian-bookworm | ok 2025-10-18 19:07:09.496814 | 2025-10-18 19:07:09.496985 | TASK [validate-host : Sanitize hostname] 2025-10-18 19:07:09.609256 | debian-bookworm | ok 2025-10-18 19:07:09.666636 | 2025-10-18 19:07:09.666779 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-10-18 19:07:10.284591 | debian-bookworm -> localhost | changed 2025-10-18 19:07:10.290554 | 2025-10-18 19:07:10.290679 | TASK [validate-host : Collect information about zuul worker] 2025-10-18 19:07:10.694083 | debian-bookworm | ok 2025-10-18 19:07:10.699252 | 2025-10-18 19:07:10.699379 | TASK [validate-host : Write out all zuul information for each host] 2025-10-18 19:07:11.225620 | debian-bookworm -> localhost | changed 2025-10-18 19:07:11.236880 | 2025-10-18 19:07:11.237007 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-10-18 19:07:11.516694 | debian-bookworm | ok 2025-10-18 19:07:11.529022 | 2025-10-18 19:07:11.529192 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-10-18 19:07:13.187838 | debian-bookworm | changed: 2025-10-18 19:07:13.188051 | debian-bookworm | cd+++++++++ src/ 2025-10-18 19:07:13.188088 | debian-bookworm | cd+++++++++ src/github.com/ 2025-10-18 19:07:13.188112 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-10-18 19:07:13.188134 | debian-bookworm | cd+++++++++ src/github.com/osism/node-image/ 2025-10-18 19:07:13.188154 | debian-bookworm | Containerfile 2025-10-18 19:07:13.188288 | debian-bookworm | localhost | ok: Runtime: 0:00:00.006034 2025-10-18 19:07:38.939008 | 2025-10-18 19:07:38.939169 | TASK [upload-logs : Upload logs to log server] 2025-10-18 19:07:39.420000 | localhost | Output suppressed because no_log was given 2025-10-18 19:07:39.421730 | 2025-10-18 19:07:39.421811 | LOOP [upload-logs : Compress console log and json output] 2025-10-18 19:07:39.473339 | localhost | skipping: Conditional result was False 2025-10-18 19:07:39.480507 | localhost | skipping: Conditional result was False 2025-10-18 19:07:39.487299 | 2025-10-18 19:07:39.487389 | LOOP [upload-logs : Upload compressed console log and json output] 2025-10-18 19:07:39.525490 | localhost | skipping: Conditional result was False 2025-10-18 19:07:39.525697 | 2025-10-18 19:07:39.530578 | localhost | skipping: Conditional result was False 2025-10-18 19:07:39.545011 | 2025-10-18 19:07:39.545214 | LOOP [upload-logs : Upload console log and json output]