2025-09-28 03:12:32.250255 | Job console starting 2025-09-28 03:12:32.260588 | Updating git repos 2025-09-28 03:12:32.312853 | Cloning repos into workspace 2025-09-28 03:12:32.417296 | Restoring repo states 2025-09-28 03:12:32.431657 | Merging changes 2025-09-28 03:12:32.431677 | Checking out repos 2025-09-28 03:12:32.535683 | Preparing playbooks 2025-09-28 03:12:34.031451 | Running Ansible setup 2025-09-28 03:12:40.142173 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-09-28 03:12:41.020542 | 2025-09-28 03:12:41.020653 | PLAY [Base pre] 2025-09-28 03:12:41.042377 | 2025-09-28 03:12:41.042557 | TASK [Setup log path fact] 2025-09-28 03:12:41.064389 | debian-bookworm | ok 2025-09-28 03:12:41.080571 | 2025-09-28 03:12:41.080673 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-09-28 03:12:41.108570 | debian-bookworm | ok 2025-09-28 03:12:41.122350 | 2025-09-28 03:12:41.122475 | TASK [emit-job-header : Print job information] 2025-09-28 03:12:41.180131 | # Job Information 2025-09-28 03:12:41.180304 | Ansible Version: 2.16.14 2025-09-28 03:12:41.180334 | Job: python-black 2025-09-28 03:12:41.180361 | Pipeline: periodic-daily 2025-09-28 03:12:41.180380 | Executor: 521e9411259a 2025-09-28 03:12:41.180397 | Triggered by: https://github.com/osism/cfg-cookiecutter 2025-09-28 03:12:41.180415 | Event ID: 51c5f94898f1412281fcb10bf164e0b6 2025-09-28 03:12:41.184300 | 2025-09-28 03:12:41.184375 | LOOP [emit-job-header : Print node information] 2025-09-28 03:12:41.275451 | debian-bookworm | ok: 2025-09-28 03:12:41.275620 | debian-bookworm | # Node Information 2025-09-28 03:12:41.275651 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-09-28 03:12:41.275672 | debian-bookworm | Hostname: debian 2025-09-28 03:12:41.275690 | debian-bookworm | Username: zuul 2025-09-28 03:12:41.275707 | debian-bookworm | Distro: Debian 12.12 2025-09-28 03:12:41.275727 | debian-bookworm | Provider: regiocloud-a 2025-09-28 03:12:41.275744 | debian-bookworm | Region: 2025-09-28 03:12:41.275761 | debian-bookworm | Label: debian-bookworm 2025-09-28 03:12:41.275777 | debian-bookworm | Product Name: OpenStack Nova 2025-09-28 03:12:41.275793 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe12:a773 2025-09-28 03:12:41.302014 | 2025-09-28 03:12:41.302105 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-09-28 03:12:41.800103 | debian-bookworm -> localhost | changed 2025-09-28 03:12:41.807318 | 2025-09-28 03:12:41.807422 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-09-28 03:12:42.956354 | debian-bookworm -> localhost | changed 2025-09-28 03:12:42.965232 | 2025-09-28 03:12:42.965316 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-09-28 03:12:43.210209 | debian-bookworm -> localhost | ok 2025-09-28 03:12:43.215876 | 2025-09-28 03:12:43.215967 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-09-28 03:12:43.232036 | debian-bookworm | ok 2025-09-28 03:12:43.244899 | debian-bookworm | included: /var/lib/zuul/builds/ed59ff10e81a468da111cdeca0fb891b/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-09-28 03:12:43.249991 | 2025-09-28 03:12:43.250059 | TASK [add-build-sshkey : Create Temp SSH key] 2025-09-28 03:12:44.856053 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-09-28 03:12:44.856471 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/ed59ff10e81a468da111cdeca0fb891b/work/ed59ff10e81a468da111cdeca0fb891b_id_rsa 2025-09-28 03:12:44.856562 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/ed59ff10e81a468da111cdeca0fb891b/work/ed59ff10e81a468da111cdeca0fb891b_id_rsa.pub 2025-09-28 03:12:44.856628 | debian-bookworm -> localhost | The key fingerprint is: 2025-09-28 03:12:44.856692 | debian-bookworm -> localhost | SHA256:qPAf8OdJ2eVU6gTTaUe42rmaJibd+EIdnUXgDtYVdvs zuul-build-sshkey 2025-09-28 03:12:44.856748 | debian-bookworm -> localhost | The key's randomart image is: 2025-09-28 03:12:44.856819 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-09-28 03:12:44.856873 | debian-bookworm -> localhost | | .+*..| 2025-09-28 03:12:44.856925 | debian-bookworm -> localhost | | +.=...| 2025-09-28 03:12:44.856977 | debian-bookworm -> localhost | | =.*+o. | 2025-09-28 03:12:44.857027 | debian-bookworm -> localhost | | . ..*++ .| 2025-09-28 03:12:44.857076 | debian-bookworm -> localhost | | . . . S. +*. E| 2025-09-28 03:12:44.857139 | debian-bookworm -> localhost | | o + .oo*o | 2025-09-28 03:12:44.857243 | debian-bookworm -> localhost | | o oo+o. o. | 2025-09-28 03:12:44.857306 | debian-bookworm -> localhost | | ..=*.o.. | 2025-09-28 03:12:44.857361 | debian-bookworm -> localhost | | .oo=+. | 2025-09-28 03:12:44.857414 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-09-28 03:12:44.857506 | debian-bookworm -> localhost | ok: Runtime: 0:00:01.169447 2025-09-28 03:12:44.866148 | 2025-09-28 03:12:44.866252 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-09-28 03:12:44.884906 | debian-bookworm | ok 2025-09-28 03:12:44.893110 | debian-bookworm | included: /var/lib/zuul/builds/ed59ff10e81a468da111cdeca0fb891b/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-09-28 03:12:44.901030 | 2025-09-28 03:12:44.901390 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-09-28 03:12:44.939635 | debian-bookworm | skipping: Conditional result was False 2025-09-28 03:12:44.949236 | 2025-09-28 03:12:44.949328 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-09-28 03:12:45.608504 | debian-bookworm | changed 2025-09-28 03:12:45.617810 | 2025-09-28 03:12:45.617971 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-09-28 03:12:45.889938 | debian-bookworm | ok 2025-09-28 03:12:45.911633 | 2025-09-28 03:12:45.911735 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-09-28 03:12:46.649608 | debian-bookworm | changed 2025-09-28 03:12:46.661085 | 2025-09-28 03:12:46.661181 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-09-28 03:12:47.477151 | debian-bookworm | changed 2025-09-28 03:12:47.492493 | 2025-09-28 03:12:47.492601 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-09-28 03:12:47.571826 | debian-bookworm | skipping: Conditional result was False 2025-09-28 03:12:47.586966 | 2025-09-28 03:12:47.587077 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-09-28 03:12:48.926999 | debian-bookworm -> localhost | changed 2025-09-28 03:12:48.944395 | 2025-09-28 03:12:48.944498 | TASK [add-build-sshkey : Add back temp key] 2025-09-28 03:12:49.351342 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/ed59ff10e81a468da111cdeca0fb891b/work/ed59ff10e81a468da111cdeca0fb891b_id_rsa (zuul-build-sshkey) 2025-09-28 03:12:49.351596 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.008158 2025-09-28 03:12:49.359990 | 2025-09-28 03:12:49.360095 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-09-28 03:12:49.724998 | debian-bookworm | ok 2025-09-28 03:12:49.734880 | 2025-09-28 03:12:49.734982 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-09-28 03:12:49.769416 | debian-bookworm | skipping: Conditional result was False 2025-09-28 03:12:49.785126 | 2025-09-28 03:12:49.785237 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-09-28 03:12:50.241267 | debian-bookworm | ok 2025-09-28 03:12:50.277996 | 2025-09-28 03:12:50.278109 | TASK [validate-host : Define zuul_info_dir fact] 2025-09-28 03:12:50.317755 | debian-bookworm | ok 2025-09-28 03:12:50.325097 | 2025-09-28 03:12:50.325198 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-09-28 03:12:51.147634 | debian-bookworm -> localhost | ok 2025-09-28 03:12:51.153651 | 2025-09-28 03:12:51.153743 | TASK [validate-host : Collect information about the host] 2025-09-28 03:12:52.517212 | debian-bookworm | ok 2025-09-28 03:12:52.537276 | 2025-09-28 03:12:52.537369 | TASK [validate-host : Sanitize hostname] 2025-09-28 03:12:52.667306 | debian-bookworm | ok 2025-09-28 03:12:52.775161 | 2025-09-28 03:12:52.775295 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-09-28 03:12:54.203750 | debian-bookworm -> localhost | changed 2025-09-28 03:12:54.209427 | 2025-09-28 03:12:54.209524 | TASK [validate-host : Collect information about zuul worker] 2025-09-28 03:12:54.861869 | debian-bookworm | ok 2025-09-28 03:12:54.866552 | 2025-09-28 03:12:54.866652 | TASK [validate-host : Write out all zuul information for each host] 2025-09-28 03:12:55.936404 | debian-bookworm -> localhost | changed 2025-09-28 03:12:55.945678 | 2025-09-28 03:12:55.945774 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-09-28 03:12:56.244343 | debian-bookworm | ok 2025-09-28 03:12:56.249798 | 2025-09-28 03:12:56.249887 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-09-28 03:12:57.672238 | debian-bookworm | changed: 2025-09-28 03:12:57.672409 | debian-bookworm | cd+++++++++ src/ 2025-09-28 03:12:57.672444 | debian-bookworm | cd+++++++++ src/github.com/ 2025-09-28 03:12:57.672468 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-09-28 03:12:57.672489 | debian-bookworm | cd+++++++++ src/github.com/osism/cfg-cookiecutter/ 2025-09-28 03:12:57.672510 | debian-bookworm | Containerfile 2025-09-28 03:12:57.672643 | debian-bookworm | localhost | ok: Runtime: 0:00:00.011988 2025-09-28 03:13:25.424880 | 2025-09-28 03:13:25.424968 | TASK [upload-logs : Upload logs to log server] 2025-09-28 03:13:25.934501 | localhost | Output suppressed because no_log was given 2025-09-28 03:13:25.936062 | 2025-09-28 03:13:25.936158 | LOOP [upload-logs : Compress console log and json output] 2025-09-28 03:13:25.982497 | localhost | skipping: Conditional result was False 2025-09-28 03:13:25.987922 | localhost | skipping: Conditional result was False 2025-09-28 03:13:25.991785 | 2025-09-28 03:13:25.991875 | LOOP [upload-logs : Upload compressed console log and json output] 2025-09-28 03:13:26.035623 | localhost | skipping: Conditional result was False 2025-09-28 03:13:26.035992 | 2025-09-28 03:13:26.040741 | localhost | skipping: Conditional result was False 2025-09-28 03:13:26.048661 | 2025-09-28 03:13:26.048746 | LOOP [upload-logs : Upload console log and json output]