2026-05-09 09:50:23.984263 | Job console starting 2026-05-09 09:50:24.013316 | Updating git repos 2026-05-09 09:50:24.093239 | Cloning repos into workspace 2026-05-09 09:50:24.269496 | Restoring repo states 2026-05-09 09:50:24.320920 | Merging changes 2026-05-09 09:50:25.311145 | Checking out repos 2026-05-09 09:50:25.406366 | Preparing playbooks 2026-05-09 09:50:27.871856 | Running Ansible setup 2026-05-09 09:50:37.006807 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-05-09 09:50:38.738495 | 2026-05-09 09:50:38.738648 | PLAY [Base pre] 2026-05-09 09:50:38.759384 | 2026-05-09 09:50:38.759564 | TASK [Setup log path fact] 2026-05-09 09:50:38.779271 | debian-bookworm | ok 2026-05-09 09:50:38.796506 | 2026-05-09 09:50:38.796651 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-09 09:50:38.847852 | debian-bookworm | ok 2026-05-09 09:50:38.861644 | 2026-05-09 09:50:38.861771 | TASK [emit-job-header : Print job information] 2026-05-09 09:50:38.962283 | # Job Information 2026-05-09 09:50:38.962464 | Ansible Version: 2.16.14 2026-05-09 09:50:38.962499 | Job: python-black 2026-05-09 09:50:38.962530 | Pipeline: check 2026-05-09 09:50:38.962552 | Executor: 521e9411259a 2026-05-09 09:50:38.962573 | Triggered by: https://github.com/osism/ansible-collection-services/pull/1964 2026-05-09 09:50:38.962596 | Event ID: 457f7620-4b89-11f1-95e6-cf7291b0a5e7 2026-05-09 09:50:38.984749 | 2026-05-09 09:50:38.984894 | LOOP [emit-job-header : Print node information] 2026-05-09 09:50:39.164140 | debian-bookworm | ok: 2026-05-09 09:50:39.164328 | debian-bookworm | # Node Information 2026-05-09 09:50:39.164362 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-05-09 09:50:39.164387 | debian-bookworm | Hostname: debian 2026-05-09 09:50:39.164409 | debian-bookworm | Username: zuul 2026-05-09 09:50:39.164430 | debian-bookworm | Distro: Debian 12.13 2026-05-09 09:50:39.164454 | debian-bookworm | Provider: regiocloud-a 2026-05-09 09:50:39.164475 | debian-bookworm | Region: 2026-05-09 09:50:39.164496 | debian-bookworm | Label: debian-bookworm 2026-05-09 09:50:39.164516 | debian-bookworm | Product Name: OpenStack Nova 2026-05-09 09:50:39.164535 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe3b:bc6d 2026-05-09 09:50:39.191897 | 2026-05-09 09:50:39.192059 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-05-09 09:50:40.289512 | debian-bookworm -> localhost | changed 2026-05-09 09:50:40.297635 | 2026-05-09 09:50:40.297765 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-05-09 09:50:42.965347 | debian-bookworm -> localhost | changed 2026-05-09 09:50:42.984002 | 2026-05-09 09:50:42.984108 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-05-09 09:50:43.780943 | debian-bookworm -> localhost | ok 2026-05-09 09:50:43.787424 | 2026-05-09 09:50:43.787558 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-05-09 09:50:43.828625 | debian-bookworm | ok 2026-05-09 09:50:43.862982 | debian-bookworm | included: /var/lib/zuul/builds/b5539f5032ad4f589b2b92e3888fe414/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-05-09 09:50:43.870751 | 2026-05-09 09:50:43.870882 | TASK [add-build-sshkey : Create Temp SSH key] 2026-05-09 09:50:45.401496 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-05-09 09:50:45.401695 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/b5539f5032ad4f589b2b92e3888fe414/work/b5539f5032ad4f589b2b92e3888fe414_id_rsa 2026-05-09 09:50:45.401726 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/b5539f5032ad4f589b2b92e3888fe414/work/b5539f5032ad4f589b2b92e3888fe414_id_rsa.pub 2026-05-09 09:50:45.401748 | debian-bookworm -> localhost | The key fingerprint is: 2026-05-09 09:50:45.401768 | debian-bookworm -> localhost | SHA256:Z4mIDQ+RWceWQnxFtUuyWV1BO+ZweIPKfzdtcryPMvU zuul-build-sshkey 2026-05-09 09:50:45.401786 | debian-bookworm -> localhost | The key's randomart image is: 2026-05-09 09:50:45.401812 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-05-09 09:50:45.401830 | debian-bookworm -> localhost | | .*o..+o.. .oo| 2026-05-09 09:50:45.401849 | debian-bookworm -> localhost | | o.o.= oo..| 2026-05-09 09:50:45.401866 | debian-bookworm -> localhost | | o + . ++.B | 2026-05-09 09:50:45.401883 | debian-bookworm -> localhost | | * . ..B..* o| 2026-05-09 09:50:45.401899 | debian-bookworm -> localhost | | . + S *o. . | 2026-05-09 09:50:45.401920 | debian-bookworm -> localhost | | o . ...| 2026-05-09 09:50:45.401947 | debian-bookworm -> localhost | | o.+*| 2026-05-09 09:50:45.401964 | debian-bookworm -> localhost | | o .=E| 2026-05-09 09:50:45.401981 | debian-bookworm -> localhost | | o..o| 2026-05-09 09:50:45.401998 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-05-09 09:50:45.402042 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.665595 2026-05-09 09:50:45.407520 | 2026-05-09 09:50:45.407623 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-05-09 09:50:45.425085 | debian-bookworm | ok 2026-05-09 09:50:45.433846 | debian-bookworm | included: /var/lib/zuul/builds/b5539f5032ad4f589b2b92e3888fe414/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-05-09 09:50:45.454369 | 2026-05-09 09:50:45.454488 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-05-09 09:50:45.468996 | debian-bookworm | skipping: Conditional result was False 2026-05-09 09:50:45.475868 | 2026-05-09 09:50:45.476018 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-05-09 09:50:46.374707 | debian-bookworm | changed 2026-05-09 09:50:46.384921 | 2026-05-09 09:50:46.385081 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-05-09 09:50:46.784132 | debian-bookworm | ok 2026-05-09 09:50:46.791134 | 2026-05-09 09:50:46.791256 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-05-09 09:50:47.468115 | debian-bookworm | changed 2026-05-09 09:50:47.483287 | 2026-05-09 09:50:47.483428 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-05-09 09:50:48.217486 | debian-bookworm | changed 2026-05-09 09:50:48.226414 | 2026-05-09 09:50:48.226520 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-05-09 09:50:48.259774 | debian-bookworm | skipping: Conditional result was False 2026-05-09 09:50:48.265166 | 2026-05-09 09:50:48.265263 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-05-09 09:50:48.975428 | debian-bookworm -> localhost | changed 2026-05-09 09:50:48.988394 | 2026-05-09 09:50:48.988522 | TASK [add-build-sshkey : Add back temp key] 2026-05-09 09:50:49.693889 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/b5539f5032ad4f589b2b92e3888fe414/work/b5539f5032ad4f589b2b92e3888fe414_id_rsa (zuul-build-sshkey) 2026-05-09 09:50:49.694091 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.025107 2026-05-09 09:50:49.705831 | 2026-05-09 09:50:49.708279 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-05-09 09:50:50.462996 | debian-bookworm | ok 2026-05-09 09:50:50.467568 | 2026-05-09 09:50:50.467647 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-05-09 09:50:50.553563 | debian-bookworm | skipping: Conditional result was False 2026-05-09 09:50:50.562922 | 2026-05-09 09:50:50.563017 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-05-09 09:50:51.340441 | debian-bookworm | ok 2026-05-09 09:50:51.358358 | 2026-05-09 09:50:51.358459 | TASK [validate-host : Define zuul_info_dir fact] 2026-05-09 09:50:51.426330 | debian-bookworm | ok 2026-05-09 09:50:51.430497 | 2026-05-09 09:50:51.430574 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-05-09 09:50:52.196174 | debian-bookworm -> localhost | ok 2026-05-09 09:50:52.201338 | 2026-05-09 09:50:52.201418 | TASK [validate-host : Collect information about the host] 2026-05-09 09:50:53.747079 | debian-bookworm | ok 2026-05-09 09:50:53.763534 | 2026-05-09 09:50:53.763632 | TASK [validate-host : Sanitize hostname] 2026-05-09 09:50:54.092486 | debian-bookworm | ok 2026-05-09 09:50:54.187620 | 2026-05-09 09:50:54.187733 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-05-09 09:50:55.611598 | debian-bookworm -> localhost | changed 2026-05-09 09:50:55.618189 | 2026-05-09 09:50:55.618302 | TASK [validate-host : Collect information about zuul worker] 2026-05-09 09:50:56.354638 | debian-bookworm | ok 2026-05-09 09:50:56.359346 | 2026-05-09 09:50:56.359442 | TASK [validate-host : Write out all zuul information for each host] 2026-05-09 09:50:58.308004 | debian-bookworm -> localhost | changed 2026-05-09 09:50:58.333782 | 2026-05-09 09:50:58.333963 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-05-09 09:50:58.635308 | debian-bookworm | ok 2026-05-09 09:50:58.640900 | 2026-05-09 09:50:58.641015 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-05-09 09:51:00.858146 | debian-bookworm | changed: 2026-05-09 09:51:00.858315 | debian-bookworm | cd+++++++++ src/ 2026-05-09 09:51:00.858342 | debian-bookworm | cd+++++++++ src/github.com/ 2026-05-09 09:51:00.858361 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-05-09 09:51:00.858379 | debian-bookworm | cd+++++++++ src/github.com/osism/ansible-collection-services/ 2026-05-09 09:51:00.858396 | debian-bookworm | localhost | ok: "/var/lib/zuul/builds/b5539f5032ad4f589b2b92e3888fe414/work/logs" 2026-05-09 09:51:33.819759 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/b5539f5032ad4f589b2b92e3888fe414/work/artifacts" 2026-05-09 09:51:34.375807 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/b5539f5032ad4f589b2b92e3888fe414/work/docs" 2026-05-09 09:51:34.394136 | 2026-05-09 09:51:34.394288 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-05-09 09:51:35.260846 | debian-bookworm | changed: 2026-05-09 09:51:35.263925 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-05-09 09:51:35.264022 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-05-09 09:51:35.264058 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-05-09 09:51:35.264107 | debian-bookworm | changed: All items complete 2026-05-09 09:51:35.264132 | 2026-05-09 09:51:36.015307 | debian-bookworm | changed: .d..t...... ./ 2026-05-09 09:51:36.838738 | debian-bookworm | changed: .d..t...... ./ 2026-05-09 09:51:36.852146 | 2026-05-09 09:51:36.852268 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-05-09 09:51:37.694118 | debian-bookworm -> localhost | ok: Item: artifacts Runtime: 0:00:00.024809 2026-05-09 09:51:38.231207 | debian-bookworm -> localhost | ok: Item: docs Runtime: 0:00:00.037579 2026-05-09 09:51:38.248390 | 2026-05-09 09:51:38.248670 | PLAY RECAP 2026-05-09 09:51:38.248743 | debian-bookworm | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-05-09 09:51:38.248770 | 2026-05-09 09:51:38.378482 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-05-09 09:51:38.379320 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-09 09:51:39.205860 | 2026-05-09 09:51:39.206024 | PLAY [Base post] 2026-05-09 09:51:39.218190 | 2026-05-09 09:51:39.218326 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-05-09 09:51:39.751027 | debian-bookworm | changed 2026-05-09 09:51:39.757213 | 2026-05-09 09:51:39.757319 | PLAY RECAP 2026-05-09 09:51:39.757383 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-05-09 09:51:39.757448 | 2026-05-09 09:51:39.921817 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-09 09:51:39.922603 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-05-09 09:51:40.768030 | 2026-05-09 09:51:40.768184 | PLAY [Base post-logs] 2026-05-09 09:51:40.777087 | 2026-05-09 09:51:40.777220 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-05-09 09:51:41.202051 | localhost | changed 2026-05-09 09:51:41.217811 | 2026-05-09 09:51:41.225262 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-05-09 09:51:41.277016 | localhost | ok 2026-05-09 09:51:41.281175 | 2026-05-09 09:51:41.281293 | TASK [Set zuul-log-path fact] 2026-05-09 09:51:41.309130 | localhost | ok 2026-05-09 09:51:41.319545 | 2026-05-09 09:51:41.319686 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-09 09:51:41.380402 | localhost | ok 2026-05-09 09:51:41.383448 | 2026-05-09 09:51:41.383545 | TASK [upload-logs : Create log directories] 2026-05-09 09:51:42.103332 | localhost | changed 2026-05-09 09:51:42.106244 | 2026-05-09 09:51:42.106346 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-05-09 09:51:42.933635 | localhost -> localhost | ok: Runtime: 0:00:00.005369 2026-05-09 09:51:42.937827 | 2026-05-09 09:51:42.937968 | TASK [upload-logs : Upload logs to log server] 2026-05-09 09:51:43.864415 | localhost | Output suppressed because no_log was given 2026-05-09 09:51:43.866186 | 2026-05-09 09:51:43.866291 | LOOP [upload-logs : Compress console log and json output] 2026-05-09 09:51:43.959642 | localhost | skipping: Conditional result was False 2026-05-09 09:51:43.965330 | localhost | skipping: Conditional result was False 2026-05-09 09:51:43.974134 | 2026-05-09 09:51:43.974267 | LOOP [upload-logs : Upload compressed console log and json output] 2026-05-09 09:51:44.030762 | localhost | skipping: Conditional result was False 2026-05-09 09:51:44.031165 | 2026-05-09 09:51:44.035980 | localhost | skipping: Conditional result was False 2026-05-09 09:51:44.047775 | 2026-05-09 09:51:44.047954 | LOOP [upload-logs : Upload console log and json output]