2025-01-22 19:23:21.856281 | Job console starting... 2025-01-22 19:23:21.877190 | Updating repositories 2025-01-22 19:23:21.942665 | Preparing job workspace 2025-01-22 19:23:23.753200 | Running Ansible setup... 2025-01-22 19:23:29.490815 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-01-22 19:23:30.256915 | 2025-01-22 19:23:30.257090 | PLAY [Base pre] 2025-01-22 19:23:30.325424 | 2025-01-22 19:23:30.325599 | TASK [Setup log path fact] 2025-01-22 19:23:30.392366 | ubuntu-noble | ok 2025-01-22 19:23:30.441435 | 2025-01-22 19:23:30.441605 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-01-22 19:23:30.534285 | ubuntu-noble | ok 2025-01-22 19:23:30.541127 | 2025-01-22 19:23:30.541283 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-01-22 19:23:30.577075 | ubuntu-noble | skipping: Conditional result was False 2025-01-22 19:23:30.584450 | 2025-01-22 19:23:30.584583 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-01-22 19:23:30.630078 | ubuntu-noble | skipping: Conditional result was False 2025-01-22 19:23:30.639214 | 2025-01-22 19:23:30.639370 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-01-22 19:23:30.667764 | ubuntu-noble | skipping: Conditional result was False 2025-01-22 19:23:30.675215 | 2025-01-22 19:23:30.675342 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-01-22 19:23:30.700912 | ubuntu-noble | skipping: Conditional result was False 2025-01-22 19:23:30.708626 | 2025-01-22 19:23:30.708762 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-01-22 19:23:30.733510 | ubuntu-noble | skipping: Conditional result was False 2025-01-22 19:23:30.751518 | 2025-01-22 19:23:30.751656 | TASK [emit-job-header : Print job information] 2025-01-22 19:23:30.804803 | # Job Information 2025-01-22 19:23:30.804985 | Ansible Version: 2.15.3 2025-01-22 19:23:30.805016 | Job: python-black 2025-01-22 19:23:30.805045 | Pipeline: check 2025-01-22 19:23:30.805066 | Executor: 7d211f194f6a 2025-01-22 19:23:30.805085 | Triggered by: https://github.com/osism/testbed/pull/2594 2025-01-22 19:23:30.805104 | Event ID: 52766520-d8f6-11ef-95ec-107aacac139e 2025-01-22 19:23:30.811628 | 2025-01-22 19:23:30.811747 | LOOP [emit-job-header : Print node information] 2025-01-22 19:23:30.989342 | ubuntu-noble | ok: 2025-01-22 19:23:30.989536 | ubuntu-noble | # Node Information 2025-01-22 19:23:30.989570 | ubuntu-noble | Inventory Hostname: ubuntu-noble 2025-01-22 19:23:30.989595 | ubuntu-noble | Hostname: ubuntu 2025-01-22 19:23:30.989616 | ubuntu-noble | Username: zuul 2025-01-22 19:23:30.989637 | ubuntu-noble | Distro: Ubuntu 24.04 2025-01-22 19:23:30.989656 | ubuntu-noble | Provider: regiocloud-a 2025-01-22 19:23:30.989675 | ubuntu-noble | Label: ubuntu-noble 2025-01-22 19:23:30.989694 | ubuntu-noble | Product Name: OpenStack Nova 2025-01-22 19:23:30.989714 | ubuntu-noble | Interface IP: 81.163.192.49 2025-01-22 19:23:31.003383 | 2025-01-22 19:23:31.003512 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-01-22 19:23:31.609010 | ubuntu-noble -> localhost | changed 2025-01-22 19:23:31.618333 | 2025-01-22 19:23:31.618470 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-01-22 19:23:32.902681 | ubuntu-noble -> localhost | changed 2025-01-22 19:23:32.918789 | 2025-01-22 19:23:32.918928 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-01-22 19:23:33.301749 | ubuntu-noble -> localhost | ok 2025-01-22 19:23:33.315003 | 2025-01-22 19:23:33.315137 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-01-22 19:23:33.349843 | ubuntu-noble | ok 2025-01-22 19:23:33.367513 | ubuntu-noble | included: /var/lib/zuul/builds/7bada10364be481e91a5f1979c5d5033/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-01-22 19:23:33.375828 | 2025-01-22 19:23:33.375946 | TASK [add-build-sshkey : Create Temp SSH key] 2025-01-22 19:23:34.562433 | ubuntu-noble -> localhost | Generating public/private rsa key pair. 2025-01-22 19:23:34.562704 | ubuntu-noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/7bada10364be481e91a5f1979c5d5033/work/7bada10364be481e91a5f1979c5d5033_id_rsa 2025-01-22 19:23:34.562745 | ubuntu-noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/7bada10364be481e91a5f1979c5d5033/work/7bada10364be481e91a5f1979c5d5033_id_rsa.pub 2025-01-22 19:23:34.562771 | ubuntu-noble -> localhost | The key fingerprint is: 2025-01-22 19:23:34.562793 | ubuntu-noble -> localhost | SHA256:WEqhFkukNXkzOcIHY6MDIGjfh2KN/x+GwPLsPMtPRK8 zuul-build-sshkey 2025-01-22 19:23:34.562816 | ubuntu-noble -> localhost | The key's randomart image is: 2025-01-22 19:23:34.562841 | ubuntu-noble -> localhost | +---[RSA 3072]----+ 2025-01-22 19:23:34.562862 | ubuntu-noble -> localhost | |* o@o.. | 2025-01-22 19:23:34.562882 | ubuntu-noble -> localhost | |oo *+BB. | 2025-01-22 19:23:34.562902 | ubuntu-noble -> localhost | |. = B+o=. | 2025-01-22 19:23:34.562921 | ubuntu-noble -> localhost | | B.=.=. | 2025-01-22 19:23:34.562940 | ubuntu-noble -> localhost | | ..oo+.S. | 2025-01-22 19:23:34.562959 | ubuntu-noble -> localhost | | +.o o | 2025-01-22 19:23:34.562978 | ubuntu-noble -> localhost | | o.E o | 2025-01-22 19:23:34.562998 | ubuntu-noble -> localhost | | +.... . | 2025-01-22 19:23:34.563017 | ubuntu-noble -> localhost | | =+... | 2025-01-22 19:23:34.563035 | ubuntu-noble -> localhost | +----[SHA256]-----+ 2025-01-22 19:23:34.563089 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.594535 2025-01-22 19:23:34.571915 | 2025-01-22 19:23:34.572044 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-01-22 19:23:34.611914 | ubuntu-noble | ok 2025-01-22 19:23:34.623448 | ubuntu-noble | included: /var/lib/zuul/builds/7bada10364be481e91a5f1979c5d5033/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-01-22 19:23:34.634843 | 2025-01-22 19:23:34.634970 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-01-22 19:23:34.662091 | ubuntu-noble | skipping: Conditional result was False 2025-01-22 19:23:34.669814 | 2025-01-22 19:23:34.669948 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-01-22 19:23:35.366413 | ubuntu-noble | changed 2025-01-22 19:23:35.376511 | 2025-01-22 19:23:35.376644 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-01-22 19:23:35.660228 | ubuntu-noble | ok 2025-01-22 19:23:35.668050 | 2025-01-22 19:23:35.668194 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-01-22 19:23:36.335975 | ubuntu-noble | changed 2025-01-22 19:23:36.347073 | 2025-01-22 19:23:36.347233 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-01-22 19:23:37.042805 | ubuntu-noble | changed 2025-01-22 19:23:37.053632 | 2025-01-22 19:23:37.053768 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-01-22 19:23:37.079283 | ubuntu-noble | skipping: Conditional result was False 2025-01-22 19:23:37.087638 | 2025-01-22 19:23:37.087782 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-01-22 19:23:37.572267 | ubuntu-noble -> localhost | changed 2025-01-22 19:23:37.589985 | 2025-01-22 19:23:37.590130 | TASK [add-build-sshkey : Add back temp key] 2025-01-22 19:23:38.235674 | ubuntu-noble -> localhost | Identity added: /var/lib/zuul/builds/7bada10364be481e91a5f1979c5d5033/work/7bada10364be481e91a5f1979c5d5033_id_rsa (zuul-build-sshkey) 2025-01-22 19:23:38.235868 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.014980 2025-01-22 19:23:38.243291 | 2025-01-22 19:23:38.243409 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-01-22 19:23:38.647544 | ubuntu-noble | ok 2025-01-22 19:23:38.655815 | 2025-01-22 19:23:38.655958 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-01-22 19:23:38.681970 | ubuntu-noble | skipping: Conditional result was False 2025-01-22 19:23:38.703953 | 2025-01-22 19:23:38.704096 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-01-22 19:23:39.151423 | ubuntu-noble | ok 2025-01-22 19:23:39.165703 | 2025-01-22 19:23:39.165838 | TASK [validate-host : Define zuul_info_dir fact] 2025-01-22 19:23:39.201582 | ubuntu-noble | ok 2025-01-22 19:23:39.212093 | 2025-01-22 19:23:39.212247 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-01-22 19:23:39.587999 | ubuntu-noble -> localhost | ok 2025-01-22 19:23:39.597806 | 2025-01-22 19:23:39.597938 | TASK [validate-host : Collect information about the host] 2025-01-22 19:23:40.846472 | ubuntu-noble | ok 2025-01-22 19:23:40.859684 | 2025-01-22 19:23:40.859817 | TASK [validate-host : Sanitize hostname] 2025-01-22 19:23:40.924281 | ubuntu-noble | ok 2025-01-22 19:23:40.931132 | 2025-01-22 19:23:40.931296 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-01-22 19:23:41.547494 | ubuntu-noble -> localhost | changed 2025-01-22 19:23:41.555777 | 2025-01-22 19:23:41.555909 | TASK [validate-host : Collect information about zuul worker] 2025-01-22 19:23:42.068867 | ubuntu-noble | ok 2025-01-22 19:23:42.075351 | 2025-01-22 19:23:42.075475 | TASK [validate-host : Write out all zuul information for each host] 2025-01-22 19:23:42.724938 | ubuntu-noble -> localhost | changed 2025-01-22 19:23:42.738387 | 2025-01-22 19:23:42.738507 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-01-22 19:23:43.062951 | ubuntu-noble | ok 2025-01-22 19:23:43.070828 | 2025-01-22 19:23:43.070953 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-01-22 19:23:44.052830 | ubuntu-noble | changed: 2025-01-22 19:23:44.053066 | ubuntu-noble | cd+++++++++ src/ 2025-01-22 19:23:44.053107 | ubuntu-noble | cd+++++++++ src/github.com/ 2025-01-22 19:23:44.053135 | ubuntu-noble | cd+++++++++ src/github.com/osism/ 2025-01-22 19:23:44.053181 | ubuntu-noble | cd+++++++++ src/github.com/osism/testbed/ 2025-01-22 19:23:44.053207 | ubuntu-noble | localhost | ok: Runtime: 0:00:00.007996 2025-01-22 19:24:17.387562 | 2025-01-22 19:24:17.387706 | TASK [upload-logs : Upload logs to log server] 2025-01-22 19:24:18.037951 | localhost | Output suppressed because no_log was given 2025-01-22 19:24:18.043158 | 2025-01-22 19:24:18.043314 | LOOP [upload-logs : Compress console log and json output] 2025-01-22 19:24:18.126668 | localhost | skipping: Conditional result was False 2025-01-22 19:24:18.147328 | localhost | skipping: Conditional result was False 2025-01-22 19:24:18.153935 | 2025-01-22 19:24:18.154066 | LOOP [upload-logs : Upload compressed console log and json output] 2025-01-22 19:24:18.223578 | localhost | skipping: Conditional result was False 2025-01-22 19:24:18.223900 | 2025-01-22 19:24:18.241357 | localhost | skipping: Conditional result was False 2025-01-22 19:24:18.247410 | 2025-01-22 19:24:18.249578 | LOOP [upload-logs : Upload console log and json output]