2025-02-18 19:15:50.521748 | Job console starting... 2025-02-18 19:15:50.584668 | Updating repositories 2025-02-18 19:15:50.709952 | Preparing job workspace 2025-02-18 19:15:53.539291 | Running Ansible setup... 2025-02-18 19:16:00.031953 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-02-18 19:16:00.952589 | 2025-02-18 19:16:00.952749 | PLAY [Base pre] 2025-02-18 19:16:00.995903 | 2025-02-18 19:16:00.996063 | TASK [Setup log path fact] 2025-02-18 19:16:01.027648 | ubuntu-noble | ok 2025-02-18 19:16:01.054868 | 2025-02-18 19:16:01.055020 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-02-18 19:16:01.161964 | ubuntu-noble | ok 2025-02-18 19:16:01.184930 | 2025-02-18 19:16:01.185047 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-02-18 19:16:01.280405 | ubuntu-noble | skipping: Conditional result was False 2025-02-18 19:16:01.287144 | 2025-02-18 19:16:01.287332 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-02-18 19:16:01.331029 | ubuntu-noble | skipping: Conditional result was False 2025-02-18 19:16:01.337113 | 2025-02-18 19:16:01.337209 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-02-18 19:16:01.360483 | ubuntu-noble | skipping: Conditional result was False 2025-02-18 19:16:01.367468 | 2025-02-18 19:16:01.367567 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-02-18 19:16:01.424123 | ubuntu-noble | skipping: Conditional result was False 2025-02-18 19:16:01.429981 | 2025-02-18 19:16:01.430061 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-02-18 19:16:01.463233 | ubuntu-noble | skipping: Conditional result was False 2025-02-18 19:16:01.475190 | 2025-02-18 19:16:01.475277 | TASK [emit-job-header : Print job information] 2025-02-18 19:16:01.526930 | # Job Information 2025-02-18 19:16:01.527079 | Ansible Version: 2.15.3 2025-02-18 19:16:01.527110 | Job: python-black 2025-02-18 19:16:01.527138 | Pipeline: check 2025-02-18 19:16:01.527158 | Executor: 7d211f194f6a 2025-02-18 19:16:01.527208 | Triggered by: https://github.com/osism/ansible-collection-services/pull/1764 2025-02-18 19:16:01.527228 | Event ID: bef90fe0-ee2c-11ef-98e7-aaa7cad212a0 2025-02-18 19:16:01.533265 | 2025-02-18 19:16:01.533356 | LOOP [emit-job-header : Print node information] 2025-02-18 19:16:01.668375 | ubuntu-noble | ok: 2025-02-18 19:16:01.668538 | ubuntu-noble | # Node Information 2025-02-18 19:16:01.668565 | ubuntu-noble | Inventory Hostname: ubuntu-noble 2025-02-18 19:16:01.668586 | ubuntu-noble | Hostname: ubuntu 2025-02-18 19:16:01.668604 | ubuntu-noble | Username: zuul 2025-02-18 19:16:01.668620 | ubuntu-noble | Distro: Ubuntu 24.04 2025-02-18 19:16:01.668637 | ubuntu-noble | Provider: regiocloud-a 2025-02-18 19:16:01.668652 | ubuntu-noble | Label: ubuntu-noble 2025-02-18 19:16:01.668668 | ubuntu-noble | Product Name: OpenStack Nova 2025-02-18 19:16:01.668684 | ubuntu-noble | Interface IP: 81.163.193.59 2025-02-18 19:16:01.681550 | 2025-02-18 19:16:01.681653 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-02-18 19:16:02.151613 | ubuntu-noble -> localhost | changed 2025-02-18 19:16:02.160049 | 2025-02-18 19:16:02.160150 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-02-18 19:16:03.471484 | ubuntu-noble -> localhost | changed 2025-02-18 19:16:03.490624 | 2025-02-18 19:16:03.490725 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-02-18 19:16:03.849758 | ubuntu-noble -> localhost | ok 2025-02-18 19:16:03.859027 | 2025-02-18 19:16:03.859146 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-02-18 19:16:03.919620 | ubuntu-noble | ok 2025-02-18 19:16:03.970528 | ubuntu-noble | included: /var/lib/zuul/builds/3a121986b5284367acfe681008b073aa/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-02-18 19:16:03.980359 | 2025-02-18 19:16:03.980459 | TASK [add-build-sshkey : Create Temp SSH key] 2025-02-18 19:16:04.764795 | ubuntu-noble -> localhost | Generating public/private rsa key pair. 2025-02-18 19:16:04.764955 | ubuntu-noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/3a121986b5284367acfe681008b073aa/work/3a121986b5284367acfe681008b073aa_id_rsa 2025-02-18 19:16:04.764986 | ubuntu-noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/3a121986b5284367acfe681008b073aa/work/3a121986b5284367acfe681008b073aa_id_rsa.pub 2025-02-18 19:16:04.765007 | ubuntu-noble -> localhost | The key fingerprint is: 2025-02-18 19:16:04.765025 | ubuntu-noble -> localhost | SHA256:lHoAHPZOo9x6NcfU1dDGZ1GGsgwAzZtoJcj9F+aimr8 zuul-build-sshkey 2025-02-18 19:16:04.765044 | ubuntu-noble -> localhost | The key's randomart image is: 2025-02-18 19:16:04.765065 | ubuntu-noble -> localhost | +---[RSA 3072]----+ 2025-02-18 19:16:04.765084 | ubuntu-noble -> localhost | | o++.+.. oB=| 2025-02-18 19:16:04.765101 | ubuntu-noble -> localhost | | .+oo +.+.....B| 2025-02-18 19:16:04.765118 | ubuntu-noble -> localhost | | ==o=.+.o o.| 2025-02-18 19:16:04.765133 | ubuntu-noble -> localhost | | . =o==oo o | 2025-02-18 19:16:04.765149 | ubuntu-noble -> localhost | | o.+.Soo | 2025-02-18 19:16:04.765177 | ubuntu-noble -> localhost | | ..o o | 2025-02-18 19:16:04.765195 | ubuntu-noble -> localhost | | .o. | 2025-02-18 19:16:04.765211 | ubuntu-noble -> localhost | | o. | 2025-02-18 19:16:04.765227 | ubuntu-noble -> localhost | | .E. | 2025-02-18 19:16:04.765243 | ubuntu-noble -> localhost | +----[SHA256]-----+ 2025-02-18 19:16:04.765283 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.166796 2025-02-18 19:16:04.771776 | 2025-02-18 19:16:04.771861 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-02-18 19:16:04.824570 | ubuntu-noble | ok 2025-02-18 19:16:04.833677 | ubuntu-noble | included: /var/lib/zuul/builds/3a121986b5284367acfe681008b073aa/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-02-18 19:16:04.841703 | 2025-02-18 19:16:04.841779 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-02-18 19:16:04.855183 | ubuntu-noble | skipping: Conditional result was False 2025-02-18 19:16:04.862620 | 2025-02-18 19:16:04.862715 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-02-18 19:16:05.580267 | ubuntu-noble | changed 2025-02-18 19:16:05.587798 | 2025-02-18 19:16:05.587887 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-02-18 19:16:05.874203 | ubuntu-noble | ok 2025-02-18 19:16:05.881990 | 2025-02-18 19:16:05.882090 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-02-18 19:16:06.591526 | ubuntu-noble | changed 2025-02-18 19:16:06.598311 | 2025-02-18 19:16:06.598399 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-02-18 19:16:07.338998 | ubuntu-noble | changed 2025-02-18 19:16:07.356156 | 2025-02-18 19:16:07.356275 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-02-18 19:16:07.400636 | ubuntu-noble | skipping: Conditional result was False 2025-02-18 19:16:07.408220 | 2025-02-18 19:16:07.408316 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-02-18 19:16:08.273395 | ubuntu-noble -> localhost | changed 2025-02-18 19:16:08.290080 | 2025-02-18 19:16:08.290194 | TASK [add-build-sshkey : Add back temp key] 2025-02-18 19:16:08.844504 | ubuntu-noble -> localhost | Identity added: /var/lib/zuul/builds/3a121986b5284367acfe681008b073aa/work/3a121986b5284367acfe681008b073aa_id_rsa (zuul-build-sshkey) 2025-02-18 19:16:08.844676 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.020982 2025-02-18 19:16:08.873954 | 2025-02-18 19:16:08.874045 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-02-18 19:16:09.281069 | ubuntu-noble | ok 2025-02-18 19:16:09.287898 | 2025-02-18 19:16:09.288011 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-02-18 19:16:09.312096 | ubuntu-noble | skipping: Conditional result was False 2025-02-18 19:16:09.325117 | 2025-02-18 19:16:09.325231 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-02-18 19:16:09.737226 | ubuntu-noble | ok 2025-02-18 19:16:09.752997 | 2025-02-18 19:16:09.753087 | TASK [validate-host : Define zuul_info_dir fact] 2025-02-18 19:16:09.785665 | ubuntu-noble | ok 2025-02-18 19:16:09.791254 | 2025-02-18 19:16:09.791336 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-02-18 19:16:10.122229 | ubuntu-noble -> localhost | ok 2025-02-18 19:16:10.128599 | 2025-02-18 19:16:10.128678 | TASK [validate-host : Collect information about the host] 2025-02-18 19:16:11.306049 | ubuntu-noble | ok 2025-02-18 19:16:11.329971 | 2025-02-18 19:16:11.330138 | TASK [validate-host : Sanitize hostname] 2025-02-18 19:16:11.416246 | ubuntu-noble | ok 2025-02-18 19:16:11.436740 | 2025-02-18 19:16:11.436992 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-02-18 19:16:12.038257 | ubuntu-noble -> localhost | changed 2025-02-18 19:16:12.046879 | 2025-02-18 19:16:12.046980 | TASK [validate-host : Collect information about zuul worker] 2025-02-18 19:16:12.546753 | ubuntu-noble | ok 2025-02-18 19:16:12.552633 | 2025-02-18 19:16:12.552726 | TASK [validate-host : Write out all zuul information for each host] 2025-02-18 19:16:13.150919 | ubuntu-noble -> localhost | changed 2025-02-18 19:16:13.179930 | 2025-02-18 19:16:13.180034 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-02-18 19:16:13.512123 | ubuntu-noble | ok 2025-02-18 19:16:13.518868 | 2025-02-18 19:16:13.518954 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-02-18 19:16:15.075636 | ubuntu-noble | changed: 2025-02-18 19:16:15.075794 | ubuntu-noble | cd+++++++++ src/ 2025-02-18 19:16:15.075826 | ubuntu-noble | cd+++++++++ src/github.com/ 2025-02-18 19:16:15.075848 | ubuntu-noble | cd+++++++++ src/github.com/osism/ 2025-02-18 19:16:15.075869 | ubuntu-noble | cd+++++++++ src/github.com/osism/ansible-collection-services/ 2025-02-18 19:16:15.075889 | ubuntu-noble | localhost | ok: Runtime: 0:00:00.007752 2025-02-18 19:16:49.656122 | 2025-02-18 19:16:49.656728 | TASK [upload-logs : Upload logs to log server] 2025-02-18 19:16:50.229017 | localhost | Output suppressed because no_log was given 2025-02-18 19:16:50.232801 | 2025-02-18 19:16:50.232922 | LOOP [upload-logs : Compress console log and json output] 2025-02-18 19:16:50.304194 | localhost | skipping: Conditional result was False 2025-02-18 19:16:50.322231 | localhost | skipping: Conditional result was False 2025-02-18 19:16:50.330400 | 2025-02-18 19:16:50.330618 | LOOP [upload-logs : Upload compressed console log and json output] 2025-02-18 19:16:50.396650 | localhost | skipping: Conditional result was False 2025-02-18 19:16:50.397096 | 2025-02-18 19:16:50.415057 | localhost | skipping: Conditional result was False 2025-02-18 19:16:50.422765 | 2025-02-18 19:16:50.422877 | LOOP [upload-logs : Upload console log and json output]