2025-01-24 18:30:30.785068 | Job console starting... 2025-01-24 18:30:30.805744 | Updating repositories 2025-01-24 18:30:30.858086 | Preparing job workspace 2025-01-24 18:30:32.451358 | Running Ansible setup... 2025-01-24 18:30:37.238017 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-01-24 18:30:38.022930 | 2025-01-24 18:30:38.023109 | PLAY [Base pre] 2025-01-24 18:30:38.053951 | 2025-01-24 18:30:38.054120 | TASK [Setup log path fact] 2025-01-24 18:30:38.087841 | ubuntu-noble | ok 2025-01-24 18:30:38.110233 | 2025-01-24 18:30:38.110399 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-01-24 18:30:38.181663 | ubuntu-noble | ok 2025-01-24 18:30:38.191349 | 2025-01-24 18:30:38.191495 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-01-24 18:30:38.227546 | ubuntu-noble | skipping: Conditional result was False 2025-01-24 18:30:38.236499 | 2025-01-24 18:30:38.236650 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-01-24 18:30:38.282408 | ubuntu-noble | skipping: Conditional result was False 2025-01-24 18:30:38.290525 | 2025-01-24 18:30:38.290679 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-01-24 18:30:38.316251 | ubuntu-noble | skipping: Conditional result was False 2025-01-24 18:30:38.323802 | 2025-01-24 18:30:38.323931 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-01-24 18:30:38.349389 | ubuntu-noble | skipping: Conditional result was False 2025-01-24 18:30:38.357284 | 2025-01-24 18:30:38.357421 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-01-24 18:30:38.382208 | ubuntu-noble | skipping: Conditional result was False 2025-01-24 18:30:38.398518 | 2025-01-24 18:30:38.398658 | TASK [emit-job-header : Print job information] 2025-01-24 18:30:38.465644 | # Job Information 2025-01-24 18:30:38.465857 | Ansible Version: 2.15.3 2025-01-24 18:30:38.465895 | Job: python-black 2025-01-24 18:30:38.465923 | Pipeline: check 2025-01-24 18:30:38.465943 | Executor: 7d211f194f6a 2025-01-24 18:30:38.465962 | Triggered by: https://github.com/osism/container-images-kolla/pull/537 2025-01-24 18:30:38.465980 | Event ID: 46283050-da81-11ef-8928-0a65e22a1523 2025-01-24 18:30:38.477603 | 2025-01-24 18:30:38.477957 | LOOP [emit-job-header : Print node information] 2025-01-24 18:30:38.621984 | ubuntu-noble | ok: 2025-01-24 18:30:38.622193 | ubuntu-noble | # Node Information 2025-01-24 18:30:38.622227 | ubuntu-noble | Inventory Hostname: ubuntu-noble 2025-01-24 18:30:38.622251 | ubuntu-noble | Hostname: ubuntu 2025-01-24 18:30:38.622273 | ubuntu-noble | Username: zuul 2025-01-24 18:30:38.622293 | ubuntu-noble | Distro: Ubuntu 24.04 2025-01-24 18:30:38.622312 | ubuntu-noble | Provider: regiocloud-a 2025-01-24 18:30:38.622331 | ubuntu-noble | Label: ubuntu-noble 2025-01-24 18:30:38.622350 | ubuntu-noble | Product Name: OpenStack Nova 2025-01-24 18:30:38.622370 | ubuntu-noble | Interface IP: 81.163.192.195 2025-01-24 18:30:38.642742 | 2025-01-24 18:30:38.642879 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-01-24 18:30:39.168875 | ubuntu-noble -> localhost | changed 2025-01-24 18:30:39.177666 | 2025-01-24 18:30:39.177789 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-01-24 18:30:40.315645 | ubuntu-noble -> localhost | changed 2025-01-24 18:30:40.336635 | 2025-01-24 18:30:40.336767 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-01-24 18:30:40.643126 | ubuntu-noble -> localhost | ok 2025-01-24 18:30:40.653286 | 2025-01-24 18:30:40.653434 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-01-24 18:30:40.705745 | ubuntu-noble | ok 2025-01-24 18:30:40.727973 | ubuntu-noble | included: /var/lib/zuul/builds/5023d042dfeb431fae034f0e1e4e6ea1/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-01-24 18:30:40.736049 | 2025-01-24 18:30:40.736190 | TASK [add-build-sshkey : Create Temp SSH key] 2025-01-24 18:30:41.462122 | ubuntu-noble -> localhost | Generating public/private rsa key pair. 2025-01-24 18:30:41.462431 | ubuntu-noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/5023d042dfeb431fae034f0e1e4e6ea1/work/5023d042dfeb431fae034f0e1e4e6ea1_id_rsa 2025-01-24 18:30:41.462470 | ubuntu-noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/5023d042dfeb431fae034f0e1e4e6ea1/work/5023d042dfeb431fae034f0e1e4e6ea1_id_rsa.pub 2025-01-24 18:30:41.462495 | ubuntu-noble -> localhost | The key fingerprint is: 2025-01-24 18:30:41.462519 | ubuntu-noble -> localhost | SHA256:1NM5bmdWHmn/qNqmJ2CAA2PJwkuMD05MPllaCYbjOtM zuul-build-sshkey 2025-01-24 18:30:41.462541 | ubuntu-noble -> localhost | The key's randomart image is: 2025-01-24 18:30:41.462567 | ubuntu-noble -> localhost | +---[RSA 3072]----+ 2025-01-24 18:30:41.462589 | ubuntu-noble -> localhost | |B=.+. | 2025-01-24 18:30:41.462609 | ubuntu-noble -> localhost | |O*@. . . . .| 2025-01-24 18:30:41.462629 | ubuntu-noble -> localhost | |=@.o . . o + +.| 2025-01-24 18:30:41.462648 | ubuntu-noble -> localhost | | +o o .. o ..oo| 2025-01-24 18:30:41.462667 | ubuntu-noble -> localhost | |.. . .S o + o| 2025-01-24 18:30:41.462686 | ubuntu-noble -> localhost | |+ E o . + ..| 2025-01-24 18:30:41.462705 | ubuntu-noble -> localhost | | o . . . .| 2025-01-24 18:30:41.462724 | ubuntu-noble -> localhost | | ..o. | 2025-01-24 18:30:41.462743 | ubuntu-noble -> localhost | | o*o | 2025-01-24 18:30:41.462762 | ubuntu-noble -> localhost | +----[SHA256]-----+ 2025-01-24 18:30:41.462813 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.215018 2025-01-24 18:30:41.473699 | 2025-01-24 18:30:41.473895 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-01-24 18:30:41.513753 | ubuntu-noble | ok 2025-01-24 18:30:41.531562 | ubuntu-noble | included: /var/lib/zuul/builds/5023d042dfeb431fae034f0e1e4e6ea1/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-01-24 18:30:41.541644 | 2025-01-24 18:30:41.541773 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-01-24 18:30:41.568055 | ubuntu-noble | skipping: Conditional result was False 2025-01-24 18:30:41.576452 | 2025-01-24 18:30:41.576624 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-01-24 18:30:42.232269 | ubuntu-noble | changed 2025-01-24 18:30:42.246837 | 2025-01-24 18:30:42.247004 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-01-24 18:30:42.532521 | ubuntu-noble | ok 2025-01-24 18:30:42.542445 | 2025-01-24 18:30:42.542591 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-01-24 18:30:43.203273 | ubuntu-noble | changed 2025-01-24 18:30:43.214054 | 2025-01-24 18:30:43.214234 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-01-24 18:30:43.858941 | ubuntu-noble | changed 2025-01-24 18:30:43.870006 | 2025-01-24 18:30:43.870131 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-01-24 18:30:43.895225 | ubuntu-noble | skipping: Conditional result was False 2025-01-24 18:30:43.904723 | 2025-01-24 18:30:43.904838 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-01-24 18:30:44.391806 | ubuntu-noble -> localhost | changed 2025-01-24 18:30:44.415443 | 2025-01-24 18:30:44.415590 | TASK [add-build-sshkey : Add back temp key] 2025-01-24 18:30:44.793113 | ubuntu-noble -> localhost | Identity added: /var/lib/zuul/builds/5023d042dfeb431fae034f0e1e4e6ea1/work/5023d042dfeb431fae034f0e1e4e6ea1_id_rsa (zuul-build-sshkey) 2025-01-24 18:30:44.793522 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.019030 2025-01-24 18:30:44.801517 | 2025-01-24 18:30:44.801640 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-01-24 18:30:45.274194 | ubuntu-noble | ok 2025-01-24 18:30:45.280684 | 2025-01-24 18:30:45.280799 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-01-24 18:30:45.306500 | ubuntu-noble | skipping: Conditional result was False 2025-01-24 18:30:45.323812 | 2025-01-24 18:30:45.323937 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-01-24 18:30:45.734372 | ubuntu-noble | ok 2025-01-24 18:30:45.751660 | 2025-01-24 18:30:45.751807 | TASK [validate-host : Define zuul_info_dir fact] 2025-01-24 18:30:45.801278 | ubuntu-noble | ok 2025-01-24 18:30:45.808131 | 2025-01-24 18:30:45.808263 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-01-24 18:30:46.151320 | ubuntu-noble -> localhost | ok 2025-01-24 18:30:46.168584 | 2025-01-24 18:30:46.168721 | TASK [validate-host : Collect information about the host] 2025-01-24 18:30:47.325830 | ubuntu-noble | ok 2025-01-24 18:30:47.339821 | 2025-01-24 18:30:47.339969 | TASK [validate-host : Sanitize hostname] 2025-01-24 18:30:47.421579 | ubuntu-noble | ok 2025-01-24 18:30:47.431573 | 2025-01-24 18:30:47.431733 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-01-24 18:30:48.050407 | ubuntu-noble -> localhost | changed 2025-01-24 18:30:48.058035 | 2025-01-24 18:30:48.058186 | TASK [validate-host : Collect information about zuul worker] 2025-01-24 18:30:48.543884 | ubuntu-noble | ok 2025-01-24 18:30:48.554323 | 2025-01-24 18:30:48.554468 | TASK [validate-host : Write out all zuul information for each host] 2025-01-24 18:30:49.144351 | ubuntu-noble -> localhost | changed 2025-01-24 18:30:49.165905 | 2025-01-24 18:30:49.166039 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-01-24 18:30:49.481983 | ubuntu-noble | ok 2025-01-24 18:30:49.490227 | 2025-01-24 18:30:49.490342 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-01-24 18:30:50.360719 | ubuntu-noble | changed: 2025-01-24 18:30:50.360927 | ubuntu-noble | cd+++++++++ src/ 2025-01-24 18:30:50.360959 | ubuntu-noble | cd+++++++++ src/github.com/ 2025-01-24 18:30:50.360982 | ubuntu-noble | cd+++++++++ src/github.com/osism/ 2025-01-24 18:30:50.361004 | ubuntu-noble | cd+++++++++ src/github.com/osism/container-images-kolla/ 2025-01-24 18:30:50.361024 | ubuntu-noble | localhost | ok: Runtime: 0:00:00.009211 2025-01-24 18:31:21.969339 | 2025-01-24 18:31:21.969453 | TASK [upload-logs : Upload logs to log server] 2025-01-24 18:31:22.579339 | localhost | Output suppressed because no_log was given 2025-01-24 18:31:22.583017 | 2025-01-24 18:31:22.583181 | LOOP [upload-logs : Compress console log and json output] 2025-01-24 18:31:22.650612 | localhost | skipping: Conditional result was False 2025-01-24 18:31:22.670552 | localhost | skipping: Conditional result was False 2025-01-24 18:31:22.677780 | 2025-01-24 18:31:22.678033 | LOOP [upload-logs : Upload compressed console log and json output] 2025-01-24 18:31:22.761236 | localhost | skipping: Conditional result was False 2025-01-24 18:31:22.761579 | 2025-01-24 18:31:22.785512 | localhost | skipping: Conditional result was False 2025-01-24 18:31:22.807698 | 2025-01-24 18:31:22.808008 | LOOP [upload-logs : Upload console log and json output]