2026-05-31 00:46:08.476657 | Job console starting 2026-05-31 00:46:08.494733 | Updating git repos 2026-05-31 00:46:09.034577 | Cloning repos into workspace 2026-05-31 00:46:09.126118 | Restoring repo states 2026-05-31 00:46:09.141433 | Merging changes 2026-05-31 00:46:09.795775 | Checking out repos 2026-05-31 00:46:09.893772 | Preparing playbooks 2026-05-31 00:46:11.504867 | Running Ansible setup 2026-05-31 00:46:17.965585 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-05-31 00:46:19.608366 | 2026-05-31 00:46:19.608505 | PLAY [Base pre] 2026-05-31 00:46:19.668253 | 2026-05-31 00:46:19.668381 | TASK [Setup log path fact] 2026-05-31 00:46:19.718986 | debian-bookworm | ok 2026-05-31 00:46:19.761074 | 2026-05-31 00:46:19.761211 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-31 00:46:19.834346 | debian-bookworm | ok 2026-05-31 00:46:19.865197 | 2026-05-31 00:46:19.865317 | TASK [emit-job-header : Print job information] 2026-05-31 00:46:19.964608 | # Job Information 2026-05-31 00:46:19.964822 | Ansible Version: 2.16.14 2026-05-31 00:46:19.964863 | Job: python-black 2026-05-31 00:46:19.964911 | Pipeline: check 2026-05-31 00:46:19.964935 | Executor: 521e9411259a 2026-05-31 00:46:19.964956 | Triggered by: https://github.com/osism/openstack-image-manager/pull/1211 2026-05-31 00:46:19.964979 | Event ID: 12220e20-5c8a-11f1-861f-a25727db8e5c 2026-05-31 00:46:19.969821 | 2026-05-31 00:46:19.969931 | LOOP [emit-job-header : Print node information] 2026-05-31 00:46:20.221918 | debian-bookworm | ok: 2026-05-31 00:46:20.222091 | debian-bookworm | # Node Information 2026-05-31 00:46:20.222124 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-05-31 00:46:20.222149 | debian-bookworm | Hostname: debian 2026-05-31 00:46:20.222171 | debian-bookworm | Username: zuul 2026-05-31 00:46:20.222192 | debian-bookworm | Distro: Debian 12.14 2026-05-31 00:46:20.222216 | debian-bookworm | Provider: regiocloud-a 2026-05-31 00:46:20.222237 | debian-bookworm | Region: 2026-05-31 00:46:20.222258 | debian-bookworm | Label: debian-bookworm 2026-05-31 00:46:20.222278 | debian-bookworm | Product Name: OpenStack Nova 2026-05-31 00:46:20.222297 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe5c:2628 2026-05-31 00:46:20.246430 | 2026-05-31 00:46:20.246546 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-05-31 00:46:21.678096 | debian-bookworm -> localhost | changed 2026-05-31 00:46:21.687027 | 2026-05-31 00:46:21.687200 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-05-31 00:46:24.392046 | debian-bookworm -> localhost | changed 2026-05-31 00:46:24.400756 | 2026-05-31 00:46:24.400846 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-05-31 00:46:25.012568 | debian-bookworm -> localhost | ok 2026-05-31 00:46:25.017489 | 2026-05-31 00:46:25.017590 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-05-31 00:46:25.056555 | debian-bookworm | ok 2026-05-31 00:46:25.097466 | debian-bookworm | included: /var/lib/zuul/builds/d9ab51bd7c3c4ed3aa3710de41d5fce0/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-05-31 00:46:25.112210 | 2026-05-31 00:46:25.113004 | TASK [add-build-sshkey : Create Temp SSH key] 2026-05-31 00:46:26.844420 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-05-31 00:46:26.844585 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/d9ab51bd7c3c4ed3aa3710de41d5fce0/work/d9ab51bd7c3c4ed3aa3710de41d5fce0_id_rsa 2026-05-31 00:46:26.844614 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/d9ab51bd7c3c4ed3aa3710de41d5fce0/work/d9ab51bd7c3c4ed3aa3710de41d5fce0_id_rsa.pub 2026-05-31 00:46:26.844636 | debian-bookworm -> localhost | The key fingerprint is: 2026-05-31 00:46:26.844656 | debian-bookworm -> localhost | SHA256:eIYejoX8u3lr/oV9TjviUAF8GFIEJqpBVy0fRSw0Pu4 zuul-build-sshkey 2026-05-31 00:46:26.844674 | debian-bookworm -> localhost | The key's randomart image is: 2026-05-31 00:46:26.844700 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-05-31 00:46:26.844719 | debian-bookworm -> localhost | | . ..oo*OBo | 2026-05-31 00:46:26.844736 | debian-bookworm -> localhost | | . . ..++o+.. | 2026-05-31 00:46:26.844753 | debian-bookworm -> localhost | | . . ooo .. | 2026-05-31 00:46:26.844769 | debian-bookworm -> localhost | | + . +.. . | 2026-05-31 00:46:26.844785 | debian-bookworm -> localhost | | . o = S . | 2026-05-31 00:46:26.844816 | debian-bookworm -> localhost | | * = + | 2026-05-31 00:46:26.844836 | debian-bookworm -> localhost | | . + E o o o | 2026-05-31 00:46:26.844854 | debian-bookworm -> localhost | | oo o.+.. | 2026-05-31 00:46:26.844871 | debian-bookworm -> localhost | | +=oo....o. | 2026-05-31 00:46:26.844888 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-05-31 00:46:26.844960 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.785850 2026-05-31 00:46:26.850402 | 2026-05-31 00:46:26.850497 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-05-31 00:46:26.889791 | debian-bookworm | ok 2026-05-31 00:46:26.906957 | debian-bookworm | included: /var/lib/zuul/builds/d9ab51bd7c3c4ed3aa3710de41d5fce0/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-05-31 00:46:26.928796 | 2026-05-31 00:46:26.928914 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-05-31 00:46:26.959369 | debian-bookworm | skipping: Conditional result was False 2026-05-31 00:46:26.964876 | 2026-05-31 00:46:26.964989 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-05-31 00:46:27.744749 | debian-bookworm | changed 2026-05-31 00:46:27.749065 | 2026-05-31 00:46:27.749139 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-05-31 00:46:28.021519 | debian-bookworm | ok 2026-05-31 00:46:28.030358 | 2026-05-31 00:46:28.030452 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-05-31 00:46:28.756453 | debian-bookworm | changed 2026-05-31 00:46:28.766449 | 2026-05-31 00:46:28.766574 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-05-31 00:46:29.438297 | debian-bookworm | changed 2026-05-31 00:46:29.444119 | 2026-05-31 00:46:29.444214 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-05-31 00:46:29.460023 | debian-bookworm | skipping: Conditional result was False 2026-05-31 00:46:29.471562 | 2026-05-31 00:46:29.471680 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-05-31 00:46:30.219394 | debian-bookworm -> localhost | changed 2026-05-31 00:46:30.232170 | 2026-05-31 00:46:30.232275 | TASK [add-build-sshkey : Add back temp key] 2026-05-31 00:46:31.218479 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/d9ab51bd7c3c4ed3aa3710de41d5fce0/work/d9ab51bd7c3c4ed3aa3710de41d5fce0_id_rsa (zuul-build-sshkey) 2026-05-31 00:46:31.218688 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.016298 2026-05-31 00:46:31.226450 | 2026-05-31 00:46:31.226555 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-05-31 00:46:31.772127 | debian-bookworm | ok 2026-05-31 00:46:31.777116 | 2026-05-31 00:46:31.777216 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-05-31 00:46:31.820931 | debian-bookworm | skipping: Conditional result was False 2026-05-31 00:46:31.831766 | 2026-05-31 00:46:31.831873 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-05-31 00:46:32.369039 | debian-bookworm | ok 2026-05-31 00:46:32.398129 | 2026-05-31 00:46:32.398241 | TASK [validate-host : Define zuul_info_dir fact] 2026-05-31 00:46:32.483541 | debian-bookworm | ok 2026-05-31 00:46:32.488980 | 2026-05-31 00:46:32.489094 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-05-31 00:46:33.353419 | debian-bookworm -> localhost | ok 2026-05-31 00:46:33.359701 | 2026-05-31 00:46:33.359791 | TASK [validate-host : Collect information about the host] 2026-05-31 00:46:35.062968 | debian-bookworm | ok 2026-05-31 00:46:35.076273 | 2026-05-31 00:46:35.076362 | TASK [validate-host : Sanitize hostname] 2026-05-31 00:46:35.350474 | debian-bookworm | ok 2026-05-31 00:46:35.379787 | 2026-05-31 00:46:35.379882 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-05-31 00:46:36.090032 | debian-bookworm -> localhost | changed 2026-05-31 00:46:36.095037 | 2026-05-31 00:46:36.095126 | TASK [validate-host : Collect information about zuul worker] 2026-05-31 00:46:36.551116 | debian-bookworm | ok 2026-05-31 00:46:36.554986 | 2026-05-31 00:46:36.555061 | TASK [validate-host : Write out all zuul information for each host] 2026-05-31 00:46:37.113826 | debian-bookworm -> localhost | changed 2026-05-31 00:46:37.121959 | 2026-05-31 00:46:37.122046 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-05-31 00:46:37.415546 | debian-bookworm | ok 2026-05-31 00:46:37.424556 | 2026-05-31 00:46:37.424663 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-05-31 00:46:38.356516 | debian-bookworm | changed: 2026-05-31 00:46:38.356726 | debian-bookworm | cd+++++++++ src/ 2026-05-31 00:46:38.356784 | debian-bookworm | cd+++++++++ src/github.com/ 2026-05-31 00:46:38.356812 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-05-31 00:46:38.356834 | debian-bookworm | cd+++++++++ src/github.com/osism/openstack-image-manager/ 2026-05-31 00:46:38.356855 | debian-bookworm | localhost | ok: "/var/lib/zuul/builds/d9ab51bd7c3c4ed3aa3710de41d5fce0/work/logs" 2026-05-31 00:47:06.919794 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/d9ab51bd7c3c4ed3aa3710de41d5fce0/work/artifacts" 2026-05-31 00:47:07.627460 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/d9ab51bd7c3c4ed3aa3710de41d5fce0/work/docs" 2026-05-31 00:47:07.635359 | 2026-05-31 00:47:07.635448 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-05-31 00:47:08.860075 | debian-bookworm | changed: 2026-05-31 00:47:08.861019 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-05-31 00:47:08.861160 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-05-31 00:47:08.861193 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-05-31 00:47:08.861235 | debian-bookworm | changed: All items complete 2026-05-31 00:47:08.861258 | 2026-05-31 00:47:10.216955 | debian-bookworm | changed: .d..t...... ./ 2026-05-31 00:47:11.502679 | debian-bookworm | changed: .d..t...... ./ 2026-05-31 00:47:11.522045 | 2026-05-31 00:47:11.522161 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-05-31 00:47:12.893173 | debian-bookworm -> localhost | ok: Item: artifacts Runtime: 0:00:00.038802 2026-05-31 00:47:13.999858 | debian-bookworm -> localhost | ok: Item: docs Runtime: 0:00:00.035100 2026-05-31 00:47:14.012427 | 2026-05-31 00:47:14.012539 | PLAY RECAP 2026-05-31 00:47:14.012590 | debian-bookworm | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-05-31 00:47:14.012614 | 2026-05-31 00:47:14.184621 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-05-31 00:47:14.185458 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-31 00:47:15.578851 | 2026-05-31 00:47:15.578990 | PLAY [Base post] 2026-05-31 00:47:15.598665 | 2026-05-31 00:47:15.598814 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-05-31 00:47:16.332970 | debian-bookworm | changed 2026-05-31 00:47:16.371843 | 2026-05-31 00:47:16.371968 | PLAY RECAP 2026-05-31 00:47:16.372025 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-05-31 00:47:16.372077 | 2026-05-31 00:47:16.455842 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-31 00:47:16.456668 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-05-31 00:47:17.983576 | 2026-05-31 00:47:17.983697 | PLAY [Base post-logs] 2026-05-31 00:47:17.992541 | 2026-05-31 00:47:17.992657 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-05-31 00:47:18.504690 | localhost | changed 2026-05-31 00:47:18.512786 | 2026-05-31 00:47:18.512900 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-05-31 00:47:18.558385 | localhost | ok 2026-05-31 00:47:18.561368 | 2026-05-31 00:47:18.561448 | TASK [Set zuul-log-path fact] 2026-05-31 00:47:18.585923 | localhost | ok 2026-05-31 00:47:18.592842 | 2026-05-31 00:47:18.592938 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-31 00:47:18.632572 | localhost | ok 2026-05-31 00:47:18.635238 | 2026-05-31 00:47:18.635326 | TASK [upload-logs : Create log directories] 2026-05-31 00:47:20.133634 | localhost | changed 2026-05-31 00:47:20.135913 | 2026-05-31 00:47:20.136000 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-05-31 00:47:20.984534 | localhost -> localhost | ok: Runtime: 0:00:00.031364 2026-05-31 00:47:20.988015 | 2026-05-31 00:47:20.988111 | TASK [upload-logs : Upload logs to log server] 2026-05-31 00:47:21.886344 | localhost | Output suppressed because no_log was given 2026-05-31 00:47:21.888010 | 2026-05-31 00:47:21.888098 | LOOP [upload-logs : Compress console log and json output] 2026-05-31 00:47:22.006506 | localhost | skipping: Conditional result was False 2026-05-31 00:47:22.012180 | localhost | skipping: Conditional result was False 2026-05-31 00:47:22.014899 | 2026-05-31 00:47:22.014996 | LOOP [upload-logs : Upload compressed console log and json output] 2026-05-31 00:47:22.060298 | localhost | skipping: Conditional result was False 2026-05-31 00:47:22.060546 | 2026-05-31 00:47:22.065061 | localhost | skipping: Conditional result was False 2026-05-31 00:47:22.069858 | 2026-05-31 00:47:22.069947 | LOOP [upload-logs : Upload console log and json output]