2025-02-25 21:40:41.209585 | Job console starting... 2025-02-25 21:40:41.238342 | Updating repositories 2025-02-25 21:40:41.285270 | Preparing job workspace 2025-02-25 21:40:43.464570 | Running Ansible setup... 2025-02-25 21:40:48.848337 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-02-25 21:40:49.566604 | 2025-02-25 21:40:49.566766 | PLAY [Base pre] 2025-02-25 21:40:49.596723 | 2025-02-25 21:40:49.596895 | TASK [Setup log path fact] 2025-02-25 21:40:49.649777 | ubuntu-noble | ok 2025-02-25 21:40:49.678468 | 2025-02-25 21:40:49.678619 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-02-25 21:40:49.730002 | ubuntu-noble | ok 2025-02-25 21:40:49.737002 | 2025-02-25 21:40:49.737130 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-02-25 21:40:49.779513 | ubuntu-noble | skipping: Conditional result was False 2025-02-25 21:40:49.789517 | 2025-02-25 21:40:49.789672 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-02-25 21:40:49.897318 | ubuntu-noble | skipping: Conditional result was False 2025-02-25 21:40:49.917692 | 2025-02-25 21:40:49.917863 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-02-25 21:40:49.974973 | ubuntu-noble | skipping: Conditional result was False 2025-02-25 21:40:49.984053 | 2025-02-25 21:40:49.984211 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-02-25 21:40:50.019605 | ubuntu-noble | skipping: Conditional result was False 2025-02-25 21:40:50.029039 | 2025-02-25 21:40:50.029177 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-02-25 21:40:50.069140 | ubuntu-noble | skipping: Conditional result was False 2025-02-25 21:40:50.094315 | 2025-02-25 21:40:50.094443 | TASK [emit-job-header : Print job information] 2025-02-25 21:40:50.146714 | # Job Information 2025-02-25 21:40:50.146900 | Ansible Version: 2.15.3 2025-02-25 21:40:50.146933 | Job: python-black 2025-02-25 21:40:50.146961 | Pipeline: check 2025-02-25 21:40:50.146983 | Executor: 7d211f194f6a 2025-02-25 21:40:50.147001 | Triggered by: https://github.com/osism/zuul-jobs/pull/149 2025-02-25 21:40:50.147020 | Event ID: 234f4510-f3c1-11ef-9661-a9458485afcc 2025-02-25 21:40:50.154088 | 2025-02-25 21:40:50.154198 | LOOP [emit-job-header : Print node information] 2025-02-25 21:40:50.341623 | ubuntu-noble | ok: 2025-02-25 21:40:50.341821 | ubuntu-noble | # Node Information 2025-02-25 21:40:50.341854 | ubuntu-noble | Inventory Hostname: ubuntu-noble 2025-02-25 21:40:50.341938 | ubuntu-noble | Hostname: ubuntu 2025-02-25 21:40:50.341960 | ubuntu-noble | Username: zuul 2025-02-25 21:40:50.341980 | ubuntu-noble | Distro: Ubuntu 24.04 2025-02-25 21:40:50.341999 | ubuntu-noble | Provider: regiocloud-a 2025-02-25 21:40:50.342018 | ubuntu-noble | Label: ubuntu-noble 2025-02-25 21:40:50.342037 | ubuntu-noble | Product Name: OpenStack Nova 2025-02-25 21:40:50.342058 | ubuntu-noble | Interface IP: 81.163.192.56 2025-02-25 21:40:50.356542 | 2025-02-25 21:40:50.356675 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-02-25 21:40:50.931376 | ubuntu-noble -> localhost | changed 2025-02-25 21:40:50.940435 | 2025-02-25 21:40:50.940550 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-02-25 21:40:51.940139 | ubuntu-noble -> localhost | changed 2025-02-25 21:40:51.953023 | 2025-02-25 21:40:51.953114 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-02-25 21:40:52.221252 | ubuntu-noble -> localhost | ok 2025-02-25 21:40:52.229341 | 2025-02-25 21:40:52.229431 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-02-25 21:40:52.259129 | ubuntu-noble | ok 2025-02-25 21:40:52.274630 | ubuntu-noble | included: /var/lib/zuul/builds/6c3223d8ca3e4650b38bc829699e31ab/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-02-25 21:40:52.282640 | 2025-02-25 21:40:52.282724 | TASK [add-build-sshkey : Create Temp SSH key] 2025-02-25 21:40:53.058409 | ubuntu-noble -> localhost | Generating public/private rsa key pair. 2025-02-25 21:40:53.058864 | ubuntu-noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/6c3223d8ca3e4650b38bc829699e31ab/work/6c3223d8ca3e4650b38bc829699e31ab_id_rsa 2025-02-25 21:40:53.059023 | ubuntu-noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/6c3223d8ca3e4650b38bc829699e31ab/work/6c3223d8ca3e4650b38bc829699e31ab_id_rsa.pub 2025-02-25 21:40:53.059088 | ubuntu-noble -> localhost | The key fingerprint is: 2025-02-25 21:40:53.059147 | ubuntu-noble -> localhost | SHA256:gu/D39g8JIHf9k5NM1gJHwTDLS6OYvmRRONsiPJ+gPI zuul-build-sshkey 2025-02-25 21:40:53.059204 | ubuntu-noble -> localhost | The key's randomart image is: 2025-02-25 21:40:53.059267 | ubuntu-noble -> localhost | +---[RSA 3072]----+ 2025-02-25 21:40:53.059321 | ubuntu-noble -> localhost | | .++o | 2025-02-25 21:40:53.059372 | ubuntu-noble -> localhost | | o o+.o | 2025-02-25 21:40:53.059419 | ubuntu-noble -> localhost | | . * . . .+ | 2025-02-25 21:40:53.059463 | ubuntu-noble -> localhost | | . ..o * . .o | 2025-02-25 21:40:53.059506 | ubuntu-noble -> localhost | | +. .=S* .. + | 2025-02-25 21:40:53.059551 | ubuntu-noble -> localhost | |. . o.+.* = o o | 2025-02-25 21:40:53.059595 | ubuntu-noble -> localhost | | o . +.o = .. . | 2025-02-25 21:40:53.059640 | ubuntu-noble -> localhost | | E ..+ .=... | 2025-02-25 21:40:53.059684 | ubuntu-noble -> localhost | | ..oo +o. | 2025-02-25 21:40:53.059727 | ubuntu-noble -> localhost | +----[SHA256]-----+ 2025-02-25 21:40:53.059826 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.300153 2025-02-25 21:40:53.072682 | 2025-02-25 21:40:53.072785 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-02-25 21:40:53.122106 | ubuntu-noble | ok 2025-02-25 21:40:53.135517 | ubuntu-noble | included: /var/lib/zuul/builds/6c3223d8ca3e4650b38bc829699e31ab/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-02-25 21:40:53.145047 | 2025-02-25 21:40:53.145131 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-02-25 21:40:53.181224 | ubuntu-noble | skipping: Conditional result was False 2025-02-25 21:40:53.193032 | 2025-02-25 21:40:53.193150 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-02-25 21:40:53.914180 | ubuntu-noble | changed 2025-02-25 21:40:53.922666 | 2025-02-25 21:40:53.922759 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-02-25 21:40:54.244878 | ubuntu-noble | ok 2025-02-25 21:40:54.254804 | 2025-02-25 21:40:54.254941 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-02-25 21:40:55.000649 | ubuntu-noble | changed 2025-02-25 21:40:55.007353 | 2025-02-25 21:40:55.007440 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-02-25 21:40:55.754666 | ubuntu-noble | changed 2025-02-25 21:40:55.762896 | 2025-02-25 21:40:55.762985 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-02-25 21:40:55.786579 | ubuntu-noble | skipping: Conditional result was False 2025-02-25 21:40:55.793609 | 2025-02-25 21:40:55.793700 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-02-25 21:40:56.209761 | ubuntu-noble -> localhost | changed 2025-02-25 21:40:56.223130 | 2025-02-25 21:40:56.223222 | TASK [add-build-sshkey : Add back temp key] 2025-02-25 21:40:56.562923 | ubuntu-noble -> localhost | Identity added: /var/lib/zuul/builds/6c3223d8ca3e4650b38bc829699e31ab/work/6c3223d8ca3e4650b38bc829699e31ab_id_rsa (zuul-build-sshkey) 2025-02-25 21:40:56.563117 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.010035 2025-02-25 21:40:56.572733 | 2025-02-25 21:40:56.572930 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-02-25 21:40:57.097161 | ubuntu-noble | ok 2025-02-25 21:40:57.106193 | 2025-02-25 21:40:57.106282 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-02-25 21:40:57.161205 | ubuntu-noble | skipping: Conditional result was False 2025-02-25 21:40:57.185319 | 2025-02-25 21:40:57.185433 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-02-25 21:40:57.667796 | ubuntu-noble | ok 2025-02-25 21:40:57.683049 | 2025-02-25 21:40:57.683139 | TASK [validate-host : Define zuul_info_dir fact] 2025-02-25 21:40:57.712695 | ubuntu-noble | ok 2025-02-25 21:40:57.718928 | 2025-02-25 21:40:57.719008 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-02-25 21:40:57.986424 | ubuntu-noble -> localhost | ok 2025-02-25 21:40:57.994481 | 2025-02-25 21:40:57.994574 | TASK [validate-host : Collect information about the host] 2025-02-25 21:40:59.174884 | ubuntu-noble | ok 2025-02-25 21:40:59.190476 | 2025-02-25 21:40:59.190574 | TASK [validate-host : Sanitize hostname] 2025-02-25 21:40:59.255303 | ubuntu-noble | ok 2025-02-25 21:40:59.261859 | 2025-02-25 21:40:59.261960 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-02-25 21:40:59.766721 | ubuntu-noble -> localhost | changed 2025-02-25 21:40:59.779554 | 2025-02-25 21:40:59.779839 | TASK [validate-host : Collect information about zuul worker] 2025-02-25 21:41:00.282360 | ubuntu-noble | ok 2025-02-25 21:41:00.289618 | 2025-02-25 21:41:00.289714 | TASK [validate-host : Write out all zuul information for each host] 2025-02-25 21:41:00.830485 | ubuntu-noble -> localhost | changed 2025-02-25 21:41:00.842691 | 2025-02-25 21:41:00.842783 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-02-25 21:41:01.171570 | ubuntu-noble | ok 2025-02-25 21:41:01.178407 | 2025-02-25 21:41:01.178496 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-02-25 21:41:02.023622 | ubuntu-noble | changed: 2025-02-25 21:41:02.023820 | ubuntu-noble | cd+++++++++ src/ 2025-02-25 21:41:02.023852 | ubuntu-noble | cd+++++++++ src/github.com/ 2025-02-25 21:41:02.023914 | ubuntu-noble | cd+++++++++ src/github.com/osism/ 2025-02-25 21:41:02.023938 | ubuntu-noble | cd+++++++++ src/github.com/osism/zuul-jobs/ 2025-02-25 21:41:02.023958 | ubuntu-noble | localhost | ok: Runtime: 0:00:00.003718 2025-02-25 21:41:38.361845 | 2025-02-25 21:41:38.361979 | TASK [upload-logs : Upload logs to log server] 2025-02-25 21:41:39.068981 | localhost | Output suppressed because no_log was given 2025-02-25 21:41:39.071325 | 2025-02-25 21:41:39.071394 | LOOP [upload-logs : Compress console log and json output] 2025-02-25 21:41:39.146383 | localhost | skipping: Conditional result was False 2025-02-25 21:41:39.161735 | localhost | skipping: Conditional result was False 2025-02-25 21:41:39.170233 | 2025-02-25 21:41:39.170307 | LOOP [upload-logs : Upload compressed console log and json output] 2025-02-25 21:41:39.237184 | localhost | skipping: Conditional result was False 2025-02-25 21:41:39.237403 | 2025-02-25 21:41:39.247109 | localhost | skipping: Conditional result was False 2025-02-25 21:41:39.257519 | 2025-02-25 21:41:39.257595 | LOOP [upload-logs : Upload console log and json output]