2025-03-14 00:07:45.507624 | Job console starting... 2025-03-14 00:07:45.523346 | Updating repositories 2025-03-14 00:07:45.544762 | Preparing job workspace 2025-03-14 00:07:46.936140 | Running Ansible setup... 2025-03-14 00:07:52.481618 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-03-14 00:07:53.238030 | 2025-03-14 00:07:53.238183 | PLAY [Base pre] 2025-03-14 00:07:53.270915 | 2025-03-14 00:07:53.271062 | TASK [Setup log path fact] 2025-03-14 00:07:53.304517 | ubuntu-noble | ok 2025-03-14 00:07:53.330396 | 2025-03-14 00:07:53.330527 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-03-14 00:07:53.385733 | ubuntu-noble | ok 2025-03-14 00:07:53.392908 | 2025-03-14 00:07:53.393039 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-03-14 00:07:53.445107 | ubuntu-noble | skipping: Conditional result was False 2025-03-14 00:07:53.454358 | 2025-03-14 00:07:53.454494 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-03-14 00:07:53.511672 | ubuntu-noble | skipping: Conditional result was False 2025-03-14 00:07:53.519729 | 2025-03-14 00:07:53.519859 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-03-14 00:07:53.544372 | ubuntu-noble | skipping: Conditional result was False 2025-03-14 00:07:53.557031 | 2025-03-14 00:07:53.557157 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-03-14 00:07:53.581346 | ubuntu-noble | skipping: Conditional result was False 2025-03-14 00:07:53.589339 | 2025-03-14 00:07:53.589457 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-03-14 00:07:53.613617 | ubuntu-noble | skipping: Conditional result was False 2025-03-14 00:07:53.629693 | 2025-03-14 00:07:53.629818 | TASK [emit-job-header : Print job information] 2025-03-14 00:07:53.682091 | # Job Information 2025-03-14 00:07:53.682338 | Ansible Version: 2.15.3 2025-03-14 00:07:53.682375 | Job: python-black 2025-03-14 00:07:53.682404 | Pipeline: check 2025-03-14 00:07:53.682425 | Executor: 7d211f194f6a 2025-03-14 00:07:53.682444 | Triggered by: https://github.com/osism/testbed/pull/2632 2025-03-14 00:07:53.682463 | Event ID: 2d89a6c0-0068-11f0-82b1-583bc23632a3 2025-03-14 00:07:53.689141 | 2025-03-14 00:07:53.689255 | LOOP [emit-job-header : Print node information] 2025-03-14 00:07:53.871537 | ubuntu-noble | ok: 2025-03-14 00:07:53.871901 | ubuntu-noble | # Node Information 2025-03-14 00:07:53.871943 | ubuntu-noble | Inventory Hostname: ubuntu-noble 2025-03-14 00:07:53.872016 | ubuntu-noble | Hostname: ubuntu 2025-03-14 00:07:53.872038 | ubuntu-noble | Username: zuul 2025-03-14 00:07:53.872059 | ubuntu-noble | Distro: Ubuntu 24.04 2025-03-14 00:07:53.872079 | ubuntu-noble | Provider: regiocloud-a 2025-03-14 00:07:53.872098 | ubuntu-noble | Label: ubuntu-noble 2025-03-14 00:07:53.872117 | ubuntu-noble | Product Name: OpenStack Nova 2025-03-14 00:07:53.872138 | ubuntu-noble | Interface IP: 81.163.192.198 2025-03-14 00:07:53.890445 | 2025-03-14 00:07:53.890564 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-03-14 00:07:54.372675 | ubuntu-noble -> localhost | changed 2025-03-14 00:07:54.386996 | 2025-03-14 00:07:54.387170 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-03-14 00:07:55.547577 | ubuntu-noble -> localhost | changed 2025-03-14 00:07:55.568766 | 2025-03-14 00:07:55.568899 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-03-14 00:07:55.882540 | ubuntu-noble -> localhost | ok 2025-03-14 00:07:55.894206 | 2025-03-14 00:07:55.894338 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-03-14 00:07:55.935315 | ubuntu-noble | ok 2025-03-14 00:07:55.960237 | ubuntu-noble | included: /var/lib/zuul/builds/fd82c7ada3bd403eb9b64e536c04cf67/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-03-14 00:07:55.968236 | 2025-03-14 00:07:55.968924 | TASK [add-build-sshkey : Create Temp SSH key] 2025-03-14 00:07:56.699715 | ubuntu-noble -> localhost | Generating public/private rsa key pair. 2025-03-14 00:07:56.700019 | ubuntu-noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/fd82c7ada3bd403eb9b64e536c04cf67/work/fd82c7ada3bd403eb9b64e536c04cf67_id_rsa 2025-03-14 00:07:56.700064 | ubuntu-noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/fd82c7ada3bd403eb9b64e536c04cf67/work/fd82c7ada3bd403eb9b64e536c04cf67_id_rsa.pub 2025-03-14 00:07:56.700089 | ubuntu-noble -> localhost | The key fingerprint is: 2025-03-14 00:07:56.700112 | ubuntu-noble -> localhost | SHA256:H8h4TsRdLJbZx7wPrPnU4Efpc+MdrQ7vvNXhBZplqhE zuul-build-sshkey 2025-03-14 00:07:56.700133 | ubuntu-noble -> localhost | The key's randomart image is: 2025-03-14 00:07:56.700159 | ubuntu-noble -> localhost | +---[RSA 3072]----+ 2025-03-14 00:07:56.700180 | ubuntu-noble -> localhost | | =.o | 2025-03-14 00:07:56.700201 | ubuntu-noble -> localhost | | . .=.o + | 2025-03-14 00:07:56.700220 | ubuntu-noble -> localhost | | o.E. o+..| 2025-03-14 00:07:56.700239 | ubuntu-noble -> localhost | | + . . *=o.| 2025-03-14 00:07:56.700259 | ubuntu-noble -> localhost | | . S o ++.Bo| 2025-03-14 00:07:56.700278 | ubuntu-noble -> localhost | | + . +o +=X| 2025-03-14 00:07:56.700298 | ubuntu-noble -> localhost | | . o .o.+O| 2025-03-14 00:07:56.700318 | ubuntu-noble -> localhost | | +oo.| 2025-03-14 00:07:56.700338 | ubuntu-noble -> localhost | | o*. | 2025-03-14 00:07:56.700357 | ubuntu-noble -> localhost | +----[SHA256]-----+ 2025-03-14 00:07:56.700434 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.198013 2025-03-14 00:07:56.709313 | 2025-03-14 00:07:56.709427 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-03-14 00:07:56.741428 | ubuntu-noble | ok 2025-03-14 00:07:56.752815 | ubuntu-noble | included: /var/lib/zuul/builds/fd82c7ada3bd403eb9b64e536c04cf67/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-03-14 00:07:56.762711 | 2025-03-14 00:07:56.762809 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-03-14 00:07:56.789275 | ubuntu-noble | skipping: Conditional result was False 2025-03-14 00:07:56.797352 | 2025-03-14 00:07:56.797457 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-03-14 00:07:57.647184 | ubuntu-noble | changed 2025-03-14 00:07:57.658081 | 2025-03-14 00:07:57.658217 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-03-14 00:07:58.038135 | ubuntu-noble | ok 2025-03-14 00:07:58.045190 | 2025-03-14 00:07:58.045295 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-03-14 00:07:58.896597 | ubuntu-noble | changed 2025-03-14 00:07:58.906400 | 2025-03-14 00:07:58.906515 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-03-14 00:07:59.830996 | ubuntu-noble | changed 2025-03-14 00:07:59.838504 | 2025-03-14 00:07:59.838620 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-03-14 00:07:59.874035 | ubuntu-noble | skipping: Conditional result was False 2025-03-14 00:07:59.881698 | 2025-03-14 00:07:59.881807 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-03-14 00:08:00.316740 | ubuntu-noble -> localhost | changed 2025-03-14 00:08:00.336146 | 2025-03-14 00:08:00.336282 | TASK [add-build-sshkey : Add back temp key] 2025-03-14 00:08:00.814685 | ubuntu-noble -> localhost | Identity added: /var/lib/zuul/builds/fd82c7ada3bd403eb9b64e536c04cf67/work/fd82c7ada3bd403eb9b64e536c04cf67_id_rsa (zuul-build-sshkey) 2025-03-14 00:08:00.815393 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.027797 2025-03-14 00:08:00.885778 | 2025-03-14 00:08:00.885912 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-03-14 00:08:01.409169 | ubuntu-noble | ok 2025-03-14 00:08:01.418864 | 2025-03-14 00:08:01.419054 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-03-14 00:08:01.455068 | ubuntu-noble | skipping: Conditional result was False 2025-03-14 00:08:01.479241 | 2025-03-14 00:08:01.479412 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-03-14 00:08:02.098112 | ubuntu-noble | ok 2025-03-14 00:08:02.111725 | 2025-03-14 00:08:02.111851 | TASK [validate-host : Define zuul_info_dir fact] 2025-03-14 00:08:02.154139 | ubuntu-noble | ok 2025-03-14 00:08:02.161121 | 2025-03-14 00:08:02.161240 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-03-14 00:08:02.441721 | ubuntu-noble -> localhost | ok 2025-03-14 00:08:02.449917 | 2025-03-14 00:08:02.450049 | TASK [validate-host : Collect information about the host] 2025-03-14 00:08:03.889235 | ubuntu-noble | ok 2025-03-14 00:08:03.905625 | 2025-03-14 00:08:03.905755 | TASK [validate-host : Sanitize hostname] 2025-03-14 00:08:03.968971 | ubuntu-noble | ok 2025-03-14 00:08:03.976723 | 2025-03-14 00:08:03.976877 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-03-14 00:08:04.606495 | ubuntu-noble -> localhost | changed 2025-03-14 00:08:04.625817 | 2025-03-14 00:08:04.626074 | TASK [validate-host : Collect information about zuul worker] 2025-03-14 00:08:05.158328 | ubuntu-noble | ok 2025-03-14 00:08:05.167409 | 2025-03-14 00:08:05.167544 | TASK [validate-host : Write out all zuul information for each host] 2025-03-14 00:08:05.743511 | ubuntu-noble -> localhost | changed 2025-03-14 00:08:05.758653 | 2025-03-14 00:08:05.758771 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-03-14 00:08:06.154431 | ubuntu-noble | ok 2025-03-14 00:08:06.167636 | 2025-03-14 00:08:06.167764 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-03-14 00:08:07.638943 | ubuntu-noble | changed: 2025-03-14 00:08:07.639179 | ubuntu-noble | cd+++++++++ src/ 2025-03-14 00:08:07.639212 | ubuntu-noble | cd+++++++++ src/github.com/ 2025-03-14 00:08:07.639236 | ubuntu-noble | cd+++++++++ src/github.com/osism/ 2025-03-14 00:08:07.639258 | ubuntu-noble | cd+++++++++ src/github.com/osism/testbed/ 2025-03-14 00:08:07.639279 | ubuntu-noble | localhost | ok: Runtime: 0:00:00.004111 2025-03-14 00:08:44.509606 | 2025-03-14 00:08:44.509765 | TASK [upload-logs : Upload logs to log server] 2025-03-14 00:08:45.117543 | localhost | Output suppressed because no_log was given 2025-03-14 00:08:45.122735 | 2025-03-14 00:08:45.122850 | LOOP [upload-logs : Compress console log and json output] 2025-03-14 00:08:45.197987 | localhost | skipping: Conditional result was False 2025-03-14 00:08:45.220587 | localhost | skipping: Conditional result was False 2025-03-14 00:08:45.230828 | 2025-03-14 00:08:45.230997 | LOOP [upload-logs : Upload compressed console log and json output] 2025-03-14 00:08:45.293935 | localhost | skipping: Conditional result was False 2025-03-14 00:08:45.294258 | 2025-03-14 00:08:45.307940 | localhost | skipping: Conditional result was False 2025-03-14 00:08:45.317939 | 2025-03-14 00:08:45.318181 | LOOP [upload-logs : Upload console log and json output]