2025-01-16 14:40:33.779951 | Job console starting... 2025-01-16 14:40:33.815788 | Updating repositories 2025-01-16 14:40:33.925125 | Preparing job workspace 2025-01-16 14:40:36.570417 | Running Ansible setup... 2025-01-16 14:40:43.288319 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-01-16 14:40:44.139015 | 2025-01-16 14:40:44.139129 | PLAY [Base pre] 2025-01-16 14:40:44.195748 | 2025-01-16 14:40:44.195860 | TASK [Setup log path fact] 2025-01-16 14:40:44.246000 | ubuntu-noble | ok 2025-01-16 14:40:44.273393 | 2025-01-16 14:40:44.273508 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-01-16 14:40:44.374988 | ubuntu-noble | ok 2025-01-16 14:40:44.380470 | 2025-01-16 14:40:44.380550 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-01-16 14:40:44.444407 | ubuntu-noble | skipping: Conditional result was False 2025-01-16 14:40:44.450250 | 2025-01-16 14:40:44.450340 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-01-16 14:40:44.514053 | ubuntu-noble | skipping: Conditional result was False 2025-01-16 14:40:44.520142 | 2025-01-16 14:40:44.520243 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-01-16 14:40:44.563825 | ubuntu-noble | skipping: Conditional result was False 2025-01-16 14:40:44.569745 | 2025-01-16 14:40:44.569834 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-01-16 14:40:44.636246 | ubuntu-noble | skipping: Conditional result was False 2025-01-16 14:40:44.643133 | 2025-01-16 14:40:44.643234 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-01-16 14:40:44.686892 | ubuntu-noble | skipping: Conditional result was False 2025-01-16 14:40:44.697904 | 2025-01-16 14:40:44.703401 | TASK [emit-job-header : Print job information] 2025-01-16 14:40:44.864651 | # Job Information 2025-01-16 14:40:44.864792 | Ansible Version: 2.15.3 2025-01-16 14:40:44.864817 | Job: python-black 2025-01-16 14:40:44.864841 | Pipeline: check 2025-01-16 14:40:44.864859 | Executor: 7d211f194f6a 2025-01-16 14:40:44.864875 | Triggered by: https://github.com/osism/python-osism/pull/1177 2025-01-16 14:40:44.864891 | Event ID: d26e3930-d417-11ef-8f3c-78376428a64a 2025-01-16 14:40:44.871623 | 2025-01-16 14:40:44.871713 | LOOP [emit-job-header : Print node information] 2025-01-16 14:40:45.038045 | ubuntu-noble | ok: 2025-01-16 14:40:45.038201 | ubuntu-noble | # Node Information 2025-01-16 14:40:45.038229 | ubuntu-noble | Inventory Hostname: ubuntu-noble 2025-01-16 14:40:45.038249 | ubuntu-noble | Hostname: ubuntu 2025-01-16 14:40:45.038267 | ubuntu-noble | Username: zuul 2025-01-16 14:40:45.038283 | ubuntu-noble | Distro: Ubuntu 24.04 2025-01-16 14:40:45.038299 | ubuntu-noble | Provider: regiocloud-a 2025-01-16 14:40:45.038314 | ubuntu-noble | Label: ubuntu-noble 2025-01-16 14:40:45.038423 | ubuntu-noble | Product Name: OpenStack Nova 2025-01-16 14:40:45.038448 | ubuntu-noble | Interface IP: 81.163.193.245 2025-01-16 14:40:45.071142 | 2025-01-16 14:40:45.071264 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-01-16 14:40:45.870381 | ubuntu-noble -> localhost | changed 2025-01-16 14:40:45.877214 | 2025-01-16 14:40:45.877314 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-01-16 14:40:47.578973 | ubuntu-noble -> localhost | changed 2025-01-16 14:40:47.590496 | 2025-01-16 14:40:47.590592 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-01-16 14:40:48.076611 | ubuntu-noble -> localhost | ok 2025-01-16 14:40:48.082602 | 2025-01-16 14:40:48.082683 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-01-16 14:40:48.121200 | ubuntu-noble | ok 2025-01-16 14:40:48.135009 | ubuntu-noble | included: /var/lib/zuul/builds/6a1b510921bd4359bd8831c35fe0a310/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-01-16 14:40:48.141056 | 2025-01-16 14:40:48.141127 | TASK [add-build-sshkey : Create Temp SSH key] 2025-01-16 14:40:48.819769 | ubuntu-noble -> localhost | Generating public/private rsa key pair. 2025-01-16 14:40:48.819928 | ubuntu-noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/6a1b510921bd4359bd8831c35fe0a310/work/6a1b510921bd4359bd8831c35fe0a310_id_rsa 2025-01-16 14:40:48.819959 | ubuntu-noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/6a1b510921bd4359bd8831c35fe0a310/work/6a1b510921bd4359bd8831c35fe0a310_id_rsa.pub 2025-01-16 14:40:48.819980 | ubuntu-noble -> localhost | The key fingerprint is: 2025-01-16 14:40:48.819999 | ubuntu-noble -> localhost | SHA256:CYviDW/udFrpKa5hk052qfzTgBaqwMlJv7yU5Fp8eiY zuul-build-sshkey 2025-01-16 14:40:48.820016 | ubuntu-noble -> localhost | The key's randomart image is: 2025-01-16 14:40:48.820037 | ubuntu-noble -> localhost | +---[RSA 3072]----+ 2025-01-16 14:40:48.820054 | ubuntu-noble -> localhost | | | 2025-01-16 14:40:48.820070 | ubuntu-noble -> localhost | | | 2025-01-16 14:40:48.820086 | ubuntu-noble -> localhost | | . | 2025-01-16 14:40:48.820101 | ubuntu-noble -> localhost | | .. . o . | 2025-01-16 14:40:48.820117 | ubuntu-noble -> localhost | |+.*+. . S | 2025-01-16 14:40:48.820132 | ubuntu-noble -> localhost | |o**Bo. . | 2025-01-16 14:40:48.820172 | ubuntu-noble -> localhost | |o.X*O++ | 2025-01-16 14:40:48.820192 | ubuntu-noble -> localhost | |.*+E=B.. | 2025-01-16 14:40:48.820208 | ubuntu-noble -> localhost | | .=B@oo | 2025-01-16 14:40:48.820224 | ubuntu-noble -> localhost | +----[SHA256]-----+ 2025-01-16 14:40:48.820263 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.199258 2025-01-16 14:40:48.826410 | 2025-01-16 14:40:48.826491 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-01-16 14:40:48.855060 | ubuntu-noble | ok 2025-01-16 14:40:48.866202 | ubuntu-noble | included: /var/lib/zuul/builds/6a1b510921bd4359bd8831c35fe0a310/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-01-16 14:40:48.873755 | 2025-01-16 14:40:48.873838 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-01-16 14:40:48.921745 | ubuntu-noble | skipping: Conditional result was False 2025-01-16 14:40:48.927620 | 2025-01-16 14:40:48.927700 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-01-16 14:40:49.673578 | ubuntu-noble | changed 2025-01-16 14:40:49.685872 | 2025-01-16 14:40:49.686080 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-01-16 14:40:50.002434 | ubuntu-noble | ok 2025-01-16 14:40:50.015967 | 2025-01-16 14:40:50.016218 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-01-16 14:40:50.776881 | ubuntu-noble | changed 2025-01-16 14:40:50.786967 | 2025-01-16 14:40:50.787070 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-01-16 14:40:51.534007 | ubuntu-noble | changed 2025-01-16 14:40:51.539821 | 2025-01-16 14:40:51.539901 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-01-16 14:40:51.563201 | ubuntu-noble | skipping: Conditional result was False 2025-01-16 14:40:51.569070 | 2025-01-16 14:40:51.569179 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-01-16 14:40:52.123622 | ubuntu-noble -> localhost | changed 2025-01-16 14:40:52.136451 | 2025-01-16 14:40:52.136544 | TASK [add-build-sshkey : Add back temp key] 2025-01-16 14:40:52.635371 | ubuntu-noble -> localhost | Identity added: /var/lib/zuul/builds/6a1b510921bd4359bd8831c35fe0a310/work/6a1b510921bd4359bd8831c35fe0a310_id_rsa (zuul-build-sshkey) 2025-01-16 14:40:52.635507 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.024040 2025-01-16 14:40:52.641191 | 2025-01-16 14:40:52.641275 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-01-16 14:40:53.015846 | ubuntu-noble | ok 2025-01-16 14:40:53.021900 | 2025-01-16 14:40:53.021997 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-01-16 14:40:53.045274 | ubuntu-noble | skipping: Conditional result was False 2025-01-16 14:40:53.055942 | 2025-01-16 14:40:53.056031 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-01-16 14:40:53.503492 | ubuntu-noble | ok 2025-01-16 14:40:53.518887 | 2025-01-16 14:40:53.525930 | TASK [validate-host : Define zuul_info_dir fact] 2025-01-16 14:40:53.555477 | ubuntu-noble | ok 2025-01-16 14:40:53.561889 | 2025-01-16 14:40:53.561993 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-01-16 14:40:53.841504 | ubuntu-noble -> localhost | ok 2025-01-16 14:40:53.849466 | 2025-01-16 14:40:53.849568 | TASK [validate-host : Collect information about the host] 2025-01-16 14:40:54.988716 | ubuntu-noble | ok 2025-01-16 14:40:55.004956 | 2025-01-16 14:40:55.005049 | TASK [validate-host : Sanitize hostname] 2025-01-16 14:40:55.107837 | ubuntu-noble | ok 2025-01-16 14:40:55.118215 | 2025-01-16 14:40:55.118309 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-01-16 14:40:55.740745 | ubuntu-noble -> localhost | changed 2025-01-16 14:40:55.747574 | 2025-01-16 14:40:55.747667 | TASK [validate-host : Collect information about zuul worker] 2025-01-16 14:40:56.256368 | ubuntu-noble | ok 2025-01-16 14:40:56.261275 | 2025-01-16 14:40:56.261352 | TASK [validate-host : Write out all zuul information for each host] 2025-01-16 14:40:56.922940 | ubuntu-noble -> localhost | changed 2025-01-16 14:40:56.936772 | 2025-01-16 14:40:56.936865 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-01-16 14:40:57.248516 | ubuntu-noble | ok 2025-01-16 14:40:57.258070 | 2025-01-16 14:40:57.258172 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-01-16 14:40:58.383191 | ubuntu-noble | changed: 2025-01-16 14:40:58.383332 | ubuntu-noble | cd+++++++++ src/ 2025-01-16 14:40:58.383359 | ubuntu-noble | cd+++++++++ src/github.com/ 2025-01-16 14:40:58.383379 | ubuntu-noble | cd+++++++++ src/github.com/osism/ 2025-01-16 14:40:58.383396 | ubuntu-noble | cd+++++++++ src/github.com/osism/python-osism/ 2025-01-16 14:40:58.383413 | ubuntu-noble | Containerfile 2025-01-16 14:40:58.383505 | ubuntu-noble | localhost | ok: Runtime: 0:00:00.007223 2025-01-16 14:41:31.947957 | 2025-01-16 14:41:31.948065 | TASK [upload-logs : Upload logs to log server] 2025-01-16 14:41:32.531431 | localhost | Output suppressed because no_log was given 2025-01-16 14:41:32.536841 | 2025-01-16 14:41:32.536993 | LOOP [upload-logs : Compress console log and json output] 2025-01-16 14:41:32.611402 | localhost | skipping: Conditional result was False 2025-01-16 14:41:32.628372 | localhost | skipping: Conditional result was False 2025-01-16 14:41:32.644573 | 2025-01-16 14:41:32.644739 | LOOP [upload-logs : Upload compressed console log and json output] 2025-01-16 14:41:32.706574 | localhost | skipping: Conditional result was False 2025-01-16 14:41:32.706862 | 2025-01-16 14:41:32.722280 | localhost | skipping: Conditional result was False 2025-01-16 14:41:32.742000 | 2025-01-16 14:41:32.742202 | LOOP [upload-logs : Upload console log and json output]