2026-05-11 05:14:16.148031 | Job console starting 2026-05-11 05:14:16.168978 | Updating git repos 2026-05-11 05:14:16.226121 | Cloning repos into workspace 2026-05-11 05:14:16.338658 | Restoring repo states 2026-05-11 05:14:16.354272 | Merging changes 2026-05-11 05:14:17.143555 | Checking out repos 2026-05-11 05:14:17.261604 | Preparing playbooks 2026-05-11 05:14:19.098487 | Running Ansible setup 2026-05-11 05:14:26.596168 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-05-11 05:14:28.246859 | 2026-05-11 05:14:28.246982 | PLAY [Base pre] 2026-05-11 05:14:28.281496 | 2026-05-11 05:14:28.281612 | TASK [Setup log path fact] 2026-05-11 05:14:28.298256 | debian-bookworm | ok 2026-05-11 05:14:28.313036 | 2026-05-11 05:14:28.313145 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-11 05:14:28.340811 | debian-bookworm | ok 2026-05-11 05:14:28.349380 | 2026-05-11 05:14:28.349478 | TASK [emit-job-header : Print job information] 2026-05-11 05:14:28.407961 | # Job Information 2026-05-11 05:14:28.408154 | Ansible Version: 2.16.14 2026-05-11 05:14:28.408184 | Job: python-black 2026-05-11 05:14:28.408235 | Pipeline: check 2026-05-11 05:14:28.408257 | Executor: 521e9411259a 2026-05-11 05:14:28.408275 | Triggered by: https://github.com/osism/cfg-cookiecutter/pull/832 2026-05-11 05:14:28.408294 | Event ID: 3bd87d40-4cf8-11f1-9605-115ccc8a5fd1 2026-05-11 05:14:28.412568 | 2026-05-11 05:14:28.412663 | LOOP [emit-job-header : Print node information] 2026-05-11 05:14:28.566648 | debian-bookworm | ok: 2026-05-11 05:14:28.566881 | debian-bookworm | # Node Information 2026-05-11 05:14:28.566917 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-05-11 05:14:28.566939 | debian-bookworm | Hostname: debian 2026-05-11 05:14:28.566958 | debian-bookworm | Username: zuul 2026-05-11 05:14:28.566975 | debian-bookworm | Distro: Debian 12.13 2026-05-11 05:14:28.566998 | debian-bookworm | Provider: regiocloud-a 2026-05-11 05:14:28.567017 | debian-bookworm | Region: 2026-05-11 05:14:28.567035 | debian-bookworm | Label: debian-bookworm 2026-05-11 05:14:28.567051 | debian-bookworm | Product Name: OpenStack Nova 2026-05-11 05:14:28.567068 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe52:d57 2026-05-11 05:14:28.587603 | 2026-05-11 05:14:28.587711 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-05-11 05:14:29.727294 | debian-bookworm -> localhost | changed 2026-05-11 05:14:29.733672 | 2026-05-11 05:14:29.733767 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-05-11 05:14:32.115303 | debian-bookworm -> localhost | changed 2026-05-11 05:14:32.125577 | 2026-05-11 05:14:32.125690 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-05-11 05:14:32.659157 | debian-bookworm -> localhost | ok 2026-05-11 05:14:32.685503 | 2026-05-11 05:14:32.685628 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-05-11 05:14:32.724350 | debian-bookworm | ok 2026-05-11 05:14:32.758337 | debian-bookworm | included: /var/lib/zuul/builds/f860486718dd411c8a5534027609a63c/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-05-11 05:14:32.770524 | 2026-05-11 05:14:32.770610 | TASK [add-build-sshkey : Create Temp SSH key] 2026-05-11 05:14:35.955465 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-05-11 05:14:35.955645 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/f860486718dd411c8a5534027609a63c/work/f860486718dd411c8a5534027609a63c_id_rsa 2026-05-11 05:14:35.955676 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/f860486718dd411c8a5534027609a63c/work/f860486718dd411c8a5534027609a63c_id_rsa.pub 2026-05-11 05:14:35.955697 | debian-bookworm -> localhost | The key fingerprint is: 2026-05-11 05:14:35.955717 | debian-bookworm -> localhost | SHA256:9lr75/icnqvHbg51GnR8ArhM19KPwkEEwCOtZAySHtQ zuul-build-sshkey 2026-05-11 05:14:35.955735 | debian-bookworm -> localhost | The key's randomart image is: 2026-05-11 05:14:35.955763 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-05-11 05:14:35.955782 | debian-bookworm -> localhost | | .oo.o o...=+o | 2026-05-11 05:14:35.955800 | debian-bookworm -> localhost | | o.E = + o +.o. | 2026-05-11 05:14:35.955817 | debian-bookworm -> localhost | | . . o o + + ooo+| 2026-05-11 05:14:35.955834 | debian-bookworm -> localhost | | . . o o..oo| 2026-05-11 05:14:35.955851 | debian-bookworm -> localhost | | S .o .| 2026-05-11 05:14:35.955868 | debian-bookworm -> localhost | | . . . + | 2026-05-11 05:14:35.955885 | debian-bookworm -> localhost | | o ... | 2026-05-11 05:14:35.955901 | debian-bookworm -> localhost | | o . +=o | 2026-05-11 05:14:35.955919 | debian-bookworm -> localhost | | . ..+@@. | 2026-05-11 05:14:35.955935 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-05-11 05:14:35.955982 | debian-bookworm -> localhost | ok: Runtime: 0:00:02.161748 2026-05-11 05:14:35.961419 | 2026-05-11 05:14:35.961514 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-05-11 05:14:35.978291 | debian-bookworm | ok 2026-05-11 05:14:35.987046 | debian-bookworm | included: /var/lib/zuul/builds/f860486718dd411c8a5534027609a63c/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-05-11 05:14:36.007540 | 2026-05-11 05:14:36.007655 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-05-11 05:14:36.040907 | debian-bookworm | skipping: Conditional result was False 2026-05-11 05:14:36.046026 | 2026-05-11 05:14:36.046125 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-05-11 05:14:36.741349 | debian-bookworm | changed 2026-05-11 05:14:36.753551 | 2026-05-11 05:14:36.753682 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-05-11 05:14:37.019049 | debian-bookworm | ok 2026-05-11 05:14:37.023598 | 2026-05-11 05:14:37.023691 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-05-11 05:14:37.689257 | debian-bookworm | changed 2026-05-11 05:14:37.700987 | 2026-05-11 05:14:37.701091 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-05-11 05:14:38.528088 | debian-bookworm | changed 2026-05-11 05:14:38.532790 | 2026-05-11 05:14:38.532879 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-05-11 05:14:38.572617 | debian-bookworm | skipping: Conditional result was False 2026-05-11 05:14:38.577658 | 2026-05-11 05:14:38.577749 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-05-11 05:14:39.389570 | debian-bookworm -> localhost | changed 2026-05-11 05:14:39.400556 | 2026-05-11 05:14:39.400678 | TASK [add-build-sshkey : Add back temp key] 2026-05-11 05:14:40.131083 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/f860486718dd411c8a5534027609a63c/work/f860486718dd411c8a5534027609a63c_id_rsa (zuul-build-sshkey) 2026-05-11 05:14:40.131315 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.025690 2026-05-11 05:14:40.144817 | 2026-05-11 05:14:40.145014 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-05-11 05:14:40.772270 | debian-bookworm | ok 2026-05-11 05:14:40.783966 | 2026-05-11 05:14:40.784084 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-05-11 05:14:40.817242 | debian-bookworm | skipping: Conditional result was False 2026-05-11 05:14:40.827613 | 2026-05-11 05:14:40.827726 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-05-11 05:14:41.485259 | debian-bookworm | ok 2026-05-11 05:14:41.506104 | 2026-05-11 05:14:41.506254 | TASK [validate-host : Define zuul_info_dir fact] 2026-05-11 05:14:41.534035 | debian-bookworm | ok 2026-05-11 05:14:41.539165 | 2026-05-11 05:14:41.539296 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-05-11 05:14:41.825796 | debian-bookworm -> localhost | ok 2026-05-11 05:14:41.832264 | 2026-05-11 05:14:41.832369 | TASK [validate-host : Collect information about the host] 2026-05-11 05:14:42.945061 | debian-bookworm | ok 2026-05-11 05:14:42.956504 | 2026-05-11 05:14:42.956595 | TASK [validate-host : Sanitize hostname] 2026-05-11 05:14:43.118384 | debian-bookworm | ok 2026-05-11 05:14:43.174822 | 2026-05-11 05:14:43.174935 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-05-11 05:14:44.143194 | debian-bookworm -> localhost | changed 2026-05-11 05:14:44.148777 | 2026-05-11 05:14:44.148877 | TASK [validate-host : Collect information about zuul worker] 2026-05-11 05:14:44.597506 | debian-bookworm | ok 2026-05-11 05:14:44.602090 | 2026-05-11 05:14:44.602193 | TASK [validate-host : Write out all zuul information for each host] 2026-05-11 05:14:45.661009 | debian-bookworm -> localhost | changed 2026-05-11 05:14:45.677529 | 2026-05-11 05:14:45.677657 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-05-11 05:14:45.977981 | debian-bookworm | ok 2026-05-11 05:14:45.984237 | 2026-05-11 05:14:45.984329 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-05-11 05:14:46.988009 | debian-bookworm | changed: 2026-05-11 05:14:46.988163 | debian-bookworm | cd+++++++++ src/ 2026-05-11 05:14:46.988190 | debian-bookworm | cd+++++++++ src/github.com/ 2026-05-11 05:14:46.988236 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-05-11 05:14:46.988254 | debian-bookworm | cd+++++++++ src/github.com/osism/cfg-cookiecutter/ 2026-05-11 05:14:46.988271 | debian-bookworm | Containerfile 2026-05-11 05:14:46.988380 | debian-bookworm | localhost | ok: "/var/lib/zuul/builds/f860486718dd411c8a5534027609a63c/work/logs" 2026-05-11 05:15:14.817886 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/f860486718dd411c8a5534027609a63c/work/artifacts" 2026-05-11 05:15:15.137690 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/f860486718dd411c8a5534027609a63c/work/docs" 2026-05-11 05:15:15.155849 | 2026-05-11 05:15:15.156006 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-05-11 05:15:15.970776 | debian-bookworm | changed: 2026-05-11 05:15:15.971204 | debian-bookworm | .d..t...... ./ 2026-05-11 05:15:15.971259 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-05-11 05:15:15.971291 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-05-11 05:15:15.971322 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-05-11 05:15:15.971370 | debian-bookworm | changed: All items complete 2026-05-11 05:15:15.971394 | 2026-05-11 05:15:16.628487 | debian-bookworm | changed: .d..t...... ./ 2026-05-11 05:15:17.532555 | debian-bookworm | changed: .d..t...... ./ 2026-05-11 05:15:17.556988 | 2026-05-11 05:15:17.557142 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-05-11 05:15:18.472714 | debian-bookworm -> localhost | ok: Item: artifacts Runtime: 0:00:00.007011 2026-05-11 05:15:18.976358 | debian-bookworm -> localhost | ok: Item: docs Runtime: 0:00:00.017615 2026-05-11 05:15:18.996813 | 2026-05-11 05:15:18.996935 | PLAY RECAP 2026-05-11 05:15:18.996990 | debian-bookworm | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-05-11 05:15:18.997017 | 2026-05-11 05:15:19.155914 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-05-11 05:15:19.156808 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-11 05:15:20.019590 | 2026-05-11 05:15:20.019788 | PLAY [Base post] 2026-05-11 05:15:20.034800 | 2026-05-11 05:15:20.034988 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-05-11 05:15:20.670630 | debian-bookworm | changed 2026-05-11 05:15:20.678008 | 2026-05-11 05:15:20.678983 | PLAY RECAP 2026-05-11 05:15:20.679081 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-05-11 05:15:20.679145 | 2026-05-11 05:15:20.853994 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-11 05:15:20.855025 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-05-11 05:15:21.834728 | 2026-05-11 05:15:21.834945 | PLAY [Base post-logs] 2026-05-11 05:15:21.846543 | 2026-05-11 05:15:21.846707 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-05-11 05:15:22.387960 | localhost | changed 2026-05-11 05:15:22.398629 | 2026-05-11 05:15:22.398892 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-05-11 05:15:22.435892 | localhost | ok 2026-05-11 05:15:22.439255 | 2026-05-11 05:15:22.439374 | TASK [Set zuul-log-path fact] 2026-05-11 05:15:22.456475 | localhost | ok 2026-05-11 05:15:22.467719 | 2026-05-11 05:15:22.467903 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-11 05:15:22.495292 | localhost | ok 2026-05-11 05:15:22.500071 | 2026-05-11 05:15:22.500249 | TASK [upload-logs : Create log directories] 2026-05-11 05:15:23.062671 | localhost | changed 2026-05-11 05:15:23.065763 | 2026-05-11 05:15:23.065944 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-05-11 05:15:23.654362 | localhost -> localhost | ok: Runtime: 0:00:00.012670 2026-05-11 05:15:23.662892 | 2026-05-11 05:15:23.663089 | TASK [upload-logs : Upload logs to log server] 2026-05-11 05:15:24.256047 | localhost | Output suppressed because no_log was given 2026-05-11 05:15:24.258371 | 2026-05-11 05:15:24.258498 | LOOP [upload-logs : Compress console log and json output] 2026-05-11 05:15:24.319393 | localhost | skipping: Conditional result was False 2026-05-11 05:15:24.327036 | localhost | skipping: Conditional result was False 2026-05-11 05:15:24.338413 | 2026-05-11 05:15:24.338564 | LOOP [upload-logs : Upload compressed console log and json output] 2026-05-11 05:15:24.405548 | localhost | skipping: Conditional result was False 2026-05-11 05:15:24.406327 | 2026-05-11 05:15:24.415764 | localhost | skipping: Conditional result was False 2026-05-11 05:15:24.420255 | 2026-05-11 05:15:24.420389 | LOOP [upload-logs : Upload console log and json output]