2025-01-16 18:14:03.419705 | Job console starting... 2025-01-16 18:14:03.433640 | Updating repositories 2025-01-16 18:14:03.499257 | Preparing job workspace 2025-01-16 18:14:05.244370 | Running Ansible setup... 2025-01-16 18:14:10.868992 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-01-16 18:14:11.620494 | 2025-01-16 18:14:11.620658 | PLAY [Base pre] 2025-01-16 18:14:11.649715 | 2025-01-16 18:14:11.649865 | TASK [Setup log path fact] 2025-01-16 18:14:11.692394 | ubuntu-noble | ok 2025-01-16 18:14:11.717107 | 2025-01-16 18:14:11.717274 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-01-16 18:14:11.768878 | ubuntu-noble | ok 2025-01-16 18:14:11.775674 | 2025-01-16 18:14:11.775792 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-01-16 18:14:11.812215 | ubuntu-noble | skipping: Conditional result was False 2025-01-16 18:14:11.819653 | 2025-01-16 18:14:11.819781 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-01-16 18:14:11.906059 | ubuntu-noble | skipping: Conditional result was False 2025-01-16 18:14:11.915051 | 2025-01-16 18:14:11.915182 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-01-16 18:14:11.960038 | ubuntu-noble | skipping: Conditional result was False 2025-01-16 18:14:11.967599 | 2025-01-16 18:14:11.967726 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-01-16 18:14:12.014223 | ubuntu-noble | skipping: Conditional result was False 2025-01-16 18:14:12.021856 | 2025-01-16 18:14:12.030132 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-01-16 18:14:12.058224 | ubuntu-noble | skipping: Conditional result was False 2025-01-16 18:14:12.085529 | 2025-01-16 18:14:12.085693 | TASK [emit-job-header : Print job information] 2025-01-16 18:14:12.137614 | # Job Information 2025-01-16 18:14:12.137811 | Ansible Version: 2.15.3 2025-01-16 18:14:12.137843 | Job: python-black 2025-01-16 18:14:12.137872 | Pipeline: check 2025-01-16 18:14:12.137893 | Executor: 7d211f194f6a 2025-01-16 18:14:12.137941 | Triggered by: https://github.com/osism/openstack-project-manager/pull/211 2025-01-16 18:14:12.137961 | Event ID: a6164fd0-d435-11ef-96d8-204cf1c443d8 2025-01-16 18:14:12.153556 | 2025-01-16 18:14:12.153692 | LOOP [emit-job-header : Print node information] 2025-01-16 18:14:12.329381 | ubuntu-noble | ok: 2025-01-16 18:14:12.329592 | ubuntu-noble | # Node Information 2025-01-16 18:14:12.329625 | ubuntu-noble | Inventory Hostname: ubuntu-noble 2025-01-16 18:14:12.329649 | ubuntu-noble | Hostname: ubuntu 2025-01-16 18:14:12.329671 | ubuntu-noble | Username: zuul 2025-01-16 18:14:12.329691 | ubuntu-noble | Distro: Ubuntu 24.04 2025-01-16 18:14:12.329710 | ubuntu-noble | Provider: regiocloud-a 2025-01-16 18:14:12.329729 | ubuntu-noble | Label: ubuntu-noble 2025-01-16 18:14:12.329748 | ubuntu-noble | Product Name: OpenStack Nova 2025-01-16 18:14:12.329769 | ubuntu-noble | Interface IP: 81.163.193.64 2025-01-16 18:14:12.354191 | 2025-01-16 18:14:12.354330 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-01-16 18:14:12.923452 | ubuntu-noble -> localhost | changed 2025-01-16 18:14:12.932117 | 2025-01-16 18:14:12.932212 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-01-16 18:14:14.050367 | ubuntu-noble -> localhost | changed 2025-01-16 18:14:14.071475 | 2025-01-16 18:14:14.071605 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-01-16 18:14:14.410654 | ubuntu-noble -> localhost | ok 2025-01-16 18:14:14.418532 | 2025-01-16 18:14:14.418792 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-01-16 18:14:14.447946 | ubuntu-noble | ok 2025-01-16 18:14:14.463521 | ubuntu-noble | included: /var/lib/zuul/builds/4637a8ce0ca34545994e41bf376da2a7/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-01-16 18:14:14.471015 | 2025-01-16 18:14:14.471097 | TASK [add-build-sshkey : Create Temp SSH key] 2025-01-16 18:14:15.143175 | ubuntu-noble -> localhost | Generating public/private rsa key pair. 2025-01-16 18:14:15.143336 | ubuntu-noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/4637a8ce0ca34545994e41bf376da2a7/work/4637a8ce0ca34545994e41bf376da2a7_id_rsa 2025-01-16 18:14:15.143365 | ubuntu-noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/4637a8ce0ca34545994e41bf376da2a7/work/4637a8ce0ca34545994e41bf376da2a7_id_rsa.pub 2025-01-16 18:14:15.143386 | ubuntu-noble -> localhost | The key fingerprint is: 2025-01-16 18:14:15.143426 | ubuntu-noble -> localhost | SHA256:o48yKMomg/KTlmmmsaEF8DXKlCP2LXWmyw4wmE76UvM zuul-build-sshkey 2025-01-16 18:14:15.143446 | ubuntu-noble -> localhost | The key's randomart image is: 2025-01-16 18:14:15.143466 | ubuntu-noble -> localhost | +---[RSA 3072]----+ 2025-01-16 18:14:15.143484 | ubuntu-noble -> localhost | | | 2025-01-16 18:14:15.143500 | ubuntu-noble -> localhost | | . | 2025-01-16 18:14:15.143516 | ubuntu-noble -> localhost | |o.+ o. o | 2025-01-16 18:14:15.143531 | ubuntu-noble -> localhost | |+*.+o.+ | 2025-01-16 18:14:15.143547 | ubuntu-noble -> localhost | |+++o o S | 2025-01-16 18:14:15.143562 | ubuntu-noble -> localhost | |+.= o .. . | 2025-01-16 18:14:15.143578 | ubuntu-noble -> localhost | |=o.O o. | 2025-01-16 18:14:15.143594 | ubuntu-noble -> localhost | |XBX E o | 2025-01-16 18:14:15.143609 | ubuntu-noble -> localhost | |X@o. +. . | 2025-01-16 18:14:15.143625 | ubuntu-noble -> localhost | +----[SHA256]-----+ 2025-01-16 18:14:15.143667 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.145432 2025-01-16 18:14:15.149826 | 2025-01-16 18:14:15.149905 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-01-16 18:14:15.178646 | ubuntu-noble | ok 2025-01-16 18:14:15.187418 | ubuntu-noble | included: /var/lib/zuul/builds/4637a8ce0ca34545994e41bf376da2a7/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-01-16 18:14:15.195024 | 2025-01-16 18:14:15.195091 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-01-16 18:14:15.207960 | ubuntu-noble | skipping: Conditional result was False 2025-01-16 18:14:15.214803 | 2025-01-16 18:14:15.214886 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-01-16 18:14:15.960748 | ubuntu-noble | changed 2025-01-16 18:14:15.971293 | 2025-01-16 18:14:15.971435 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-01-16 18:14:16.318506 | ubuntu-noble | ok 2025-01-16 18:14:16.327239 | 2025-01-16 18:14:16.327335 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-01-16 18:14:17.024645 | ubuntu-noble | changed 2025-01-16 18:14:17.039907 | 2025-01-16 18:14:17.040066 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-01-16 18:14:17.775000 | ubuntu-noble | changed 2025-01-16 18:14:17.785760 | 2025-01-16 18:14:17.785889 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-01-16 18:14:17.810952 | ubuntu-noble | skipping: Conditional result was False 2025-01-16 18:14:17.820837 | 2025-01-16 18:14:17.820937 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-01-16 18:14:18.198001 | ubuntu-noble -> localhost | changed 2025-01-16 18:14:18.220995 | 2025-01-16 18:14:18.221117 | TASK [add-build-sshkey : Add back temp key] 2025-01-16 18:14:18.540706 | ubuntu-noble -> localhost | Identity added: /var/lib/zuul/builds/4637a8ce0ca34545994e41bf376da2a7/work/4637a8ce0ca34545994e41bf376da2a7_id_rsa (zuul-build-sshkey) 2025-01-16 18:14:18.540901 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.014746 2025-01-16 18:14:18.547977 | 2025-01-16 18:14:18.548069 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-01-16 18:14:19.019332 | ubuntu-noble | ok 2025-01-16 18:14:19.026469 | 2025-01-16 18:14:19.026567 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-01-16 18:14:19.080632 | ubuntu-noble | skipping: Conditional result was False 2025-01-16 18:14:19.098088 | 2025-01-16 18:14:19.098196 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-01-16 18:14:19.518696 | ubuntu-noble | ok 2025-01-16 18:14:19.531710 | 2025-01-16 18:14:19.531819 | TASK [validate-host : Define zuul_info_dir fact] 2025-01-16 18:14:19.572760 | ubuntu-noble | ok 2025-01-16 18:14:19.579144 | 2025-01-16 18:14:19.579233 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-01-16 18:14:19.840944 | ubuntu-noble -> localhost | ok 2025-01-16 18:14:19.851510 | 2025-01-16 18:14:19.851619 | TASK [validate-host : Collect information about the host] 2025-01-16 18:14:20.988880 | ubuntu-noble | ok 2025-01-16 18:14:21.007444 | 2025-01-16 18:14:21.007579 | TASK [validate-host : Sanitize hostname] 2025-01-16 18:14:21.078776 | ubuntu-noble | ok 2025-01-16 18:14:21.087941 | 2025-01-16 18:14:21.088049 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-01-16 18:14:21.621528 | ubuntu-noble -> localhost | changed 2025-01-16 18:14:21.633887 | 2025-01-16 18:14:21.634037 | TASK [validate-host : Collect information about zuul worker] 2025-01-16 18:14:22.100140 | ubuntu-noble | ok 2025-01-16 18:14:22.106381 | 2025-01-16 18:14:22.106500 | TASK [validate-host : Write out all zuul information for each host] 2025-01-16 18:14:22.648178 | ubuntu-noble -> localhost | changed 2025-01-16 18:14:22.660837 | 2025-01-16 18:14:22.660934 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-01-16 18:14:22.975988 | ubuntu-noble | ok 2025-01-16 18:14:22.982580 | 2025-01-16 18:14:22.982678 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-01-16 18:14:23.840301 | ubuntu-noble | changed: 2025-01-16 18:14:23.840517 | ubuntu-noble | cd+++++++++ src/ 2025-01-16 18:14:23.840549 | ubuntu-noble | cd+++++++++ src/github.com/ 2025-01-16 18:14:23.840573 | ubuntu-noble | cd+++++++++ src/github.com/osism/ 2025-01-16 18:14:23.840594 | ubuntu-noble | cd+++++++++ src/github.com/osism/openstack-project-manager/ 2025-01-16 18:14:23.840614 | ubuntu-noble | localhost | ok: Runtime: 0:00:00.008331 2025-01-16 18:14:56.263778 | 2025-01-16 18:14:56.263957 | TASK [upload-logs : Upload logs to log server] 2025-01-16 18:14:56.838036 | localhost | Output suppressed because no_log was given 2025-01-16 18:14:56.841534 | 2025-01-16 18:14:56.841648 | LOOP [upload-logs : Compress console log and json output] 2025-01-16 18:14:56.903829 | localhost | skipping: Conditional result was False 2025-01-16 18:14:56.921551 | localhost | skipping: Conditional result was False 2025-01-16 18:14:56.932989 | 2025-01-16 18:14:56.933104 | LOOP [upload-logs : Upload compressed console log and json output] 2025-01-16 18:14:56.997014 | localhost | skipping: Conditional result was False 2025-01-16 18:14:56.997340 | 2025-01-16 18:14:57.010035 | localhost | skipping: Conditional result was False 2025-01-16 18:14:57.017346 | 2025-01-16 18:14:57.017513 | LOOP [upload-logs : Upload console log and json output]