2025-08-24 09:36:53.733972 | Job console starting 2025-08-24 09:36:53.760829 | Updating git repos 2025-08-24 09:36:53.864412 | Cloning repos into workspace 2025-08-24 09:36:53.945925 | Restoring repo states 2025-08-24 09:36:53.971288 | Merging changes 2025-08-24 09:36:54.679756 | Checking out repos 2025-08-24 09:36:54.790074 | Preparing playbooks 2025-08-24 09:36:55.651274 | Running Ansible setup 2025-08-24 09:37:01.013309 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-08-24 09:37:01.987707 | 2025-08-24 09:37:01.987818 | PLAY [Base pre] 2025-08-24 09:37:02.000739 | 2025-08-24 09:37:02.000908 | TASK [Setup log path fact] 2025-08-24 09:37:02.024545 | debian-bookworm | ok 2025-08-24 09:37:02.038930 | 2025-08-24 09:37:02.039037 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-08-24 09:37:02.099398 | debian-bookworm | ok 2025-08-24 09:37:02.113799 | 2025-08-24 09:37:02.113899 | TASK [emit-job-header : Print job information] 2025-08-24 09:37:02.150861 | # Job Information 2025-08-24 09:37:02.150994 | Ansible Version: 2.16.14 2025-08-24 09:37:02.151022 | Job: python-black 2025-08-24 09:37:02.151049 | Pipeline: check 2025-08-24 09:37:02.151068 | Executor: 521e9411259a 2025-08-24 09:37:02.151085 | Triggered by: https://github.com/osism/python-osism/pull/1666 2025-08-24 09:37:02.151103 | Event ID: d9442c20-80cd-11f0-8822-4646e6a47d0a 2025-08-24 09:37:02.155003 | 2025-08-24 09:37:02.155080 | LOOP [emit-job-header : Print node information] 2025-08-24 09:37:02.398851 | debian-bookworm | ok: 2025-08-24 09:37:02.399000 | debian-bookworm | # Node Information 2025-08-24 09:37:02.399028 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-08-24 09:37:02.399050 | debian-bookworm | Hostname: debian 2025-08-24 09:37:02.399068 | debian-bookworm | Username: zuul 2025-08-24 09:37:02.399085 | debian-bookworm | Distro: Debian 12.11 2025-08-24 09:37:02.399105 | debian-bookworm | Provider: regiocloud-a 2025-08-24 09:37:02.399122 | debian-bookworm | Region: 2025-08-24 09:37:02.399140 | debian-bookworm | Label: debian-bookworm 2025-08-24 09:37:02.399156 | debian-bookworm | Product Name: OpenStack Nova 2025-08-24 09:37:02.399172 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fedf:59c4 2025-08-24 09:37:02.413092 | 2025-08-24 09:37:02.413179 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-08-24 09:37:03.240385 | debian-bookworm -> localhost | changed 2025-08-24 09:37:03.247154 | 2025-08-24 09:37:03.247252 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-08-24 09:37:04.867345 | debian-bookworm -> localhost | changed 2025-08-24 09:37:04.875731 | 2025-08-24 09:37:04.875807 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-08-24 09:37:05.298718 | debian-bookworm -> localhost | ok 2025-08-24 09:37:05.304887 | 2025-08-24 09:37:05.304992 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-08-24 09:37:05.331677 | debian-bookworm | ok 2025-08-24 09:37:05.355597 | debian-bookworm | included: /var/lib/zuul/builds/0225acb2008e4fc79a802b4231472e03/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-08-24 09:37:05.360430 | 2025-08-24 09:37:05.360507 | TASK [add-build-sshkey : Create Temp SSH key] 2025-08-24 09:37:06.543165 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-08-24 09:37:06.543339 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/0225acb2008e4fc79a802b4231472e03/work/0225acb2008e4fc79a802b4231472e03_id_rsa 2025-08-24 09:37:06.543373 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/0225acb2008e4fc79a802b4231472e03/work/0225acb2008e4fc79a802b4231472e03_id_rsa.pub 2025-08-24 09:37:06.543396 | debian-bookworm -> localhost | The key fingerprint is: 2025-08-24 09:37:06.543427 | debian-bookworm -> localhost | SHA256:fwJ4JHR9jGLfX6cpn7JBb+qIH0B3Mj48iMALq0Vv7NM zuul-build-sshkey 2025-08-24 09:37:06.543447 | debian-bookworm -> localhost | The key's randomart image is: 2025-08-24 09:37:06.543472 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-08-24 09:37:06.543490 | debian-bookworm -> localhost | | . .. o | 2025-08-24 09:37:06.543508 | debian-bookworm -> localhost | | . . .o o o | 2025-08-24 09:37:06.543524 | debian-bookworm -> localhost | | o o ..oo+o. | 2025-08-24 09:37:06.543541 | debian-bookworm -> localhost | | . = o * =.+. o| 2025-08-24 09:37:06.543557 | debian-bookworm -> localhost | | o = o S = .. +.| 2025-08-24 09:37:06.543577 | debian-bookworm -> localhost | | o o . . + +..+ | 2025-08-24 09:37:06.543594 | debian-bookworm -> localhost | |. o E + ooo. | 2025-08-24 09:37:06.543610 | debian-bookworm -> localhost | | . . =.+o | 2025-08-24 09:37:06.543626 | debian-bookworm -> localhost | | ..o.+o | 2025-08-24 09:37:06.543643 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-08-24 09:37:06.543684 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.580801 2025-08-24 09:37:06.549396 | 2025-08-24 09:37:06.549493 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-08-24 09:37:06.565521 | debian-bookworm | ok 2025-08-24 09:37:06.572843 | debian-bookworm | included: /var/lib/zuul/builds/0225acb2008e4fc79a802b4231472e03/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-08-24 09:37:06.583081 | 2025-08-24 09:37:06.583147 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-08-24 09:37:06.595354 | debian-bookworm | skipping: Conditional result was False 2025-08-24 09:37:06.599922 | 2025-08-24 09:37:06.599989 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-08-24 09:37:07.213829 | debian-bookworm | changed 2025-08-24 09:37:07.220168 | 2025-08-24 09:37:07.220260 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-08-24 09:37:07.490518 | debian-bookworm | ok 2025-08-24 09:37:07.496065 | 2025-08-24 09:37:07.496150 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-08-24 09:37:08.117690 | debian-bookworm | changed 2025-08-24 09:37:08.124228 | 2025-08-24 09:37:08.124321 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-08-24 09:37:08.740208 | debian-bookworm | changed 2025-08-24 09:37:08.747437 | 2025-08-24 09:37:08.747544 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-08-24 09:37:08.760618 | debian-bookworm | skipping: Conditional result was False 2025-08-24 09:37:08.772286 | 2025-08-24 09:37:08.772370 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-08-24 09:37:09.260125 | debian-bookworm -> localhost | changed 2025-08-24 09:37:09.268687 | 2025-08-24 09:37:09.268766 | TASK [add-build-sshkey : Add back temp key] 2025-08-24 09:37:09.725043 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/0225acb2008e4fc79a802b4231472e03/work/0225acb2008e4fc79a802b4231472e03_id_rsa (zuul-build-sshkey) 2025-08-24 09:37:09.725218 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.015419 2025-08-24 09:37:09.736805 | 2025-08-24 09:37:09.736891 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-08-24 09:37:10.217204 | debian-bookworm | ok 2025-08-24 09:37:10.222195 | 2025-08-24 09:37:10.222287 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-08-24 09:37:10.249675 | debian-bookworm | skipping: Conditional result was False 2025-08-24 09:37:10.268515 | 2025-08-24 09:37:10.268622 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-08-24 09:37:10.703002 | debian-bookworm | ok 2025-08-24 09:37:10.717540 | 2025-08-24 09:37:10.717639 | TASK [validate-host : Define zuul_info_dir fact] 2025-08-24 09:37:10.736088 | debian-bookworm | ok 2025-08-24 09:37:10.741073 | 2025-08-24 09:37:10.741156 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-08-24 09:37:11.016299 | debian-bookworm -> localhost | ok 2025-08-24 09:37:11.022574 | 2025-08-24 09:37:11.022666 | TASK [validate-host : Collect information about the host] 2025-08-24 09:37:12.168011 | debian-bookworm | ok 2025-08-24 09:37:12.176322 | 2025-08-24 09:37:12.176419 | TASK [validate-host : Sanitize hostname] 2025-08-24 09:37:12.263702 | debian-bookworm | ok 2025-08-24 09:37:12.290138 | 2025-08-24 09:37:12.290225 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-08-24 09:37:12.986738 | debian-bookworm -> localhost | changed 2025-08-24 09:37:12.991189 | 2025-08-24 09:37:12.991268 | TASK [validate-host : Collect information about zuul worker] 2025-08-24 09:37:13.457664 | debian-bookworm | ok 2025-08-24 09:37:13.465576 | 2025-08-24 09:37:13.465662 | TASK [validate-host : Write out all zuul information for each host] 2025-08-24 09:37:14.225105 | debian-bookworm -> localhost | changed 2025-08-24 09:37:14.248813 | 2025-08-24 09:37:14.248924 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-08-24 09:37:14.527395 | debian-bookworm | ok 2025-08-24 09:37:14.531754 | 2025-08-24 09:37:14.531828 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-08-24 09:37:15.512010 | debian-bookworm | changed: 2025-08-24 09:37:15.512168 | debian-bookworm | cd+++++++++ src/ 2025-08-24 09:37:15.512201 | debian-bookworm | cd+++++++++ src/github.com/ 2025-08-24 09:37:15.512225 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-08-24 09:37:15.512247 | debian-bookworm | cd+++++++++ src/github.com/osism/python-osism/ 2025-08-24 09:37:15.512267 | debian-bookworm | Containerfile 2025-08-24 09:37:15.512380 | debian-bookworm | Containerfile 2025-08-24 09:37:15.514418 | debian-bookworm | localhost | ok: Runtime: 0:00:00.007877 2025-08-24 09:37:41.474741 | 2025-08-24 09:37:41.474872 | TASK [upload-logs : Upload logs to log server] 2025-08-24 09:37:42.028689 | localhost | Output suppressed because no_log was given 2025-08-24 09:37:42.032799 | 2025-08-24 09:37:42.032988 | LOOP [upload-logs : Compress console log and json output] 2025-08-24 09:37:42.093648 | localhost | skipping: Conditional result was False 2025-08-24 09:37:42.098886 | localhost | skipping: Conditional result was False 2025-08-24 09:37:42.104796 | 2025-08-24 09:37:42.104969 | LOOP [upload-logs : Upload compressed console log and json output] 2025-08-24 09:37:42.153332 | localhost | skipping: Conditional result was False 2025-08-24 09:37:42.153948 | 2025-08-24 09:37:42.157524 | localhost | skipping: Conditional result was False 2025-08-24 09:37:42.171079 | 2025-08-24 09:37:42.171310 | LOOP [upload-logs : Upload console log and json output]