2025-06-09 11:45:34.777716 | Job console starting 2025-06-09 11:45:34.807915 | Updating git repos 2025-06-09 11:45:35.015374 | Cloning repos into workspace 2025-06-09 11:45:35.063964 | Restoring repo states 2025-06-09 11:45:35.084481 | Merging changes 2025-06-09 11:45:35.703763 | Checking out repos 2025-06-09 11:45:35.776849 | Preparing playbooks 2025-06-09 11:45:37.398933 | Running Ansible setup 2025-06-09 11:45:45.281720 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-06-09 11:45:47.347426 | 2025-06-09 11:45:47.347611 | PLAY [Base pre] 2025-06-09 11:45:47.380518 | 2025-06-09 11:45:47.380679 | TASK [Setup log path fact] 2025-06-09 11:45:47.424269 | debian-bookworm | ok 2025-06-09 11:45:47.443553 | 2025-06-09 11:45:47.443716 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-06-09 11:45:47.496053 | debian-bookworm | ok 2025-06-09 11:45:47.508949 | 2025-06-09 11:45:47.509180 | TASK [emit-job-header : Print job information] 2025-06-09 11:45:47.579057 | # Job Information 2025-06-09 11:45:47.579821 | Ansible Version: 2.16.14 2025-06-09 11:45:47.579893 | Job: python-black 2025-06-09 11:45:47.579938 | Pipeline: check 2025-06-09 11:45:47.579967 | Executor: 521e9411259a 2025-06-09 11:45:47.579987 | Triggered by: https://github.com/osism/cfg-cookiecutter/pull/755 2025-06-09 11:45:47.580009 | Event ID: 3b7bde20-4527-11f0-9299-c68ac16078f4 2025-06-09 11:45:47.592545 | 2025-06-09 11:45:47.592677 | LOOP [emit-job-header : Print node information] 2025-06-09 11:45:47.804394 | debian-bookworm | ok: 2025-06-09 11:45:47.804623 | debian-bookworm | # Node Information 2025-06-09 11:45:47.804661 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-06-09 11:45:47.804685 | debian-bookworm | Hostname: debian 2025-06-09 11:45:47.804706 | debian-bookworm | Username: zuul 2025-06-09 11:45:47.804727 | debian-bookworm | Distro: Debian 12.11 2025-06-09 11:45:47.804750 | debian-bookworm | Provider: regiocloud-a 2025-06-09 11:45:47.804771 | debian-bookworm | Region: 2025-06-09 11:45:47.804792 | debian-bookworm | Label: debian-bookworm 2025-06-09 11:45:47.804811 | debian-bookworm | Product Name: OpenStack Nova 2025-06-09 11:45:47.804830 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe6a:944e 2025-06-09 11:45:47.831126 | 2025-06-09 11:45:47.831282 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-06-09 11:45:49.323400 | debian-bookworm -> localhost | changed 2025-06-09 11:45:49.329743 | 2025-06-09 11:45:49.329836 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-06-09 11:45:51.223921 | debian-bookworm -> localhost | changed 2025-06-09 11:45:51.232367 | 2025-06-09 11:45:51.232453 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-06-09 11:45:51.866853 | debian-bookworm -> localhost | ok 2025-06-09 11:45:51.871848 | 2025-06-09 11:45:51.871939 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-06-09 11:45:51.908652 | debian-bookworm | ok 2025-06-09 11:45:51.931461 | debian-bookworm | included: /var/lib/zuul/builds/2bc1161f2e3e4558b91d27d3a1c8b3ce/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-06-09 11:45:51.936301 | 2025-06-09 11:45:51.936389 | TASK [add-build-sshkey : Create Temp SSH key] 2025-06-09 11:45:55.537334 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-06-09 11:45:55.537517 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/2bc1161f2e3e4558b91d27d3a1c8b3ce/work/2bc1161f2e3e4558b91d27d3a1c8b3ce_id_rsa 2025-06-09 11:45:55.537550 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/2bc1161f2e3e4558b91d27d3a1c8b3ce/work/2bc1161f2e3e4558b91d27d3a1c8b3ce_id_rsa.pub 2025-06-09 11:45:55.537571 | debian-bookworm -> localhost | The key fingerprint is: 2025-06-09 11:45:55.537591 | debian-bookworm -> localhost | SHA256:6JEZO6D3bpNUB/bPOIJ1AXJPfI9yIlBsBR4gy7u+9B8 zuul-build-sshkey 2025-06-09 11:45:55.537609 | debian-bookworm -> localhost | The key's randomart image is: 2025-06-09 11:45:55.537636 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-06-09 11:45:55.537654 | debian-bookworm -> localhost | | . o+*=o | 2025-06-09 11:45:55.537672 | debian-bookworm -> localhost | | . o.+=+o . | 2025-06-09 11:45:55.537689 | debian-bookworm -> localhost | | + .+.o.o o | 2025-06-09 11:45:55.537706 | debian-bookworm -> localhost | | . o *+ * o . | 2025-06-09 11:45:55.537723 | debian-bookworm -> localhost | | . o B+S+ B | 2025-06-09 11:45:55.537743 | debian-bookworm -> localhost | | . +oo. o o | 2025-06-09 11:45:55.537761 | debian-bookworm -> localhost | | o.o.E. . | 2025-06-09 11:45:55.537777 | debian-bookworm -> localhost | | o o+ . | 2025-06-09 11:45:55.537794 | debian-bookworm -> localhost | | oooo. | 2025-06-09 11:45:55.537810 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-06-09 11:45:55.537854 | debian-bookworm -> localhost | ok: Runtime: 0:00:02.842272 2025-06-09 11:45:55.543348 | 2025-06-09 11:45:55.543438 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-06-09 11:45:55.560680 | debian-bookworm | ok 2025-06-09 11:45:55.568373 | debian-bookworm | included: /var/lib/zuul/builds/2bc1161f2e3e4558b91d27d3a1c8b3ce/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-06-09 11:45:55.575672 | 2025-06-09 11:45:55.575754 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-06-09 11:45:55.609364 | debian-bookworm | skipping: Conditional result was False 2025-06-09 11:45:55.623744 | 2025-06-09 11:45:55.623904 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-06-09 11:45:56.237864 | debian-bookworm | changed 2025-06-09 11:45:56.272648 | 2025-06-09 11:45:56.272762 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-06-09 11:45:56.560780 | debian-bookworm | ok 2025-06-09 11:45:56.567807 | 2025-06-09 11:45:56.567910 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-06-09 11:45:57.209304 | debian-bookworm | changed 2025-06-09 11:45:57.222217 | 2025-06-09 11:45:57.222337 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-06-09 11:45:57.904390 | debian-bookworm | changed 2025-06-09 11:45:57.909528 | 2025-06-09 11:45:57.909612 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-06-09 11:45:57.930665 | debian-bookworm | skipping: Conditional result was False 2025-06-09 11:45:57.936621 | 2025-06-09 11:45:57.936712 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-06-09 11:45:58.657402 | debian-bookworm -> localhost | changed 2025-06-09 11:45:58.669074 | 2025-06-09 11:45:58.669181 | TASK [add-build-sshkey : Add back temp key] 2025-06-09 11:45:59.522147 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/2bc1161f2e3e4558b91d27d3a1c8b3ce/work/2bc1161f2e3e4558b91d27d3a1c8b3ce_id_rsa (zuul-build-sshkey) 2025-06-09 11:45:59.522476 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.023577 2025-06-09 11:45:59.537602 | 2025-06-09 11:45:59.537765 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-06-09 11:45:59.988466 | debian-bookworm | ok 2025-06-09 11:46:00.000062 | 2025-06-09 11:46:00.000269 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-06-09 11:46:00.026035 | debian-bookworm | skipping: Conditional result was False 2025-06-09 11:46:00.070134 | 2025-06-09 11:46:00.070351 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-06-09 11:46:00.715079 | debian-bookworm | ok 2025-06-09 11:46:00.768596 | 2025-06-09 11:46:00.768752 | TASK [validate-host : Define zuul_info_dir fact] 2025-06-09 11:46:00.856223 | debian-bookworm | ok 2025-06-09 11:46:00.861945 | 2025-06-09 11:46:00.862081 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-06-09 11:46:01.643376 | debian-bookworm -> localhost | ok 2025-06-09 11:46:01.652467 | 2025-06-09 11:46:01.652643 | TASK [validate-host : Collect information about the host] 2025-06-09 11:46:02.840117 | debian-bookworm | ok 2025-06-09 11:46:02.868259 | 2025-06-09 11:46:02.868362 | TASK [validate-host : Sanitize hostname] 2025-06-09 11:46:03.045951 | debian-bookworm | ok 2025-06-09 11:46:03.152172 | 2025-06-09 11:46:03.152284 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-06-09 11:46:04.041363 | debian-bookworm -> localhost | changed 2025-06-09 11:46:04.046692 | 2025-06-09 11:46:04.046798 | TASK [validate-host : Collect information about zuul worker] 2025-06-09 11:46:04.624513 | debian-bookworm | ok 2025-06-09 11:46:04.632142 | 2025-06-09 11:46:04.632239 | TASK [validate-host : Write out all zuul information for each host] 2025-06-09 11:46:05.329923 | debian-bookworm -> localhost | changed 2025-06-09 11:46:05.338688 | 2025-06-09 11:46:05.338788 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-06-09 11:46:05.640774 | debian-bookworm | ok 2025-06-09 11:46:05.649165 | 2025-06-09 11:46:05.649262 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-06-09 11:46:06.642743 | debian-bookworm | changed: 2025-06-09 11:46:06.643027 | debian-bookworm | cd+++++++++ src/ 2025-06-09 11:46:06.643066 | debian-bookworm | cd+++++++++ src/github.com/ 2025-06-09 11:46:06.643092 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-06-09 11:46:06.643115 | debian-bookworm | cd+++++++++ src/github.com/osism/cfg-cookiecutter/ 2025-06-09 11:46:06.643139 | debian-bookworm | Containerfile 2025-06-09 11:46:06.643277 | debian-bookworm | localhost | ok: Runtime: 0:00:00.010931 2025-06-09 11:46:32.164147 | 2025-06-09 11:46:32.164273 | TASK [upload-logs : Upload logs to log server] 2025-06-09 11:46:32.756036 | localhost | Output suppressed because no_log was given 2025-06-09 11:46:32.758267 | 2025-06-09 11:46:32.758407 | LOOP [upload-logs : Compress console log and json output] 2025-06-09 11:46:32.823226 | localhost | skipping: Conditional result was False 2025-06-09 11:46:32.830441 | localhost | skipping: Conditional result was False 2025-06-09 11:46:32.841197 | 2025-06-09 11:46:32.841320 | LOOP [upload-logs : Upload compressed console log and json output] 2025-06-09 11:46:32.887306 | localhost | skipping: Conditional result was False 2025-06-09 11:46:32.887840 | 2025-06-09 11:46:32.892402 | localhost | skipping: Conditional result was False 2025-06-09 11:46:32.904640 | 2025-06-09 11:46:32.904885 | LOOP [upload-logs : Upload console log and json output]