2025-10-09 22:46:17.902214 | Job console starting 2025-10-09 22:46:17.926000 | Updating git repos 2025-10-09 22:46:17.959596 | Cloning repos into workspace 2025-10-09 22:46:18.020346 | Restoring repo states 2025-10-09 22:46:18.025268 | Merging changes 2025-10-09 22:46:18.556018 | Checking out repos 2025-10-09 22:46:18.634881 | Preparing playbooks 2025-10-09 22:46:19.240911 | Running Ansible setup 2025-10-09 22:46:23.649428 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-10-09 22:46:24.398215 | 2025-10-09 22:46:24.398395 | PLAY [Base pre] 2025-10-09 22:46:24.420075 | 2025-10-09 22:46:24.420222 | TASK [Setup log path fact] 2025-10-09 22:46:24.439962 | debian-bookworm | ok 2025-10-09 22:46:24.454895 | 2025-10-09 22:46:24.455044 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-09 22:46:24.484648 | debian-bookworm | ok 2025-10-09 22:46:24.494470 | 2025-10-09 22:46:24.494587 | TASK [emit-job-header : Print job information] 2025-10-09 22:46:24.534092 | # Job Information 2025-10-09 22:46:24.534298 | Ansible Version: 2.16.14 2025-10-09 22:46:24.534335 | Job: python-black 2025-10-09 22:46:24.534368 | Pipeline: check 2025-10-09 22:46:24.534391 | Executor: 521e9411259a 2025-10-09 22:46:24.534412 | Triggered by: https://github.com/osism/testbed/pull/2777 2025-10-09 22:46:24.534435 | Event ID: bf7768d0-a561-11f0-979b-bd437f3d447c 2025-10-09 22:46:24.539398 | 2025-10-09 22:46:24.539513 | LOOP [emit-job-header : Print node information] 2025-10-09 22:46:24.649038 | debian-bookworm | ok: 2025-10-09 22:46:24.649307 | debian-bookworm | # Node Information 2025-10-09 22:46:24.649344 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-10-09 22:46:24.649368 | debian-bookworm | Hostname: debian 2025-10-09 22:46:24.649390 | debian-bookworm | Username: zuul 2025-10-09 22:46:24.649410 | debian-bookworm | Distro: Debian 12.12 2025-10-09 22:46:24.649434 | debian-bookworm | Provider: regiocloud-a 2025-10-09 22:46:24.649455 | debian-bookworm | Region: 2025-10-09 22:46:24.649476 | debian-bookworm | Label: debian-bookworm 2025-10-09 22:46:24.649495 | debian-bookworm | Product Name: OpenStack Nova 2025-10-09 22:46:24.649514 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fedc:7bbe 2025-10-09 22:46:24.669441 | 2025-10-09 22:46:24.669580 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-10-09 22:46:25.192405 | debian-bookworm -> localhost | changed 2025-10-09 22:46:25.199930 | 2025-10-09 22:46:25.200067 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-10-09 22:46:26.321783 | debian-bookworm -> localhost | changed 2025-10-09 22:46:26.334584 | 2025-10-09 22:46:26.334738 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-10-09 22:46:26.652729 | debian-bookworm -> localhost | ok 2025-10-09 22:46:26.659010 | 2025-10-09 22:46:26.659139 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-10-09 22:46:26.680485 | debian-bookworm | ok 2025-10-09 22:46:26.701343 | debian-bookworm | included: /var/lib/zuul/builds/66b791d74894477aac96ee5f37c92940/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-10-09 22:46:26.707539 | 2025-10-09 22:46:26.707650 | TASK [add-build-sshkey : Create Temp SSH key] 2025-10-09 22:46:28.611502 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-10-09 22:46:28.611703 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/66b791d74894477aac96ee5f37c92940/work/66b791d74894477aac96ee5f37c92940_id_rsa 2025-10-09 22:46:28.611746 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/66b791d74894477aac96ee5f37c92940/work/66b791d74894477aac96ee5f37c92940_id_rsa.pub 2025-10-09 22:46:28.611774 | debian-bookworm -> localhost | The key fingerprint is: 2025-10-09 22:46:28.611801 | debian-bookworm -> localhost | SHA256:zfF0fVQLl12MZ+RKpM76PqSW9kAusBzBQGmSRwRoz4g zuul-build-sshkey 2025-10-09 22:46:28.611824 | debian-bookworm -> localhost | The key's randomart image is: 2025-10-09 22:46:28.611854 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-10-09 22:46:28.611876 | debian-bookworm -> localhost | | ..*=. ..=X| 2025-10-09 22:46:28.611898 | debian-bookworm -> localhost | |..o +o o+=*| 2025-10-09 22:46:28.611919 | debian-bookworm -> localhost | |o ++ o . o o++| 2025-10-09 22:46:28.611939 | debian-bookworm -> localhost | |E. o . o * o ..| 2025-10-09 22:46:28.611959 | debian-bookworm -> localhost | | o S + + . | 2025-10-09 22:46:28.611986 | debian-bookworm -> localhost | | . + o .. | 2025-10-09 22:46:28.612007 | debian-bookworm -> localhost | | o . ++ | 2025-10-09 22:46:28.612027 | debian-bookworm -> localhost | | .=o. | 2025-10-09 22:46:28.612047 | debian-bookworm -> localhost | | o o+. | 2025-10-09 22:46:28.612067 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-10-09 22:46:28.612116 | debian-bookworm -> localhost | ok: Runtime: 0:00:01.400626 2025-10-09 22:46:28.618308 | 2025-10-09 22:46:28.618398 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-10-09 22:46:28.645389 | debian-bookworm | ok 2025-10-09 22:46:28.653907 | debian-bookworm | included: /var/lib/zuul/builds/66b791d74894477aac96ee5f37c92940/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-10-09 22:46:28.662480 | 2025-10-09 22:46:28.662556 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-10-09 22:46:28.685541 | debian-bookworm | skipping: Conditional result was False 2025-10-09 22:46:28.694579 | 2025-10-09 22:46:28.694664 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-10-09 22:46:29.357773 | debian-bookworm | changed 2025-10-09 22:46:29.366243 | 2025-10-09 22:46:29.366369 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-10-09 22:46:29.643873 | debian-bookworm | ok 2025-10-09 22:46:29.652670 | 2025-10-09 22:46:29.652800 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-10-09 22:46:30.347960 | debian-bookworm | changed 2025-10-09 22:46:30.358100 | 2025-10-09 22:46:30.358216 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-10-09 22:46:31.024387 | debian-bookworm | changed 2025-10-09 22:46:31.030301 | 2025-10-09 22:46:31.030392 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-10-09 22:46:31.053301 | debian-bookworm | skipping: Conditional result was False 2025-10-09 22:46:31.059641 | 2025-10-09 22:46:31.059733 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-10-09 22:46:31.439229 | debian-bookworm -> localhost | changed 2025-10-09 22:46:31.449761 | 2025-10-09 22:46:31.449873 | TASK [add-build-sshkey : Add back temp key] 2025-10-09 22:46:31.793374 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/66b791d74894477aac96ee5f37c92940/work/66b791d74894477aac96ee5f37c92940_id_rsa (zuul-build-sshkey) 2025-10-09 22:46:31.793845 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.017868 2025-10-09 22:46:31.810099 | 2025-10-09 22:46:31.810309 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-10-09 22:46:32.216971 | debian-bookworm | ok 2025-10-09 22:46:32.222116 | 2025-10-09 22:46:32.222222 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-10-09 22:46:32.245629 | debian-bookworm | skipping: Conditional result was False 2025-10-09 22:46:32.255988 | 2025-10-09 22:46:32.256099 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-10-09 22:46:32.675902 | debian-bookworm | ok 2025-10-09 22:46:32.687172 | 2025-10-09 22:46:32.687341 | TASK [validate-host : Define zuul_info_dir fact] 2025-10-09 22:46:32.715727 | debian-bookworm | ok 2025-10-09 22:46:32.720968 | 2025-10-09 22:46:32.721069 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-10-09 22:46:32.999183 | debian-bookworm -> localhost | ok 2025-10-09 22:46:33.011145 | 2025-10-09 22:46:33.011347 | TASK [validate-host : Collect information about the host] 2025-10-09 22:46:34.156438 | debian-bookworm | ok 2025-10-09 22:46:34.188868 | 2025-10-09 22:46:34.189003 | TASK [validate-host : Sanitize hostname] 2025-10-09 22:46:34.290217 | debian-bookworm | ok 2025-10-09 22:46:34.330903 | 2025-10-09 22:46:34.331030 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-10-09 22:46:34.898347 | debian-bookworm -> localhost | changed 2025-10-09 22:46:34.911983 | 2025-10-09 22:46:34.912171 | TASK [validate-host : Collect information about zuul worker] 2025-10-09 22:46:35.337946 | debian-bookworm | ok 2025-10-09 22:46:35.342722 | 2025-10-09 22:46:35.343025 | TASK [validate-host : Write out all zuul information for each host] 2025-10-09 22:46:35.923580 | debian-bookworm -> localhost | changed 2025-10-09 22:46:35.942793 | 2025-10-09 22:46:35.943026 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-10-09 22:46:36.245649 | debian-bookworm | ok 2025-10-09 22:46:36.253677 | 2025-10-09 22:46:36.253805 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-10-09 22:46:37.521370 | debian-bookworm | changed: 2025-10-09 22:46:37.521581 | debian-bookworm | cd+++++++++ src/ 2025-10-09 22:46:37.521616 | debian-bookworm | cd+++++++++ src/github.com/ 2025-10-09 22:46:37.521640 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-10-09 22:46:37.521662 | debian-bookworm | cd+++++++++ src/github.com/osism/testbed/ 2025-10-09 22:46:37.521681 | debian-bookworm | localhost | ok: Runtime: 0:00:00.006831 2025-10-09 22:47:03.179495 | 2025-10-09 22:47:03.179638 | TASK [upload-logs : Upload logs to log server] 2025-10-09 22:47:03.834585 | localhost | Output suppressed because no_log was given 2025-10-09 22:47:03.836637 | 2025-10-09 22:47:03.836745 | LOOP [upload-logs : Compress console log and json output] 2025-10-09 22:47:03.896893 | localhost | skipping: Conditional result was False 2025-10-09 22:47:03.902338 | localhost | skipping: Conditional result was False 2025-10-09 22:47:03.914394 | 2025-10-09 22:47:03.914528 | LOOP [upload-logs : Upload compressed console log and json output] 2025-10-09 22:47:03.957234 | localhost | skipping: Conditional result was False 2025-10-09 22:47:03.957698 | 2025-10-09 22:47:03.961497 | localhost | skipping: Conditional result was False 2025-10-09 22:47:03.972640 | 2025-10-09 22:47:03.972840 | LOOP [upload-logs : Upload console log and json output]