2026-05-05 08:48:09.521380 | Job console starting 2026-05-05 08:48:09.545549 | Updating git repos 2026-05-05 08:48:09.590621 | Cloning repos into workspace 2026-05-05 08:48:09.654092 | Restoring repo states 2026-05-05 08:48:09.670516 | Merging changes 2026-05-05 08:48:10.335727 | Checking out repos 2026-05-05 08:48:10.437811 | Preparing playbooks 2026-05-05 08:48:11.551145 | Running Ansible setup 2026-05-05 08:48:16.773651 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-05-05 08:48:17.955261 | 2026-05-05 08:48:17.955401 | PLAY [Base pre] 2026-05-05 08:48:17.973624 | 2026-05-05 08:48:17.973758 | TASK [Setup log path fact] 2026-05-05 08:48:18.002432 | debian-bookworm | ok 2026-05-05 08:48:18.018722 | 2026-05-05 08:48:18.018897 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-05 08:48:18.067810 | debian-bookworm | ok 2026-05-05 08:48:18.096130 | 2026-05-05 08:48:18.096242 | TASK [emit-job-header : Print job information] 2026-05-05 08:48:18.135273 | # Job Information 2026-05-05 08:48:18.135441 | Ansible Version: 2.16.14 2026-05-05 08:48:18.135476 | Job: python-black 2026-05-05 08:48:18.135509 | Pipeline: check 2026-05-05 08:48:18.135533 | Executor: 521e9411259a 2026-05-05 08:48:18.135553 | Triggered by: https://github.com/osism/testbed/pull/2886 2026-05-05 08:48:18.135602 | Event ID: d35590a0-485b-11f1-869e-db316e890eba 2026-05-05 08:48:18.140631 | 2026-05-05 08:48:18.140741 | LOOP [emit-job-header : Print node information] 2026-05-05 08:48:18.244742 | debian-bookworm | ok: 2026-05-05 08:48:18.244910 | debian-bookworm | # Node Information 2026-05-05 08:48:18.244945 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-05-05 08:48:18.244970 | debian-bookworm | Hostname: debian 2026-05-05 08:48:18.244993 | debian-bookworm | Username: zuul 2026-05-05 08:48:18.245014 | debian-bookworm | Distro: Debian 12.13 2026-05-05 08:48:18.245038 | debian-bookworm | Provider: regiocloud-a 2026-05-05 08:48:18.245060 | debian-bookworm | Region: 2026-05-05 08:48:18.245081 | debian-bookworm | Label: debian-bookworm 2026-05-05 08:48:18.245101 | debian-bookworm | Product Name: OpenStack Nova 2026-05-05 08:48:18.245121 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe82:6207 2026-05-05 08:48:18.261286 | 2026-05-05 08:48:18.261401 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-05-05 08:48:18.857988 | debian-bookworm -> localhost | changed 2026-05-05 08:48:18.866731 | 2026-05-05 08:48:18.866893 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-05-05 08:48:21.968706 | debian-bookworm -> localhost | changed 2026-05-05 08:48:21.982591 | 2026-05-05 08:48:21.982701 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-05-05 08:48:22.888310 | debian-bookworm -> localhost | ok 2026-05-05 08:48:22.894377 | 2026-05-05 08:48:22.894477 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-05-05 08:48:22.934908 | debian-bookworm | ok 2026-05-05 08:48:22.969364 | debian-bookworm | included: /var/lib/zuul/builds/f24e96f60eb04f9c809e070df2020a08/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-05-05 08:48:22.978551 | 2026-05-05 08:48:22.978651 | TASK [add-build-sshkey : Create Temp SSH key] 2026-05-05 08:48:25.074169 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-05-05 08:48:25.074344 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/f24e96f60eb04f9c809e070df2020a08/work/f24e96f60eb04f9c809e070df2020a08_id_rsa 2026-05-05 08:48:25.074374 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/f24e96f60eb04f9c809e070df2020a08/work/f24e96f60eb04f9c809e070df2020a08_id_rsa.pub 2026-05-05 08:48:25.074396 | debian-bookworm -> localhost | The key fingerprint is: 2026-05-05 08:48:25.074416 | debian-bookworm -> localhost | SHA256:6NNRPTVQkmUWOcblTEE4jTi48F7x3o8twvQNWLlMhns zuul-build-sshkey 2026-05-05 08:48:25.074434 | debian-bookworm -> localhost | The key's randomart image is: 2026-05-05 08:48:25.074461 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-05-05 08:48:25.074480 | debian-bookworm -> localhost | | . +*&*+| 2026-05-05 08:48:25.074498 | debian-bookworm -> localhost | | . . =.O=* | 2026-05-05 08:48:25.074516 | debian-bookworm -> localhost | | o o *o.oo| 2026-05-05 08:48:25.074545 | debian-bookworm -> localhost | | . + ..o= | 2026-05-05 08:48:25.074562 | debian-bookworm -> localhost | | . S . .B.. | 2026-05-05 08:48:25.074579 | debian-bookworm -> localhost | | . . o +.E. | 2026-05-05 08:48:25.074596 | debian-bookworm -> localhost | | o . o o =.| 2026-05-05 08:48:25.074613 | debian-bookworm -> localhost | | . o + +| 2026-05-05 08:48:25.074631 | debian-bookworm -> localhost | | . . | 2026-05-05 08:48:25.074648 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-05-05 08:48:25.074693 | debian-bookworm -> localhost | ok: Runtime: 0:00:01.101994 2026-05-05 08:48:25.079975 | 2026-05-05 08:48:25.080058 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-05-05 08:48:25.116825 | debian-bookworm | ok 2026-05-05 08:48:25.125490 | debian-bookworm | included: /var/lib/zuul/builds/f24e96f60eb04f9c809e070df2020a08/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-05-05 08:48:25.144223 | 2026-05-05 08:48:25.144315 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-05-05 08:48:25.187586 | debian-bookworm | skipping: Conditional result was False 2026-05-05 08:48:25.193713 | 2026-05-05 08:48:25.193831 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-05-05 08:48:26.092563 | debian-bookworm | changed 2026-05-05 08:48:26.097457 | 2026-05-05 08:48:26.097583 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-05-05 08:48:26.372813 | debian-bookworm | ok 2026-05-05 08:48:26.377442 | 2026-05-05 08:48:26.377543 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-05-05 08:48:27.091973 | debian-bookworm | changed 2026-05-05 08:48:27.097467 | 2026-05-05 08:48:27.097573 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-05-05 08:48:27.848025 | debian-bookworm | changed 2026-05-05 08:48:27.852554 | 2026-05-05 08:48:27.852642 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-05-05 08:48:27.877929 | debian-bookworm | skipping: Conditional result was False 2026-05-05 08:48:27.883667 | 2026-05-05 08:48:27.883762 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-05-05 08:48:28.748674 | debian-bookworm -> localhost | changed 2026-05-05 08:48:28.757321 | 2026-05-05 08:48:28.757409 | TASK [add-build-sshkey : Add back temp key] 2026-05-05 08:48:29.260986 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/f24e96f60eb04f9c809e070df2020a08/work/f24e96f60eb04f9c809e070df2020a08_id_rsa (zuul-build-sshkey) 2026-05-05 08:48:29.261660 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.026430 2026-05-05 08:48:29.281899 | 2026-05-05 08:48:29.282030 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-05-05 08:48:29.665036 | debian-bookworm | ok 2026-05-05 08:48:29.677012 | 2026-05-05 08:48:29.677145 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-05-05 08:48:29.711828 | debian-bookworm | skipping: Conditional result was False 2026-05-05 08:48:29.723005 | 2026-05-05 08:48:29.723140 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-05-05 08:48:30.317036 | debian-bookworm | ok 2026-05-05 08:48:30.354157 | 2026-05-05 08:48:30.354284 | TASK [validate-host : Define zuul_info_dir fact] 2026-05-05 08:48:30.396961 | debian-bookworm | ok 2026-05-05 08:48:30.402508 | 2026-05-05 08:48:30.402609 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-05-05 08:48:31.147209 | debian-bookworm -> localhost | ok 2026-05-05 08:48:31.160729 | 2026-05-05 08:48:31.160838 | TASK [validate-host : Collect information about the host] 2026-05-05 08:48:32.535460 | debian-bookworm | ok 2026-05-05 08:48:32.550631 | 2026-05-05 08:48:32.550736 | TASK [validate-host : Sanitize hostname] 2026-05-05 08:48:32.770189 | debian-bookworm | ok 2026-05-05 08:48:32.872813 | 2026-05-05 08:48:32.872916 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-05-05 08:48:34.004000 | debian-bookworm -> localhost | changed 2026-05-05 08:48:34.008798 | 2026-05-05 08:48:34.008895 | TASK [validate-host : Collect information about zuul worker] 2026-05-05 08:48:34.484236 | debian-bookworm | ok 2026-05-05 08:48:34.488039 | 2026-05-05 08:48:34.488121 | TASK [validate-host : Write out all zuul information for each host] 2026-05-05 08:48:35.479041 | debian-bookworm -> localhost | changed 2026-05-05 08:48:35.487535 | 2026-05-05 08:48:35.487626 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-05-05 08:48:35.756898 | debian-bookworm | ok 2026-05-05 08:48:35.761279 | 2026-05-05 08:48:35.761373 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-05-05 08:48:36.952785 | debian-bookworm | changed: 2026-05-05 08:48:36.952945 | debian-bookworm | cd+++++++++ src/ 2026-05-05 08:48:36.952974 | debian-bookworm | cd+++++++++ src/github.com/ 2026-05-05 08:48:36.952995 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-05-05 08:48:36.953014 | debian-bookworm | cd+++++++++ src/github.com/osism/testbed/ 2026-05-05 08:48:36.953031 | debian-bookworm | localhost | ok: "/var/lib/zuul/builds/f24e96f60eb04f9c809e070df2020a08/work/logs" 2026-05-05 08:49:03.896900 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/f24e96f60eb04f9c809e070df2020a08/work/artifacts" 2026-05-05 08:49:04.169843 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/f24e96f60eb04f9c809e070df2020a08/work/docs" 2026-05-05 08:49:04.182261 | 2026-05-05 08:49:04.182447 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-05-05 08:49:05.457189 | debian-bookworm | changed: 2026-05-05 08:49:05.457499 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-05-05 08:49:05.457541 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-05-05 08:49:05.457571 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-05-05 08:49:05.457623 | debian-bookworm | changed: All items complete 2026-05-05 08:49:05.457651 | 2026-05-05 08:49:06.083529 | debian-bookworm | changed: .d..t...... ./ 2026-05-05 08:49:06.731755 | debian-bookworm | changed: .d..t...... ./ 2026-05-05 08:49:06.762775 | 2026-05-05 08:49:06.762948 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-05-05 08:49:07.583635 | debian-bookworm -> localhost | ok: Item: artifacts Runtime: 0:00:00.014168 2026-05-05 08:49:08.064070 | debian-bookworm -> localhost | ok: Item: docs Runtime: 0:00:00.024265 2026-05-05 08:49:08.091647 | 2026-05-05 08:49:08.091836 | PLAY RECAP 2026-05-05 08:49:08.091926 | debian-bookworm | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-05-05 08:49:08.092073 | 2026-05-05 08:49:08.265723 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-05-05 08:49:08.268012 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-05 08:49:09.879039 | 2026-05-05 08:49:09.879166 | PLAY [Base post] 2026-05-05 08:49:09.910764 | 2026-05-05 08:49:09.910917 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-05-05 08:49:10.570589 | debian-bookworm | changed 2026-05-05 08:49:10.590390 | 2026-05-05 08:49:10.590977 | PLAY RECAP 2026-05-05 08:49:10.591048 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-05-05 08:49:10.591110 | 2026-05-05 08:49:10.848749 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-05 08:49:10.850279 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-05-05 08:49:11.810789 | 2026-05-05 08:49:11.810930 | PLAY [Base post-logs] 2026-05-05 08:49:11.828243 | 2026-05-05 08:49:11.828392 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-05-05 08:49:12.248221 | localhost | changed 2026-05-05 08:49:12.257855 | 2026-05-05 08:49:12.257991 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-05-05 08:49:12.282392 | localhost | ok 2026-05-05 08:49:12.285605 | 2026-05-05 08:49:12.285694 | TASK [Set zuul-log-path fact] 2026-05-05 08:49:12.302197 | localhost | ok 2026-05-05 08:49:12.318717 | 2026-05-05 08:49:12.318889 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-05 08:49:12.345908 | localhost | ok 2026-05-05 08:49:12.349862 | 2026-05-05 08:49:12.349970 | TASK [upload-logs : Create log directories] 2026-05-05 08:49:12.947117 | localhost | changed 2026-05-05 08:49:12.949657 | 2026-05-05 08:49:12.949741 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-05-05 08:49:13.529503 | localhost -> localhost | ok: Runtime: 0:00:00.017587 2026-05-05 08:49:13.533021 | 2026-05-05 08:49:13.533113 | TASK [upload-logs : Upload logs to log server] 2026-05-05 08:49:14.245816 | localhost | Output suppressed because no_log was given 2026-05-05 08:49:14.247805 | 2026-05-05 08:49:14.247897 | LOOP [upload-logs : Compress console log and json output] 2026-05-05 08:49:14.296052 | localhost | skipping: Conditional result was False 2026-05-05 08:49:14.308255 | localhost | skipping: Conditional result was False 2026-05-05 08:49:14.313558 | 2026-05-05 08:49:14.313664 | LOOP [upload-logs : Upload compressed console log and json output] 2026-05-05 08:49:14.358224 | localhost | skipping: Conditional result was False 2026-05-05 08:49:14.358477 | 2026-05-05 08:49:14.365354 | localhost | skipping: Conditional result was False 2026-05-05 08:49:14.369500 | 2026-05-05 08:49:14.369617 | LOOP [upload-logs : Upload console log and json output]