2025-01-26 18:00:45.037457 | Job console starting... 2025-01-26 18:00:45.078333 | Updating repositories 2025-01-26 18:00:45.196739 | Preparing job workspace 2025-01-26 18:00:48.142037 | Running Ansible setup... 2025-01-26 18:00:54.903326 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-01-26 18:00:56.190742 | 2025-01-26 18:00:56.190948 | PLAY [Base pre] 2025-01-26 18:00:56.255222 | 2025-01-26 18:00:56.255728 | TASK [Setup log path fact] 2025-01-26 18:00:56.324668 | ubuntu-noble | ok 2025-01-26 18:00:56.371172 | 2025-01-26 18:00:56.371349 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-01-26 18:00:56.469767 | ubuntu-noble | ok 2025-01-26 18:00:56.488586 | 2025-01-26 18:00:56.488734 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-01-26 18:00:56.602042 | ubuntu-noble | skipping: Conditional result was False 2025-01-26 18:00:56.610469 | 2025-01-26 18:00:56.610623 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-01-26 18:00:56.730991 | ubuntu-noble | skipping: Conditional result was False 2025-01-26 18:00:56.739182 | 2025-01-26 18:00:56.739326 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-01-26 18:00:56.807275 | ubuntu-noble | skipping: Conditional result was False 2025-01-26 18:00:56.815655 | 2025-01-26 18:00:56.815797 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-01-26 18:00:56.911549 | ubuntu-noble | skipping: Conditional result was False 2025-01-26 18:00:56.922229 | 2025-01-26 18:00:56.922371 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-01-26 18:00:56.980948 | ubuntu-noble | skipping: Conditional result was False 2025-01-26 18:00:56.995829 | 2025-01-26 18:00:56.995991 | TASK [emit-job-header : Print job information] 2025-01-26 18:00:57.153534 | # Job Information 2025-01-26 18:00:57.153761 | Ansible Version: 2.15.3 2025-01-26 18:00:57.153794 | Job: python-black 2025-01-26 18:00:57.153857 | Pipeline: check 2025-01-26 18:00:57.153880 | Executor: 7d211f194f6a 2025-01-26 18:00:57.153899 | Triggered by: https://github.com/osism/container-image-ceph-ansible/pull/553 2025-01-26 18:00:57.153917 | Event ID: 71353fb0-dc0f-11ef-82d2-d4ab4397eecd 2025-01-26 18:00:57.164034 | 2025-01-26 18:00:57.164154 | LOOP [emit-job-header : Print node information] 2025-01-26 18:00:57.466580 | ubuntu-noble | ok: 2025-01-26 18:00:57.470863 | ubuntu-noble | # Node Information 2025-01-26 18:00:57.470961 | ubuntu-noble | Inventory Hostname: ubuntu-noble 2025-01-26 18:00:57.470990 | ubuntu-noble | Hostname: ubuntu 2025-01-26 18:00:57.471011 | ubuntu-noble | Username: zuul 2025-01-26 18:00:57.471032 | ubuntu-noble | Distro: Ubuntu 24.04 2025-01-26 18:00:57.471051 | ubuntu-noble | Provider: regiocloud-a 2025-01-26 18:00:57.471070 | ubuntu-noble | Label: ubuntu-noble 2025-01-26 18:00:57.471089 | ubuntu-noble | Product Name: OpenStack Nova 2025-01-26 18:00:57.471110 | ubuntu-noble | Interface IP: 81.163.193.0 2025-01-26 18:00:57.491886 | 2025-01-26 18:00:57.492055 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-01-26 18:00:58.224292 | ubuntu-noble -> localhost | changed 2025-01-26 18:00:58.231288 | 2025-01-26 18:00:58.231367 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-01-26 18:00:59.802403 | ubuntu-noble -> localhost | changed 2025-01-26 18:00:59.832307 | 2025-01-26 18:00:59.836509 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-01-26 18:01:00.448990 | ubuntu-noble -> localhost | ok 2025-01-26 18:01:00.455248 | 2025-01-26 18:01:00.455332 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-01-26 18:01:00.494031 | ubuntu-noble | ok 2025-01-26 18:01:00.516920 | ubuntu-noble | included: /var/lib/zuul/builds/29096fd9711442db8994d815241896f6/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-01-26 18:01:00.529191 | 2025-01-26 18:01:00.529274 | TASK [add-build-sshkey : Create Temp SSH key] 2025-01-26 18:01:01.293382 | ubuntu-noble -> localhost | Generating public/private rsa key pair. 2025-01-26 18:01:01.293588 | ubuntu-noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/29096fd9711442db8994d815241896f6/work/29096fd9711442db8994d815241896f6_id_rsa 2025-01-26 18:01:01.293625 | ubuntu-noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/29096fd9711442db8994d815241896f6/work/29096fd9711442db8994d815241896f6_id_rsa.pub 2025-01-26 18:01:01.293649 | ubuntu-noble -> localhost | The key fingerprint is: 2025-01-26 18:01:01.293672 | ubuntu-noble -> localhost | SHA256:m/vG2Ag/rLzwslouBJrW0s25qPLgWm+C3ClRurPHIVg zuul-build-sshkey 2025-01-26 18:01:01.293693 | ubuntu-noble -> localhost | The key's randomart image is: 2025-01-26 18:01:01.293718 | ubuntu-noble -> localhost | +---[RSA 3072]----+ 2025-01-26 18:01:01.293739 | ubuntu-noble -> localhost | | | 2025-01-26 18:01:01.293760 | ubuntu-noble -> localhost | | | 2025-01-26 18:01:01.293779 | ubuntu-noble -> localhost | | | 2025-01-26 18:01:01.293798 | ubuntu-noble -> localhost | |. E. | 2025-01-26 18:01:01.293831 | ubuntu-noble -> localhost | |o+= o . S | 2025-01-26 18:01:01.293851 | ubuntu-noble -> localhost | |+*.+ +. o | 2025-01-26 18:01:01.293870 | ubuntu-noble -> localhost | |+oO * .+o= | 2025-01-26 18:01:01.293890 | ubuntu-noble -> localhost | |+BoXo= *.+ | 2025-01-26 18:01:01.293909 | ubuntu-noble -> localhost | |+=O=+o=o.+. | 2025-01-26 18:01:01.293928 | ubuntu-noble -> localhost | +----[SHA256]-----+ 2025-01-26 18:01:01.293977 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.052190 2025-01-26 18:01:01.304156 | 2025-01-26 18:01:01.304257 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-01-26 18:01:01.355061 | ubuntu-noble | ok 2025-01-26 18:01:01.376458 | ubuntu-noble | included: /var/lib/zuul/builds/29096fd9711442db8994d815241896f6/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-01-26 18:01:01.387291 | 2025-01-26 18:01:01.387378 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-01-26 18:01:01.425389 | ubuntu-noble | skipping: Conditional result was False 2025-01-26 18:01:01.432555 | 2025-01-26 18:01:01.432647 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-01-26 18:01:02.089531 | ubuntu-noble | changed 2025-01-26 18:01:02.098634 | 2025-01-26 18:01:02.098741 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-01-26 18:01:02.372899 | ubuntu-noble | ok 2025-01-26 18:01:02.379019 | 2025-01-26 18:01:02.379101 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-01-26 18:01:03.064957 | ubuntu-noble | changed 2025-01-26 18:01:03.078441 | 2025-01-26 18:01:03.078552 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-01-26 18:01:03.783043 | ubuntu-noble | changed 2025-01-26 18:01:03.802302 | 2025-01-26 18:01:03.802436 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-01-26 18:01:03.838561 | ubuntu-noble | skipping: Conditional result was False 2025-01-26 18:01:03.846669 | 2025-01-26 18:01:03.846766 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-01-26 18:01:04.452979 | ubuntu-noble -> localhost | changed 2025-01-26 18:01:04.465015 | 2025-01-26 18:01:04.465111 | TASK [add-build-sshkey : Add back temp key] 2025-01-26 18:01:04.990154 | ubuntu-noble -> localhost | Identity added: /var/lib/zuul/builds/29096fd9711442db8994d815241896f6/work/29096fd9711442db8994d815241896f6_id_rsa (zuul-build-sshkey) 2025-01-26 18:01:04.990345 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.017072 2025-01-26 18:01:04.997730 | 2025-01-26 18:01:04.997846 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-01-26 18:01:05.385149 | ubuntu-noble | ok 2025-01-26 18:01:05.391529 | 2025-01-26 18:01:05.391622 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-01-26 18:01:05.415667 | ubuntu-noble | skipping: Conditional result was False 2025-01-26 18:01:05.428889 | 2025-01-26 18:01:05.429001 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-01-26 18:01:05.911395 | ubuntu-noble | ok 2025-01-26 18:01:05.924902 | 2025-01-26 18:01:05.925026 | TASK [validate-host : Define zuul_info_dir fact] 2025-01-26 18:01:05.960342 | ubuntu-noble | ok 2025-01-26 18:01:05.966723 | 2025-01-26 18:01:05.966842 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-01-26 18:01:06.258112 | ubuntu-noble -> localhost | ok 2025-01-26 18:01:06.266321 | 2025-01-26 18:01:06.266435 | TASK [validate-host : Collect information about the host] 2025-01-26 18:01:07.367587 | ubuntu-noble | ok 2025-01-26 18:01:07.388807 | 2025-01-26 18:01:07.388952 | TASK [validate-host : Sanitize hostname] 2025-01-26 18:01:07.455304 | ubuntu-noble | ok 2025-01-26 18:01:07.462936 | 2025-01-26 18:01:07.463048 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-01-26 18:01:08.079570 | ubuntu-noble -> localhost | changed 2025-01-26 18:01:08.089397 | 2025-01-26 18:01:08.089536 | TASK [validate-host : Collect information about zuul worker] 2025-01-26 18:01:08.724039 | ubuntu-noble | ok 2025-01-26 18:01:08.733757 | 2025-01-26 18:01:08.733895 | TASK [validate-host : Write out all zuul information for each host] 2025-01-26 18:01:09.371641 | ubuntu-noble -> localhost | changed 2025-01-26 18:01:09.385483 | 2025-01-26 18:01:09.385640 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-01-26 18:01:09.714770 | ubuntu-noble | ok 2025-01-26 18:01:09.727478 | 2025-01-26 18:01:09.727611 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-01-26 18:01:10.827764 | ubuntu-noble | changed: 2025-01-26 18:01:10.828032 | ubuntu-noble | cd+++++++++ src/ 2025-01-26 18:01:10.828066 | ubuntu-noble | cd+++++++++ src/github.com/ 2025-01-26 18:01:10.828090 | ubuntu-noble | cd+++++++++ src/github.com/osism/ 2025-01-26 18:01:10.828111 | ubuntu-noble | cd+++++++++ src/github.com/osism/container-image-ceph-ansible/ 2025-01-26 18:01:10.828132 | ubuntu-noble | Containerfile 2025-01-26 18:01:10.828279 | ubuntu-noble | localhost | ok: Runtime: 0:00:00.009202 2025-01-26 18:01:41.950170 | 2025-01-26 18:01:41.950335 | TASK [upload-logs : Upload logs to log server] 2025-01-26 18:01:42.573362 | localhost | Output suppressed because no_log was given 2025-01-26 18:01:42.577493 | 2025-01-26 18:01:42.577625 | LOOP [upload-logs : Compress console log and json output] 2025-01-26 18:01:42.663649 | localhost | skipping: Conditional result was False 2025-01-26 18:01:42.671601 | localhost | skipping: Conditional result was False 2025-01-26 18:01:42.679059 | 2025-01-26 18:01:42.679226 | LOOP [upload-logs : Upload compressed console log and json output] 2025-01-26 18:01:42.762542 | localhost | skipping: Conditional result was False 2025-01-26 18:01:42.762944 | 2025-01-26 18:01:42.777775 | localhost | skipping: Conditional result was False 2025-01-26 18:01:42.783744 | 2025-01-26 18:01:42.783944 | LOOP [upload-logs : Upload console log and json output]