2025-10-15 14:50:38.842265 | Job console starting 2025-10-15 14:50:38.877845 | Updating git repos 2025-10-15 14:50:38.908617 | Cloning repos into workspace 2025-10-15 14:50:39.005908 | Restoring repo states 2025-10-15 14:50:39.010079 | Merging changes 2025-10-15 14:50:39.585852 | Checking out repos 2025-10-15 14:50:39.675865 | Preparing playbooks 2025-10-15 14:50:40.341843 | Running Ansible setup 2025-10-15 14:50:44.580876 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-10-15 14:50:45.347149 | 2025-10-15 14:50:45.347322 | PLAY [Base pre] 2025-10-15 14:50:45.366825 | 2025-10-15 14:50:45.366994 | TASK [Setup log path fact] 2025-10-15 14:50:45.396248 | debian-bookworm | ok 2025-10-15 14:50:45.412136 | 2025-10-15 14:50:45.412339 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-15 14:50:45.463389 | debian-bookworm | ok 2025-10-15 14:50:45.474615 | 2025-10-15 14:50:45.474743 | TASK [emit-job-header : Print job information] 2025-10-15 14:50:45.513853 | # Job Information 2025-10-15 14:50:45.514040 | Ansible Version: 2.16.14 2025-10-15 14:50:45.514074 | Job: python-black 2025-10-15 14:50:45.514105 | Pipeline: check 2025-10-15 14:50:45.514127 | Executor: 521e9411259a 2025-10-15 14:50:45.514148 | Triggered by: https://github.com/osism/testbed/pull/2777 2025-10-15 14:50:45.514171 | Event ID: 4b1db920-a9d6-11f0-8877-0434343a64ea 2025-10-15 14:50:45.519098 | 2025-10-15 14:50:45.519250 | LOOP [emit-job-header : Print node information] 2025-10-15 14:50:45.633570 | debian-bookworm | ok: 2025-10-15 14:50:45.633765 | debian-bookworm | # Node Information 2025-10-15 14:50:45.633799 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-10-15 14:50:45.633824 | debian-bookworm | Hostname: debian 2025-10-15 14:50:45.633846 | debian-bookworm | Username: zuul 2025-10-15 14:50:45.633868 | debian-bookworm | Distro: Debian 12.12 2025-10-15 14:50:45.633892 | debian-bookworm | Provider: regiocloud-a 2025-10-15 14:50:45.633913 | debian-bookworm | Region: 2025-10-15 14:50:45.633934 | debian-bookworm | Label: debian-bookworm 2025-10-15 14:50:45.633954 | debian-bookworm | Product Name: OpenStack Nova 2025-10-15 14:50:45.633975 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe20:ff95 2025-10-15 14:50:45.646447 | 2025-10-15 14:50:45.646584 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-10-15 14:50:46.157295 | debian-bookworm -> localhost | changed 2025-10-15 14:50:46.166361 | 2025-10-15 14:50:46.166499 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-10-15 14:50:47.317862 | debian-bookworm -> localhost | changed 2025-10-15 14:50:47.337373 | 2025-10-15 14:50:47.337501 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-10-15 14:50:47.675299 | debian-bookworm -> localhost | ok 2025-10-15 14:50:47.683976 | 2025-10-15 14:50:47.684225 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-10-15 14:50:47.715476 | debian-bookworm | ok 2025-10-15 14:50:47.740884 | debian-bookworm | included: /var/lib/zuul/builds/9a390a29dbb14466a654249b4e8ebb0e/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-10-15 14:50:47.750544 | 2025-10-15 14:50:47.750664 | TASK [add-build-sshkey : Create Temp SSH key] 2025-10-15 14:50:48.896061 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-10-15 14:50:48.896307 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/9a390a29dbb14466a654249b4e8ebb0e/work/9a390a29dbb14466a654249b4e8ebb0e_id_rsa 2025-10-15 14:50:48.896347 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/9a390a29dbb14466a654249b4e8ebb0e/work/9a390a29dbb14466a654249b4e8ebb0e_id_rsa.pub 2025-10-15 14:50:48.896373 | debian-bookworm -> localhost | The key fingerprint is: 2025-10-15 14:50:48.896398 | debian-bookworm -> localhost | SHA256:/3J5+qz1qkWDB6j3934yLTP2JX1v04pdDAgmrvBTZxk zuul-build-sshkey 2025-10-15 14:50:48.896421 | debian-bookworm -> localhost | The key's randomart image is: 2025-10-15 14:50:48.896454 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-10-15 14:50:48.896476 | debian-bookworm -> localhost | | | 2025-10-15 14:50:48.896499 | debian-bookworm -> localhost | | . | 2025-10-15 14:50:48.896519 | debian-bookworm -> localhost | | . E . | 2025-10-15 14:50:48.896539 | debian-bookworm -> localhost | | . + + + | 2025-10-15 14:50:48.896559 | debian-bookworm -> localhost | | . S = o = | 2025-10-15 14:50:48.896590 | debian-bookworm -> localhost | | o o = . o = | 2025-10-15 14:50:48.896611 | debian-bookworm -> localhost | | + . ..=.B| 2025-10-15 14:50:48.896631 | debian-bookworm -> localhost | | . ..oBO*O| 2025-10-15 14:50:48.896652 | debian-bookworm -> localhost | | o*B*%O| 2025-10-15 14:50:48.896671 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-10-15 14:50:48.896730 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.494916 2025-10-15 14:50:48.903272 | 2025-10-15 14:50:48.903376 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-10-15 14:50:48.932246 | debian-bookworm | ok 2025-10-15 14:50:48.941435 | debian-bookworm | included: /var/lib/zuul/builds/9a390a29dbb14466a654249b4e8ebb0e/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-10-15 14:50:48.950393 | 2025-10-15 14:50:48.950486 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-10-15 14:50:48.967206 | debian-bookworm | skipping: Conditional result was False 2025-10-15 14:50:48.973101 | 2025-10-15 14:50:48.973234 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-10-15 14:50:49.606736 | debian-bookworm | changed 2025-10-15 14:50:49.615682 | 2025-10-15 14:50:49.615814 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-10-15 14:50:49.861817 | debian-bookworm | ok 2025-10-15 14:50:49.868371 | 2025-10-15 14:50:49.868488 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-10-15 14:50:50.541428 | debian-bookworm | changed 2025-10-15 14:50:50.554680 | 2025-10-15 14:50:50.554824 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-10-15 14:50:51.184439 | debian-bookworm | changed 2025-10-15 14:50:51.191499 | 2025-10-15 14:50:51.191612 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-10-15 14:50:51.226263 | debian-bookworm | skipping: Conditional result was False 2025-10-15 14:50:51.244568 | 2025-10-15 14:50:51.244748 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-10-15 14:50:51.717972 | debian-bookworm -> localhost | changed 2025-10-15 14:50:51.729192 | 2025-10-15 14:50:51.729314 | TASK [add-build-sshkey : Add back temp key] 2025-10-15 14:50:52.056698 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/9a390a29dbb14466a654249b4e8ebb0e/work/9a390a29dbb14466a654249b4e8ebb0e_id_rsa (zuul-build-sshkey) 2025-10-15 14:50:52.056936 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.011935 2025-10-15 14:50:52.065039 | 2025-10-15 14:50:52.065151 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-10-15 14:50:52.472438 | debian-bookworm | ok 2025-10-15 14:50:52.484906 | 2025-10-15 14:50:52.485039 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-10-15 14:50:52.509842 | debian-bookworm | skipping: Conditional result was False 2025-10-15 14:50:52.520501 | 2025-10-15 14:50:52.520618 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-10-15 14:50:52.965089 | debian-bookworm | ok 2025-10-15 14:50:52.980921 | 2025-10-15 14:50:52.981054 | TASK [validate-host : Define zuul_info_dir fact] 2025-10-15 14:50:53.021407 | debian-bookworm | ok 2025-10-15 14:50:53.026556 | 2025-10-15 14:50:53.026664 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-10-15 14:50:53.327238 | debian-bookworm -> localhost | ok 2025-10-15 14:50:53.333878 | 2025-10-15 14:50:53.333984 | TASK [validate-host : Collect information about the host] 2025-10-15 14:50:54.452805 | debian-bookworm | ok 2025-10-15 14:50:54.464085 | 2025-10-15 14:50:54.464215 | TASK [validate-host : Sanitize hostname] 2025-10-15 14:50:54.564567 | debian-bookworm | ok 2025-10-15 14:50:54.602748 | 2025-10-15 14:50:54.602901 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-10-15 14:50:55.142346 | debian-bookworm -> localhost | changed 2025-10-15 14:50:55.148467 | 2025-10-15 14:50:55.148589 | TASK [validate-host : Collect information about zuul worker] 2025-10-15 14:50:55.571412 | debian-bookworm | ok 2025-10-15 14:50:55.576322 | 2025-10-15 14:50:55.576436 | TASK [validate-host : Write out all zuul information for each host] 2025-10-15 14:50:56.105617 | debian-bookworm -> localhost | changed 2025-10-15 14:50:56.116845 | 2025-10-15 14:50:56.117034 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-10-15 14:50:56.391245 | debian-bookworm | ok 2025-10-15 14:50:56.398552 | 2025-10-15 14:50:56.398665 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-10-15 14:50:58.036528 | debian-bookworm | changed: 2025-10-15 14:50:58.036733 | debian-bookworm | cd+++++++++ src/ 2025-10-15 14:50:58.036769 | debian-bookworm | cd+++++++++ src/github.com/ 2025-10-15 14:50:58.036794 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-10-15 14:50:58.036815 | debian-bookworm | cd+++++++++ src/github.com/osism/testbed/ 2025-10-15 14:50:58.036836 | debian-bookworm | localhost | ok: Runtime: 0:00:00.004885 2025-10-15 14:51:23.515243 | 2025-10-15 14:51:23.515364 | TASK [upload-logs : Upload logs to log server] 2025-10-15 14:51:24.054617 | localhost | Output suppressed because no_log was given 2025-10-15 14:51:24.057948 | 2025-10-15 14:51:24.058190 | LOOP [upload-logs : Compress console log and json output] 2025-10-15 14:51:24.107714 | localhost | skipping: Conditional result was False 2025-10-15 14:51:24.113598 | localhost | skipping: Conditional result was False 2025-10-15 14:51:24.119058 | 2025-10-15 14:51:24.119236 | LOOP [upload-logs : Upload compressed console log and json output] 2025-10-15 14:51:24.163942 | localhost | skipping: Conditional result was False 2025-10-15 14:51:24.164256 | 2025-10-15 14:51:24.169790 | localhost | skipping: Conditional result was False 2025-10-15 14:51:24.177137 | 2025-10-15 14:51:24.177265 | LOOP [upload-logs : Upload console log and json output]