2025-03-04 20:00:38.355058 | Job console starting... 2025-03-04 20:00:38.372996 | Updating repositories 2025-03-04 20:00:38.400610 | Preparing job workspace 2025-03-04 20:00:39.832414 | Running Ansible setup... 2025-03-04 20:00:45.159992 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-03-04 20:00:45.852603 | 2025-03-04 20:00:45.852775 | PLAY [Base pre] 2025-03-04 20:00:45.882729 | 2025-03-04 20:00:45.882886 | TASK [Setup log path fact] 2025-03-04 20:00:45.905670 | ubuntu-noble | ok 2025-03-04 20:00:45.929907 | 2025-03-04 20:00:45.930072 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-03-04 20:00:45.981689 | ubuntu-noble | ok 2025-03-04 20:00:45.988459 | 2025-03-04 20:00:45.988584 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-03-04 20:00:46.024701 | ubuntu-noble | skipping: Conditional result was False 2025-03-04 20:00:46.032019 | 2025-03-04 20:00:46.032155 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-03-04 20:00:46.124691 | ubuntu-noble | skipping: Conditional result was False 2025-03-04 20:00:46.132219 | 2025-03-04 20:00:46.132352 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-03-04 20:00:46.157373 | ubuntu-noble | skipping: Conditional result was False 2025-03-04 20:00:46.164750 | 2025-03-04 20:00:46.164877 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-03-04 20:00:46.200360 | ubuntu-noble | skipping: Conditional result was False 2025-03-04 20:00:46.207841 | 2025-03-04 20:00:46.207996 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-03-04 20:00:46.232842 | ubuntu-noble | skipping: Conditional result was False 2025-03-04 20:00:46.248106 | 2025-03-04 20:00:46.248244 | TASK [emit-job-header : Print job information] 2025-03-04 20:00:46.300723 | # Job Information 2025-03-04 20:00:46.300910 | Ansible Version: 2.15.3 2025-03-04 20:00:46.300943 | Job: python-black 2025-03-04 20:00:46.300987 | Pipeline: check 2025-03-04 20:00:46.301008 | Executor: 7d211f194f6a 2025-03-04 20:00:46.301027 | Triggered by: https://github.com/osism/openstack-resource-manager/pull/132 2025-03-04 20:00:46.301045 | Event ID: 53b03580-f933-11ef-9c00-fc818080659d 2025-03-04 20:00:46.307658 | 2025-03-04 20:00:46.307779 | LOOP [emit-job-header : Print node information] 2025-03-04 20:00:46.467902 | ubuntu-noble | ok: 2025-03-04 20:00:46.468185 | ubuntu-noble | # Node Information 2025-03-04 20:00:46.468223 | ubuntu-noble | Inventory Hostname: ubuntu-noble 2025-03-04 20:00:46.468247 | ubuntu-noble | Hostname: ubuntu 2025-03-04 20:00:46.468269 | ubuntu-noble | Username: zuul 2025-03-04 20:00:46.468288 | ubuntu-noble | Distro: Ubuntu 24.04 2025-03-04 20:00:46.468307 | ubuntu-noble | Provider: regiocloud-a 2025-03-04 20:00:46.468327 | ubuntu-noble | Label: ubuntu-noble 2025-03-04 20:00:46.468345 | ubuntu-noble | Product Name: OpenStack Nova 2025-03-04 20:00:46.468366 | ubuntu-noble | Interface IP: 81.163.192.41 2025-03-04 20:00:46.489103 | 2025-03-04 20:00:46.489247 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-03-04 20:00:47.045531 | ubuntu-noble -> localhost | changed 2025-03-04 20:00:47.054404 | 2025-03-04 20:00:47.054521 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-03-04 20:00:48.093786 | ubuntu-noble -> localhost | changed 2025-03-04 20:00:48.106620 | 2025-03-04 20:00:48.106714 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-03-04 20:00:48.358323 | ubuntu-noble -> localhost | ok 2025-03-04 20:00:48.366444 | 2025-03-04 20:00:48.366538 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-03-04 20:00:48.416364 | ubuntu-noble | ok 2025-03-04 20:00:48.434074 | ubuntu-noble | included: /var/lib/zuul/builds/9613fee93c414a8bbba757834d4ecfdf/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-03-04 20:00:48.442573 | 2025-03-04 20:00:48.442672 | TASK [add-build-sshkey : Create Temp SSH key] 2025-03-04 20:00:49.064667 | ubuntu-noble -> localhost | Generating public/private rsa key pair. 2025-03-04 20:00:49.064894 | ubuntu-noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/9613fee93c414a8bbba757834d4ecfdf/work/9613fee93c414a8bbba757834d4ecfdf_id_rsa 2025-03-04 20:00:49.064931 | ubuntu-noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/9613fee93c414a8bbba757834d4ecfdf/work/9613fee93c414a8bbba757834d4ecfdf_id_rsa.pub 2025-03-04 20:00:49.064966 | ubuntu-noble -> localhost | The key fingerprint is: 2025-03-04 20:00:49.064990 | ubuntu-noble -> localhost | SHA256:P2hWqzM/axvU6YW1rhE6O0eZYbHwPzHGxCnaeX5sJlA zuul-build-sshkey 2025-03-04 20:00:49.065012 | ubuntu-noble -> localhost | The key's randomart image is: 2025-03-04 20:00:49.065036 | ubuntu-noble -> localhost | +---[RSA 3072]----+ 2025-03-04 20:00:49.065058 | ubuntu-noble -> localhost | | . . | 2025-03-04 20:00:49.065078 | ubuntu-noble -> localhost | | . o E | 2025-03-04 20:00:49.065098 | ubuntu-noble -> localhost | | = O. | 2025-03-04 20:00:49.065117 | ubuntu-noble -> localhost | | ..O+*. | 2025-03-04 20:00:49.065136 | ubuntu-noble -> localhost | | S o.=Xo+ | 2025-03-04 20:00:49.065155 | ubuntu-noble -> localhost | | = +++= =| 2025-03-04 20:00:49.065174 | ubuntu-noble -> localhost | | + B.o .* | 2025-03-04 20:00:49.065194 | ubuntu-noble -> localhost | | oo.+=.o | 2025-03-04 20:00:49.065214 | ubuntu-noble -> localhost | | .==*. | 2025-03-04 20:00:49.065232 | ubuntu-noble -> localhost | +----[SHA256]-----+ 2025-03-04 20:00:49.065281 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.213959 2025-03-04 20:00:49.072979 | 2025-03-04 20:00:49.073079 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-03-04 20:00:49.103503 | ubuntu-noble | ok 2025-03-04 20:00:49.114023 | ubuntu-noble | included: /var/lib/zuul/builds/9613fee93c414a8bbba757834d4ecfdf/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-03-04 20:00:49.124233 | 2025-03-04 20:00:49.124319 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-03-04 20:00:49.148050 | ubuntu-noble | skipping: Conditional result was False 2025-03-04 20:00:49.154941 | 2025-03-04 20:00:49.155056 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-03-04 20:00:49.946266 | ubuntu-noble | changed 2025-03-04 20:00:49.957620 | 2025-03-04 20:00:49.957725 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-03-04 20:00:50.282814 | ubuntu-noble | ok 2025-03-04 20:00:50.291388 | 2025-03-04 20:00:50.291494 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-03-04 20:00:51.073719 | ubuntu-noble | changed 2025-03-04 20:00:51.080691 | 2025-03-04 20:00:51.080784 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-03-04 20:00:51.897129 | ubuntu-noble | changed 2025-03-04 20:00:51.908717 | 2025-03-04 20:00:51.908849 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-03-04 20:00:51.933405 | ubuntu-noble | skipping: Conditional result was False 2025-03-04 20:00:51.940496 | 2025-03-04 20:00:51.940585 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-03-04 20:00:52.399910 | ubuntu-noble -> localhost | changed 2025-03-04 20:00:52.412883 | 2025-03-04 20:00:52.413003 | TASK [add-build-sshkey : Add back temp key] 2025-03-04 20:00:52.735112 | ubuntu-noble -> localhost | Identity added: /var/lib/zuul/builds/9613fee93c414a8bbba757834d4ecfdf/work/9613fee93c414a8bbba757834d4ecfdf_id_rsa (zuul-build-sshkey) 2025-03-04 20:00:52.735325 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.014591 2025-03-04 20:00:52.750641 | 2025-03-04 20:00:52.750743 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-03-04 20:00:53.257326 | ubuntu-noble | ok 2025-03-04 20:00:53.263580 | 2025-03-04 20:00:53.263667 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-03-04 20:00:53.287229 | ubuntu-noble | skipping: Conditional result was False 2025-03-04 20:00:53.301000 | 2025-03-04 20:00:53.301084 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-03-04 20:00:53.738259 | ubuntu-noble | ok 2025-03-04 20:00:53.750992 | 2025-03-04 20:00:53.751089 | TASK [validate-host : Define zuul_info_dir fact] 2025-03-04 20:00:53.780746 | ubuntu-noble | ok 2025-03-04 20:00:53.786890 | 2025-03-04 20:00:53.786985 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-03-04 20:00:54.033993 | ubuntu-noble -> localhost | ok 2025-03-04 20:00:54.041404 | 2025-03-04 20:00:54.041510 | TASK [validate-host : Collect information about the host] 2025-03-04 20:00:55.255175 | ubuntu-noble | ok 2025-03-04 20:00:55.268379 | 2025-03-04 20:00:55.268521 | TASK [validate-host : Sanitize hostname] 2025-03-04 20:00:55.329944 | ubuntu-noble | ok 2025-03-04 20:00:55.336722 | 2025-03-04 20:00:55.336843 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-03-04 20:00:55.977124 | ubuntu-noble -> localhost | changed 2025-03-04 20:00:55.984456 | 2025-03-04 20:00:55.984578 | TASK [validate-host : Collect information about zuul worker] 2025-03-04 20:00:56.505348 | ubuntu-noble | ok 2025-03-04 20:00:56.511681 | 2025-03-04 20:00:56.511799 | TASK [validate-host : Write out all zuul information for each host] 2025-03-04 20:00:57.049598 | ubuntu-noble -> localhost | changed 2025-03-04 20:00:57.062338 | 2025-03-04 20:00:57.062461 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-03-04 20:00:57.481558 | ubuntu-noble | ok 2025-03-04 20:00:57.488551 | 2025-03-04 20:00:57.488668 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-03-04 20:00:58.399007 | ubuntu-noble | changed: 2025-03-04 20:00:58.399212 | ubuntu-noble | cd+++++++++ src/ 2025-03-04 20:00:58.399244 | ubuntu-noble | cd+++++++++ src/github.com/ 2025-03-04 20:00:58.399269 | ubuntu-noble | cd+++++++++ src/github.com/osism/ 2025-03-04 20:00:58.399291 | ubuntu-noble | cd+++++++++ src/github.com/osism/openstack-resource-manager/ 2025-03-04 20:00:58.399311 | ubuntu-noble | localhost | ok: Runtime: 0:00:00.007276 2025-03-04 20:01:35.697814 | 2025-03-04 20:01:35.698062 | TASK [upload-logs : Upload logs to log server] 2025-03-04 20:01:36.248041 | localhost | Output suppressed because no_log was given 2025-03-04 20:01:36.251339 | 2025-03-04 20:01:36.251451 | LOOP [upload-logs : Compress console log and json output] 2025-03-04 20:01:36.325606 | localhost | skipping: Conditional result was False 2025-03-04 20:01:36.338761 | localhost | skipping: Conditional result was False 2025-03-04 20:01:36.355842 | 2025-03-04 20:01:36.356080 | LOOP [upload-logs : Upload compressed console log and json output] 2025-03-04 20:01:36.418106 | localhost | skipping: Conditional result was False 2025-03-04 20:01:36.418695 | 2025-03-04 20:01:36.430433 | localhost | skipping: Conditional result was False 2025-03-04 20:01:36.440155 | 2025-03-04 20:01:36.440316 | LOOP [upload-logs : Upload console log and json output]