2025-01-27 20:34:29.921825 | Job console starting... 2025-01-27 20:34:29.950002 | Updating repositories 2025-01-27 20:34:29.989244 | Preparing job workspace 2025-01-27 20:34:32.926550 | Running Ansible setup... 2025-01-27 20:34:41.293247 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-01-27 20:34:42.593277 | 2025-01-27 20:34:42.593394 | PLAY [Base pre] 2025-01-27 20:34:42.619188 | 2025-01-27 20:34:42.619293 | TASK [Setup log path fact] 2025-01-27 20:34:42.648685 | ubuntu-noble | ok 2025-01-27 20:34:42.668150 | 2025-01-27 20:34:42.668256 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-01-27 20:34:42.727503 | ubuntu-noble | ok 2025-01-27 20:34:42.733008 | 2025-01-27 20:34:42.733089 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-01-27 20:34:42.824925 | ubuntu-noble | skipping: Conditional result was False 2025-01-27 20:34:42.831189 | 2025-01-27 20:34:42.831283 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-01-27 20:34:42.921156 | ubuntu-noble | skipping: Conditional result was False 2025-01-27 20:34:42.927457 | 2025-01-27 20:34:42.927558 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-01-27 20:34:42.962014 | ubuntu-noble | skipping: Conditional result was False 2025-01-27 20:34:42.968193 | 2025-01-27 20:34:42.968291 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-01-27 20:34:42.991883 | ubuntu-noble | skipping: Conditional result was False 2025-01-27 20:34:42.998576 | 2025-01-27 20:34:42.998668 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-01-27 20:34:43.046862 | ubuntu-noble | skipping: Conditional result was False 2025-01-27 20:34:43.059396 | 2025-01-27 20:34:43.059490 | TASK [emit-job-header : Print job information] 2025-01-27 20:34:43.110673 | # Job Information 2025-01-27 20:34:43.110873 | Ansible Version: 2.15.3 2025-01-27 20:34:43.110926 | Job: python-black 2025-01-27 20:34:43.110957 | Pipeline: check 2025-01-27 20:34:43.110978 | Executor: 7d211f194f6a 2025-01-27 20:34:43.110994 | Triggered by: https://github.com/osism/cfg-cookiecutter/pull/698 2025-01-27 20:34:43.111009 | Event ID: 168b2680-dcee-11ef-8bcd-b890f53daee4 2025-01-27 20:34:43.118658 | 2025-01-27 20:34:43.118747 | LOOP [emit-job-header : Print node information] 2025-01-27 20:34:43.289470 | ubuntu-noble | ok: 2025-01-27 20:34:43.289710 | ubuntu-noble | # Node Information 2025-01-27 20:34:43.289745 | ubuntu-noble | Inventory Hostname: ubuntu-noble 2025-01-27 20:34:43.289766 | ubuntu-noble | Hostname: ubuntu 2025-01-27 20:34:43.289784 | ubuntu-noble | Username: zuul 2025-01-27 20:34:43.289800 | ubuntu-noble | Distro: Ubuntu 24.04 2025-01-27 20:34:43.289816 | ubuntu-noble | Provider: regiocloud-a 2025-01-27 20:34:43.289832 | ubuntu-noble | Label: ubuntu-noble 2025-01-27 20:34:43.289847 | ubuntu-noble | Product Name: OpenStack Nova 2025-01-27 20:34:43.289864 | ubuntu-noble | Interface IP: 81.163.193.86 2025-01-27 20:34:43.311768 | 2025-01-27 20:34:43.311859 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-01-27 20:34:44.041777 | ubuntu-noble -> localhost | changed 2025-01-27 20:34:44.052069 | 2025-01-27 20:34:44.052164 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-01-27 20:34:45.966457 | ubuntu-noble -> localhost | changed 2025-01-27 20:34:45.984372 | 2025-01-27 20:34:45.984480 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-01-27 20:34:46.460535 | ubuntu-noble -> localhost | ok 2025-01-27 20:34:46.466673 | 2025-01-27 20:34:46.466762 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-01-27 20:34:46.506128 | ubuntu-noble | ok 2025-01-27 20:34:46.528258 | ubuntu-noble | included: /var/lib/zuul/builds/fd7275fc90fd4ff4b79c9a621698a984/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-01-27 20:34:46.547056 | 2025-01-27 20:34:46.547153 | TASK [add-build-sshkey : Create Temp SSH key] 2025-01-27 20:34:47.513032 | ubuntu-noble -> localhost | Generating public/private rsa key pair. 2025-01-27 20:34:47.513256 | ubuntu-noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/fd7275fc90fd4ff4b79c9a621698a984/work/fd7275fc90fd4ff4b79c9a621698a984_id_rsa 2025-01-27 20:34:47.513296 | ubuntu-noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/fd7275fc90fd4ff4b79c9a621698a984/work/fd7275fc90fd4ff4b79c9a621698a984_id_rsa.pub 2025-01-27 20:34:47.513321 | ubuntu-noble -> localhost | The key fingerprint is: 2025-01-27 20:34:47.513344 | ubuntu-noble -> localhost | SHA256:YMYYF+v4VqyC0blcHfq/CeM5OsapAMzgwcwX9msbAPA zuul-build-sshkey 2025-01-27 20:34:47.513365 | ubuntu-noble -> localhost | The key's randomart image is: 2025-01-27 20:34:47.513390 | ubuntu-noble -> localhost | +---[RSA 3072]----+ 2025-01-27 20:34:47.513411 | ubuntu-noble -> localhost | |o. o. o. | 2025-01-27 20:34:47.513431 | ubuntu-noble -> localhost | |+.o o= . | 2025-01-27 20:34:47.513451 | ubuntu-noble -> localhost | |.=Eo..* . | 2025-01-27 20:34:47.513470 | ubuntu-noble -> localhost | |= o..*.= . | 2025-01-27 20:34:47.513489 | ubuntu-noble -> localhost | |.+. +++ S | 2025-01-27 20:34:47.513507 | ubuntu-noble -> localhost | | . +.+o+ | 2025-01-27 20:34:47.513526 | ubuntu-noble -> localhost | | o +o+.+ | 2025-01-27 20:34:47.513546 | ubuntu-noble -> localhost | | . o=..= . | 2025-01-27 20:34:47.513565 | ubuntu-noble -> localhost | | .o.oo.+. | 2025-01-27 20:34:47.513584 | ubuntu-noble -> localhost | +----[SHA256]-----+ 2025-01-27 20:34:47.513629 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.101581 2025-01-27 20:34:47.525112 | 2025-01-27 20:34:47.525219 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-01-27 20:34:47.560898 | ubuntu-noble | ok 2025-01-27 20:34:47.573658 | ubuntu-noble | included: /var/lib/zuul/builds/fd7275fc90fd4ff4b79c9a621698a984/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-01-27 20:34:47.584465 | 2025-01-27 20:34:47.584569 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-01-27 20:34:47.608634 | ubuntu-noble | skipping: Conditional result was False 2025-01-27 20:34:47.616319 | 2025-01-27 20:34:47.616443 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-01-27 20:34:48.323895 | ubuntu-noble | changed 2025-01-27 20:34:48.335199 | 2025-01-27 20:34:48.335331 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-01-27 20:34:48.619715 | ubuntu-noble | ok 2025-01-27 20:34:48.625840 | 2025-01-27 20:34:48.625943 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-01-27 20:34:49.300196 | ubuntu-noble | changed 2025-01-27 20:34:49.307059 | 2025-01-27 20:34:49.307153 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-01-27 20:34:49.989237 | ubuntu-noble | changed 2025-01-27 20:34:49.996928 | 2025-01-27 20:34:49.997020 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-01-27 20:34:50.020505 | ubuntu-noble | skipping: Conditional result was False 2025-01-27 20:34:50.026428 | 2025-01-27 20:34:50.026512 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-01-27 20:34:50.716518 | ubuntu-noble -> localhost | changed 2025-01-27 20:34:50.788741 | 2025-01-27 20:34:50.788846 | TASK [add-build-sshkey : Add back temp key] 2025-01-27 20:34:51.345319 | ubuntu-noble -> localhost | Identity added: /var/lib/zuul/builds/fd7275fc90fd4ff4b79c9a621698a984/work/fd7275fc90fd4ff4b79c9a621698a984_id_rsa (zuul-build-sshkey) 2025-01-27 20:34:51.345488 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.034835 2025-01-27 20:34:51.351589 | 2025-01-27 20:34:51.351673 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-01-27 20:34:51.828864 | ubuntu-noble | ok 2025-01-27 20:34:51.834339 | 2025-01-27 20:34:51.834421 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-01-27 20:34:51.871798 | ubuntu-noble | skipping: Conditional result was False 2025-01-27 20:34:51.883507 | 2025-01-27 20:34:51.883607 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-01-27 20:34:52.341872 | ubuntu-noble | ok 2025-01-27 20:34:52.352005 | 2025-01-27 20:34:52.352091 | TASK [validate-host : Define zuul_info_dir fact] 2025-01-27 20:34:52.424316 | ubuntu-noble | ok 2025-01-27 20:34:52.429900 | 2025-01-27 20:34:52.429996 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-01-27 20:34:52.791946 | ubuntu-noble -> localhost | ok 2025-01-27 20:34:52.799739 | 2025-01-27 20:34:52.799822 | TASK [validate-host : Collect information about the host] 2025-01-27 20:34:54.313788 | ubuntu-noble | ok 2025-01-27 20:34:54.328357 | 2025-01-27 20:34:54.337353 | TASK [validate-host : Sanitize hostname] 2025-01-27 20:34:54.411104 | ubuntu-noble | ok 2025-01-27 20:34:54.432525 | 2025-01-27 20:34:54.432637 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-01-27 20:34:55.304953 | ubuntu-noble -> localhost | changed 2025-01-27 20:34:55.311722 | 2025-01-27 20:34:55.311814 | TASK [validate-host : Collect information about zuul worker] 2025-01-27 20:34:55.762982 | ubuntu-noble | ok 2025-01-27 20:34:55.769174 | 2025-01-27 20:34:55.769262 | TASK [validate-host : Write out all zuul information for each host] 2025-01-27 20:34:56.531264 | ubuntu-noble -> localhost | changed 2025-01-27 20:34:56.544114 | 2025-01-27 20:34:56.544253 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-01-27 20:34:56.843868 | ubuntu-noble | ok 2025-01-27 20:34:56.849956 | 2025-01-27 20:34:56.850045 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-01-27 20:34:57.625907 | ubuntu-noble | changed: 2025-01-27 20:34:57.626090 | ubuntu-noble | cd+++++++++ src/ 2025-01-27 20:34:57.626118 | ubuntu-noble | cd+++++++++ src/github.com/ 2025-01-27 20:34:57.626138 | ubuntu-noble | cd+++++++++ src/github.com/osism/ 2025-01-27 20:34:57.626156 | ubuntu-noble | cd+++++++++ src/github.com/osism/cfg-cookiecutter/ 2025-01-27 20:34:57.626173 | ubuntu-noble | Containerfile 2025-01-27 20:34:57.626279 | ubuntu-noble | localhost | ok: Runtime: 0:00:00.008912 2025-01-27 20:35:32.402821 | 2025-01-27 20:35:32.402904 | TASK [upload-logs : Upload logs to log server] 2025-01-27 20:35:33.443317 | localhost | Output suppressed because no_log was given 2025-01-27 20:35:33.452364 | 2025-01-27 20:35:33.455856 | LOOP [upload-logs : Compress console log and json output] 2025-01-27 20:35:33.668184 | localhost | skipping: Conditional result was False 2025-01-27 20:35:33.689964 | localhost | skipping: Conditional result was False 2025-01-27 20:35:33.703469 | 2025-01-27 20:35:33.703567 | LOOP [upload-logs : Upload compressed console log and json output] 2025-01-27 20:35:33.859559 | localhost | skipping: Conditional result was False 2025-01-27 20:35:33.859956 | 2025-01-27 20:35:33.897193 | localhost | skipping: Conditional result was False 2025-01-27 20:35:33.904795 | 2025-01-27 20:35:33.904896 | LOOP [upload-logs : Upload console log and json output]