2026-05-11 05:08:56.848494 | Job console starting 2026-05-11 05:08:56.873621 | Updating git repos 2026-05-11 05:08:56.952653 | Cloning repos into workspace 2026-05-11 05:08:57.098595 | Restoring repo states 2026-05-11 05:08:57.113870 | Merging changes 2026-05-11 05:08:57.906112 | Checking out repos 2026-05-11 05:08:57.974579 | Preparing playbooks 2026-05-11 05:08:59.507730 | Running Ansible setup 2026-05-11 05:09:07.291528 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-05-11 05:09:08.515507 | 2026-05-11 05:09:08.515630 | PLAY [Base pre] 2026-05-11 05:09:08.547686 | 2026-05-11 05:09:08.547808 | TASK [Setup log path fact] 2026-05-11 05:09:08.575220 | debian-bookworm | ok 2026-05-11 05:09:08.598202 | 2026-05-11 05:09:08.598341 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-11 05:09:08.642092 | debian-bookworm | ok 2026-05-11 05:09:08.658045 | 2026-05-11 05:09:08.658144 | TASK [emit-job-header : Print job information] 2026-05-11 05:09:08.718373 | # Job Information 2026-05-11 05:09:08.718526 | Ansible Version: 2.16.14 2026-05-11 05:09:08.718556 | Job: python-black 2026-05-11 05:09:08.718583 | Pipeline: check 2026-05-11 05:09:08.718602 | Executor: 521e9411259a 2026-05-11 05:09:08.718619 | Triggered by: https://github.com/osism/openstack-image-manager/pull/944 2026-05-11 05:09:08.718636 | Event ID: 7d15a680-4cf7-11f1-8130-cd8ff56e0e11 2026-05-11 05:09:08.723300 | 2026-05-11 05:09:08.723417 | LOOP [emit-job-header : Print node information] 2026-05-11 05:09:08.955524 | debian-bookworm | ok: 2026-05-11 05:09:08.956209 | debian-bookworm | # Node Information 2026-05-11 05:09:08.956250 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-05-11 05:09:08.956273 | debian-bookworm | Hostname: debian 2026-05-11 05:09:08.956292 | debian-bookworm | Username: zuul 2026-05-11 05:09:08.956322 | debian-bookworm | Distro: Debian 12.13 2026-05-11 05:09:08.956342 | debian-bookworm | Provider: regiocloud-a 2026-05-11 05:09:08.956361 | debian-bookworm | Region: 2026-05-11 05:09:08.956378 | debian-bookworm | Label: debian-bookworm 2026-05-11 05:09:08.956395 | debian-bookworm | Product Name: OpenStack Nova 2026-05-11 05:09:08.956411 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fedd:f3ce 2026-05-11 05:09:08.974796 | 2026-05-11 05:09:08.974914 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-05-11 05:09:09.869424 | debian-bookworm -> localhost | changed 2026-05-11 05:09:09.875223 | 2026-05-11 05:09:09.875339 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-05-11 05:09:11.555596 | debian-bookworm -> localhost | changed 2026-05-11 05:09:11.564677 | 2026-05-11 05:09:11.564827 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-05-11 05:09:12.166359 | debian-bookworm -> localhost | ok 2026-05-11 05:09:12.171625 | 2026-05-11 05:09:12.171716 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-05-11 05:09:12.221011 | debian-bookworm | ok 2026-05-11 05:09:12.240592 | debian-bookworm | included: /var/lib/zuul/builds/cc4c0dd0e6d649c3a31c75e11ffe6035/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-05-11 05:09:12.248890 | 2026-05-11 05:09:12.248984 | TASK [add-build-sshkey : Create Temp SSH key] 2026-05-11 05:09:13.478058 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-05-11 05:09:13.478277 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/cc4c0dd0e6d649c3a31c75e11ffe6035/work/cc4c0dd0e6d649c3a31c75e11ffe6035_id_rsa 2026-05-11 05:09:13.478328 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/cc4c0dd0e6d649c3a31c75e11ffe6035/work/cc4c0dd0e6d649c3a31c75e11ffe6035_id_rsa.pub 2026-05-11 05:09:13.478355 | debian-bookworm -> localhost | The key fingerprint is: 2026-05-11 05:09:13.478378 | debian-bookworm -> localhost | SHA256:5RCZiuMPc+MCwkJD4PTFPdveVNRYUIIkHo0b/WwHvuw zuul-build-sshkey 2026-05-11 05:09:13.478400 | debian-bookworm -> localhost | The key's randomart image is: 2026-05-11 05:09:13.478432 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-05-11 05:09:13.478453 | debian-bookworm -> localhost | |o. ... .==.o+*o | 2026-05-11 05:09:13.478474 | debian-bookworm -> localhost | |o.. .. o++o+ +.. | 2026-05-11 05:09:13.478494 | debian-bookworm -> localhost | |.. . . .=.+ = . | 2026-05-11 05:09:13.478513 | debian-bookworm -> localhost | | o o .. * . = . | 2026-05-11 05:09:13.478532 | debian-bookworm -> localhost | |o .. . S + o o | 2026-05-11 05:09:13.478556 | debian-bookworm -> localhost | |o.. + o . . o | 2026-05-11 05:09:13.478576 | debian-bookworm -> localhost | |.. . * . . | 2026-05-11 05:09:13.478595 | debian-bookworm -> localhost | | . o E | 2026-05-11 05:09:13.478615 | debian-bookworm -> localhost | | . | 2026-05-11 05:09:13.478635 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-05-11 05:09:13.478687 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.397301 2026-05-11 05:09:13.484721 | 2026-05-11 05:09:13.484814 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-05-11 05:09:13.513535 | debian-bookworm | ok 2026-05-11 05:09:13.532406 | debian-bookworm | included: /var/lib/zuul/builds/cc4c0dd0e6d649c3a31c75e11ffe6035/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-05-11 05:09:13.566728 | 2026-05-11 05:09:13.566861 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-05-11 05:09:13.607253 | debian-bookworm | skipping: Conditional result was False 2026-05-11 05:09:13.613138 | 2026-05-11 05:09:13.613250 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-05-11 05:09:14.520249 | debian-bookworm | changed 2026-05-11 05:09:14.525619 | 2026-05-11 05:09:14.525714 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-05-11 05:09:14.787594 | debian-bookworm | ok 2026-05-11 05:09:14.793163 | 2026-05-11 05:09:14.793264 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-05-11 05:09:15.418996 | debian-bookworm | changed 2026-05-11 05:09:15.431490 | 2026-05-11 05:09:15.431609 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-05-11 05:09:16.051725 | debian-bookworm | changed 2026-05-11 05:09:16.060615 | 2026-05-11 05:09:16.060726 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-05-11 05:09:16.104124 | debian-bookworm | skipping: Conditional result was False 2026-05-11 05:09:16.111586 | 2026-05-11 05:09:16.111714 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-05-11 05:09:17.083221 | debian-bookworm -> localhost | changed 2026-05-11 05:09:17.115498 | 2026-05-11 05:09:17.115614 | TASK [add-build-sshkey : Add back temp key] 2026-05-11 05:09:17.682250 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/cc4c0dd0e6d649c3a31c75e11ffe6035/work/cc4c0dd0e6d649c3a31c75e11ffe6035_id_rsa (zuul-build-sshkey) 2026-05-11 05:09:17.682470 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.043041 2026-05-11 05:09:17.690039 | 2026-05-11 05:09:17.690137 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-05-11 05:09:18.165714 | debian-bookworm | ok 2026-05-11 05:09:18.176394 | 2026-05-11 05:09:18.176508 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-05-11 05:09:18.219702 | debian-bookworm | skipping: Conditional result was False 2026-05-11 05:09:18.239041 | 2026-05-11 05:09:18.239161 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-05-11 05:09:18.876205 | debian-bookworm | ok 2026-05-11 05:09:18.890508 | 2026-05-11 05:09:18.890623 | TASK [validate-host : Define zuul_info_dir fact] 2026-05-11 05:09:18.954794 | debian-bookworm | ok 2026-05-11 05:09:18.960207 | 2026-05-11 05:09:18.960325 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-05-11 05:09:19.557138 | debian-bookworm -> localhost | ok 2026-05-11 05:09:19.566572 | 2026-05-11 05:09:19.566677 | TASK [validate-host : Collect information about the host] 2026-05-11 05:09:20.657199 | debian-bookworm | ok 2026-05-11 05:09:20.671625 | 2026-05-11 05:09:20.671728 | TASK [validate-host : Sanitize hostname] 2026-05-11 05:09:20.796771 | debian-bookworm | ok 2026-05-11 05:09:20.856695 | 2026-05-11 05:09:20.856817 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-05-11 05:09:21.761147 | debian-bookworm -> localhost | changed 2026-05-11 05:09:21.765830 | 2026-05-11 05:09:21.765915 | TASK [validate-host : Collect information about zuul worker] 2026-05-11 05:09:22.137464 | debian-bookworm | ok 2026-05-11 05:09:22.142460 | 2026-05-11 05:09:22.142569 | TASK [validate-host : Write out all zuul information for each host] 2026-05-11 05:09:22.880399 | debian-bookworm -> localhost | changed 2026-05-11 05:09:22.889554 | 2026-05-11 05:09:22.889702 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-05-11 05:09:23.166331 | debian-bookworm | ok 2026-05-11 05:09:23.173373 | 2026-05-11 05:09:23.173478 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-05-11 05:09:24.542615 | debian-bookworm | changed: 2026-05-11 05:09:24.542790 | debian-bookworm | cd+++++++++ src/ 2026-05-11 05:09:24.542824 | debian-bookworm | cd+++++++++ src/github.com/ 2026-05-11 05:09:24.543108 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-05-11 05:09:24.543133 | debian-bookworm | cd+++++++++ src/github.com/osism/openstack-image-manager/ 2026-05-11 05:09:24.543155 | debian-bookworm | localhost | ok: "/var/lib/zuul/builds/cc4c0dd0e6d649c3a31c75e11ffe6035/work/logs" 2026-05-11 05:09:50.827578 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/cc4c0dd0e6d649c3a31c75e11ffe6035/work/artifacts" 2026-05-11 05:09:51.168598 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/cc4c0dd0e6d649c3a31c75e11ffe6035/work/docs" 2026-05-11 05:09:51.184682 | 2026-05-11 05:09:51.184906 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-05-11 05:09:52.137913 | debian-bookworm | changed: 2026-05-11 05:09:52.141391 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-05-11 05:09:52.141492 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-05-11 05:09:52.141540 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-05-11 05:09:52.141601 | debian-bookworm | changed: All items complete 2026-05-11 05:09:52.141628 | 2026-05-11 05:09:52.829694 | debian-bookworm | changed: .d..t...... ./ 2026-05-11 05:09:53.548916 | debian-bookworm | changed: .d..t...... ./ 2026-05-11 05:09:53.580255 | 2026-05-11 05:09:53.580433 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-05-11 05:09:54.783846 | debian-bookworm -> localhost | ok: Item: artifacts Runtime: 0:00:00.022924 2026-05-11 05:09:55.185988 | debian-bookworm -> localhost | ok: Item: docs Runtime: 0:00:00.024425 2026-05-11 05:09:55.199788 | 2026-05-11 05:09:55.199912 | PLAY RECAP 2026-05-11 05:09:55.199968 | debian-bookworm | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-05-11 05:09:55.200145 | 2026-05-11 05:09:55.458144 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-05-11 05:09:55.459077 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-11 05:09:56.979086 | 2026-05-11 05:09:56.979223 | PLAY [Base post] 2026-05-11 05:09:57.014326 | 2026-05-11 05:09:57.014466 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-05-11 05:09:57.662902 | debian-bookworm | changed 2026-05-11 05:09:57.670229 | 2026-05-11 05:09:57.670345 | PLAY RECAP 2026-05-11 05:09:57.670409 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-05-11 05:09:57.670472 | 2026-05-11 05:09:57.870257 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-11 05:09:57.871036 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-05-11 05:09:58.571674 | 2026-05-11 05:09:58.571801 | PLAY [Base post-logs] 2026-05-11 05:09:58.587531 | 2026-05-11 05:09:58.587655 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-05-11 05:09:59.132529 | localhost | changed 2026-05-11 05:09:59.140178 | 2026-05-11 05:09:59.140312 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-05-11 05:09:59.168018 | localhost | ok 2026-05-11 05:09:59.175997 | 2026-05-11 05:09:59.176205 | TASK [Set zuul-log-path fact] 2026-05-11 05:09:59.193439 | localhost | ok 2026-05-11 05:09:59.216100 | 2026-05-11 05:09:59.216225 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-11 05:09:59.242102 | localhost | ok 2026-05-11 05:09:59.245740 | 2026-05-11 05:09:59.245839 | TASK [upload-logs : Create log directories] 2026-05-11 05:09:59.729951 | localhost | changed 2026-05-11 05:09:59.732554 | 2026-05-11 05:09:59.732647 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-05-11 05:10:00.177726 | localhost -> localhost | ok: Runtime: 0:00:00.006624 2026-05-11 05:10:00.188871 | 2026-05-11 05:10:00.189081 | TASK [upload-logs : Upload logs to log server] 2026-05-11 05:10:00.851946 | localhost | Output suppressed because no_log was given 2026-05-11 05:10:00.853887 | 2026-05-11 05:10:00.853988 | LOOP [upload-logs : Compress console log and json output] 2026-05-11 05:10:00.949718 | localhost | skipping: Conditional result was False 2026-05-11 05:10:00.966242 | localhost | skipping: Conditional result was False 2026-05-11 05:10:00.972966 | 2026-05-11 05:10:00.973127 | LOOP [upload-logs : Upload compressed console log and json output] 2026-05-11 05:10:01.050492 | localhost | skipping: Conditional result was False 2026-05-11 05:10:01.050726 | 2026-05-11 05:10:01.061731 | localhost | skipping: Conditional result was False 2026-05-11 05:10:01.074124 | 2026-05-11 05:10:01.074757 | LOOP [upload-logs : Upload console log and json output]