2026-05-12 11:47:34.649076 | Job console starting 2026-05-12 11:47:34.662348 | Updating git repos 2026-05-12 11:47:34.701156 | Cloning repos into workspace 2026-05-12 11:47:34.786377 | Restoring repo states 2026-05-12 11:47:34.798136 | Merging changes 2026-05-12 11:47:35.481828 | Checking out repos 2026-05-12 11:47:35.550694 | Preparing playbooks 2026-05-12 11:47:36.816018 | Running Ansible setup 2026-05-12 11:47:42.637736 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-05-12 11:47:44.186593 | 2026-05-12 11:47:44.186722 | PLAY [Base pre] 2026-05-12 11:47:44.203201 | 2026-05-12 11:47:44.203319 | TASK [Setup log path fact] 2026-05-12 11:47:44.255876 | debian-bookworm | ok 2026-05-12 11:47:44.285575 | 2026-05-12 11:47:44.285717 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-12 11:47:44.343864 | debian-bookworm | ok 2026-05-12 11:47:44.353638 | 2026-05-12 11:47:44.353744 | TASK [emit-job-header : Print job information] 2026-05-12 11:47:44.391782 | # Job Information 2026-05-12 11:47:44.391936 | Ansible Version: 2.16.14 2026-05-12 11:47:44.391970 | Job: python-black 2026-05-12 11:47:44.392001 | Pipeline: check 2026-05-12 11:47:44.392024 | Executor: 521e9411259a 2026-05-12 11:47:44.392044 | Triggered by: https://github.com/osism/python-osism/pull/1917 2026-05-12 11:47:44.392067 | Event ID: 4508aa60-4df8-11f1-9b01-b9fffac8018a 2026-05-12 11:47:44.402786 | 2026-05-12 11:47:44.402911 | LOOP [emit-job-header : Print node information] 2026-05-12 11:47:44.574300 | debian-bookworm | ok: 2026-05-12 11:47:44.574446 | debian-bookworm | # Node Information 2026-05-12 11:47:44.574477 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-05-12 11:47:44.574498 | debian-bookworm | Hostname: debian 2026-05-12 11:47:44.574515 | debian-bookworm | Username: zuul 2026-05-12 11:47:44.574531 | debian-bookworm | Distro: Debian 12.13 2026-05-12 11:47:44.574550 | debian-bookworm | Provider: regiocloud-a 2026-05-12 11:47:44.574592 | debian-bookworm | Region: 2026-05-12 11:47:44.574611 | debian-bookworm | Label: debian-bookworm 2026-05-12 11:47:44.574627 | debian-bookworm | Product Name: OpenStack Nova 2026-05-12 11:47:44.574643 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fed4:3371 2026-05-12 11:47:44.592086 | 2026-05-12 11:47:44.592188 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-05-12 11:47:45.475935 | debian-bookworm -> localhost | changed 2026-05-12 11:47:45.481967 | 2026-05-12 11:47:45.482057 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-05-12 11:47:47.343659 | debian-bookworm -> localhost | changed 2026-05-12 11:47:47.372793 | 2026-05-12 11:47:47.372911 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-05-12 11:47:47.629005 | debian-bookworm -> localhost | ok 2026-05-12 11:47:47.634875 | 2026-05-12 11:47:47.634974 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-05-12 11:47:47.655129 | debian-bookworm | ok 2026-05-12 11:47:47.679608 | debian-bookworm | included: /var/lib/zuul/builds/e1e352c6223040aab0ac6e39ed4c0e8b/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-05-12 11:47:47.684711 | 2026-05-12 11:47:47.684799 | TASK [add-build-sshkey : Create Temp SSH key] 2026-05-12 11:47:49.688077 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-05-12 11:47:49.688250 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/e1e352c6223040aab0ac6e39ed4c0e8b/work/e1e352c6223040aab0ac6e39ed4c0e8b_id_rsa 2026-05-12 11:47:49.688282 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/e1e352c6223040aab0ac6e39ed4c0e8b/work/e1e352c6223040aab0ac6e39ed4c0e8b_id_rsa.pub 2026-05-12 11:47:49.688304 | debian-bookworm -> localhost | The key fingerprint is: 2026-05-12 11:47:49.688323 | debian-bookworm -> localhost | SHA256:eW6AxTqxSJJ/4hZl9YheBWc/K9B1CtRY1aqB6xXZP5g zuul-build-sshkey 2026-05-12 11:47:49.688342 | debian-bookworm -> localhost | The key's randomart image is: 2026-05-12 11:47:49.688368 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-05-12 11:47:49.688386 | debian-bookworm -> localhost | | oo*+o.o. | 2026-05-12 11:47:49.688404 | debian-bookworm -> localhost | | . + B.+.o .| 2026-05-12 11:47:49.688541 | debian-bookworm -> localhost | | o . = * o.+o . | 2026-05-12 11:47:49.688587 | debian-bookworm -> localhost | | + = B o. +oo | 2026-05-12 11:47:49.688608 | debian-bookworm -> localhost | | = B S o..+o. | 2026-05-12 11:47:49.688644 | debian-bookworm -> localhost | | . + . +..oE ..| 2026-05-12 11:47:49.688720 | debian-bookworm -> localhost | | o .o. .| 2026-05-12 11:47:49.688741 | debian-bookworm -> localhost | | . .. | 2026-05-12 11:47:49.688760 | debian-bookworm -> localhost | | | 2026-05-12 11:47:49.688778 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-05-12 11:47:49.688825 | debian-bookworm -> localhost | ok: Runtime: 0:00:01.076432 2026-05-12 11:47:49.694666 | 2026-05-12 11:47:49.694796 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-05-12 11:47:49.722741 | debian-bookworm | ok 2026-05-12 11:47:49.732643 | debian-bookworm | included: /var/lib/zuul/builds/e1e352c6223040aab0ac6e39ed4c0e8b/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-05-12 11:47:49.756420 | 2026-05-12 11:47:49.756510 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-05-12 11:47:49.874090 | debian-bookworm | skipping: Conditional result was False 2026-05-12 11:47:49.880541 | 2026-05-12 11:47:49.880677 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-05-12 11:47:50.776973 | debian-bookworm | changed 2026-05-12 11:47:50.782180 | 2026-05-12 11:47:50.782277 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-05-12 11:47:51.095076 | debian-bookworm | ok 2026-05-12 11:47:51.100926 | 2026-05-12 11:47:51.101024 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-05-12 11:47:51.834674 | debian-bookworm | changed 2026-05-12 11:47:51.845324 | 2026-05-12 11:47:51.845434 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-05-12 11:47:52.552815 | debian-bookworm | changed 2026-05-12 11:47:52.559663 | 2026-05-12 11:47:52.559779 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-05-12 11:47:52.573937 | debian-bookworm | skipping: Conditional result was False 2026-05-12 11:47:52.582102 | 2026-05-12 11:47:52.582203 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-05-12 11:47:53.538052 | debian-bookworm -> localhost | changed 2026-05-12 11:47:53.561002 | 2026-05-12 11:47:53.561125 | TASK [add-build-sshkey : Add back temp key] 2026-05-12 11:47:54.198604 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/e1e352c6223040aab0ac6e39ed4c0e8b/work/e1e352c6223040aab0ac6e39ed4c0e8b_id_rsa (zuul-build-sshkey) 2026-05-12 11:47:54.198821 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.036181 2026-05-12 11:47:54.206756 | 2026-05-12 11:47:54.206896 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-05-12 11:47:54.768537 | debian-bookworm | ok 2026-05-12 11:47:54.776246 | 2026-05-12 11:47:54.776351 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-05-12 11:47:54.815553 | debian-bookworm | skipping: Conditional result was False 2026-05-12 11:47:54.833679 | 2026-05-12 11:47:54.833791 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-05-12 11:47:55.566137 | debian-bookworm | ok 2026-05-12 11:47:55.588608 | 2026-05-12 11:47:55.588752 | TASK [validate-host : Define zuul_info_dir fact] 2026-05-12 11:47:55.654295 | debian-bookworm | ok 2026-05-12 11:47:55.669986 | 2026-05-12 11:47:55.670104 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-05-12 11:47:56.624850 | debian-bookworm -> localhost | ok 2026-05-12 11:47:56.632002 | 2026-05-12 11:47:56.632106 | TASK [validate-host : Collect information about the host] 2026-05-12 11:47:58.071351 | debian-bookworm | ok 2026-05-12 11:47:58.110760 | 2026-05-12 11:47:58.110894 | TASK [validate-host : Sanitize hostname] 2026-05-12 11:47:58.449339 | debian-bookworm | ok 2026-05-12 11:47:58.641188 | 2026-05-12 11:47:58.641303 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-05-12 11:48:00.774198 | debian-bookworm -> localhost | changed 2026-05-12 11:48:00.779524 | 2026-05-12 11:48:00.779643 | TASK [validate-host : Collect information about zuul worker] 2026-05-12 11:48:01.328750 | debian-bookworm | ok 2026-05-12 11:48:01.332899 | 2026-05-12 11:48:01.332992 | TASK [validate-host : Write out all zuul information for each host] 2026-05-12 11:48:02.605689 | debian-bookworm -> localhost | changed 2026-05-12 11:48:02.613437 | 2026-05-12 11:48:02.613527 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-05-12 11:48:02.955132 | debian-bookworm | ok 2026-05-12 11:48:02.959946 | 2026-05-12 11:48:02.960039 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-05-12 11:48:04.517851 | debian-bookworm | changed: 2026-05-12 11:48:04.518090 | debian-bookworm | cd+++++++++ src/ 2026-05-12 11:48:04.518138 | debian-bookworm | cd+++++++++ src/github.com/ 2026-05-12 11:48:04.518165 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-05-12 11:48:04.518187 | debian-bookworm | cd+++++++++ src/github.com/osism/python-osism/ 2026-05-12 11:48:04.518207 | debian-bookworm | Containerfile 2026-05-12 11:48:04.518336 | debian-bookworm | Containerfile 2026-05-12 11:48:04.629982 | debian-bookworm | localhost | ok: "/var/lib/zuul/builds/e1e352c6223040aab0ac6e39ed4c0e8b/work/logs" 2026-05-12 11:48:41.975787 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/e1e352c6223040aab0ac6e39ed4c0e8b/work/artifacts" 2026-05-12 11:48:42.219471 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/e1e352c6223040aab0ac6e39ed4c0e8b/work/docs" 2026-05-12 11:48:42.235319 | 2026-05-12 11:48:42.235432 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-05-12 11:48:43.183926 | debian-bookworm | changed: 2026-05-12 11:48:43.184094 | debian-bookworm | .d..t...... ./ 2026-05-12 11:48:43.184122 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-05-12 11:48:43.184152 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-05-12 11:48:43.184178 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-05-12 11:48:43.184211 | debian-bookworm | changed: All items complete 2026-05-12 11:48:43.184232 | 2026-05-12 11:48:44.433613 | debian-bookworm | changed: .d..t...... ./ 2026-05-12 11:48:45.053554 | debian-bookworm | changed: .d..t...... ./ 2026-05-12 11:48:45.063651 | 2026-05-12 11:48:45.063747 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-05-12 11:48:46.404469 | debian-bookworm -> localhost | ok: Item: artifacts Runtime: 0:00:00.037809 2026-05-12 11:48:46.975539 | debian-bookworm -> localhost | ok: Item: docs Runtime: 0:00:00.041388 2026-05-12 11:48:46.987462 | 2026-05-12 11:48:46.987686 | PLAY RECAP 2026-05-12 11:48:46.987810 | debian-bookworm | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-05-12 11:48:46.987837 | 2026-05-12 11:48:47.231194 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-05-12 11:48:47.231886 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-12 11:48:48.015696 | 2026-05-12 11:48:48.015813 | PLAY [Base post] 2026-05-12 11:48:48.026460 | 2026-05-12 11:48:48.026566 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-05-12 11:48:48.655746 | debian-bookworm | changed 2026-05-12 11:48:48.661141 | 2026-05-12 11:48:48.661224 | PLAY RECAP 2026-05-12 11:48:48.661275 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-05-12 11:48:48.661323 | 2026-05-12 11:48:48.887962 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-12 11:48:48.888595 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-05-12 11:48:50.222620 | 2026-05-12 11:48:50.222752 | PLAY [Base post-logs] 2026-05-12 11:48:50.264879 | 2026-05-12 11:48:50.264999 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-05-12 11:48:51.377488 | localhost | changed 2026-05-12 11:48:51.387919 | 2026-05-12 11:48:51.388053 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-05-12 11:48:51.436351 | localhost | ok 2026-05-12 11:48:51.438828 | 2026-05-12 11:48:51.438969 | TASK [Set zuul-log-path fact] 2026-05-12 11:48:51.463049 | localhost | ok 2026-05-12 11:48:51.469407 | 2026-05-12 11:48:51.469495 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-12 11:48:51.495721 | localhost | ok 2026-05-12 11:48:51.498161 | 2026-05-12 11:48:51.498238 | TASK [upload-logs : Create log directories] 2026-05-12 11:48:52.455645 | localhost | changed 2026-05-12 11:48:52.457767 | 2026-05-12 11:48:52.457842 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-05-12 11:48:53.587103 | localhost -> localhost | ok: Runtime: 0:00:00.008379 2026-05-12 11:48:53.590180 | 2026-05-12 11:48:53.590267 | TASK [upload-logs : Upload logs to log server] 2026-05-12 11:48:54.456449 | localhost | Output suppressed because no_log was given 2026-05-12 11:48:54.457887 | 2026-05-12 11:48:54.457961 | LOOP [upload-logs : Compress console log and json output] 2026-05-12 11:48:54.538703 | localhost | skipping: Conditional result was False 2026-05-12 11:48:54.555397 | localhost | skipping: Conditional result was False 2026-05-12 11:48:54.564065 | 2026-05-12 11:48:54.564165 | LOOP [upload-logs : Upload compressed console log and json output] 2026-05-12 11:48:54.608121 | localhost | skipping: Conditional result was False 2026-05-12 11:48:54.608374 | 2026-05-12 11:48:54.613586 | localhost | skipping: Conditional result was False 2026-05-12 11:48:54.620442 | 2026-05-12 11:48:54.620525 | LOOP [upload-logs : Upload console log and json output]