2026-10-01 17:24:39.793861 | Job console starting 2026-10-01 17:24:39.806693 | Updating git repos 2026-10-01 17:24:39.892001 | Cloning repos into workspace 2026-10-01 17:24:39.967125 | Restoring repo states 2026-10-01 17:24:39.986196 | Merging changes 2026-10-01 17:24:41.002547 | Checking out repos 2026-10-01 17:24:41.150323 | Preparing playbooks 2026-10-01 17:24:43.177750 | Running Ansible setup 2026-10-01 17:24:49.938000 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-10-01 17:24:51.515410 | 2026-10-01 17:24:51.515529 | PLAY [Base pre] 2026-10-01 17:24:51.560376 | 2026-10-01 17:24:51.560547 | TASK [Setup log path fact] 2026-10-01 17:24:51.597847 | debian-bookworm | ok 2026-10-01 17:24:51.655425 | 2026-10-01 17:24:51.655573 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-10-01 17:24:51.754319 | debian-bookworm | ok 2026-10-01 17:24:51.762654 | 2026-10-01 17:24:51.762744 | TASK [emit-job-header : Print job information] 2026-10-01 17:24:51.814686 | # Job Information 2026-10-01 17:24:51.814815 | Ansible Version: 2.16.14 2026-10-01 17:24:51.814844 | Job: python-black 2026-10-01 17:24:51.814872 | Pipeline: check 2026-10-01 17:24:51.814891 | Executor: 521e9411259a 2026-10-01 17:24:51.814908 | Triggered by: https://github.com/osism/python-osism/pull/2742 2026-10-01 17:24:51.814927 | Event ID: f46f6b40-bdbc-11f1-90a9-ba3d93abbcd4 2026-10-01 17:24:51.828965 | 2026-10-01 17:24:51.829062 | LOOP [emit-job-header : Print node information] 2026-10-01 17:24:52.049282 | debian-bookworm | ok: 2026-10-01 17:24:52.049465 | debian-bookworm | # Node Information 2026-10-01 17:24:52.049497 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-10-01 17:24:52.049539 | debian-bookworm | Hostname: debian 2026-10-01 17:24:52.049592 | debian-bookworm | Username: zuul 2026-10-01 17:24:52.049610 | debian-bookworm | Distro: Debian 12.15 2026-10-01 17:24:52.049635 | debian-bookworm | Provider: regiocloud-a 2026-10-01 17:24:52.049657 | debian-bookworm | Region: 2026-10-01 17:24:52.049675 | debian-bookworm | Label: debian-bookworm 2026-10-01 17:24:52.049691 | debian-bookworm | Product Name: OpenStack Nova 2026-10-01 17:24:52.049707 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe01:a99b 2026-10-01 17:24:52.062384 | 2026-10-01 17:24:52.062475 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-10-01 17:24:52.827086 | debian-bookworm -> localhost | changed 2026-10-01 17:24:52.833909 | 2026-10-01 17:24:52.834002 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-10-01 17:24:54.579338 | debian-bookworm -> localhost | changed 2026-10-01 17:24:54.588183 | 2026-10-01 17:24:54.588270 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-10-01 17:24:55.367775 | debian-bookworm -> localhost | ok 2026-10-01 17:24:55.372693 | 2026-10-01 17:24:55.372774 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-10-01 17:24:55.410572 | debian-bookworm | ok 2026-10-01 17:24:55.437953 | debian-bookworm | included: /var/lib/zuul/builds/d7cd930e67984c60ba0effc044e7e4f7/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-10-01 17:24:55.456809 | 2026-10-01 17:24:55.456905 | TASK [add-build-sshkey : Create Temp SSH key] 2026-10-01 17:24:57.376252 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-10-01 17:24:57.376478 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/d7cd930e67984c60ba0effc044e7e4f7/work/d7cd930e67984c60ba0effc044e7e4f7_id_rsa 2026-10-01 17:24:57.376534 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/d7cd930e67984c60ba0effc044e7e4f7/work/d7cd930e67984c60ba0effc044e7e4f7_id_rsa.pub 2026-10-01 17:24:57.376560 | debian-bookworm -> localhost | The key fingerprint is: 2026-10-01 17:24:57.376582 | debian-bookworm -> localhost | SHA256:p16CoObyV1v3BfcjWGMO9k1Mes6U8vKS91YcMOfnrVQ zuul-build-sshkey 2026-10-01 17:24:57.376601 | debian-bookworm -> localhost | The key's randomart image is: 2026-10-01 17:24:57.376625 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-10-01 17:24:57.376644 | debian-bookworm -> localhost | | | 2026-10-01 17:24:57.376661 | debian-bookworm -> localhost | | o o | 2026-10-01 17:24:57.376678 | debian-bookworm -> localhost | | O .| 2026-10-01 17:24:57.376694 | debian-bookworm -> localhost | | o.*.OE| 2026-10-01 17:24:57.376711 | debian-bookworm -> localhost | | . S o Bo@=+| 2026-10-01 17:24:57.376734 | debian-bookworm -> localhost | | . ....o.. =o**| 2026-10-01 17:24:57.376751 | debian-bookworm -> localhost | | o ..oo... o=.o| 2026-10-01 17:24:57.376766 | debian-bookworm -> localhost | |.o . .. o .o.o.| 2026-10-01 17:24:57.376783 | debian-bookworm -> localhost | | oo. . o.o| 2026-10-01 17:24:57.376800 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-10-01 17:24:57.376846 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.994119 2026-10-01 17:24:57.381914 | 2026-10-01 17:24:57.381995 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-10-01 17:24:57.398313 | debian-bookworm | ok 2026-10-01 17:24:57.421042 | debian-bookworm | included: /var/lib/zuul/builds/d7cd930e67984c60ba0effc044e7e4f7/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-10-01 17:24:57.427571 | 2026-10-01 17:24:57.427641 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-10-01 17:24:57.460152 | debian-bookworm | skipping: Conditional result was False 2026-10-01 17:24:57.464736 | 2026-10-01 17:24:57.464818 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-10-01 17:24:58.179445 | debian-bookworm | changed 2026-10-01 17:24:58.193495 | 2026-10-01 17:24:58.195099 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-10-01 17:24:58.472530 | debian-bookworm | ok 2026-10-01 17:24:58.478661 | 2026-10-01 17:24:58.478772 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-10-01 17:24:59.191303 | debian-bookworm | changed 2026-10-01 17:24:59.196638 | 2026-10-01 17:24:59.196727 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-10-01 17:24:59.979112 | debian-bookworm | changed 2026-10-01 17:25:00.001738 | 2026-10-01 17:25:00.001848 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-10-01 17:25:00.059195 | debian-bookworm | skipping: Conditional result was False 2026-10-01 17:25:00.091269 | 2026-10-01 17:25:00.091387 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-10-01 17:25:01.166753 | debian-bookworm -> localhost | changed 2026-10-01 17:25:01.175220 | 2026-10-01 17:25:01.175306 | TASK [add-build-sshkey : Add back temp key] 2026-10-01 17:25:01.873699 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/d7cd930e67984c60ba0effc044e7e4f7/work/d7cd930e67984c60ba0effc044e7e4f7_id_rsa (zuul-build-sshkey) 2026-10-01 17:25:01.873877 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.030335 2026-10-01 17:25:01.879950 | 2026-10-01 17:25:01.880029 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-10-01 17:25:02.330886 | debian-bookworm | ok 2026-10-01 17:25:02.335412 | 2026-10-01 17:25:02.335494 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-10-01 17:25:02.380830 | debian-bookworm | skipping: Conditional result was False 2026-10-01 17:25:02.391083 | 2026-10-01 17:25:02.391209 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-10-01 17:25:02.940664 | debian-bookworm | ok 2026-10-01 17:25:02.956461 | 2026-10-01 17:25:02.956575 | TASK [validate-host : Define zuul_info_dir fact] 2026-10-01 17:25:03.007427 | debian-bookworm | ok 2026-10-01 17:25:03.011606 | 2026-10-01 17:25:03.011712 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-10-01 17:25:03.321001 | debian-bookworm -> localhost | ok 2026-10-01 17:25:03.326315 | 2026-10-01 17:25:03.326398 | TASK [validate-host : Collect information about the host] 2026-10-01 17:25:04.610250 | debian-bookworm | ok 2026-10-01 17:25:04.621923 | 2026-10-01 17:25:04.622008 | TASK [validate-host : Sanitize hostname] 2026-10-01 17:25:04.764139 | debian-bookworm | ok 2026-10-01 17:25:04.774386 | 2026-10-01 17:25:04.774472 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-10-01 17:25:05.838788 | debian-bookworm -> localhost | changed 2026-10-01 17:25:05.844659 | 2026-10-01 17:25:05.844749 | TASK [validate-host : Collect information about zuul worker] 2026-10-01 17:25:06.378960 | debian-bookworm | ok 2026-10-01 17:25:06.382781 | 2026-10-01 17:25:06.382857 | TASK [validate-host : Write out all zuul information for each host] 2026-10-01 17:25:07.422679 | debian-bookworm -> localhost | changed 2026-10-01 17:25:07.430157 | 2026-10-01 17:25:07.430241 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-10-01 17:25:07.750083 | debian-bookworm | ok 2026-10-01 17:25:07.754716 | 2026-10-01 17:25:07.754794 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-10-01 17:25:10.422056 | debian-bookworm | changed: 2026-10-01 17:25:10.422209 | debian-bookworm | cd+++++++++ src/ 2026-10-01 17:25:10.422238 | debian-bookworm | cd+++++++++ src/github.com/ 2026-10-01 17:25:10.422257 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-10-01 17:25:10.422274 | debian-bookworm | cd+++++++++ src/github.com/osism/python-osism/ 2026-10-01 17:25:10.422291 | debian-bookworm | Containerfile 2026-10-01 17:25:10.422410 | debian-bookworm | Containerfile 2026-10-01 17:25:10.434139 | debian-bookworm | localhost | ok: "/var/lib/zuul/builds/d7cd930e67984c60ba0effc044e7e4f7/work/logs" 2026-10-01 17:26:30.581046 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/d7cd930e67984c60ba0effc044e7e4f7/work/artifacts" 2026-10-01 17:26:30.870325 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/d7cd930e67984c60ba0effc044e7e4f7/work/docs" 2026-10-01 17:26:30.885076 | 2026-10-01 17:26:30.885206 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-10-01 17:26:31.681718 | debian-bookworm | changed: 2026-10-01 17:26:31.682039 | debian-bookworm | .d..t...... ./ 2026-10-01 17:26:31.682089 | debian-bookworm | >f+++++++++ net-debug.post.txt 2026-10-01 17:26:31.682114 | debian-bookworm | >f+++++++++ net-debug.pre.txt 2026-10-01 17:26:31.682136 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-10-01 17:26:31.682156 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-10-01 17:26:31.682175 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-10-01 17:26:31.682214 | debian-bookworm | changed: All items complete 2026-10-01 17:26:31.682236 | 2026-10-01 17:26:32.226326 | debian-bookworm | changed: .d..t...... ./ 2026-10-01 17:26:32.840588 | debian-bookworm | changed: .d..t...... ./ 2026-10-01 17:26:32.861012 | 2026-10-01 17:26:32.861141 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-10-01 17:26:33.445719 | debian-bookworm -> localhost | ok: Item: artifacts Runtime: 0:00:00.023790 2026-10-01 17:26:33.774405 | debian-bookworm -> localhost | ok: Item: docs Runtime: 0:00:00.009864 2026-10-01 17:26:33.782967 | 2026-10-01 17:26:33.783060 | PLAY RECAP 2026-10-01 17:26:33.783112 | debian-bookworm | ok: 8 changed: 3 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-10-01 17:26:33.783137 | 2026-10-01 17:26:33.934410 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-10-01 17:26:33.938304 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-10-01 17:26:34.794084 | 2026-10-01 17:26:34.794257 | PLAY [Base post] 2026-10-01 17:26:34.808403 | 2026-10-01 17:26:34.808589 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-10-01 17:26:35.452034 | debian-bookworm | changed 2026-10-01 17:26:35.462401 | 2026-10-01 17:26:35.462590 | PLAY RECAP 2026-10-01 17:26:35.462669 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-10-01 17:26:35.462742 | 2026-10-01 17:26:35.614112 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-10-01 17:26:35.616315 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-10-01 17:26:36.532215 | 2026-10-01 17:26:36.532386 | PLAY [Base post-logs] 2026-10-01 17:26:36.543748 | 2026-10-01 17:26:36.543892 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-10-01 17:26:37.084993 | localhost | changed 2026-10-01 17:26:37.097130 | 2026-10-01 17:26:37.097280 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-10-01 17:26:37.128249 | localhost | ok 2026-10-01 17:26:37.140535 | 2026-10-01 17:26:37.140663 | TASK [Set zuul-log-path fact] 2026-10-01 17:26:37.156073 | localhost | ok 2026-10-01 17:26:37.199208 | 2026-10-01 17:26:37.199352 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-10-01 17:26:37.230018 | localhost | ok 2026-10-01 17:26:37.233332 | 2026-10-01 17:26:37.233437 | TASK [upload-logs : Create log directories] 2026-10-01 17:26:37.762173 | localhost | changed 2026-10-01 17:26:37.765199 | 2026-10-01 17:26:37.766490 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-10-01 17:26:38.366539 | localhost -> localhost | ok: Runtime: 0:00:00.005676 2026-10-01 17:26:38.370616 | 2026-10-01 17:26:38.370738 | TASK [upload-logs : Upload logs to log server] 2026-10-01 17:26:38.982926 | localhost | Output suppressed because no_log was given 2026-10-01 17:26:38.984810 | 2026-10-01 17:26:38.984918 | LOOP [upload-logs : Compress console log and json output] 2026-10-01 17:26:39.089092 | localhost | skipping: Conditional result was False 2026-10-01 17:26:39.105992 | localhost | skipping: Conditional result was False 2026-10-01 17:26:39.118145 | 2026-10-01 17:26:39.118281 | LOOP [upload-logs : Upload compressed console log and json output] 2026-10-01 17:26:39.217922 | localhost | skipping: Conditional result was False 2026-10-01 17:26:39.218213 | 2026-10-01 17:26:39.233843 | localhost | skipping: Conditional result was False 2026-10-01 17:26:39.244864 | 2026-10-01 17:26:39.245501 | LOOP [upload-logs : Upload console log and json output]