2026-05-22 11:34:51.890812 | Job console starting 2026-05-22 11:34:51.915014 | Updating git repos 2026-05-22 11:34:51.984950 | Cloning repos into workspace 2026-05-22 11:34:52.145743 | Restoring repo states 2026-05-22 11:34:52.178646 | Merging changes 2026-05-22 11:34:52.860332 | Checking out repos 2026-05-22 11:34:52.962859 | Preparing playbooks 2026-05-22 11:34:54.335154 | Running Ansible setup 2026-05-22 11:34:59.603474 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-05-22 11:35:00.441907 | 2026-05-22 11:35:00.442080 | PLAY [Base pre] 2026-05-22 11:35:00.477479 | 2026-05-22 11:35:00.477658 | TASK [Setup log path fact] 2026-05-22 11:35:00.517511 | debian-bookworm | ok 2026-05-22 11:35:00.563666 | 2026-05-22 11:35:00.563852 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-22 11:35:00.627062 | debian-bookworm | ok 2026-05-22 11:35:00.647621 | 2026-05-22 11:35:00.647775 | TASK [emit-job-header : Print job information] 2026-05-22 11:35:00.722546 | # Job Information 2026-05-22 11:35:00.722865 | Ansible Version: 2.16.14 2026-05-22 11:35:00.722910 | Job: python-black 2026-05-22 11:35:00.722946 | Pipeline: check 2026-05-22 11:35:00.722971 | Executor: 521e9411259a 2026-05-22 11:35:00.722992 | Triggered by: https://github.com/osism/container-image-kolla-ansible/pull/908 2026-05-22 11:35:00.723014 | Event ID: 38ceb500-55d2-11f1-8470-a99ad4bfe5c8 2026-05-22 11:35:00.735200 | 2026-05-22 11:35:00.735395 | LOOP [emit-job-header : Print node information] 2026-05-22 11:35:00.952350 | debian-bookworm | ok: 2026-05-22 11:35:00.952673 | debian-bookworm | # Node Information 2026-05-22 11:35:00.952718 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-05-22 11:35:00.952744 | debian-bookworm | Hostname: debian 2026-05-22 11:35:00.952766 | debian-bookworm | Username: zuul 2026-05-22 11:35:00.952787 | debian-bookworm | Distro: Debian 12.14 2026-05-22 11:35:00.952810 | debian-bookworm | Provider: regiocloud-a 2026-05-22 11:35:00.952831 | debian-bookworm | Region: 2026-05-22 11:35:00.952852 | debian-bookworm | Label: debian-bookworm 2026-05-22 11:35:00.952872 | debian-bookworm | Product Name: OpenStack Nova 2026-05-22 11:35:00.952892 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fefa:89ef 2026-05-22 11:35:00.984516 | 2026-05-22 11:35:00.984677 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-05-22 11:35:02.155266 | debian-bookworm -> localhost | changed 2026-05-22 11:35:02.162207 | 2026-05-22 11:35:02.162348 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-05-22 11:35:03.805991 | debian-bookworm -> localhost | changed 2026-05-22 11:35:03.818306 | 2026-05-22 11:35:03.818419 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-05-22 11:35:04.276032 | debian-bookworm -> localhost | ok 2026-05-22 11:35:04.282313 | 2026-05-22 11:35:04.282424 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-05-22 11:35:04.322147 | debian-bookworm | ok 2026-05-22 11:35:04.348134 | debian-bookworm | included: /var/lib/zuul/builds/b3d941acf24b46a299b3199196a147a3/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-05-22 11:35:04.357565 | 2026-05-22 11:35:04.357672 | TASK [add-build-sshkey : Create Temp SSH key] 2026-05-22 11:35:07.855569 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-05-22 11:35:07.855759 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/b3d941acf24b46a299b3199196a147a3/work/b3d941acf24b46a299b3199196a147a3_id_rsa 2026-05-22 11:35:07.855795 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/b3d941acf24b46a299b3199196a147a3/work/b3d941acf24b46a299b3199196a147a3_id_rsa.pub 2026-05-22 11:35:07.855822 | debian-bookworm -> localhost | The key fingerprint is: 2026-05-22 11:35:07.855846 | debian-bookworm -> localhost | SHA256:2kRq3IiOo0DxexBrV4Sq0tzKEnTd32TqzMyOK9HzfbU zuul-build-sshkey 2026-05-22 11:35:07.855878 | debian-bookworm -> localhost | The key's randomart image is: 2026-05-22 11:35:07.855901 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-05-22 11:35:07.855922 | debian-bookworm -> localhost | | .. | 2026-05-22 11:35:07.855943 | debian-bookworm -> localhost | | .. | 2026-05-22 11:35:07.855964 | debian-bookworm -> localhost | | . .o ... | 2026-05-22 11:35:07.855983 | debian-bookworm -> localhost | | .ooo+.* o | 2026-05-22 11:35:07.856003 | debian-bookworm -> localhost | |.+o*..* S = | 2026-05-22 11:35:07.856028 | debian-bookworm -> localhost | |+.+o=o * o . . | 2026-05-22 11:35:07.856049 | debian-bookworm -> localhost | |oooo..o X . . .| 2026-05-22 11:35:07.856069 | debian-bookworm -> localhost | |o.o... .B . . E | 2026-05-22 11:35:07.856089 | debian-bookworm -> localhost | |.. .oo. . | 2026-05-22 11:35:07.856109 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-05-22 11:35:07.856155 | debian-bookworm -> localhost | ok: Runtime: 0:00:02.608708 2026-05-22 11:35:07.862400 | 2026-05-22 11:35:07.862493 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-05-22 11:35:07.890000 | debian-bookworm | ok 2026-05-22 11:35:07.898687 | debian-bookworm | included: /var/lib/zuul/builds/b3d941acf24b46a299b3199196a147a3/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-05-22 11:35:07.907476 | 2026-05-22 11:35:07.907564 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-05-22 11:35:07.920419 | debian-bookworm | skipping: Conditional result was False 2026-05-22 11:35:07.925848 | 2026-05-22 11:35:07.925934 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-05-22 11:35:08.527383 | debian-bookworm | changed 2026-05-22 11:35:08.535976 | 2026-05-22 11:35:08.536094 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-05-22 11:35:08.784022 | debian-bookworm | ok 2026-05-22 11:35:08.795240 | 2026-05-22 11:35:08.795418 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-05-22 11:35:09.416784 | debian-bookworm | changed 2026-05-22 11:35:09.425673 | 2026-05-22 11:35:09.425780 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-05-22 11:35:10.043816 | debian-bookworm | changed 2026-05-22 11:35:10.049198 | 2026-05-22 11:35:10.049314 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-05-22 11:35:10.082488 | debian-bookworm | skipping: Conditional result was False 2026-05-22 11:35:10.093896 | 2026-05-22 11:35:10.094005 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-05-22 11:35:10.708709 | debian-bookworm -> localhost | changed 2026-05-22 11:35:10.722935 | 2026-05-22 11:35:10.723046 | TASK [add-build-sshkey : Add back temp key] 2026-05-22 11:35:11.143267 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/b3d941acf24b46a299b3199196a147a3/work/b3d941acf24b46a299b3199196a147a3_id_rsa (zuul-build-sshkey) 2026-05-22 11:35:11.143487 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.025925 2026-05-22 11:35:11.151025 | 2026-05-22 11:35:11.151137 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-05-22 11:35:11.591735 | debian-bookworm | ok 2026-05-22 11:35:11.599129 | 2026-05-22 11:35:11.599254 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-05-22 11:35:11.623179 | debian-bookworm | skipping: Conditional result was False 2026-05-22 11:35:11.637714 | 2026-05-22 11:35:11.637825 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-05-22 11:35:12.077187 | debian-bookworm | ok 2026-05-22 11:35:12.097538 | 2026-05-22 11:35:12.097663 | TASK [validate-host : Define zuul_info_dir fact] 2026-05-22 11:35:12.126302 | debian-bookworm | ok 2026-05-22 11:35:12.131810 | 2026-05-22 11:35:12.131927 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-05-22 11:35:12.495030 | debian-bookworm -> localhost | ok 2026-05-22 11:35:12.501972 | 2026-05-22 11:35:12.502103 | TASK [validate-host : Collect information about the host] 2026-05-22 11:35:13.703603 | debian-bookworm | ok 2026-05-22 11:35:13.714414 | 2026-05-22 11:35:13.714647 | TASK [validate-host : Sanitize hostname] 2026-05-22 11:35:13.836690 | debian-bookworm | ok 2026-05-22 11:35:13.888415 | 2026-05-22 11:35:13.888573 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-05-22 11:35:14.723431 | debian-bookworm -> localhost | changed 2026-05-22 11:35:14.728340 | 2026-05-22 11:35:14.728435 | TASK [validate-host : Collect information about zuul worker] 2026-05-22 11:35:15.181347 | debian-bookworm | ok 2026-05-22 11:35:15.185089 | 2026-05-22 11:35:15.185175 | TASK [validate-host : Write out all zuul information for each host] 2026-05-22 11:35:15.880333 | debian-bookworm -> localhost | changed 2026-05-22 11:35:15.889534 | 2026-05-22 11:35:15.889639 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-05-22 11:35:16.206343 | debian-bookworm | ok 2026-05-22 11:35:16.210735 | 2026-05-22 11:35:16.215802 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-05-22 11:35:17.143360 | debian-bookworm | changed: 2026-05-22 11:35:17.143520 | debian-bookworm | cd+++++++++ src/ 2026-05-22 11:35:17.143553 | debian-bookworm | cd+++++++++ src/github.com/ 2026-05-22 11:35:17.143578 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-05-22 11:35:17.143600 | debian-bookworm | cd+++++++++ src/github.com/osism/container-image-kolla-ansible/ 2026-05-22 11:35:17.143621 | debian-bookworm | Containerfile 2026-05-22 11:35:17.143770 | debian-bookworm | localhost | ok: "/var/lib/zuul/builds/b3d941acf24b46a299b3199196a147a3/work/logs" 2026-05-22 11:35:42.812453 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/b3d941acf24b46a299b3199196a147a3/work/artifacts" 2026-05-22 11:35:43.075670 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/b3d941acf24b46a299b3199196a147a3/work/docs" 2026-05-22 11:35:43.095228 | 2026-05-22 11:35:43.095455 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-05-22 11:35:43.886626 | debian-bookworm | changed: 2026-05-22 11:35:43.887008 | debian-bookworm | .d..t...... ./ 2026-05-22 11:35:43.887069 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-05-22 11:35:43.887118 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-05-22 11:35:43.887162 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-05-22 11:35:43.887227 | debian-bookworm | changed: All items complete 2026-05-22 11:35:43.887317 | 2026-05-22 11:35:44.487164 | debian-bookworm | changed: .d..t...... ./ 2026-05-22 11:35:45.060136 | debian-bookworm | changed: .d..t...... ./ 2026-05-22 11:35:45.073566 | 2026-05-22 11:35:45.073700 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-05-22 11:35:45.635874 | debian-bookworm -> localhost | ok: Item: artifacts Runtime: 0:00:00.017062 2026-05-22 11:35:45.937860 | debian-bookworm -> localhost | ok: Item: docs Runtime: 0:00:00.013921 2026-05-22 11:35:45.950486 | 2026-05-22 11:35:45.950590 | PLAY RECAP 2026-05-22 11:35:45.950642 | debian-bookworm | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-05-22 11:35:45.950668 | 2026-05-22 11:35:46.093845 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-05-22 11:35:46.094716 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-22 11:35:46.849734 | 2026-05-22 11:35:46.850002 | PLAY [Base post] 2026-05-22 11:35:46.866822 | 2026-05-22 11:35:46.866992 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-05-22 11:35:47.474781 | debian-bookworm | changed 2026-05-22 11:35:47.481315 | 2026-05-22 11:35:47.481426 | PLAY RECAP 2026-05-22 11:35:47.481487 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-05-22 11:35:47.481549 | 2026-05-22 11:35:47.616367 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-22 11:35:47.617716 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-05-22 11:35:48.511377 | 2026-05-22 11:35:48.511565 | PLAY [Base post-logs] 2026-05-22 11:35:48.522973 | 2026-05-22 11:35:48.523139 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-05-22 11:35:49.026445 | localhost | changed 2026-05-22 11:35:49.039506 | 2026-05-22 11:35:49.039717 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-05-22 11:35:49.067574 | localhost | ok 2026-05-22 11:35:49.070892 | 2026-05-22 11:35:49.071006 | TASK [Set zuul-log-path fact] 2026-05-22 11:35:49.087932 | localhost | ok 2026-05-22 11:35:49.096512 | 2026-05-22 11:35:49.096643 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-22 11:35:49.122618 | localhost | ok 2026-05-22 11:35:49.125999 | 2026-05-22 11:35:49.126118 | TASK [upload-logs : Create log directories] 2026-05-22 11:35:49.654383 | localhost | changed 2026-05-22 11:35:49.657427 | 2026-05-22 11:35:49.657553 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-05-22 11:35:50.233178 | localhost -> localhost | ok: Runtime: 0:00:00.005147 2026-05-22 11:35:50.238678 | 2026-05-22 11:35:50.238805 | TASK [upload-logs : Upload logs to log server] 2026-05-22 11:35:50.946468 | localhost | Output suppressed because no_log was given 2026-05-22 11:35:50.948547 | 2026-05-22 11:35:50.948664 | LOOP [upload-logs : Compress console log and json output] 2026-05-22 11:35:50.997068 | localhost | skipping: Conditional result was False 2026-05-22 11:35:51.003399 | localhost | skipping: Conditional result was False 2026-05-22 11:35:51.006876 | 2026-05-22 11:35:51.007006 | LOOP [upload-logs : Upload compressed console log and json output] 2026-05-22 11:35:51.062012 | localhost | skipping: Conditional result was False 2026-05-22 11:35:51.062476 | 2026-05-22 11:35:51.072023 | localhost | skipping: Conditional result was False 2026-05-22 11:35:51.076443 | 2026-05-22 11:35:51.076598 | LOOP [upload-logs : Upload console log and json output]