2026-05-02 03:13:43.528024 | Job console starting 2026-05-02 03:13:43.538202 | Updating git repos 2026-05-02 03:13:43.578773 | Cloning repos into workspace 2026-05-02 03:13:43.691075 | Restoring repo states 2026-05-02 03:13:43.695918 | Merging changes 2026-05-02 03:13:43.695933 | Checking out repos 2026-05-02 03:13:43.735226 | Preparing playbooks 2026-05-02 03:13:44.600150 | Running Ansible setup 2026-05-02 03:13:49.085267 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-05-02 03:13:49.854896 | 2026-05-02 03:13:49.855037 | PLAY [Base pre] 2026-05-02 03:13:49.871462 | 2026-05-02 03:13:49.871599 | TASK [Setup log path fact] 2026-05-02 03:13:49.889929 | debian-bookworm | ok 2026-05-02 03:13:49.905475 | 2026-05-02 03:13:49.905609 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-02 03:13:49.933611 | debian-bookworm | ok 2026-05-02 03:13:49.943190 | 2026-05-02 03:13:49.943319 | TASK [emit-job-header : Print job information] 2026-05-02 03:13:49.981528 | # Job Information 2026-05-02 03:13:49.981704 | Ansible Version: 2.16.14 2026-05-02 03:13:49.981741 | Job: python-black 2026-05-02 03:13:49.981774 | Pipeline: periodic-daily 2026-05-02 03:13:49.981797 | Executor: 521e9411259a 2026-05-02 03:13:49.981817 | Triggered by: https://github.com/osism/cfg-cookiecutter 2026-05-02 03:13:49.981840 | Event ID: 2581713a607b4dc3a1562c8b4d5df35f 2026-05-02 03:13:49.986924 | 2026-05-02 03:13:49.987032 | LOOP [emit-job-header : Print node information] 2026-05-02 03:13:50.095494 | debian-bookworm | ok: 2026-05-02 03:13:50.095725 | debian-bookworm | # Node Information 2026-05-02 03:13:50.095762 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-05-02 03:13:50.095787 | debian-bookworm | Hostname: debian 2026-05-02 03:13:50.095809 | debian-bookworm | Username: zuul 2026-05-02 03:13:50.095829 | debian-bookworm | Distro: Debian 12.13 2026-05-02 03:13:50.095859 | debian-bookworm | Provider: regiocloud-a 2026-05-02 03:13:50.095884 | debian-bookworm | Region: 2026-05-02 03:13:50.095906 | debian-bookworm | Label: debian-bookworm 2026-05-02 03:13:50.095925 | debian-bookworm | Product Name: OpenStack Nova 2026-05-02 03:13:50.095944 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:febe:effa 2026-05-02 03:13:50.117433 | 2026-05-02 03:13:50.117544 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-05-02 03:13:50.669905 | debian-bookworm -> localhost | changed 2026-05-02 03:13:50.692650 | 2026-05-02 03:13:50.692773 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-05-02 03:13:52.560683 | debian-bookworm -> localhost | changed 2026-05-02 03:13:52.575816 | 2026-05-02 03:13:52.575944 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-05-02 03:13:53.419621 | debian-bookworm -> localhost | ok 2026-05-02 03:13:53.425607 | 2026-05-02 03:13:53.425727 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-05-02 03:13:53.466306 | debian-bookworm | ok 2026-05-02 03:13:53.503974 | debian-bookworm | included: /var/lib/zuul/builds/df79504168fe4977b16bc00372169c12/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-05-02 03:13:53.525651 | 2026-05-02 03:13:53.525790 | TASK [add-build-sshkey : Create Temp SSH key] 2026-05-02 03:13:55.407802 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-05-02 03:13:55.408039 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/df79504168fe4977b16bc00372169c12/work/df79504168fe4977b16bc00372169c12_id_rsa 2026-05-02 03:13:55.408078 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/df79504168fe4977b16bc00372169c12/work/df79504168fe4977b16bc00372169c12_id_rsa.pub 2026-05-02 03:13:55.408105 | debian-bookworm -> localhost | The key fingerprint is: 2026-05-02 03:13:55.408131 | debian-bookworm -> localhost | SHA256:++pjtTzUpqVAQZdk5QwHzkvCTTlqvKaU0eI8DtGgaUs zuul-build-sshkey 2026-05-02 03:13:55.408153 | debian-bookworm -> localhost | The key's randomart image is: 2026-05-02 03:13:55.408184 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-05-02 03:13:55.408207 | debian-bookworm -> localhost | | ...B=o | 2026-05-02 03:13:55.408230 | debian-bookworm -> localhost | | . ..B+= | 2026-05-02 03:13:55.408251 | debian-bookworm -> localhost | | o o ooo=.o | 2026-05-02 03:13:55.408272 | debian-bookworm -> localhost | | E . + *o . | 2026-05-02 03:13:55.408322 | debian-bookworm -> localhost | | o . + *S... | 2026-05-02 03:13:55.408349 | debian-bookworm -> localhost | | . . * +.o + | 2026-05-02 03:13:55.408370 | debian-bookworm -> localhost | | + +.= * | 2026-05-02 03:13:55.408390 | debian-bookworm -> localhost | | o o.* | 2026-05-02 03:13:55.408411 | debian-bookworm -> localhost | | ooo.. | 2026-05-02 03:13:55.408431 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-05-02 03:13:55.408492 | debian-bookworm -> localhost | ok: Runtime: 0:00:01.085550 2026-05-02 03:13:55.415412 | 2026-05-02 03:13:55.415523 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-05-02 03:13:55.468735 | debian-bookworm | ok 2026-05-02 03:13:55.492590 | debian-bookworm | included: /var/lib/zuul/builds/df79504168fe4977b16bc00372169c12/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-05-02 03:13:55.534231 | 2026-05-02 03:13:55.534378 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-05-02 03:13:55.561307 | debian-bookworm | skipping: Conditional result was False 2026-05-02 03:13:55.567463 | 2026-05-02 03:13:55.567563 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-05-02 03:13:56.498268 | debian-bookworm | changed 2026-05-02 03:13:56.507646 | 2026-05-02 03:13:56.507747 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-05-02 03:13:56.834428 | debian-bookworm | ok 2026-05-02 03:13:56.839470 | 2026-05-02 03:13:56.839630 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-05-02 03:13:57.690211 | debian-bookworm | changed 2026-05-02 03:13:57.696312 | 2026-05-02 03:13:57.696397 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-05-02 03:13:58.400189 | debian-bookworm | changed 2026-05-02 03:13:58.404913 | 2026-05-02 03:13:58.405000 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-05-02 03:13:58.433735 | debian-bookworm | skipping: Conditional result was False 2026-05-02 03:13:58.442537 | 2026-05-02 03:13:58.442754 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-05-02 03:13:59.413242 | debian-bookworm -> localhost | changed 2026-05-02 03:13:59.423672 | 2026-05-02 03:13:59.423776 | TASK [add-build-sshkey : Add back temp key] 2026-05-02 03:14:00.154865 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/df79504168fe4977b16bc00372169c12/work/df79504168fe4977b16bc00372169c12_id_rsa (zuul-build-sshkey) 2026-05-02 03:14:00.155077 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.009803 2026-05-02 03:14:00.168893 | 2026-05-02 03:14:00.170933 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-05-02 03:14:00.562812 | debian-bookworm | ok 2026-05-02 03:14:00.567103 | 2026-05-02 03:14:00.567197 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-05-02 03:14:00.589871 | debian-bookworm | skipping: Conditional result was False 2026-05-02 03:14:00.598394 | 2026-05-02 03:14:00.598484 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-05-02 03:14:01.019315 | debian-bookworm | ok 2026-05-02 03:14:01.030132 | 2026-05-02 03:14:01.030231 | TASK [validate-host : Define zuul_info_dir fact] 2026-05-02 03:14:01.099863 | debian-bookworm | ok 2026-05-02 03:14:01.106022 | 2026-05-02 03:14:01.106119 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-05-02 03:14:01.537159 | debian-bookworm -> localhost | ok 2026-05-02 03:14:01.549363 | 2026-05-02 03:14:01.549738 | TASK [validate-host : Collect information about the host] 2026-05-02 03:14:03.046123 | debian-bookworm | ok 2026-05-02 03:14:03.061284 | 2026-05-02 03:14:03.061402 | TASK [validate-host : Sanitize hostname] 2026-05-02 03:14:03.192225 | debian-bookworm | ok 2026-05-02 03:14:03.230252 | 2026-05-02 03:14:03.230382 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-05-02 03:14:03.982623 | debian-bookworm -> localhost | changed 2026-05-02 03:14:03.988498 | 2026-05-02 03:14:03.988608 | TASK [validate-host : Collect information about zuul worker] 2026-05-02 03:14:04.395451 | debian-bookworm | ok 2026-05-02 03:14:04.401073 | 2026-05-02 03:14:04.401189 | TASK [validate-host : Write out all zuul information for each host] 2026-05-02 03:14:05.051910 | debian-bookworm -> localhost | changed 2026-05-02 03:14:05.073032 | 2026-05-02 03:14:05.073131 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-05-02 03:14:05.384527 | debian-bookworm | ok 2026-05-02 03:14:05.389444 | 2026-05-02 03:14:05.389536 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-05-02 03:14:06.568377 | debian-bookworm | changed: 2026-05-02 03:14:06.568562 | debian-bookworm | cd+++++++++ src/ 2026-05-02 03:14:06.568612 | debian-bookworm | cd+++++++++ src/github.com/ 2026-05-02 03:14:06.568638 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-05-02 03:14:06.568661 | debian-bookworm | cd+++++++++ src/github.com/osism/cfg-cookiecutter/ 2026-05-02 03:14:06.568682 | debian-bookworm | Containerfile 2026-05-02 03:14:06.568814 | debian-bookworm | localhost | ok: "/var/lib/zuul/builds/df79504168fe4977b16bc00372169c12/work/logs" 2026-05-02 03:14:33.845858 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/df79504168fe4977b16bc00372169c12/work/artifacts" 2026-05-02 03:14:34.069910 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/df79504168fe4977b16bc00372169c12/work/docs" 2026-05-02 03:14:34.087530 | 2026-05-02 03:14:34.087701 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-05-02 03:14:34.888658 | debian-bookworm | changed: 2026-05-02 03:14:34.889069 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-05-02 03:14:34.889127 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-05-02 03:14:34.889158 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-05-02 03:14:34.889208 | debian-bookworm | changed: All items complete 2026-05-02 03:14:34.889252 | 2026-05-02 03:14:35.416353 | debian-bookworm | changed: .d..t...... ./ 2026-05-02 03:14:35.936044 | debian-bookworm | changed: .d..t...... ./ 2026-05-02 03:14:35.953530 | 2026-05-02 03:14:35.953648 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-05-02 03:14:35.991148 | debian-bookworm | skipping: Conditional result was False 2026-05-02 03:14:35.997888 | debian-bookworm | skipping: Conditional result was False 2026-05-02 03:14:36.009335 | 2026-05-02 03:14:36.009423 | PLAY RECAP 2026-05-02 03:14:36.009474 | debian-bookworm | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0 2026-05-02 03:14:36.009499 | 2026-05-02 03:14:36.130484 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-05-02 03:14:36.131303 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-02 03:14:37.250821 | 2026-05-02 03:14:37.250980 | PLAY [Base post] 2026-05-02 03:14:37.279604 | 2026-05-02 03:14:37.279773 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-05-02 03:14:37.918787 | debian-bookworm | changed 2026-05-02 03:14:37.924808 | 2026-05-02 03:14:37.924894 | PLAY RECAP 2026-05-02 03:14:37.924953 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-05-02 03:14:37.925015 | 2026-05-02 03:14:38.031949 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-02 03:14:38.033198 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-05-02 03:14:38.830088 | 2026-05-02 03:14:38.830252 | PLAY [Base post-logs] 2026-05-02 03:14:38.845257 | 2026-05-02 03:14:38.845398 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-05-02 03:14:39.323320 | localhost | changed 2026-05-02 03:14:39.342735 | 2026-05-02 03:14:39.342944 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-05-02 03:14:39.380511 | localhost | ok 2026-05-02 03:14:39.386367 | 2026-05-02 03:14:39.386511 | TASK [Set zuul-log-path fact] 2026-05-02 03:14:39.404941 | localhost | ok 2026-05-02 03:14:39.415427 | 2026-05-02 03:14:39.415545 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-02 03:14:39.452611 | localhost | ok 2026-05-02 03:14:39.458208 | 2026-05-02 03:14:39.458378 | TASK [upload-logs : Create log directories] 2026-05-02 03:14:40.032142 | localhost | changed 2026-05-02 03:14:40.034712 | 2026-05-02 03:14:40.034801 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-05-02 03:14:40.576272 | localhost -> localhost | ok: Runtime: 0:00:00.005499 2026-05-02 03:14:40.580416 | 2026-05-02 03:14:40.580514 | TASK [upload-logs : Upload logs to log server] 2026-05-02 03:14:41.438620 | localhost | Output suppressed because no_log was given 2026-05-02 03:14:41.451259 | 2026-05-02 03:14:41.451526 | LOOP [upload-logs : Compress console log and json output] 2026-05-02 03:14:41.516648 | localhost | skipping: Conditional result was False 2026-05-02 03:14:41.522801 | localhost | skipping: Conditional result was False 2026-05-02 03:14:41.532962 | 2026-05-02 03:14:41.533078 | LOOP [upload-logs : Upload compressed console log and json output] 2026-05-02 03:14:41.616748 | localhost | skipping: Conditional result was False 2026-05-02 03:14:41.617059 | 2026-05-02 03:14:41.622240 | localhost | skipping: Conditional result was False 2026-05-02 03:14:41.633989 | 2026-05-02 03:14:41.634370 | LOOP [upload-logs : Upload console log and json output]