2025-02-18 08:16:36.221361 | Job console starting... 2025-02-18 08:16:36.258124 | Updating repositories 2025-02-18 08:16:36.379364 | Preparing job workspace 2025-02-18 08:16:38.801100 | Running Ansible setup... 2025-02-18 08:16:45.132948 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-02-18 08:16:46.204234 | 2025-02-18 08:16:46.204423 | PLAY [Base pre] 2025-02-18 08:16:46.234301 | 2025-02-18 08:16:46.234540 | TASK [Setup log path fact] 2025-02-18 08:16:46.286693 | ubuntu-noble | ok 2025-02-18 08:16:46.322771 | 2025-02-18 08:16:46.322926 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-02-18 08:16:46.395751 | ubuntu-noble | ok 2025-02-18 08:16:46.418823 | 2025-02-18 08:16:46.418971 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-02-18 08:16:46.474855 | ubuntu-noble | skipping: Conditional result was False 2025-02-18 08:16:46.482296 | 2025-02-18 08:16:46.482426 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-02-18 08:16:46.538082 | ubuntu-noble | skipping: Conditional result was False 2025-02-18 08:16:46.545774 | 2025-02-18 08:16:46.545905 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-02-18 08:16:46.571070 | ubuntu-noble | skipping: Conditional result was False 2025-02-18 08:16:46.578569 | 2025-02-18 08:16:46.578698 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-02-18 08:16:46.644466 | ubuntu-noble | skipping: Conditional result was False 2025-02-18 08:16:46.653382 | 2025-02-18 08:16:46.653561 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-02-18 08:16:46.679470 | ubuntu-noble | skipping: Conditional result was False 2025-02-18 08:16:46.695648 | 2025-02-18 08:16:46.695792 | TASK [emit-job-header : Print job information] 2025-02-18 08:16:46.761605 | # Job Information 2025-02-18 08:16:46.761789 | Ansible Version: 2.15.3 2025-02-18 08:16:46.761821 | Job: python-black 2025-02-18 08:16:46.761852 | Pipeline: check 2025-02-18 08:16:46.761873 | Executor: 7d211f194f6a 2025-02-18 08:16:46.761893 | Triggered by: https://github.com/osism/ansible-collection-services/pull/1763 2025-02-18 08:16:46.761912 | Event ID: a64d02a0-edd0-11ef-95bf-a93972d2b6a0 2025-02-18 08:16:46.770853 | 2025-02-18 08:16:46.770988 | LOOP [emit-job-header : Print node information] 2025-02-18 08:16:46.966435 | ubuntu-noble | ok: 2025-02-18 08:16:46.966652 | ubuntu-noble | # Node Information 2025-02-18 08:16:46.966688 | ubuntu-noble | Inventory Hostname: ubuntu-noble 2025-02-18 08:16:46.966712 | ubuntu-noble | Hostname: ubuntu 2025-02-18 08:16:46.966733 | ubuntu-noble | Username: zuul 2025-02-18 08:16:46.966753 | ubuntu-noble | Distro: Ubuntu 24.04 2025-02-18 08:16:46.966773 | ubuntu-noble | Provider: regiocloud-a 2025-02-18 08:16:46.966792 | ubuntu-noble | Label: ubuntu-noble 2025-02-18 08:16:46.966811 | ubuntu-noble | Product Name: OpenStack Nova 2025-02-18 08:16:46.966830 | ubuntu-noble | Interface IP: 81.163.193.8 2025-02-18 08:16:46.983690 | 2025-02-18 08:16:46.983822 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-02-18 08:16:47.833622 | ubuntu-noble -> localhost | changed 2025-02-18 08:16:47.842375 | 2025-02-18 08:16:47.842496 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-02-18 08:16:49.506725 | ubuntu-noble -> localhost | changed 2025-02-18 08:16:49.528308 | 2025-02-18 08:16:49.528490 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-02-18 08:16:49.863041 | ubuntu-noble -> localhost | ok 2025-02-18 08:16:49.870759 | 2025-02-18 08:16:49.870889 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-02-18 08:16:49.902922 | ubuntu-noble | ok 2025-02-18 08:16:49.939424 | ubuntu-noble | included: /var/lib/zuul/builds/52c50499382841529e13723fa7bdaf54/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-02-18 08:16:49.952294 | 2025-02-18 08:16:49.952445 | TASK [add-build-sshkey : Create Temp SSH key] 2025-02-18 08:16:51.289555 | ubuntu-noble -> localhost | Generating public/private rsa key pair. 2025-02-18 08:16:51.289743 | ubuntu-noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/52c50499382841529e13723fa7bdaf54/work/52c50499382841529e13723fa7bdaf54_id_rsa 2025-02-18 08:16:51.289780 | ubuntu-noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/52c50499382841529e13723fa7bdaf54/work/52c50499382841529e13723fa7bdaf54_id_rsa.pub 2025-02-18 08:16:51.289805 | ubuntu-noble -> localhost | The key fingerprint is: 2025-02-18 08:16:51.289829 | ubuntu-noble -> localhost | SHA256:xT+tJPY/CudRRt4v27VLvtV+UUSJNkZppjquQs1MzHM zuul-build-sshkey 2025-02-18 08:16:51.289851 | ubuntu-noble -> localhost | The key's randomart image is: 2025-02-18 08:16:51.289876 | ubuntu-noble -> localhost | +---[RSA 3072]----+ 2025-02-18 08:16:51.289898 | ubuntu-noble -> localhost | | ..o.o| 2025-02-18 08:16:51.289919 | ubuntu-noble -> localhost | | . O ..| 2025-02-18 08:16:51.289939 | ubuntu-noble -> localhost | | o o * o. | 2025-02-18 08:16:51.289958 | ubuntu-noble -> localhost | | = E o + ..| 2025-02-18 08:16:51.289977 | ubuntu-noble -> localhost | | = S + + = o| 2025-02-18 08:16:51.289996 | ubuntu-noble -> localhost | | . + + + = .o| 2025-02-18 08:16:51.290016 | ubuntu-noble -> localhost | | . . o = ..*| 2025-02-18 08:16:51.290036 | ubuntu-noble -> localhost | | . . + o+==| 2025-02-18 08:16:51.290055 | ubuntu-noble -> localhost | | ... o.o**| 2025-02-18 08:16:51.290077 | ubuntu-noble -> localhost | +----[SHA256]-----+ 2025-02-18 08:16:51.290123 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.528115 2025-02-18 08:16:51.311038 | 2025-02-18 08:16:51.311149 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-02-18 08:16:51.351391 | ubuntu-noble | ok 2025-02-18 08:16:51.366723 | ubuntu-noble | included: /var/lib/zuul/builds/52c50499382841529e13723fa7bdaf54/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-02-18 08:16:51.376696 | 2025-02-18 08:16:51.376796 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-02-18 08:16:51.400626 | ubuntu-noble | skipping: Conditional result was False 2025-02-18 08:16:51.407905 | 2025-02-18 08:16:51.408005 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-02-18 08:16:52.123643 | ubuntu-noble | changed 2025-02-18 08:16:52.134200 | 2025-02-18 08:16:52.134322 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-02-18 08:16:52.426423 | ubuntu-noble | ok 2025-02-18 08:16:52.436530 | 2025-02-18 08:16:52.436638 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-02-18 08:16:53.159803 | ubuntu-noble | changed 2025-02-18 08:16:53.167303 | 2025-02-18 08:16:53.167391 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-02-18 08:16:53.860683 | ubuntu-noble | changed 2025-02-18 08:16:53.873670 | 2025-02-18 08:16:53.873773 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-02-18 08:16:53.897963 | ubuntu-noble | skipping: Conditional result was False 2025-02-18 08:16:53.905348 | 2025-02-18 08:16:53.905576 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-02-18 08:16:54.799981 | ubuntu-noble -> localhost | changed 2025-02-18 08:16:54.874083 | 2025-02-18 08:16:54.874240 | TASK [add-build-sshkey : Add back temp key] 2025-02-18 08:16:55.365148 | ubuntu-noble -> localhost | Identity added: /var/lib/zuul/builds/52c50499382841529e13723fa7bdaf54/work/52c50499382841529e13723fa7bdaf54_id_rsa (zuul-build-sshkey) 2025-02-18 08:16:55.365351 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.009879 2025-02-18 08:16:55.372586 | 2025-02-18 08:16:55.372681 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-02-18 08:16:55.793246 | ubuntu-noble | ok 2025-02-18 08:16:55.803610 | 2025-02-18 08:16:55.803712 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-02-18 08:16:55.844947 | ubuntu-noble | skipping: Conditional result was False 2025-02-18 08:16:55.864684 | 2025-02-18 08:16:55.864810 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-02-18 08:16:56.285452 | ubuntu-noble | ok 2025-02-18 08:16:56.304121 | 2025-02-18 08:16:56.304226 | TASK [validate-host : Define zuul_info_dir fact] 2025-02-18 08:16:56.344241 | ubuntu-noble | ok 2025-02-18 08:16:56.352201 | 2025-02-18 08:16:56.352295 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-02-18 08:16:56.766311 | ubuntu-noble -> localhost | ok 2025-02-18 08:16:56.774767 | 2025-02-18 08:16:56.774862 | TASK [validate-host : Collect information about the host] 2025-02-18 08:16:58.099444 | ubuntu-noble | ok 2025-02-18 08:16:58.112840 | 2025-02-18 08:16:58.112974 | TASK [validate-host : Sanitize hostname] 2025-02-18 08:16:58.185184 | ubuntu-noble | ok 2025-02-18 08:16:58.191902 | 2025-02-18 08:16:58.192034 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-02-18 08:16:59.088067 | ubuntu-noble -> localhost | changed 2025-02-18 08:16:59.095688 | 2025-02-18 08:16:59.095809 | TASK [validate-host : Collect information about zuul worker] 2025-02-18 08:16:59.609092 | ubuntu-noble | ok 2025-02-18 08:16:59.615396 | 2025-02-18 08:16:59.615537 | TASK [validate-host : Write out all zuul information for each host] 2025-02-18 08:17:00.422895 | ubuntu-noble -> localhost | changed 2025-02-18 08:17:00.442536 | 2025-02-18 08:17:00.442675 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-02-18 08:17:00.798332 | ubuntu-noble | ok 2025-02-18 08:17:00.816408 | 2025-02-18 08:17:00.816570 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-02-18 08:17:02.292232 | ubuntu-noble | changed: 2025-02-18 08:17:02.296537 | ubuntu-noble | cd+++++++++ src/ 2025-02-18 08:17:02.296626 | ubuntu-noble | cd+++++++++ src/github.com/ 2025-02-18 08:17:02.296661 | ubuntu-noble | cd+++++++++ src/github.com/osism/ 2025-02-18 08:17:02.296692 | ubuntu-noble | cd+++++++++ src/github.com/osism/ansible-collection-services/ 2025-02-18 08:17:02.296723 | ubuntu-noble | localhost | ok: Runtime: 0:00:00.006041 2025-02-18 08:17:39.401244 | 2025-02-18 08:17:39.401391 | TASK [upload-logs : Upload logs to log server] 2025-02-18 08:17:39.999273 | localhost | Output suppressed because no_log was given 2025-02-18 08:17:40.006080 | 2025-02-18 08:17:40.006245 | LOOP [upload-logs : Compress console log and json output] 2025-02-18 08:17:40.087234 | localhost | skipping: Conditional result was False 2025-02-18 08:17:40.104218 | localhost | skipping: Conditional result was False 2025-02-18 08:17:40.113745 | 2025-02-18 08:17:40.113918 | LOOP [upload-logs : Upload compressed console log and json output] 2025-02-18 08:17:40.177949 | localhost | skipping: Conditional result was False 2025-02-18 08:17:40.178683 | 2025-02-18 08:17:40.190411 | localhost | skipping: Conditional result was False 2025-02-18 08:17:40.203028 | 2025-02-18 08:17:40.203229 | LOOP [upload-logs : Upload console log and json output]