2025-09-02 09:50:29.638748 | Job console starting 2025-09-02 09:50:29.687082 | Updating git repos 2025-09-02 09:50:29.762592 | Cloning repos into workspace 2025-09-02 09:50:29.838000 | Restoring repo states 2025-09-02 09:50:29.851647 | Merging changes 2025-09-02 09:50:30.521016 | Checking out repos 2025-09-02 09:50:30.588745 | Preparing playbooks 2025-09-02 09:50:31.651633 | Running Ansible setup 2025-09-02 09:50:37.142730 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-09-02 09:50:38.134096 | 2025-09-02 09:50:38.134269 | PLAY [Base pre] 2025-09-02 09:50:38.181561 | 2025-09-02 09:50:38.181731 | TASK [Setup log path fact] 2025-09-02 09:50:38.214792 | debian-bookworm | ok 2025-09-02 09:50:38.248728 | 2025-09-02 09:50:38.248917 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-09-02 09:50:38.309988 | debian-bookworm | ok 2025-09-02 09:50:38.327912 | 2025-09-02 09:50:38.328051 | TASK [emit-job-header : Print job information] 2025-09-02 09:50:38.405307 | # Job Information 2025-09-02 09:50:38.405607 | Ansible Version: 2.16.14 2025-09-02 09:50:38.405652 | Job: python-black 2025-09-02 09:50:38.405686 | Pipeline: check 2025-09-02 09:50:38.405708 | Executor: 521e9411259a 2025-09-02 09:50:38.405729 | Triggered by: https://github.com/osism/python-osism/pull/1682 2025-09-02 09:50:38.405754 | Event ID: 3d1e9b20-87e2-11f0-9b1a-7b350cb274cd 2025-09-02 09:50:38.410714 | 2025-09-02 09:50:38.413256 | LOOP [emit-job-header : Print node information] 2025-09-02 09:50:38.692630 | debian-bookworm | ok: 2025-09-02 09:50:38.692830 | debian-bookworm | # Node Information 2025-09-02 09:50:38.692866 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-09-02 09:50:38.692893 | debian-bookworm | Hostname: debian 2025-09-02 09:50:38.692915 | debian-bookworm | Username: zuul 2025-09-02 09:50:38.692936 | debian-bookworm | Distro: Debian 12.11 2025-09-02 09:50:38.692960 | debian-bookworm | Provider: regiocloud-a 2025-09-02 09:50:38.692981 | debian-bookworm | Region: 2025-09-02 09:50:38.693004 | debian-bookworm | Label: debian-bookworm 2025-09-02 09:50:38.693025 | debian-bookworm | Product Name: OpenStack Nova 2025-09-02 09:50:38.693044 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe29:a3b3 2025-09-02 09:50:38.723620 | 2025-09-02 09:50:38.723769 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-09-02 09:50:39.531247 | debian-bookworm -> localhost | changed 2025-09-02 09:50:39.538657 | 2025-09-02 09:50:39.538788 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-09-02 09:50:41.115330 | debian-bookworm -> localhost | changed 2025-09-02 09:50:41.125480 | 2025-09-02 09:50:41.125644 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-09-02 09:50:41.772976 | debian-bookworm -> localhost | ok 2025-09-02 09:50:41.778761 | 2025-09-02 09:50:41.778872 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-09-02 09:50:41.816679 | debian-bookworm | ok 2025-09-02 09:50:41.857299 | debian-bookworm | included: /var/lib/zuul/builds/ef28e189f8e143a9b54bfa758737bfc5/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-09-02 09:50:41.863130 | 2025-09-02 09:50:41.863220 | TASK [add-build-sshkey : Create Temp SSH key] 2025-09-02 09:50:43.697024 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-09-02 09:50:43.697217 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/ef28e189f8e143a9b54bfa758737bfc5/work/ef28e189f8e143a9b54bfa758737bfc5_id_rsa 2025-09-02 09:50:43.697256 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/ef28e189f8e143a9b54bfa758737bfc5/work/ef28e189f8e143a9b54bfa758737bfc5_id_rsa.pub 2025-09-02 09:50:43.697283 | debian-bookworm -> localhost | The key fingerprint is: 2025-09-02 09:50:43.697309 | debian-bookworm -> localhost | SHA256:EkEQFP0L3eH6wFvRUGeQUPdh/4WSIQhDjxxGSpprF1Y zuul-build-sshkey 2025-09-02 09:50:43.697332 | debian-bookworm -> localhost | The key's randomart image is: 2025-09-02 09:50:43.697363 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-09-02 09:50:43.697386 | debian-bookworm -> localhost | | .*OE. .o+=o+o | 2025-09-02 09:50:43.697407 | debian-bookworm -> localhost | | + =.*. o..*.oo| 2025-09-02 09:50:43.697427 | debian-bookworm -> localhost | | o + +o.o +o . +| 2025-09-02 09:50:43.697448 | debian-bookworm -> localhost | | o ...o + .. o| 2025-09-02 09:50:43.697468 | debian-bookworm -> localhost | | o . .oSo . .| 2025-09-02 09:50:43.697492 | debian-bookworm -> localhost | | . . .= . | 2025-09-02 09:50:43.697514 | debian-bookworm -> localhost | | = | 2025-09-02 09:50:43.697558 | debian-bookworm -> localhost | | . . | 2025-09-02 09:50:43.697582 | debian-bookworm -> localhost | | | 2025-09-02 09:50:43.697603 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-09-02 09:50:43.697658 | debian-bookworm -> localhost | ok: Runtime: 0:00:01.150304 2025-09-02 09:50:43.703622 | 2025-09-02 09:50:43.703705 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-09-02 09:50:43.740925 | debian-bookworm | ok 2025-09-02 09:50:43.757681 | debian-bookworm | included: /var/lib/zuul/builds/ef28e189f8e143a9b54bfa758737bfc5/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-09-02 09:50:43.782608 | 2025-09-02 09:50:43.782705 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-09-02 09:50:43.813161 | debian-bookworm | skipping: Conditional result was False 2025-09-02 09:50:43.818653 | 2025-09-02 09:50:43.818740 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-09-02 09:50:44.641422 | debian-bookworm | changed 2025-09-02 09:50:44.646583 | 2025-09-02 09:50:44.646665 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-09-02 09:50:44.927485 | debian-bookworm | ok 2025-09-02 09:50:44.932835 | 2025-09-02 09:50:44.932922 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-09-02 09:50:45.630094 | debian-bookworm | changed 2025-09-02 09:50:45.635464 | 2025-09-02 09:50:45.635555 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-09-02 09:50:46.264424 | debian-bookworm | changed 2025-09-02 09:50:46.271453 | 2025-09-02 09:50:46.271631 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-09-02 09:50:46.294864 | debian-bookworm | skipping: Conditional result was False 2025-09-02 09:50:46.303598 | 2025-09-02 09:50:46.303710 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-09-02 09:50:46.731435 | debian-bookworm -> localhost | changed 2025-09-02 09:50:46.741713 | 2025-09-02 09:50:46.741804 | TASK [add-build-sshkey : Add back temp key] 2025-09-02 09:50:47.017835 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/ef28e189f8e143a9b54bfa758737bfc5/work/ef28e189f8e143a9b54bfa758737bfc5_id_rsa (zuul-build-sshkey) 2025-09-02 09:50:47.018047 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.008519 2025-09-02 09:50:47.025339 | 2025-09-02 09:50:47.025423 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-09-02 09:50:47.414932 | debian-bookworm | ok 2025-09-02 09:50:47.422477 | 2025-09-02 09:50:47.422639 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-09-02 09:50:47.446114 | debian-bookworm | skipping: Conditional result was False 2025-09-02 09:50:47.458399 | 2025-09-02 09:50:47.458502 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-09-02 09:50:47.850542 | debian-bookworm | ok 2025-09-02 09:50:47.861971 | 2025-09-02 09:50:47.862115 | TASK [validate-host : Define zuul_info_dir fact] 2025-09-02 09:50:47.910984 | debian-bookworm | ok 2025-09-02 09:50:47.915910 | 2025-09-02 09:50:47.916000 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-09-02 09:50:48.266636 | debian-bookworm -> localhost | ok 2025-09-02 09:50:48.272818 | 2025-09-02 09:50:48.272908 | TASK [validate-host : Collect information about the host] 2025-09-02 09:50:49.554097 | debian-bookworm | ok 2025-09-02 09:50:49.581963 | 2025-09-02 09:50:49.582068 | TASK [validate-host : Sanitize hostname] 2025-09-02 09:50:49.705097 | debian-bookworm | ok 2025-09-02 09:50:49.738889 | 2025-09-02 09:50:49.738997 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-09-02 09:50:50.542607 | debian-bookworm -> localhost | changed 2025-09-02 09:50:50.548146 | 2025-09-02 09:50:50.548243 | TASK [validate-host : Collect information about zuul worker] 2025-09-02 09:50:50.959937 | debian-bookworm | ok 2025-09-02 09:50:50.964386 | 2025-09-02 09:50:50.964478 | TASK [validate-host : Write out all zuul information for each host] 2025-09-02 09:50:51.489805 | debian-bookworm -> localhost | changed 2025-09-02 09:50:51.502034 | 2025-09-02 09:50:51.502143 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-09-02 09:50:51.764168 | debian-bookworm | ok 2025-09-02 09:50:51.769344 | 2025-09-02 09:50:51.769433 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-09-02 09:50:52.984887 | debian-bookworm | changed: 2025-09-02 09:50:52.985110 | debian-bookworm | cd+++++++++ src/ 2025-09-02 09:50:52.985147 | debian-bookworm | cd+++++++++ src/github.com/ 2025-09-02 09:50:52.985171 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-09-02 09:50:52.985193 | debian-bookworm | cd+++++++++ src/github.com/osism/python-osism/ 2025-09-02 09:50:52.985214 | debian-bookworm | Containerfile 2025-09-02 09:50:52.985327 | debian-bookworm | Containerfile 2025-09-02 09:50:53.024782 | debian-bookworm | localhost | ok: Runtime: 0:00:00.007605 2025-09-02 09:51:19.553615 | 2025-09-02 09:51:19.553804 | TASK [upload-logs : Upload logs to log server] 2025-09-02 09:51:20.126895 | localhost | Output suppressed because no_log was given 2025-09-02 09:51:20.129840 | 2025-09-02 09:51:20.130007 | LOOP [upload-logs : Compress console log and json output] 2025-09-02 09:51:20.192816 | localhost | skipping: Conditional result was False 2025-09-02 09:51:20.197841 | localhost | skipping: Conditional result was False 2025-09-02 09:51:20.212149 | 2025-09-02 09:51:20.212363 | LOOP [upload-logs : Upload compressed console log and json output] 2025-09-02 09:51:20.260508 | localhost | skipping: Conditional result was False 2025-09-02 09:51:20.261175 | 2025-09-02 09:51:20.264721 | localhost | skipping: Conditional result was False 2025-09-02 09:51:20.281082 | 2025-09-02 09:51:20.281341 | LOOP [upload-logs : Upload console log and json output]