2025-01-15 19:30:13.726018 | Job console starting... 2025-01-15 19:30:13.764161 | Updating repositories 2025-01-15 19:30:13.845534 | Preparing job workspace 2025-01-15 19:30:17.397740 | Running Ansible setup... 2025-01-15 19:30:24.469399 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-01-15 19:30:25.801191 | 2025-01-15 19:30:25.801309 | PLAY [Base pre] 2025-01-15 19:30:25.868551 | 2025-01-15 19:30:25.868667 | TASK [Setup log path fact] 2025-01-15 19:30:25.917781 | ubuntu-noble | ok 2025-01-15 19:30:25.961709 | 2025-01-15 19:30:25.961831 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-01-15 19:30:26.051216 | ubuntu-noble | ok 2025-01-15 19:30:26.056623 | 2025-01-15 19:30:26.056708 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-01-15 19:30:26.110221 | ubuntu-noble | skipping: Conditional result was False 2025-01-15 19:30:26.116186 | 2025-01-15 19:30:26.116279 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-01-15 19:30:26.179910 | ubuntu-noble | skipping: Conditional result was False 2025-01-15 19:30:26.186295 | 2025-01-15 19:30:26.186385 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-01-15 19:30:26.239869 | ubuntu-noble | skipping: Conditional result was False 2025-01-15 19:30:26.245897 | 2025-01-15 19:30:26.246031 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-01-15 19:30:26.309874 | ubuntu-noble | skipping: Conditional result was False 2025-01-15 19:30:26.317063 | 2025-01-15 19:30:26.317173 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-01-15 19:30:26.371249 | ubuntu-noble | skipping: Conditional result was False 2025-01-15 19:30:26.385647 | 2025-01-15 19:30:26.385777 | TASK [emit-job-header : Print job information] 2025-01-15 19:30:26.495033 | # Job Information 2025-01-15 19:30:26.495172 | Ansible Version: 2.15.3 2025-01-15 19:30:26.495198 | Job: python-black 2025-01-15 19:30:26.495222 | Pipeline: check 2025-01-15 19:30:26.495240 | Executor: 7d211f194f6a 2025-01-15 19:30:26.495255 | Triggered by: https://github.com/osism/cfg-cookiecutter/pull/696 2025-01-15 19:30:26.495270 | Event ID: 1f119670-d377-11ef-99a5-7cfc0f208445 2025-01-15 19:30:26.504140 | 2025-01-15 19:30:26.504237 | LOOP [emit-job-header : Print node information] 2025-01-15 19:30:26.770813 | ubuntu-noble | ok: 2025-01-15 19:30:26.771011 | ubuntu-noble | # Node Information 2025-01-15 19:30:26.771042 | ubuntu-noble | Inventory Hostname: ubuntu-noble 2025-01-15 19:30:26.771062 | ubuntu-noble | Hostname: ubuntu 2025-01-15 19:30:26.771080 | ubuntu-noble | Username: zuul 2025-01-15 19:30:26.771097 | ubuntu-noble | Distro: Ubuntu 24.04 2025-01-15 19:30:26.771114 | ubuntu-noble | Provider: regiocloud-a 2025-01-15 19:30:26.771130 | ubuntu-noble | Label: ubuntu-noble 2025-01-15 19:30:26.771146 | ubuntu-noble | Product Name: OpenStack Nova 2025-01-15 19:30:26.771162 | ubuntu-noble | Interface IP: 81.163.192.83 2025-01-15 19:30:26.807842 | 2025-01-15 19:30:26.807938 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-01-15 19:30:27.990783 | ubuntu-noble -> localhost | changed 2025-01-15 19:30:28.006632 | 2025-01-15 19:30:28.006728 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-01-15 19:30:29.926089 | ubuntu-noble -> localhost | changed 2025-01-15 19:30:29.946234 | 2025-01-15 19:30:29.946332 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-01-15 19:30:30.506753 | ubuntu-noble -> localhost | ok 2025-01-15 19:30:30.521822 | 2025-01-15 19:30:30.521937 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-01-15 19:30:30.583692 | ubuntu-noble | ok 2025-01-15 19:30:30.620482 | ubuntu-noble | included: /var/lib/zuul/builds/59137a8525044f578ac2f30be846c69d/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-01-15 19:30:30.648693 | 2025-01-15 19:30:30.648792 | TASK [add-build-sshkey : Create Temp SSH key] 2025-01-15 19:30:32.278212 | ubuntu-noble -> localhost | Generating public/private rsa key pair. 2025-01-15 19:30:32.278411 | ubuntu-noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/59137a8525044f578ac2f30be846c69d/work/59137a8525044f578ac2f30be846c69d_id_rsa 2025-01-15 19:30:32.278442 | ubuntu-noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/59137a8525044f578ac2f30be846c69d/work/59137a8525044f578ac2f30be846c69d_id_rsa.pub 2025-01-15 19:30:32.278463 | ubuntu-noble -> localhost | The key fingerprint is: 2025-01-15 19:30:32.278493 | ubuntu-noble -> localhost | SHA256:etZfJU7/4vWHLwI4lcVKhcg4xJC+/e1OsjpYtPMtntg zuul-build-sshkey 2025-01-15 19:30:32.278511 | ubuntu-noble -> localhost | The key's randomart image is: 2025-01-15 19:30:32.278531 | ubuntu-noble -> localhost | +---[RSA 3072]----+ 2025-01-15 19:30:32.278548 | ubuntu-noble -> localhost | | .=.o . +. | 2025-01-15 19:30:32.278564 | ubuntu-noble -> localhost | | . + o o o | 2025-01-15 19:30:32.278580 | ubuntu-noble -> localhost | | . . . + | 2025-01-15 19:30:32.278596 | ubuntu-noble -> localhost | | . . + | 2025-01-15 19:30:32.278611 | ubuntu-noble -> localhost | | + .So o .| 2025-01-15 19:30:32.278626 | ubuntu-noble -> localhost | | . =.o.. o + | 2025-01-15 19:30:32.278641 | ubuntu-noble -> localhost | | o.+++o. o.o| 2025-01-15 19:30:32.278657 | ubuntu-noble -> localhost | | . .=+=o...+.+| 2025-01-15 19:30:32.278673 | ubuntu-noble -> localhost | | o+E+o .o.+=| 2025-01-15 19:30:32.278689 | ubuntu-noble -> localhost | +----[SHA256]-----+ 2025-01-15 19:30:32.278732 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.439202 2025-01-15 19:30:32.285375 | 2025-01-15 19:30:32.285468 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-01-15 19:30:32.335905 | ubuntu-noble | ok 2025-01-15 19:30:32.355452 | ubuntu-noble | included: /var/lib/zuul/builds/59137a8525044f578ac2f30be846c69d/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-01-15 19:30:32.379264 | 2025-01-15 19:30:32.379354 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-01-15 19:30:32.446198 | ubuntu-noble | skipping: Conditional result was False 2025-01-15 19:30:32.452529 | 2025-01-15 19:30:32.452612 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-01-15 19:30:33.278924 | ubuntu-noble | changed 2025-01-15 19:30:33.291733 | 2025-01-15 19:30:33.291820 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-01-15 19:30:33.566294 | ubuntu-noble | ok 2025-01-15 19:30:33.574706 | 2025-01-15 19:30:33.574809 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-01-15 19:30:34.218403 | ubuntu-noble | changed 2025-01-15 19:30:34.225564 | 2025-01-15 19:30:34.225658 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-01-15 19:30:34.851674 | ubuntu-noble | changed 2025-01-15 19:30:34.859416 | 2025-01-15 19:30:34.859517 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-01-15 19:30:34.914809 | ubuntu-noble | skipping: Conditional result was False 2025-01-15 19:30:34.940297 | 2025-01-15 19:30:34.940495 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-01-15 19:30:35.485179 | ubuntu-noble -> localhost | changed 2025-01-15 19:30:35.498875 | 2025-01-15 19:30:35.498991 | TASK [add-build-sshkey : Add back temp key] 2025-01-15 19:30:35.957815 | ubuntu-noble -> localhost | Identity added: /var/lib/zuul/builds/59137a8525044f578ac2f30be846c69d/work/59137a8525044f578ac2f30be846c69d_id_rsa (zuul-build-sshkey) 2025-01-15 19:30:35.958022 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.030622 2025-01-15 19:30:35.965634 | 2025-01-15 19:30:35.965733 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-01-15 19:30:36.377245 | ubuntu-noble | ok 2025-01-15 19:30:36.387839 | 2025-01-15 19:30:36.387935 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-01-15 19:30:36.439612 | ubuntu-noble | skipping: Conditional result was False 2025-01-15 19:30:36.456437 | 2025-01-15 19:30:36.456554 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-01-15 19:30:36.905085 | ubuntu-noble | ok 2025-01-15 19:30:36.922255 | 2025-01-15 19:30:36.922366 | TASK [validate-host : Define zuul_info_dir fact] 2025-01-15 19:30:36.966166 | ubuntu-noble | ok 2025-01-15 19:30:36.986152 | 2025-01-15 19:30:36.986260 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-01-15 19:30:37.599903 | ubuntu-noble -> localhost | ok 2025-01-15 19:30:37.607375 | 2025-01-15 19:30:37.607486 | TASK [validate-host : Collect information about the host] 2025-01-15 19:30:38.899921 | ubuntu-noble | ok 2025-01-15 19:30:38.910426 | 2025-01-15 19:30:38.910534 | TASK [validate-host : Sanitize hostname] 2025-01-15 19:30:38.970376 | ubuntu-noble | ok 2025-01-15 19:30:38.975584 | 2025-01-15 19:30:38.975676 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-01-15 19:30:39.704439 | ubuntu-noble -> localhost | changed 2025-01-15 19:30:39.712234 | 2025-01-15 19:30:39.712322 | TASK [validate-host : Collect information about zuul worker] 2025-01-15 19:30:40.167937 | ubuntu-noble | ok 2025-01-15 19:30:40.174323 | 2025-01-15 19:30:40.174424 | TASK [validate-host : Write out all zuul information for each host] 2025-01-15 19:30:41.347531 | ubuntu-noble -> localhost | changed 2025-01-15 19:30:41.359700 | 2025-01-15 19:30:41.359806 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-01-15 19:30:41.723792 | ubuntu-noble | ok 2025-01-15 19:30:41.733685 | 2025-01-15 19:30:41.733790 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-01-15 19:30:42.574057 | ubuntu-noble | changed: 2025-01-15 19:30:42.574216 | ubuntu-noble | cd+++++++++ src/ 2025-01-15 19:30:42.574249 | ubuntu-noble | cd+++++++++ src/github.com/ 2025-01-15 19:30:42.574273 | ubuntu-noble | cd+++++++++ src/github.com/osism/ 2025-01-15 19:30:42.574294 | ubuntu-noble | cd+++++++++ src/github.com/osism/cfg-cookiecutter/ 2025-01-15 19:30:42.574416 | ubuntu-noble | Containerfile 2025-01-15 19:30:42.574589 | ubuntu-noble | localhost | ok: Runtime: 0:00:00.025594 2025-01-15 19:31:16.949266 | 2025-01-15 19:31:16.949382 | TASK [upload-logs : Upload logs to log server] 2025-01-15 19:31:17.607251 | localhost | Output suppressed because no_log was given 2025-01-15 19:31:17.610397 | 2025-01-15 19:31:17.610517 | LOOP [upload-logs : Compress console log and json output] 2025-01-15 19:31:17.675784 | localhost | skipping: Conditional result was False 2025-01-15 19:31:17.694693 | localhost | skipping: Conditional result was False 2025-01-15 19:31:17.701158 | 2025-01-15 19:31:17.701270 | LOOP [upload-logs : Upload compressed console log and json output] 2025-01-15 19:31:17.775545 | localhost | skipping: Conditional result was False 2025-01-15 19:31:17.775852 | 2025-01-15 19:31:17.791317 | localhost | skipping: Conditional result was False 2025-01-15 19:31:17.803354 | 2025-01-15 19:31:17.803540 | LOOP [upload-logs : Upload console log and json output]