2025-08-31 09:19:41.603951 | Job console starting 2025-08-31 09:19:41.629758 | Updating git repos 2025-08-31 09:19:41.709422 | Cloning repos into workspace 2025-08-31 09:19:41.792081 | Restoring repo states 2025-08-31 09:19:41.803156 | Merging changes 2025-08-31 09:19:42.399748 | Checking out repos 2025-08-31 09:19:42.449549 | Preparing playbooks 2025-08-31 09:19:43.536607 | Running Ansible setup 2025-08-31 09:19:49.422675 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-08-31 09:19:50.605025 | 2025-08-31 09:19:50.605138 | PLAY [Base pre] 2025-08-31 09:19:50.624837 | 2025-08-31 09:19:50.624935 | TASK [Setup log path fact] 2025-08-31 09:19:50.641649 | debian-bookworm | ok 2025-08-31 09:19:50.653910 | 2025-08-31 09:19:50.654031 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-08-31 09:19:50.720087 | debian-bookworm | ok 2025-08-31 09:19:50.739975 | 2025-08-31 09:19:50.740107 | TASK [emit-job-header : Print job information] 2025-08-31 09:19:50.798254 | # Job Information 2025-08-31 09:19:50.798429 | Ansible Version: 2.16.14 2025-08-31 09:19:50.798458 | Job: python-black 2025-08-31 09:19:50.798489 | Pipeline: check 2025-08-31 09:19:50.798508 | Executor: 521e9411259a 2025-08-31 09:19:50.798526 | Triggered by: https://github.com/osism/python-osism/pull/1703 2025-08-31 09:19:50.798545 | Event ID: 9afce6e0-864b-11f0-9b91-2a400b7bd55d 2025-08-31 09:19:50.802728 | 2025-08-31 09:19:50.802842 | LOOP [emit-job-header : Print node information] 2025-08-31 09:19:50.973303 | debian-bookworm | ok: 2025-08-31 09:19:50.973464 | debian-bookworm | # Node Information 2025-08-31 09:19:50.973498 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-08-31 09:19:50.973523 | debian-bookworm | Hostname: debian 2025-08-31 09:19:50.973544 | debian-bookworm | Username: zuul 2025-08-31 09:19:50.973564 | debian-bookworm | Distro: Debian 12.11 2025-08-31 09:19:50.973588 | debian-bookworm | Provider: regiocloud-a 2025-08-31 09:19:50.973608 | debian-bookworm | Region: 2025-08-31 09:19:50.973662 | debian-bookworm | Label: debian-bookworm 2025-08-31 09:19:50.973716 | debian-bookworm | Product Name: OpenStack Nova 2025-08-31 09:19:50.973740 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe49:f51b 2025-08-31 09:19:50.992082 | 2025-08-31 09:19:50.992176 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-08-31 09:19:51.651135 | debian-bookworm -> localhost | changed 2025-08-31 09:19:51.656638 | 2025-08-31 09:19:51.656719 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-08-31 09:19:53.300285 | debian-bookworm -> localhost | changed 2025-08-31 09:19:53.308852 | 2025-08-31 09:19:53.308932 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-08-31 09:19:53.751844 | debian-bookworm -> localhost | ok 2025-08-31 09:19:53.756630 | 2025-08-31 09:19:53.756711 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-08-31 09:19:53.793183 | debian-bookworm | ok 2025-08-31 09:19:53.814921 | debian-bookworm | included: /var/lib/zuul/builds/090930bf159b4d7cb2cdc169f481646b/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-08-31 09:19:53.826165 | 2025-08-31 09:19:53.826248 | TASK [add-build-sshkey : Create Temp SSH key] 2025-08-31 09:19:56.027058 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-08-31 09:19:56.027240 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/090930bf159b4d7cb2cdc169f481646b/work/090930bf159b4d7cb2cdc169f481646b_id_rsa 2025-08-31 09:19:56.027279 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/090930bf159b4d7cb2cdc169f481646b/work/090930bf159b4d7cb2cdc169f481646b_id_rsa.pub 2025-08-31 09:19:56.027305 | debian-bookworm -> localhost | The key fingerprint is: 2025-08-31 09:19:56.027328 | debian-bookworm -> localhost | SHA256:82y1mlipUP5EZiSTuV7jHyc8Z6GRyPXh7aG6WCmwAg0 zuul-build-sshkey 2025-08-31 09:19:56.027350 | debian-bookworm -> localhost | The key's randomart image is: 2025-08-31 09:19:56.027381 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-08-31 09:19:56.027402 | debian-bookworm -> localhost | | | 2025-08-31 09:19:56.027423 | debian-bookworm -> localhost | | o | 2025-08-31 09:19:56.027444 | debian-bookworm -> localhost | | E = . . . | 2025-08-31 09:19:56.027463 | debian-bookworm -> localhost | | o * o + o | 2025-08-31 09:19:56.027483 | debian-bookworm -> localhost | | . . .S O + +..| 2025-08-31 09:19:56.027508 | debian-bookworm -> localhost | | . +oX =.+.o.| 2025-08-31 09:19:56.027528 | debian-bookworm -> localhost | | ...o.OoB.+ .| 2025-08-31 09:19:56.027547 | debian-bookworm -> localhost | | .. B++.B | 2025-08-31 09:19:56.027567 | debian-bookworm -> localhost | | o.+oo | 2025-08-31 09:19:56.027587 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-08-31 09:19:56.027637 | debian-bookworm -> localhost | ok: Runtime: 0:00:01.436961 2025-08-31 09:19:56.033632 | 2025-08-31 09:19:56.033719 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-08-31 09:19:56.051219 | debian-bookworm | ok 2025-08-31 09:19:56.059951 | debian-bookworm | included: /var/lib/zuul/builds/090930bf159b4d7cb2cdc169f481646b/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-08-31 09:19:56.068727 | 2025-08-31 09:19:56.068806 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-08-31 09:19:56.091512 | debian-bookworm | skipping: Conditional result was False 2025-08-31 09:19:56.096907 | 2025-08-31 09:19:56.096988 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-08-31 09:19:56.702467 | debian-bookworm | changed 2025-08-31 09:19:56.708593 | 2025-08-31 09:19:56.708727 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-08-31 09:19:56.978778 | debian-bookworm | ok 2025-08-31 09:19:56.986694 | 2025-08-31 09:19:56.986786 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-08-31 09:19:57.619647 | debian-bookworm | changed 2025-08-31 09:19:57.636097 | 2025-08-31 09:19:57.636203 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-08-31 09:19:58.286150 | debian-bookworm | changed 2025-08-31 09:19:58.291925 | 2025-08-31 09:19:58.292040 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-08-31 09:19:58.306160 | debian-bookworm | skipping: Conditional result was False 2025-08-31 09:19:58.313992 | 2025-08-31 09:19:58.314115 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-08-31 09:19:58.901252 | debian-bookworm -> localhost | changed 2025-08-31 09:19:58.912524 | 2025-08-31 09:19:58.912622 | TASK [add-build-sshkey : Add back temp key] 2025-08-31 09:19:59.235275 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/090930bf159b4d7cb2cdc169f481646b/work/090930bf159b4d7cb2cdc169f481646b_id_rsa (zuul-build-sshkey) 2025-08-31 09:19:59.235463 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.009825 2025-08-31 09:19:59.242643 | 2025-08-31 09:19:59.242721 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-08-31 09:19:59.637283 | debian-bookworm | ok 2025-08-31 09:19:59.641254 | 2025-08-31 09:19:59.641326 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-08-31 09:19:59.681490 | debian-bookworm | skipping: Conditional result was False 2025-08-31 09:19:59.690037 | 2025-08-31 09:19:59.690123 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-08-31 09:20:00.082544 | debian-bookworm | ok 2025-08-31 09:20:00.093279 | 2025-08-31 09:20:00.093365 | TASK [validate-host : Define zuul_info_dir fact] 2025-08-31 09:20:00.123608 | debian-bookworm | ok 2025-08-31 09:20:00.129804 | 2025-08-31 09:20:00.129897 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-08-31 09:20:00.386188 | debian-bookworm -> localhost | ok 2025-08-31 09:20:00.395808 | 2025-08-31 09:20:00.395926 | TASK [validate-host : Collect information about the host] 2025-08-31 09:20:01.429114 | debian-bookworm | ok 2025-08-31 09:20:01.438198 | 2025-08-31 09:20:01.438284 | TASK [validate-host : Sanitize hostname] 2025-08-31 09:20:01.537223 | debian-bookworm | ok 2025-08-31 09:20:01.576030 | 2025-08-31 09:20:01.576133 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-08-31 09:20:02.265632 | debian-bookworm -> localhost | changed 2025-08-31 09:20:02.271087 | 2025-08-31 09:20:02.271190 | TASK [validate-host : Collect information about zuul worker] 2025-08-31 09:20:02.776517 | debian-bookworm | ok 2025-08-31 09:20:02.780881 | 2025-08-31 09:20:02.780971 | TASK [validate-host : Write out all zuul information for each host] 2025-08-31 09:20:03.492825 | debian-bookworm -> localhost | changed 2025-08-31 09:20:03.502916 | 2025-08-31 09:20:03.506193 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-08-31 09:20:03.823274 | debian-bookworm | ok 2025-08-31 09:20:03.828463 | 2025-08-31 09:20:03.828547 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-08-31 09:20:04.849861 | debian-bookworm | changed: 2025-08-31 09:20:04.850039 | debian-bookworm | cd+++++++++ src/ 2025-08-31 09:20:04.850075 | debian-bookworm | cd+++++++++ src/github.com/ 2025-08-31 09:20:04.850100 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-08-31 09:20:04.850122 | debian-bookworm | cd+++++++++ src/github.com/osism/python-osism/ 2025-08-31 09:20:04.850143 | debian-bookworm | Containerfile 2025-08-31 09:20:04.850261 | debian-bookworm | Containerfile 2025-08-31 09:20:04.884585 | debian-bookworm | localhost | ok: Runtime: 0:00:00.004411 2025-08-31 09:20:30.852226 | 2025-08-31 09:20:30.852350 | TASK [upload-logs : Upload logs to log server] 2025-08-31 09:20:31.408542 | localhost | Output suppressed because no_log was given 2025-08-31 09:20:31.411382 | 2025-08-31 09:20:31.411536 | LOOP [upload-logs : Compress console log and json output] 2025-08-31 09:20:31.474973 | localhost | skipping: Conditional result was False 2025-08-31 09:20:31.478152 | localhost | skipping: Conditional result was False 2025-08-31 09:20:31.483663 | 2025-08-31 09:20:31.483850 | LOOP [upload-logs : Upload compressed console log and json output] 2025-08-31 09:20:31.532878 | localhost | skipping: Conditional result was False 2025-08-31 09:20:31.533607 | 2025-08-31 09:20:31.536646 | localhost | skipping: Conditional result was False 2025-08-31 09:20:31.544151 | 2025-08-31 09:20:31.544334 | LOOP [upload-logs : Upload console log and json output]