2025-06-26 18:00:07.657881 | Job console starting 2025-06-26 18:00:07.670318 | Updating git repos 2025-06-26 18:00:07.749490 | Cloning repos into workspace 2025-06-26 18:00:07.821896 | Restoring repo states 2025-06-26 18:00:07.836725 | Merging changes 2025-06-26 18:00:08.475318 | Checking out repos 2025-06-26 18:00:08.543738 | Preparing playbooks 2025-06-26 18:00:09.557570 | Running Ansible setup 2025-06-26 18:00:14.870769 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-06-26 18:00:15.720618 | 2025-06-26 18:00:15.720779 | PLAY [Base pre] 2025-06-26 18:00:15.736880 | 2025-06-26 18:00:15.737025 | TASK [Setup log path fact] 2025-06-26 18:00:15.756134 | debian-bookworm | ok 2025-06-26 18:00:15.774322 | 2025-06-26 18:00:15.774480 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-06-26 18:00:15.848589 | debian-bookworm | ok 2025-06-26 18:00:15.858974 | 2025-06-26 18:00:15.859120 | TASK [emit-job-header : Print job information] 2025-06-26 18:00:15.944259 | # Job Information 2025-06-26 18:00:15.944553 | Ansible Version: 2.16.14 2025-06-26 18:00:15.944616 | Job: python-black 2025-06-26 18:00:15.944673 | Pipeline: check 2025-06-26 18:00:15.944713 | Executor: 521e9411259a 2025-06-26 18:00:15.944749 | Triggered by: https://github.com/osism/python-osism/pull/1510 2025-06-26 18:00:15.944789 | Event ID: 5fd65460-52b7-11f0-96e9-85364fea6c3d 2025-06-26 18:00:15.963265 | 2025-06-26 18:00:15.963417 | LOOP [emit-job-header : Print node information] 2025-06-26 18:00:16.186432 | debian-bookworm | ok: 2025-06-26 18:00:16.186717 | debian-bookworm | # Node Information 2025-06-26 18:00:16.186756 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-06-26 18:00:16.186782 | debian-bookworm | Hostname: debian 2025-06-26 18:00:16.186804 | debian-bookworm | Username: zuul 2025-06-26 18:00:16.186825 | debian-bookworm | Distro: Debian 12.11 2025-06-26 18:00:16.186902 | debian-bookworm | Provider: regiocloud-a 2025-06-26 18:00:16.186929 | debian-bookworm | Region: 2025-06-26 18:00:16.186950 | debian-bookworm | Label: debian-bookworm 2025-06-26 18:00:16.186971 | debian-bookworm | Product Name: OpenStack Nova 2025-06-26 18:00:16.186990 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe31:724f 2025-06-26 18:00:16.207866 | 2025-06-26 18:00:16.208005 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-06-26 18:00:16.987421 | debian-bookworm -> localhost | changed 2025-06-26 18:00:17.003678 | 2025-06-26 18:00:17.004381 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-06-26 18:00:18.677764 | debian-bookworm -> localhost | changed 2025-06-26 18:00:18.688538 | 2025-06-26 18:00:18.688665 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-06-26 18:00:19.284045 | debian-bookworm -> localhost | ok 2025-06-26 18:00:19.290589 | 2025-06-26 18:00:19.290724 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-06-26 18:00:19.320782 | debian-bookworm | ok 2025-06-26 18:00:19.350642 | debian-bookworm | included: /var/lib/zuul/builds/7d1cf60c4b224213b1fc52293e3cbd19/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-06-26 18:00:19.365391 | 2025-06-26 18:00:19.365528 | TASK [add-build-sshkey : Create Temp SSH key] 2025-06-26 18:00:21.659921 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-06-26 18:00:21.660119 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/7d1cf60c4b224213b1fc52293e3cbd19/work/7d1cf60c4b224213b1fc52293e3cbd19_id_rsa 2025-06-26 18:00:21.660156 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/7d1cf60c4b224213b1fc52293e3cbd19/work/7d1cf60c4b224213b1fc52293e3cbd19_id_rsa.pub 2025-06-26 18:00:21.660181 | debian-bookworm -> localhost | The key fingerprint is: 2025-06-26 18:00:21.660205 | debian-bookworm -> localhost | SHA256:S7hwfPTQkmakkROUoCB8JBZtn3yNMHC3BffWzmk/rZQ zuul-build-sshkey 2025-06-26 18:00:21.660228 | debian-bookworm -> localhost | The key's randomart image is: 2025-06-26 18:00:21.660258 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-06-26 18:00:21.660279 | debian-bookworm -> localhost | |+++oo++*oo | 2025-06-26 18:00:21.660301 | debian-bookworm -> localhost | |oo.=.o=++o. . | 2025-06-26 18:00:21.660321 | debian-bookworm -> localhost | | + o =oX .o . | 2025-06-26 18:00:21.660340 | debian-bookworm -> localhost | | = B =. o . | 2025-06-26 18:00:21.660360 | debian-bookworm -> localhost | | . = S . = | 2025-06-26 18:00:21.660383 | debian-bookworm -> localhost | | o + . . ...| 2025-06-26 18:00:21.660404 | debian-bookworm -> localhost | | . . Eo.| 2025-06-26 18:00:21.660424 | debian-bookworm -> localhost | | . ..| 2025-06-26 18:00:21.660446 | debian-bookworm -> localhost | | . | 2025-06-26 18:00:21.660466 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-06-26 18:00:21.660512 | debian-bookworm -> localhost | ok: Runtime: 0:00:01.588454 2025-06-26 18:00:21.666598 | 2025-06-26 18:00:21.666698 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-06-26 18:00:21.715044 | debian-bookworm | ok 2025-06-26 18:00:21.734223 | debian-bookworm | included: /var/lib/zuul/builds/7d1cf60c4b224213b1fc52293e3cbd19/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-06-26 18:00:21.745350 | 2025-06-26 18:00:21.745453 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-06-26 18:00:21.778528 | debian-bookworm | skipping: Conditional result was False 2025-06-26 18:00:21.784241 | 2025-06-26 18:00:21.784337 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-06-26 18:00:22.474224 | debian-bookworm | changed 2025-06-26 18:00:22.480903 | 2025-06-26 18:00:22.481010 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-06-26 18:00:22.723237 | debian-bookworm | ok 2025-06-26 18:00:22.732363 | 2025-06-26 18:00:22.732469 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-06-26 18:00:23.387398 | debian-bookworm | changed 2025-06-26 18:00:23.396803 | 2025-06-26 18:00:23.396961 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-06-26 18:00:24.032996 | debian-bookworm | changed 2025-06-26 18:00:24.040431 | 2025-06-26 18:00:24.040529 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-06-26 18:00:24.064364 | debian-bookworm | skipping: Conditional result was False 2025-06-26 18:00:24.073475 | 2025-06-26 18:00:24.073573 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-06-26 18:00:24.493427 | debian-bookworm -> localhost | changed 2025-06-26 18:00:24.505465 | 2025-06-26 18:00:24.505607 | TASK [add-build-sshkey : Add back temp key] 2025-06-26 18:00:24.999458 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/7d1cf60c4b224213b1fc52293e3cbd19/work/7d1cf60c4b224213b1fc52293e3cbd19_id_rsa (zuul-build-sshkey) 2025-06-26 18:00:24.999677 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.024974 2025-06-26 18:00:25.007176 | 2025-06-26 18:00:25.007269 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-06-26 18:00:25.407165 | debian-bookworm | ok 2025-06-26 18:00:25.412506 | 2025-06-26 18:00:25.412600 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-06-26 18:00:25.436032 | debian-bookworm | skipping: Conditional result was False 2025-06-26 18:00:25.446938 | 2025-06-26 18:00:25.447034 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-06-26 18:00:25.883161 | debian-bookworm | ok 2025-06-26 18:00:25.904416 | 2025-06-26 18:00:25.904572 | TASK [validate-host : Define zuul_info_dir fact] 2025-06-26 18:00:25.942887 | debian-bookworm | ok 2025-06-26 18:00:25.948082 | 2025-06-26 18:00:25.948316 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-06-26 18:00:26.381555 | debian-bookworm -> localhost | ok 2025-06-26 18:00:26.388789 | 2025-06-26 18:00:26.388923 | TASK [validate-host : Collect information about the host] 2025-06-26 18:00:27.499091 | debian-bookworm | ok 2025-06-26 18:00:27.519158 | 2025-06-26 18:00:27.519302 | TASK [validate-host : Sanitize hostname] 2025-06-26 18:00:27.651515 | debian-bookworm | ok 2025-06-26 18:00:27.708823 | 2025-06-26 18:00:27.708990 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-06-26 18:00:28.557224 | debian-bookworm -> localhost | changed 2025-06-26 18:00:28.563345 | 2025-06-26 18:00:28.563478 | TASK [validate-host : Collect information about zuul worker] 2025-06-26 18:00:28.992406 | debian-bookworm | ok 2025-06-26 18:00:29.007202 | 2025-06-26 18:00:29.007994 | TASK [validate-host : Write out all zuul information for each host] 2025-06-26 18:00:29.661791 | debian-bookworm -> localhost | changed 2025-06-26 18:00:29.671678 | 2025-06-26 18:00:29.671804 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-06-26 18:00:29.941428 | debian-bookworm | ok 2025-06-26 18:00:29.949292 | 2025-06-26 18:00:29.950071 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-06-26 18:00:31.271416 | debian-bookworm | changed: 2025-06-26 18:00:31.271639 | debian-bookworm | cd+++++++++ src/ 2025-06-26 18:00:31.271676 | debian-bookworm | cd+++++++++ src/github.com/ 2025-06-26 18:00:31.271700 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-06-26 18:00:31.271722 | debian-bookworm | cd+++++++++ src/github.com/osism/python-osism/ 2025-06-26 18:00:31.271742 | debian-bookworm | Containerfile 2025-06-26 18:00:31.271875 | debian-bookworm | localhost | ok: Runtime: 0:00:00.011502 2025-06-26 18:00:57.728792 | 2025-06-26 18:00:57.728991 | TASK [upload-logs : Upload logs to log server] 2025-06-26 18:00:58.330200 | localhost | Output suppressed because no_log was given 2025-06-26 18:00:58.334602 | 2025-06-26 18:00:58.334822 | LOOP [upload-logs : Compress console log and json output] 2025-06-26 18:00:58.384719 | localhost | skipping: Conditional result was False 2025-06-26 18:00:58.390674 | localhost | skipping: Conditional result was False 2025-06-26 18:00:58.403407 | 2025-06-26 18:00:58.403520 | LOOP [upload-logs : Upload compressed console log and json output] 2025-06-26 18:00:58.453976 | localhost | skipping: Conditional result was False 2025-06-26 18:00:58.454274 | 2025-06-26 18:00:58.459322 | localhost | skipping: Conditional result was False 2025-06-26 18:00:58.470620 | 2025-06-26 18:00:58.470919 | LOOP [upload-logs : Upload console log and json output]