2025-01-16 13:53:36.091724 | Job console starting... 2025-01-16 13:53:36.147722 | Updating repositories 2025-01-16 13:53:36.201083 | Preparing job workspace 2025-01-16 13:53:37.845020 | Running Ansible setup... 2025-01-16 13:53:43.128797 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-01-16 13:53:43.827304 | 2025-01-16 13:53:43.827466 | PLAY [Base pre] 2025-01-16 13:53:43.863363 | 2025-01-16 13:53:43.863512 | TASK [Setup log path fact] 2025-01-16 13:53:43.905186 | ubuntu-noble | ok 2025-01-16 13:53:43.930106 | 2025-01-16 13:53:43.930261 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-01-16 13:53:43.995193 | ubuntu-noble | ok 2025-01-16 13:53:44.001984 | 2025-01-16 13:53:44.002106 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-01-16 13:53:44.046949 | ubuntu-noble | skipping: Conditional result was False 2025-01-16 13:53:44.055659 | 2025-01-16 13:53:44.055786 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-01-16 13:53:44.110595 | ubuntu-noble | skipping: Conditional result was False 2025-01-16 13:53:44.118176 | 2025-01-16 13:53:44.118298 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-01-16 13:53:44.142611 | ubuntu-noble | skipping: Conditional result was False 2025-01-16 13:53:44.158401 | 2025-01-16 13:53:44.158561 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-01-16 13:53:44.183591 | ubuntu-noble | skipping: Conditional result was False 2025-01-16 13:53:44.191082 | 2025-01-16 13:53:44.191216 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-01-16 13:53:44.215346 | ubuntu-noble | skipping: Conditional result was False 2025-01-16 13:53:44.231630 | 2025-01-16 13:53:44.231752 | TASK [emit-job-header : Print job information] 2025-01-16 13:53:44.283010 | # Job Information 2025-01-16 13:53:44.283208 | Ansible Version: 2.15.3 2025-01-16 13:53:44.283241 | Job: python-black 2025-01-16 13:53:44.283271 | Pipeline: check 2025-01-16 13:53:44.283292 | Executor: 7d211f194f6a 2025-01-16 13:53:44.283311 | Triggered by: https://github.com/osism/testbed/pull/2582 2025-01-16 13:53:44.283330 | Event ID: 437246f0-d411-11ef-927c-e5cc18cd984e 2025-01-16 13:53:44.290933 | 2025-01-16 13:53:44.291049 | LOOP [emit-job-header : Print node information] 2025-01-16 13:53:44.435809 | ubuntu-noble | ok: 2025-01-16 13:53:44.436016 | ubuntu-noble | # Node Information 2025-01-16 13:53:44.436051 | ubuntu-noble | Inventory Hostname: ubuntu-noble 2025-01-16 13:53:44.436075 | ubuntu-noble | Hostname: ubuntu 2025-01-16 13:53:44.436097 | ubuntu-noble | Username: zuul 2025-01-16 13:53:44.436117 | ubuntu-noble | Distro: Ubuntu 24.04 2025-01-16 13:53:44.436137 | ubuntu-noble | Provider: regiocloud-a 2025-01-16 13:53:44.436156 | ubuntu-noble | Label: ubuntu-noble 2025-01-16 13:53:44.436175 | ubuntu-noble | Product Name: OpenStack Nova 2025-01-16 13:53:44.436196 | ubuntu-noble | Interface IP: 81.163.193.40 2025-01-16 13:53:44.460581 | 2025-01-16 13:53:44.460708 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-01-16 13:53:44.989103 | ubuntu-noble -> localhost | changed 2025-01-16 13:53:44.998274 | 2025-01-16 13:53:44.998401 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-01-16 13:53:46.168198 | ubuntu-noble -> localhost | changed 2025-01-16 13:53:46.183680 | 2025-01-16 13:53:46.183798 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-01-16 13:53:46.485690 | ubuntu-noble -> localhost | ok 2025-01-16 13:53:46.494678 | 2025-01-16 13:53:46.494798 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-01-16 13:53:46.525287 | ubuntu-noble | ok 2025-01-16 13:53:46.541678 | ubuntu-noble | included: /var/lib/zuul/builds/d9e2caaef61b4052a61af559dcf2196b/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-01-16 13:53:46.561970 | 2025-01-16 13:53:46.562090 | TASK [add-build-sshkey : Create Temp SSH key] 2025-01-16 13:53:47.380307 | ubuntu-noble -> localhost | Generating public/private rsa key pair. 2025-01-16 13:53:47.380538 | ubuntu-noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/d9e2caaef61b4052a61af559dcf2196b/work/d9e2caaef61b4052a61af559dcf2196b_id_rsa 2025-01-16 13:53:47.380575 | ubuntu-noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/d9e2caaef61b4052a61af559dcf2196b/work/d9e2caaef61b4052a61af559dcf2196b_id_rsa.pub 2025-01-16 13:53:47.380601 | ubuntu-noble -> localhost | The key fingerprint is: 2025-01-16 13:53:47.380625 | ubuntu-noble -> localhost | SHA256:tUylr3r3PS2dtt+vRIws5dPApYzPQgkORWHmCX1+X4s zuul-build-sshkey 2025-01-16 13:53:47.380648 | ubuntu-noble -> localhost | The key's randomart image is: 2025-01-16 13:53:47.380674 | ubuntu-noble -> localhost | +---[RSA 3072]----+ 2025-01-16 13:53:47.380696 | ubuntu-noble -> localhost | | o+B. . . | 2025-01-16 13:53:47.380716 | ubuntu-noble -> localhost | | B.o.B o | 2025-01-16 13:53:47.380736 | ubuntu-noble -> localhost | | +oB * | 2025-01-16 13:53:47.380756 | ubuntu-noble -> localhost | | =.O.= .| 2025-01-16 13:53:47.380775 | ubuntu-noble -> localhost | | S =.O.=..| 2025-01-16 13:53:47.380794 | ubuntu-noble -> localhost | | + E.. | 2025-01-16 13:53:47.380814 | ubuntu-noble -> localhost | | . ..o| 2025-01-16 13:53:47.380834 | ubuntu-noble -> localhost | | .. ...=+| 2025-01-16 13:53:47.380854 | ubuntu-noble -> localhost | | .. . .++X| 2025-01-16 13:53:47.380891 | ubuntu-noble -> localhost | +----[SHA256]-----+ 2025-01-16 13:53:47.380945 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.270836 2025-01-16 13:53:47.388998 | 2025-01-16 13:53:47.389115 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-01-16 13:53:47.431086 | ubuntu-noble | ok 2025-01-16 13:53:47.452465 | ubuntu-noble | included: /var/lib/zuul/builds/d9e2caaef61b4052a61af559dcf2196b/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-01-16 13:53:47.462258 | 2025-01-16 13:53:47.462358 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-01-16 13:53:47.486735 | ubuntu-noble | skipping: Conditional result was False 2025-01-16 13:53:47.494249 | 2025-01-16 13:53:47.494357 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-01-16 13:53:48.226844 | ubuntu-noble | changed 2025-01-16 13:53:48.235043 | 2025-01-16 13:53:48.235220 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-01-16 13:53:48.551254 | ubuntu-noble | ok 2025-01-16 13:53:48.560832 | 2025-01-16 13:53:48.561015 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-01-16 13:53:49.379597 | ubuntu-noble | changed 2025-01-16 13:53:49.389837 | 2025-01-16 13:53:49.390024 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-01-16 13:53:50.155077 | ubuntu-noble | changed 2025-01-16 13:53:50.164648 | 2025-01-16 13:53:50.164774 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-01-16 13:53:50.189604 | ubuntu-noble | skipping: Conditional result was False 2025-01-16 13:53:50.208531 | 2025-01-16 13:53:50.209001 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-01-16 13:53:50.704946 | ubuntu-noble -> localhost | changed 2025-01-16 13:53:50.718911 | 2025-01-16 13:53:50.719043 | TASK [add-build-sshkey : Add back temp key] 2025-01-16 13:53:51.134841 | ubuntu-noble -> localhost | Identity added: /var/lib/zuul/builds/d9e2caaef61b4052a61af559dcf2196b/work/d9e2caaef61b4052a61af559dcf2196b_id_rsa (zuul-build-sshkey) 2025-01-16 13:53:51.135112 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.008900 2025-01-16 13:53:51.145012 | 2025-01-16 13:53:51.145128 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-01-16 13:53:51.576710 | ubuntu-noble | ok 2025-01-16 13:53:51.583602 | 2025-01-16 13:53:51.583713 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-01-16 13:53:51.618750 | ubuntu-noble | skipping: Conditional result was False 2025-01-16 13:53:51.634575 | 2025-01-16 13:53:51.634692 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-01-16 13:53:52.063613 | ubuntu-noble | ok 2025-01-16 13:53:52.077838 | 2025-01-16 13:53:52.078020 | TASK [validate-host : Define zuul_info_dir fact] 2025-01-16 13:53:52.110072 | ubuntu-noble | ok 2025-01-16 13:53:52.116769 | 2025-01-16 13:53:52.116893 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-01-16 13:53:52.407573 | ubuntu-noble -> localhost | ok 2025-01-16 13:53:52.416855 | 2025-01-16 13:53:52.417031 | TASK [validate-host : Collect information about the host] 2025-01-16 13:53:53.548579 | ubuntu-noble | ok 2025-01-16 13:53:53.561510 | 2025-01-16 13:53:53.561624 | TASK [validate-host : Sanitize hostname] 2025-01-16 13:53:53.623633 | ubuntu-noble | ok 2025-01-16 13:53:53.630487 | 2025-01-16 13:53:53.630606 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-01-16 13:53:54.193467 | ubuntu-noble -> localhost | changed 2025-01-16 13:53:54.208098 | 2025-01-16 13:53:54.208381 | TASK [validate-host : Collect information about zuul worker] 2025-01-16 13:53:54.676834 | ubuntu-noble | ok 2025-01-16 13:53:54.683406 | 2025-01-16 13:53:54.683525 | TASK [validate-host : Write out all zuul information for each host] 2025-01-16 13:53:55.208331 | ubuntu-noble -> localhost | changed 2025-01-16 13:53:55.221032 | 2025-01-16 13:53:55.221154 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-01-16 13:53:55.556444 | ubuntu-noble | ok 2025-01-16 13:53:55.563534 | 2025-01-16 13:53:55.563647 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-01-16 13:53:56.802455 | ubuntu-noble | changed: 2025-01-16 13:53:56.802956 | ubuntu-noble | cd+++++++++ src/ 2025-01-16 13:53:56.803015 | ubuntu-noble | cd+++++++++ src/github.com/ 2025-01-16 13:53:56.803041 | ubuntu-noble | cd+++++++++ src/github.com/osism/ 2025-01-16 13:53:56.803064 | ubuntu-noble | cd+++++++++ src/github.com/osism/testbed/ 2025-01-16 13:53:56.803086 | ubuntu-noble | localhost | ok: Runtime: 0:00:00.007823 2025-01-16 13:54:28.896274 | 2025-01-16 13:54:28.896389 | TASK [upload-logs : Upload logs to log server] 2025-01-16 13:54:29.567417 | localhost | Output suppressed because no_log was given 2025-01-16 13:54:29.572136 | 2025-01-16 13:54:29.572254 | LOOP [upload-logs : Compress console log and json output] 2025-01-16 13:54:29.676200 | localhost | skipping: Conditional result was False 2025-01-16 13:54:29.697691 | localhost | skipping: Conditional result was False 2025-01-16 13:54:29.706587 | 2025-01-16 13:54:29.706757 | LOOP [upload-logs : Upload compressed console log and json output] 2025-01-16 13:54:29.790133 | localhost | skipping: Conditional result was False 2025-01-16 13:54:29.790559 | 2025-01-16 13:54:29.810304 | localhost | skipping: Conditional result was False 2025-01-16 13:54:29.830017 | 2025-01-16 13:54:29.830194 | LOOP [upload-logs : Upload console log and json output]