2025-02-05 20:27:38.153417 | Job console starting... 2025-02-05 20:27:38.165909 | Updating repositories 2025-02-05 20:27:38.193469 | Preparing job workspace 2025-02-05 20:27:39.789177 | Running Ansible setup... 2025-02-05 20:27:45.345497 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-02-05 20:27:46.223677 | 2025-02-05 20:27:46.223817 | PLAY [Base pre] 2025-02-05 20:27:46.271309 | 2025-02-05 20:27:46.271439 | TASK [Setup log path fact] 2025-02-05 20:27:46.308969 | ubuntu-noble | ok 2025-02-05 20:27:46.344973 | 2025-02-05 20:27:46.348050 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-02-05 20:27:46.414525 | ubuntu-noble | ok 2025-02-05 20:27:46.425818 | 2025-02-05 20:27:46.425937 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-02-05 20:27:46.500758 | ubuntu-noble | skipping: Conditional result was False 2025-02-05 20:27:46.508793 | 2025-02-05 20:27:46.508921 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-02-05 20:27:46.656915 | ubuntu-noble | skipping: Conditional result was False 2025-02-05 20:27:46.665513 | 2025-02-05 20:27:46.665615 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-02-05 20:27:46.712782 | ubuntu-noble | skipping: Conditional result was False 2025-02-05 20:27:46.721026 | 2025-02-05 20:27:46.721128 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-02-05 20:27:46.781629 | ubuntu-noble | skipping: Conditional result was False 2025-02-05 20:27:46.793176 | 2025-02-05 20:27:46.793286 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-02-05 20:27:46.855232 | ubuntu-noble | skipping: Conditional result was False 2025-02-05 20:27:46.869336 | 2025-02-05 20:27:46.869450 | TASK [emit-job-header : Print job information] 2025-02-05 20:27:47.013032 | # Job Information 2025-02-05 20:27:47.013197 | Ansible Version: 2.15.3 2025-02-05 20:27:47.013229 | Job: python-black 2025-02-05 20:27:47.013256 | Pipeline: check 2025-02-05 20:27:47.013277 | Executor: 7d211f194f6a 2025-02-05 20:27:47.013297 | Triggered by: https://github.com/osism/ansible-collection-services/pull/1751 2025-02-05 20:27:47.013316 | Event ID: 8944d550-e3ff-11ef-9be6-e35781a6a0df 2025-02-05 20:27:47.019438 | 2025-02-05 20:27:47.019528 | LOOP [emit-job-header : Print node information] 2025-02-05 20:27:47.197075 | ubuntu-noble | ok: 2025-02-05 20:27:47.197334 | ubuntu-noble | # Node Information 2025-02-05 20:27:47.197382 | ubuntu-noble | Inventory Hostname: ubuntu-noble 2025-02-05 20:27:47.197408 | ubuntu-noble | Hostname: ubuntu 2025-02-05 20:27:47.197431 | ubuntu-noble | Username: zuul 2025-02-05 20:27:47.197452 | ubuntu-noble | Distro: Ubuntu 24.04 2025-02-05 20:27:47.197473 | ubuntu-noble | Provider: regiocloud-a 2025-02-05 20:27:47.197493 | ubuntu-noble | Label: ubuntu-noble 2025-02-05 20:27:47.197513 | ubuntu-noble | Product Name: OpenStack Nova 2025-02-05 20:27:47.197534 | ubuntu-noble | Interface IP: 81.163.192.74 2025-02-05 20:27:47.226545 | 2025-02-05 20:27:47.226663 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-02-05 20:27:47.976415 | ubuntu-noble -> localhost | changed 2025-02-05 20:27:47.985056 | 2025-02-05 20:27:47.985160 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-02-05 20:27:49.113438 | ubuntu-noble -> localhost | changed 2025-02-05 20:27:49.156029 | 2025-02-05 20:27:49.156155 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-02-05 20:27:49.617297 | ubuntu-noble -> localhost | ok 2025-02-05 20:27:49.624550 | 2025-02-05 20:27:49.624650 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-02-05 20:27:49.663958 | ubuntu-noble | ok 2025-02-05 20:27:49.682721 | ubuntu-noble | included: /var/lib/zuul/builds/41d9b61a3c054e5a9a1f80619566062f/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-02-05 20:27:49.689569 | 2025-02-05 20:27:49.689650 | TASK [add-build-sshkey : Create Temp SSH key] 2025-02-05 20:27:50.489627 | ubuntu-noble -> localhost | Generating public/private rsa key pair. 2025-02-05 20:27:50.489816 | ubuntu-noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/41d9b61a3c054e5a9a1f80619566062f/work/41d9b61a3c054e5a9a1f80619566062f_id_rsa 2025-02-05 20:27:50.489853 | ubuntu-noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/41d9b61a3c054e5a9a1f80619566062f/work/41d9b61a3c054e5a9a1f80619566062f_id_rsa.pub 2025-02-05 20:27:50.489878 | ubuntu-noble -> localhost | The key fingerprint is: 2025-02-05 20:27:50.489914 | ubuntu-noble -> localhost | SHA256:g7gHTPJZyuBcLH+9CBVn/eUHyBKjZiuGJEb3aVlfJ2I zuul-build-sshkey 2025-02-05 20:27:50.489937 | ubuntu-noble -> localhost | The key's randomart image is: 2025-02-05 20:27:50.489962 | ubuntu-noble -> localhost | +---[RSA 3072]----+ 2025-02-05 20:27:50.490005 | ubuntu-noble -> localhost | | . . . +.Eoo.. | 2025-02-05 20:27:50.490031 | ubuntu-noble -> localhost | | . o . B +o+ooo | 2025-02-05 20:27:50.490053 | ubuntu-noble -> localhost | | * = B + .o o . | 2025-02-05 20:27:50.490073 | ubuntu-noble -> localhost | | + & O = . . . .| 2025-02-05 20:27:50.490093 | ubuntu-noble -> localhost | | o % = S . | 2025-02-05 20:27:50.490112 | ubuntu-noble -> localhost | | * o o | 2025-02-05 20:27:50.490132 | ubuntu-noble -> localhost | | . o . | 2025-02-05 20:27:50.490153 | ubuntu-noble -> localhost | | . | 2025-02-05 20:27:50.490172 | ubuntu-noble -> localhost | | | 2025-02-05 20:27:50.490192 | ubuntu-noble -> localhost | +----[SHA256]-----+ 2025-02-05 20:27:50.490241 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.262843 2025-02-05 20:27:50.498570 | 2025-02-05 20:27:50.498665 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-02-05 20:27:50.528659 | ubuntu-noble | ok 2025-02-05 20:27:50.542132 | ubuntu-noble | included: /var/lib/zuul/builds/41d9b61a3c054e5a9a1f80619566062f/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-02-05 20:27:50.557657 | 2025-02-05 20:27:50.557767 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-02-05 20:27:50.581884 | ubuntu-noble | skipping: Conditional result was False 2025-02-05 20:27:50.590247 | 2025-02-05 20:27:50.590346 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-02-05 20:27:51.226863 | ubuntu-noble | changed 2025-02-05 20:27:51.243639 | 2025-02-05 20:27:51.243744 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-02-05 20:27:51.562492 | ubuntu-noble | ok 2025-02-05 20:27:51.570390 | 2025-02-05 20:27:51.570482 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-02-05 20:27:52.313131 | ubuntu-noble | changed 2025-02-05 20:27:52.329017 | 2025-02-05 20:27:52.329219 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-02-05 20:27:53.059612 | ubuntu-noble | changed 2025-02-05 20:27:53.117798 | 2025-02-05 20:27:53.118712 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-02-05 20:27:53.189605 | ubuntu-noble | skipping: Conditional result was False 2025-02-05 20:27:53.246771 | 2025-02-05 20:27:53.246910 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-02-05 20:27:54.251852 | ubuntu-noble -> localhost | changed 2025-02-05 20:27:54.271048 | 2025-02-05 20:27:54.271178 | TASK [add-build-sshkey : Add back temp key] 2025-02-05 20:27:54.799338 | ubuntu-noble -> localhost | Identity added: /var/lib/zuul/builds/41d9b61a3c054e5a9a1f80619566062f/work/41d9b61a3c054e5a9a1f80619566062f_id_rsa (zuul-build-sshkey) 2025-02-05 20:27:54.799475 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.010073 2025-02-05 20:27:54.807298 | 2025-02-05 20:27:54.807381 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-02-05 20:27:55.225075 | ubuntu-noble | ok 2025-02-05 20:27:55.230505 | 2025-02-05 20:27:55.230589 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-02-05 20:27:55.264338 | ubuntu-noble | skipping: Conditional result was False 2025-02-05 20:27:55.275355 | 2025-02-05 20:27:55.275435 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-02-05 20:27:55.740075 | ubuntu-noble | ok 2025-02-05 20:27:55.764056 | 2025-02-05 20:27:55.764170 | TASK [validate-host : Define zuul_info_dir fact] 2025-02-05 20:27:55.839431 | ubuntu-noble | ok 2025-02-05 20:27:55.846161 | 2025-02-05 20:27:55.846266 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-02-05 20:27:56.137263 | ubuntu-noble -> localhost | ok 2025-02-05 20:27:56.153540 | 2025-02-05 20:27:56.153647 | TASK [validate-host : Collect information about the host] 2025-02-05 20:27:57.279191 | ubuntu-noble | ok 2025-02-05 20:27:57.302557 | 2025-02-05 20:27:57.302669 | TASK [validate-host : Sanitize hostname] 2025-02-05 20:27:57.415746 | ubuntu-noble | ok 2025-02-05 20:27:57.421739 | 2025-02-05 20:27:57.421829 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-02-05 20:27:58.354228 | ubuntu-noble -> localhost | changed 2025-02-05 20:27:58.361214 | 2025-02-05 20:27:58.361316 | TASK [validate-host : Collect information about zuul worker] 2025-02-05 20:27:58.908481 | ubuntu-noble | ok 2025-02-05 20:27:58.915585 | 2025-02-05 20:27:58.915683 | TASK [validate-host : Write out all zuul information for each host] 2025-02-05 20:27:59.869320 | ubuntu-noble -> localhost | changed 2025-02-05 20:27:59.886472 | 2025-02-05 20:27:59.886590 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-02-05 20:28:00.200721 | ubuntu-noble | ok 2025-02-05 20:28:00.208203 | 2025-02-05 20:28:00.208295 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-02-05 20:28:01.613648 | ubuntu-noble | changed: 2025-02-05 20:28:01.613822 | ubuntu-noble | cd+++++++++ src/ 2025-02-05 20:28:01.613854 | ubuntu-noble | cd+++++++++ src/github.com/ 2025-02-05 20:28:01.613878 | ubuntu-noble | cd+++++++++ src/github.com/osism/ 2025-02-05 20:28:01.613913 | ubuntu-noble | cd+++++++++ src/github.com/osism/ansible-collection-services/ 2025-02-05 20:28:01.613934 | ubuntu-noble | localhost | ok: Runtime: 0:00:00.007288 2025-02-05 20:28:33.371307 | 2025-02-05 20:28:33.371418 | TASK [upload-logs : Upload logs to log server] 2025-02-05 20:28:33.961224 | localhost | Output suppressed because no_log was given 2025-02-05 20:28:33.964451 | 2025-02-05 20:28:33.964565 | LOOP [upload-logs : Compress console log and json output] 2025-02-05 20:28:34.030915 | localhost | skipping: Conditional result was False 2025-02-05 20:28:34.047997 | localhost | skipping: Conditional result was False 2025-02-05 20:28:34.057223 | 2025-02-05 20:28:34.057346 | LOOP [upload-logs : Upload compressed console log and json output] 2025-02-05 20:28:34.119405 | localhost | skipping: Conditional result was False 2025-02-05 20:28:34.119718 | 2025-02-05 20:28:34.132702 | localhost | skipping: Conditional result was False 2025-02-05 20:28:34.147289 | 2025-02-05 20:28:34.147517 | LOOP [upload-logs : Upload console log and json output]