2025-01-08 11:36:30.351640 | Job console starting... 2025-01-08 11:36:30.361866 | Updating repositories 2025-01-08 11:36:30.403815 | Preparing job workspace 2025-01-08 11:36:32.052956 | Running Ansible setup... 2025-01-08 11:36:37.468748 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-01-08 11:36:38.813816 | 2025-01-08 11:36:38.813954 | PLAY [Base pre] 2025-01-08 11:36:38.864534 | 2025-01-08 11:36:38.864651 | TASK [Setup log path fact] 2025-01-08 11:36:38.910454 | ubuntu-noble | ok 2025-01-08 11:36:38.939868 | 2025-01-08 11:36:38.939997 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-01-08 11:36:39.040351 | ubuntu-noble | ok 2025-01-08 11:36:39.052284 | 2025-01-08 11:36:39.052386 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-01-08 11:36:39.113277 | ubuntu-noble | skipping: Conditional result was False 2025-01-08 11:36:39.120745 | 2025-01-08 11:36:39.120858 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-01-08 11:36:39.177777 | ubuntu-noble | skipping: Conditional result was False 2025-01-08 11:36:39.184062 | 2025-01-08 11:36:39.184147 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-01-08 11:36:39.197424 | ubuntu-noble | skipping: Conditional result was False 2025-01-08 11:36:39.204327 | 2025-01-08 11:36:39.204425 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-01-08 11:36:39.240646 | ubuntu-noble | skipping: Conditional result was False 2025-01-08 11:36:39.246837 | 2025-01-08 11:36:39.246924 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-01-08 11:36:39.290767 | ubuntu-noble | skipping: Conditional result was False 2025-01-08 11:36:39.345572 | 2025-01-08 11:36:39.346940 | TASK [emit-job-header : Print job information] 2025-01-08 11:36:39.509339 | # Job Information 2025-01-08 11:36:39.509472 | Ansible Version: 2.15.3 2025-01-08 11:36:39.509576 | Job: python-black 2025-01-08 11:36:39.509623 | Pipeline: check 2025-01-08 11:36:39.509644 | Executor: 7d211f194f6a 2025-01-08 11:36:39.509660 | Triggered by: https://github.com/osism/openstack-image-manager/pull/867 2025-01-08 11:36:39.509676 | Event ID: af324790-cdb4-11ef-808c-57368555ff30 2025-01-08 11:36:39.529304 | 2025-01-08 11:36:39.529401 | LOOP [emit-job-header : Print node information] 2025-01-08 11:36:39.933666 | ubuntu-noble | ok: 2025-01-08 11:36:39.933808 | ubuntu-noble | # Node Information 2025-01-08 11:36:39.933835 | ubuntu-noble | Inventory Hostname: ubuntu-noble 2025-01-08 11:36:39.933854 | ubuntu-noble | Hostname: ubuntu 2025-01-08 11:36:39.933871 | ubuntu-noble | Username: zuul 2025-01-08 11:36:39.933888 | ubuntu-noble | Distro: Ubuntu 24.04 2025-01-08 11:36:39.933903 | ubuntu-noble | Provider: regiocloud-a 2025-01-08 11:36:39.933951 | ubuntu-noble | Label: ubuntu-noble 2025-01-08 11:36:39.933968 | ubuntu-noble | Product Name: OpenStack Nova 2025-01-08 11:36:39.933985 | ubuntu-noble | Interface IP: 81.163.192.203 2025-01-08 11:36:40.058233 | 2025-01-08 11:36:40.058367 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-01-08 11:36:41.289877 | ubuntu-noble -> localhost | changed 2025-01-08 11:36:41.297416 | 2025-01-08 11:36:41.297509 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-01-08 11:36:43.231235 | ubuntu-noble -> localhost | changed 2025-01-08 11:36:43.244460 | 2025-01-08 11:36:43.244558 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-01-08 11:36:44.034673 | ubuntu-noble -> localhost | ok 2025-01-08 11:36:44.043256 | 2025-01-08 11:36:44.043356 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-01-08 11:36:44.088619 | ubuntu-noble | ok 2025-01-08 11:36:44.145679 | ubuntu-noble | included: /var/lib/zuul/builds/7ceabad653a64d2db07926f91da05e2e/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-01-08 11:36:44.152867 | 2025-01-08 11:36:44.152954 | TASK [add-build-sshkey : Create Temp SSH key] 2025-01-08 11:36:45.376178 | ubuntu-noble -> localhost | Generating public/private rsa key pair. 2025-01-08 11:36:45.376370 | ubuntu-noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/7ceabad653a64d2db07926f91da05e2e/work/7ceabad653a64d2db07926f91da05e2e_id_rsa 2025-01-08 11:36:45.376402 | ubuntu-noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/7ceabad653a64d2db07926f91da05e2e/work/7ceabad653a64d2db07926f91da05e2e_id_rsa.pub 2025-01-08 11:36:45.376424 | ubuntu-noble -> localhost | The key fingerprint is: 2025-01-08 11:36:45.376445 | ubuntu-noble -> localhost | SHA256:6oEPossEKr6VgzU2PAF/gdxlegKc6qRlcUVzRGFjBQk zuul-build-sshkey 2025-01-08 11:36:45.376464 | ubuntu-noble -> localhost | The key's randomart image is: 2025-01-08 11:36:45.376485 | ubuntu-noble -> localhost | +---[RSA 3072]----+ 2025-01-08 11:36:45.376502 | ubuntu-noble -> localhost | | .o.++E*X=. | 2025-01-08 11:36:45.376519 | ubuntu-noble -> localhost | | +=o.+=.. | 2025-01-08 11:36:45.376535 | ubuntu-noble -> localhost | | .= + . | 2025-01-08 11:36:45.376550 | ubuntu-noble -> localhost | | o+ o o | 2025-01-08 11:36:45.376566 | ubuntu-noble -> localhost | |=o B S | 2025-01-08 11:36:45.376581 | ubuntu-noble -> localhost | |+.+ =. . | 2025-01-08 11:36:45.376598 | ubuntu-noble -> localhost | |oo.+o o | 2025-01-08 11:36:45.376614 | ubuntu-noble -> localhost | |=....+ . | 2025-01-08 11:36:45.376631 | ubuntu-noble -> localhost | |o=. o | 2025-01-08 11:36:45.376646 | ubuntu-noble -> localhost | +----[SHA256]-----+ 2025-01-08 11:36:45.376686 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.492335 2025-01-08 11:36:45.383310 | 2025-01-08 11:36:45.383390 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-01-08 11:36:45.462417 | ubuntu-noble | ok 2025-01-08 11:36:45.475718 | ubuntu-noble | included: /var/lib/zuul/builds/7ceabad653a64d2db07926f91da05e2e/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-01-08 11:36:45.504353 | 2025-01-08 11:36:45.504455 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-01-08 11:36:45.558552 | ubuntu-noble | skipping: Conditional result was False 2025-01-08 11:36:45.565114 | 2025-01-08 11:36:45.565221 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-01-08 11:36:46.453400 | ubuntu-noble | changed 2025-01-08 11:36:46.461020 | 2025-01-08 11:36:46.461103 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-01-08 11:36:46.892574 | ubuntu-noble | ok 2025-01-08 11:36:46.909363 | 2025-01-08 11:36:46.909464 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-01-08 11:36:47.687532 | ubuntu-noble | changed 2025-01-08 11:36:47.694941 | 2025-01-08 11:36:47.695022 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-01-08 11:36:48.547577 | ubuntu-noble | changed 2025-01-08 11:36:48.553854 | 2025-01-08 11:36:48.553953 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-01-08 11:36:48.739014 | ubuntu-noble | skipping: Conditional result was False 2025-01-08 11:36:48.745401 | 2025-01-08 11:36:48.745489 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-01-08 11:36:49.269964 | ubuntu-noble -> localhost | changed 2025-01-08 11:36:49.291482 | 2025-01-08 11:36:49.291591 | TASK [add-build-sshkey : Add back temp key] 2025-01-08 11:36:49.573294 | ubuntu-noble -> localhost | Identity added: /var/lib/zuul/builds/7ceabad653a64d2db07926f91da05e2e/work/7ceabad653a64d2db07926f91da05e2e_id_rsa (zuul-build-sshkey) 2025-01-08 11:36:49.573494 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.010830 2025-01-08 11:36:49.581812 | 2025-01-08 11:36:49.581923 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-01-08 11:36:49.979787 | ubuntu-noble | ok 2025-01-08 11:36:49.985039 | 2025-01-08 11:36:49.985127 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-01-08 11:36:50.039246 | ubuntu-noble | skipping: Conditional result was False 2025-01-08 11:36:50.053440 | 2025-01-08 11:36:50.053536 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-01-08 11:36:50.632097 | ubuntu-noble | ok 2025-01-08 11:36:50.668613 | 2025-01-08 11:36:50.668719 | TASK [validate-host : Define zuul_info_dir fact] 2025-01-08 11:36:50.742665 | ubuntu-noble | ok 2025-01-08 11:36:50.747936 | 2025-01-08 11:36:50.748017 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-01-08 11:36:51.249874 | ubuntu-noble -> localhost | ok 2025-01-08 11:36:51.256221 | 2025-01-08 11:36:51.256298 | TASK [validate-host : Collect information about the host] 2025-01-08 11:36:52.658701 | ubuntu-noble | ok 2025-01-08 11:36:52.675558 | 2025-01-08 11:36:52.675664 | TASK [validate-host : Sanitize hostname] 2025-01-08 11:36:52.762761 | ubuntu-noble | ok 2025-01-08 11:36:52.767903 | 2025-01-08 11:36:52.767987 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-01-08 11:36:53.720484 | ubuntu-noble -> localhost | changed 2025-01-08 11:36:53.727821 | 2025-01-08 11:36:53.727916 | TASK [validate-host : Collect information about zuul worker] 2025-01-08 11:36:54.207107 | ubuntu-noble | ok 2025-01-08 11:36:54.212884 | 2025-01-08 11:36:54.212980 | TASK [validate-host : Write out all zuul information for each host] 2025-01-08 11:36:55.339444 | ubuntu-noble -> localhost | changed 2025-01-08 11:36:55.349289 | 2025-01-08 11:36:55.349374 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-01-08 11:36:55.645278 | ubuntu-noble | ok 2025-01-08 11:36:55.654988 | 2025-01-08 11:36:55.655072 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-01-08 11:36:57.064892 | ubuntu-noble | changed: 2025-01-08 11:36:57.065037 | ubuntu-noble | cd+++++++++ src/ 2025-01-08 11:36:57.065063 | ubuntu-noble | cd+++++++++ src/github.com/ 2025-01-08 11:36:57.065082 | ubuntu-noble | cd+++++++++ src/github.com/osism/ 2025-01-08 11:36:57.065100 | ubuntu-noble | cd+++++++++ src/github.com/osism/openstack-image-manager/ 2025-01-08 11:36:57.065116 | ubuntu-noble | localhost | ok: Runtime: 0:00:00.007322 2025-01-08 11:37:30.846830 | 2025-01-08 11:37:30.846981 | TASK [upload-logs : Upload logs to log server] 2025-01-08 11:37:31.444848 | localhost | Output suppressed because no_log was given 2025-01-08 11:37:31.449073 | 2025-01-08 11:37:31.449195 | LOOP [upload-logs : Compress console log and json output] 2025-01-08 11:37:31.527170 | localhost | skipping: Conditional result was False 2025-01-08 11:37:31.543461 | localhost | skipping: Conditional result was False 2025-01-08 11:37:31.559061 | 2025-01-08 11:37:31.559350 | LOOP [upload-logs : Upload compressed console log and json output] 2025-01-08 11:37:31.628619 | localhost | skipping: Conditional result was False 2025-01-08 11:37:31.628900 | 2025-01-08 11:37:31.643364 | localhost | skipping: Conditional result was False 2025-01-08 11:37:31.656341 | 2025-01-08 11:37:31.656697 | LOOP [upload-logs : Upload console log and json output]