2025-03-17 13:09:47.876551 | Job console starting... 2025-03-17 13:09:47.895140 | Updating repositories 2025-03-17 13:09:47.929617 | Preparing job workspace 2025-03-17 13:09:49.864142 | Running Ansible setup... 2025-03-17 13:09:56.185585 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-03-17 13:09:57.350375 | 2025-03-17 13:09:57.350546 | PLAY [Base pre] 2025-03-17 13:09:57.432078 | 2025-03-17 13:09:57.432242 | TASK [Setup log path fact] 2025-03-17 13:09:57.472351 | ubuntu-noble | ok 2025-03-17 13:09:57.513148 | 2025-03-17 13:09:57.513285 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-03-17 13:09:57.583874 | ubuntu-noble | ok 2025-03-17 13:09:57.602211 | 2025-03-17 13:09:57.602301 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-03-17 13:09:57.636126 | ubuntu-noble | skipping: Conditional result was False 2025-03-17 13:09:57.642999 | 2025-03-17 13:09:57.643098 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-03-17 13:09:57.697200 | ubuntu-noble | skipping: Conditional result was False 2025-03-17 13:09:57.704243 | 2025-03-17 13:09:57.704338 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-03-17 13:09:57.740483 | ubuntu-noble | skipping: Conditional result was False 2025-03-17 13:09:57.747339 | 2025-03-17 13:09:57.747434 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-03-17 13:09:57.801528 | ubuntu-noble | skipping: Conditional result was False 2025-03-17 13:09:57.808464 | 2025-03-17 13:09:57.808570 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-03-17 13:09:57.852648 | ubuntu-noble | skipping: Conditional result was False 2025-03-17 13:09:57.870931 | 2025-03-17 13:09:57.871045 | TASK [emit-job-header : Print job information] 2025-03-17 13:09:57.941543 | # Job Information 2025-03-17 13:09:57.941702 | Ansible Version: 2.15.3 2025-03-17 13:09:57.941733 | Job: python-black 2025-03-17 13:09:57.941762 | Pipeline: check 2025-03-17 13:09:57.941782 | Executor: 7d211f194f6a 2025-03-17 13:09:57.941801 | Triggered by: https://github.com/osism/cfg-cookiecutter/pull/716 2025-03-17 13:09:57.941820 | Event ID: 148ef780-0331-11f0-80e6-73cdf00d63de 2025-03-17 13:09:57.947891 | 2025-03-17 13:09:57.947980 | LOOP [emit-job-header : Print node information] 2025-03-17 13:09:58.163401 | ubuntu-noble | ok: 2025-03-17 13:09:58.163561 | ubuntu-noble | # Node Information 2025-03-17 13:09:58.163617 | ubuntu-noble | Inventory Hostname: ubuntu-noble 2025-03-17 13:09:58.163715 | ubuntu-noble | Hostname: ubuntu 2025-03-17 13:09:58.163738 | ubuntu-noble | Username: zuul 2025-03-17 13:09:58.163757 | ubuntu-noble | Distro: Ubuntu 24.04 2025-03-17 13:09:58.163774 | ubuntu-noble | Provider: regiocloud-a 2025-03-17 13:09:58.163791 | ubuntu-noble | Label: ubuntu-noble 2025-03-17 13:09:58.163806 | ubuntu-noble | Product Name: OpenStack Nova 2025-03-17 13:09:58.163823 | ubuntu-noble | Interface IP: 81.163.193.73 2025-03-17 13:09:58.195096 | 2025-03-17 13:09:58.195201 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-03-17 13:09:58.874832 | ubuntu-noble -> localhost | changed 2025-03-17 13:09:58.881463 | 2025-03-17 13:09:58.881541 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-03-17 13:10:00.229173 | ubuntu-noble -> localhost | changed 2025-03-17 13:10:00.239842 | 2025-03-17 13:10:00.239919 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-03-17 13:10:00.756168 | ubuntu-noble -> localhost | ok 2025-03-17 13:10:00.765080 | 2025-03-17 13:10:00.765183 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-03-17 13:10:00.817431 | ubuntu-noble | ok 2025-03-17 13:10:00.838454 | ubuntu-noble | included: /var/lib/zuul/builds/a1e9093c4ac946f09d07aff3e6629b0a/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-03-17 13:10:00.844622 | 2025-03-17 13:10:00.844697 | TASK [add-build-sshkey : Create Temp SSH key] 2025-03-17 13:10:01.493257 | ubuntu-noble -> localhost | Generating public/private rsa key pair. 2025-03-17 13:10:01.493415 | ubuntu-noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/a1e9093c4ac946f09d07aff3e6629b0a/work/a1e9093c4ac946f09d07aff3e6629b0a_id_rsa 2025-03-17 13:10:01.493443 | ubuntu-noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/a1e9093c4ac946f09d07aff3e6629b0a/work/a1e9093c4ac946f09d07aff3e6629b0a_id_rsa.pub 2025-03-17 13:10:01.493463 | ubuntu-noble -> localhost | The key fingerprint is: 2025-03-17 13:10:01.493481 | ubuntu-noble -> localhost | SHA256:QOfvcOUJfFOEOFcPh/V7RFQGwSdw6TWfrBtaZdFuoCQ zuul-build-sshkey 2025-03-17 13:10:01.493499 | ubuntu-noble -> localhost | The key's randomart image is: 2025-03-17 13:10:01.493519 | ubuntu-noble -> localhost | +---[RSA 3072]----+ 2025-03-17 13:10:01.493536 | ubuntu-noble -> localhost | | . . ..*OBX| 2025-03-17 13:10:01.493552 | ubuntu-noble -> localhost | | . o .E +o*O=| 2025-03-17 13:10:01.493568 | ubuntu-noble -> localhost | | . . o=+o.*O| 2025-03-17 13:10:01.493584 | ubuntu-noble -> localhost | | . . =.o.*=| 2025-03-17 13:10:01.493610 | ubuntu-noble -> localhost | | S o o +o.| 2025-03-17 13:10:01.493627 | ubuntu-noble -> localhost | | + + .| 2025-03-17 13:10:01.493643 | ubuntu-noble -> localhost | | . o o | 2025-03-17 13:10:01.493659 | ubuntu-noble -> localhost | | . . | 2025-03-17 13:10:01.493674 | ubuntu-noble -> localhost | | | 2025-03-17 13:10:01.493690 | ubuntu-noble -> localhost | +----[SHA256]-----+ 2025-03-17 13:10:01.493729 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.111275 2025-03-17 13:10:01.500453 | 2025-03-17 13:10:01.500530 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-03-17 13:10:01.518789 | ubuntu-noble | ok 2025-03-17 13:10:01.527173 | ubuntu-noble | included: /var/lib/zuul/builds/a1e9093c4ac946f09d07aff3e6629b0a/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-03-17 13:10:01.535199 | 2025-03-17 13:10:01.535266 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-03-17 13:10:01.548142 | ubuntu-noble | skipping: Conditional result was False 2025-03-17 13:10:01.554311 | 2025-03-17 13:10:01.554385 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-03-17 13:10:02.392153 | ubuntu-noble | changed 2025-03-17 13:10:02.401013 | 2025-03-17 13:10:02.401096 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-03-17 13:10:02.755517 | ubuntu-noble | ok 2025-03-17 13:10:02.762352 | 2025-03-17 13:10:02.762438 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-03-17 13:10:03.519251 | ubuntu-noble | changed 2025-03-17 13:10:03.528264 | 2025-03-17 13:10:03.528362 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-03-17 13:10:04.298580 | ubuntu-noble | changed 2025-03-17 13:10:04.313424 | 2025-03-17 13:10:04.313519 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-03-17 13:10:04.338273 | ubuntu-noble | skipping: Conditional result was False 2025-03-17 13:10:04.347873 | 2025-03-17 13:10:04.348130 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-03-17 13:10:04.867865 | ubuntu-noble -> localhost | changed 2025-03-17 13:10:04.881422 | 2025-03-17 13:10:04.881523 | TASK [add-build-sshkey : Add back temp key] 2025-03-17 13:10:05.305685 | ubuntu-noble -> localhost | Identity added: /var/lib/zuul/builds/a1e9093c4ac946f09d07aff3e6629b0a/work/a1e9093c4ac946f09d07aff3e6629b0a_id_rsa (zuul-build-sshkey) 2025-03-17 13:10:05.305891 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.008482 2025-03-17 13:10:05.335927 | 2025-03-17 13:10:05.336019 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-03-17 13:10:05.769890 | ubuntu-noble | ok 2025-03-17 13:10:05.781374 | 2025-03-17 13:10:05.781471 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-03-17 13:10:05.810984 | ubuntu-noble | skipping: Conditional result was False 2025-03-17 13:10:05.824726 | 2025-03-17 13:10:05.824837 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-03-17 13:10:06.266119 | ubuntu-noble | ok 2025-03-17 13:10:06.288521 | 2025-03-17 13:10:06.288643 | TASK [validate-host : Define zuul_info_dir fact] 2025-03-17 13:10:06.358947 | ubuntu-noble | ok 2025-03-17 13:10:06.367660 | 2025-03-17 13:10:06.367764 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-03-17 13:10:06.649983 | ubuntu-noble -> localhost | ok 2025-03-17 13:10:06.661681 | 2025-03-17 13:10:06.661791 | TASK [validate-host : Collect information about the host] 2025-03-17 13:10:07.941496 | ubuntu-noble | ok 2025-03-17 13:10:07.963042 | 2025-03-17 13:10:07.963152 | TASK [validate-host : Sanitize hostname] 2025-03-17 13:10:08.049104 | ubuntu-noble | ok 2025-03-17 13:10:08.064256 | 2025-03-17 13:10:08.064359 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-03-17 13:10:08.640647 | ubuntu-noble -> localhost | changed 2025-03-17 13:10:08.647309 | 2025-03-17 13:10:08.647397 | TASK [validate-host : Collect information about zuul worker] 2025-03-17 13:10:09.194729 | ubuntu-noble | ok 2025-03-17 13:10:09.204675 | 2025-03-17 13:10:09.204781 | TASK [validate-host : Write out all zuul information for each host] 2025-03-17 13:10:09.735541 | ubuntu-noble -> localhost | changed 2025-03-17 13:10:09.747462 | 2025-03-17 13:10:09.747556 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-03-17 13:10:10.085670 | ubuntu-noble | ok 2025-03-17 13:10:10.098687 | 2025-03-17 13:10:10.098779 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-03-17 13:10:11.006552 | ubuntu-noble | changed: 2025-03-17 13:10:11.006772 | ubuntu-noble | cd+++++++++ src/ 2025-03-17 13:10:11.006806 | ubuntu-noble | cd+++++++++ src/github.com/ 2025-03-17 13:10:11.006828 | ubuntu-noble | cd+++++++++ src/github.com/osism/ 2025-03-17 13:10:11.006849 | ubuntu-noble | cd+++++++++ src/github.com/osism/cfg-cookiecutter/ 2025-03-17 13:10:11.006868 | ubuntu-noble | Containerfile 2025-03-17 13:10:11.006996 | ubuntu-noble | localhost | ok: Runtime: 0:00:00.005622 2025-03-17 13:10:50.951738 | 2025-03-17 13:10:50.951816 | TASK [upload-logs : Upload logs to log server] 2025-03-17 13:10:51.536859 | localhost | Output suppressed because no_log was given 2025-03-17 13:10:51.543274 | 2025-03-17 13:10:51.543516 | LOOP [upload-logs : Compress console log and json output] 2025-03-17 13:10:51.635059 | localhost | skipping: Conditional result was False 2025-03-17 13:10:51.649530 | localhost | skipping: Conditional result was False 2025-03-17 13:10:51.662963 | 2025-03-17 13:10:51.663047 | LOOP [upload-logs : Upload compressed console log and json output] 2025-03-17 13:10:51.716954 | localhost | skipping: Conditional result was False 2025-03-17 13:10:51.717250 | 2025-03-17 13:10:51.729885 | localhost | skipping: Conditional result was False 2025-03-17 13:10:51.742437 | 2025-03-17 13:10:51.742705 | LOOP [upload-logs : Upload console log and json output]