2025-01-30 14:31:19.922031 | Job console starting... 2025-01-30 14:31:19.940632 | Updating repositories 2025-01-30 14:31:20.019447 | Preparing job workspace 2025-01-30 14:31:22.133255 | Running Ansible setup... 2025-01-30 14:31:28.350156 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-01-30 14:31:29.305112 | 2025-01-30 14:31:29.305285 | PLAY [Base pre] 2025-01-30 14:31:29.356546 | 2025-01-30 14:31:29.356761 | TASK [Setup log path fact] 2025-01-30 14:31:29.409883 | ubuntu-noble | ok 2025-01-30 14:31:29.453425 | 2025-01-30 14:31:29.453601 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-01-30 14:31:29.546831 | ubuntu-noble | ok 2025-01-30 14:31:29.565721 | 2025-01-30 14:31:29.565862 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-01-30 14:31:29.631573 | ubuntu-noble | skipping: Conditional result was False 2025-01-30 14:31:29.639196 | 2025-01-30 14:31:29.639335 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-01-30 14:31:29.718168 | ubuntu-noble | skipping: Conditional result was False 2025-01-30 14:31:29.725983 | 2025-01-30 14:31:29.726118 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-01-30 14:31:29.781317 | ubuntu-noble | skipping: Conditional result was False 2025-01-30 14:31:29.788982 | 2025-01-30 14:31:29.789110 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-01-30 14:31:29.844274 | ubuntu-noble | skipping: Conditional result was False 2025-01-30 14:31:29.852803 | 2025-01-30 14:31:29.852949 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-01-30 14:31:29.877818 | ubuntu-noble | skipping: Conditional result was False 2025-01-30 14:31:29.897341 | 2025-01-30 14:31:29.897476 | TASK [emit-job-header : Print job information] 2025-01-30 14:31:29.992964 | # Job Information 2025-01-30 14:31:29.993152 | Ansible Version: 2.15.3 2025-01-30 14:31:29.993186 | Job: flake8 2025-01-30 14:31:29.993217 | Pipeline: check 2025-01-30 14:31:29.993238 | Executor: 7d211f194f6a 2025-01-30 14:31:29.993258 | Triggered by: https://github.com/osism/python-osism/pull/1193 2025-01-30 14:31:29.993277 | Event ID: d97b3660-df16-11ef-9004-078252261cc0 2025-01-30 14:31:30.008766 | 2025-01-30 14:31:30.008913 | LOOP [emit-job-header : Print node information] 2025-01-30 14:31:30.201859 | ubuntu-noble | ok: 2025-01-30 14:31:30.202057 | ubuntu-noble | # Node Information 2025-01-30 14:31:30.202091 | ubuntu-noble | Inventory Hostname: ubuntu-noble 2025-01-30 14:31:30.202117 | ubuntu-noble | Hostname: ubuntu 2025-01-30 14:31:30.202139 | ubuntu-noble | Username: zuul 2025-01-30 14:31:30.202159 | ubuntu-noble | Distro: Ubuntu 24.04 2025-01-30 14:31:30.202179 | ubuntu-noble | Provider: regiocloud-a 2025-01-30 14:31:30.202198 | ubuntu-noble | Label: ubuntu-noble 2025-01-30 14:31:30.202219 | ubuntu-noble | Product Name: OpenStack Nova 2025-01-30 14:31:30.202240 | ubuntu-noble | Interface IP: 81.163.192.12 2025-01-30 14:31:30.233080 | 2025-01-30 14:31:30.233216 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-01-30 14:31:31.206651 | ubuntu-noble -> localhost | changed 2025-01-30 14:31:31.215305 | 2025-01-30 14:31:31.215433 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-01-30 14:31:32.813085 | ubuntu-noble -> localhost | changed 2025-01-30 14:31:32.837249 | 2025-01-30 14:31:32.837367 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-01-30 14:31:33.300152 | ubuntu-noble -> localhost | ok 2025-01-30 14:31:33.306855 | 2025-01-30 14:31:33.306975 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-01-30 14:31:33.336306 | ubuntu-noble | ok 2025-01-30 14:31:33.358082 | ubuntu-noble | included: /var/lib/zuul/builds/2bda976d8c6f49089b4879ff2e48b306/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-01-30 14:31:33.364428 | 2025-01-30 14:31:33.364509 | TASK [add-build-sshkey : Create Temp SSH key] 2025-01-30 14:31:34.669930 | ubuntu-noble -> localhost | Generating public/private rsa key pair. 2025-01-30 14:31:34.670110 | ubuntu-noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/2bda976d8c6f49089b4879ff2e48b306/work/2bda976d8c6f49089b4879ff2e48b306_id_rsa 2025-01-30 14:31:34.670145 | ubuntu-noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/2bda976d8c6f49089b4879ff2e48b306/work/2bda976d8c6f49089b4879ff2e48b306_id_rsa.pub 2025-01-30 14:31:34.670170 | ubuntu-noble -> localhost | The key fingerprint is: 2025-01-30 14:31:34.670192 | ubuntu-noble -> localhost | SHA256:mt5GIAZxiG27mE2fQybhsErYl+wVPEduRmKXWWPUvaY zuul-build-sshkey 2025-01-30 14:31:34.670214 | ubuntu-noble -> localhost | The key's randomart image is: 2025-01-30 14:31:34.670239 | ubuntu-noble -> localhost | +---[RSA 3072]----+ 2025-01-30 14:31:34.670260 | ubuntu-noble -> localhost | | o.o..o.+==. . | 2025-01-30 14:31:34.670279 | ubuntu-noble -> localhost | |o *. .+=+. .. . | 2025-01-30 14:31:34.670299 | ubuntu-noble -> localhost | |.* = . ++ . | 2025-01-30 14:31:34.670318 | ubuntu-noble -> localhost | |o.* O oo o | 2025-01-30 14:31:34.670337 | ubuntu-noble -> localhost | |o= X + .S o | 2025-01-30 14:31:34.670356 | ubuntu-noble -> localhost | |+ o = o. E | 2025-01-30 14:31:34.670375 | ubuntu-noble -> localhost | | .o. | 2025-01-30 14:31:34.670394 | ubuntu-noble -> localhost | | . .. | 2025-01-30 14:31:34.670413 | ubuntu-noble -> localhost | | ... | 2025-01-30 14:31:34.670432 | ubuntu-noble -> localhost | +----[SHA256]-----+ 2025-01-30 14:31:34.670478 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.524744 2025-01-30 14:31:34.678218 | 2025-01-30 14:31:34.678316 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-01-30 14:31:34.719066 | ubuntu-noble | ok 2025-01-30 14:31:34.737093 | ubuntu-noble | included: /var/lib/zuul/builds/2bda976d8c6f49089b4879ff2e48b306/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-01-30 14:31:34.763175 | 2025-01-30 14:31:34.763279 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-01-30 14:31:34.797404 | ubuntu-noble | skipping: Conditional result was False 2025-01-30 14:31:34.806135 | 2025-01-30 14:31:34.806223 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-01-30 14:31:35.525414 | ubuntu-noble | changed 2025-01-30 14:31:35.533656 | 2025-01-30 14:31:35.533774 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-01-30 14:31:35.874667 | ubuntu-noble | ok 2025-01-30 14:31:35.882821 | 2025-01-30 14:31:35.882922 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-01-30 14:31:36.655738 | ubuntu-noble | changed 2025-01-30 14:31:36.665278 | 2025-01-30 14:31:36.665388 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-01-30 14:31:37.372847 | ubuntu-noble | changed 2025-01-30 14:31:37.387635 | 2025-01-30 14:31:37.387844 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-01-30 14:31:37.405822 | ubuntu-noble | skipping: Conditional result was False 2025-01-30 14:31:37.415573 | 2025-01-30 14:31:37.415670 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-01-30 14:31:37.846892 | ubuntu-noble -> localhost | changed 2025-01-30 14:31:37.901684 | 2025-01-30 14:31:37.901800 | TASK [add-build-sshkey : Add back temp key] 2025-01-30 14:31:38.407834 | ubuntu-noble -> localhost | Identity added: /var/lib/zuul/builds/2bda976d8c6f49089b4879ff2e48b306/work/2bda976d8c6f49089b4879ff2e48b306_id_rsa (zuul-build-sshkey) 2025-01-30 14:31:38.408029 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.028779 2025-01-30 14:31:38.415352 | 2025-01-30 14:31:38.415450 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-01-30 14:31:38.794267 | ubuntu-noble | ok 2025-01-30 14:31:38.815057 | 2025-01-30 14:31:38.815645 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-01-30 14:31:38.851631 | ubuntu-noble | skipping: Conditional result was False 2025-01-30 14:31:38.879253 | 2025-01-30 14:31:38.879359 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-01-30 14:31:39.299169 | ubuntu-noble | ok 2025-01-30 14:31:39.318074 | 2025-01-30 14:31:39.318186 | TASK [validate-host : Define zuul_info_dir fact] 2025-01-30 14:31:39.364593 | ubuntu-noble | ok 2025-01-30 14:31:39.382158 | 2025-01-30 14:31:39.382263 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-01-30 14:31:39.742588 | ubuntu-noble -> localhost | ok 2025-01-30 14:31:39.756163 | 2025-01-30 14:31:39.756286 | TASK [validate-host : Collect information about the host] 2025-01-30 14:31:41.050417 | ubuntu-noble | ok 2025-01-30 14:31:41.065530 | 2025-01-30 14:31:41.065629 | TASK [validate-host : Sanitize hostname] 2025-01-30 14:31:41.158904 | ubuntu-noble | ok 2025-01-30 14:31:41.166432 | 2025-01-30 14:31:41.166541 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-01-30 14:31:41.929612 | ubuntu-noble -> localhost | changed 2025-01-30 14:31:41.937219 | 2025-01-30 14:31:41.937348 | TASK [validate-host : Collect information about zuul worker] 2025-01-30 14:31:42.583792 | ubuntu-noble | ok 2025-01-30 14:31:42.590345 | 2025-01-30 14:31:42.590470 | TASK [validate-host : Write out all zuul information for each host] 2025-01-30 14:31:43.347251 | ubuntu-noble -> localhost | changed 2025-01-30 14:31:43.360954 | 2025-01-30 14:31:43.361086 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-01-30 14:31:43.709906 | ubuntu-noble | ok 2025-01-30 14:31:43.734013 | 2025-01-30 14:31:43.734161 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-01-30 14:31:45.011066 | ubuntu-noble | changed: 2025-01-30 14:31:45.011271 | ubuntu-noble | cd+++++++++ src/ 2025-01-30 14:31:45.011306 | ubuntu-noble | cd+++++++++ src/github.com/ 2025-01-30 14:31:45.011331 | ubuntu-noble | cd+++++++++ src/github.com/osism/ 2025-01-30 14:31:45.011352 | ubuntu-noble | cd+++++++++ src/github.com/osism/python-osism/ 2025-01-30 14:31:45.011374 | ubuntu-noble | Containerfile 2025-01-30 14:31:45.011489 | ubuntu-noble | localhost | ok: Runtime: 0:00:00.007392 2025-01-30 14:32:44.639275 | 2025-01-30 14:32:44.639427 | TASK [upload-logs : Upload logs to log server] 2025-01-30 14:32:45.199226 | localhost | Output suppressed because no_log was given 2025-01-30 14:32:45.204858 | 2025-01-30 14:32:45.205002 | LOOP [upload-logs : Compress console log and json output] 2025-01-30 14:32:45.283173 | localhost | skipping: Conditional result was False 2025-01-30 14:32:45.301318 | localhost | skipping: Conditional result was False 2025-01-30 14:32:45.318809 | 2025-01-30 14:32:45.319110 | LOOP [upload-logs : Upload compressed console log and json output] 2025-01-30 14:32:45.384930 | localhost | skipping: Conditional result was False 2025-01-30 14:32:45.385481 | 2025-01-30 14:32:45.398518 | localhost | skipping: Conditional result was False 2025-01-30 14:32:45.412356 | 2025-01-30 14:32:45.412558 | LOOP [upload-logs : Upload console log and json output]