2026-05-12 03:12:00.148629 | Job console starting 2026-05-12 03:12:00.163749 | Updating git repos 2026-05-12 03:12:00.192048 | Cloning repos into workspace 2026-05-12 03:12:00.227682 | Restoring repo states 2026-05-12 03:12:00.236724 | Merging changes 2026-05-12 03:12:00.236741 | Checking out repos 2026-05-12 03:12:00.280996 | Preparing playbooks 2026-05-12 03:12:01.637856 | Running Ansible setup 2026-05-12 03:12:07.060863 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-05-12 03:12:07.935796 | 2026-05-12 03:12:07.935986 | PLAY [Base pre] 2026-05-12 03:12:07.952632 | 2026-05-12 03:12:07.952756 | TASK [Setup log path fact] 2026-05-12 03:12:07.971832 | debian-bookworm | ok 2026-05-12 03:12:07.989131 | 2026-05-12 03:12:07.989282 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-12 03:12:08.022137 | debian-bookworm | ok 2026-05-12 03:12:08.035270 | 2026-05-12 03:12:08.035429 | TASK [emit-job-header : Print job information] 2026-05-12 03:12:08.076341 | # Job Information 2026-05-12 03:12:08.076509 | Ansible Version: 2.16.14 2026-05-12 03:12:08.076546 | Job: python-black 2026-05-12 03:12:08.076578 | Pipeline: periodic-daily 2026-05-12 03:12:08.076601 | Executor: 521e9411259a 2026-05-12 03:12:08.076621 | Triggered by: https://github.com/osism/node-image 2026-05-12 03:12:08.076643 | Event ID: b923976162f9436c9ba08802afff5760 2026-05-12 03:12:08.082025 | 2026-05-12 03:12:08.082124 | LOOP [emit-job-header : Print node information] 2026-05-12 03:12:08.219805 | debian-bookworm | ok: 2026-05-12 03:12:08.220070 | debian-bookworm | # Node Information 2026-05-12 03:12:08.220111 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-05-12 03:12:08.220137 | debian-bookworm | Hostname: debian 2026-05-12 03:12:08.220170 | debian-bookworm | Username: zuul 2026-05-12 03:12:08.220193 | debian-bookworm | Distro: Debian 12.13 2026-05-12 03:12:08.220217 | debian-bookworm | Provider: regiocloud-a 2026-05-12 03:12:08.220238 | debian-bookworm | Region: 2026-05-12 03:12:08.220260 | debian-bookworm | Label: debian-bookworm 2026-05-12 03:12:08.220279 | debian-bookworm | Product Name: OpenStack Nova 2026-05-12 03:12:08.220298 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:febb:661d 2026-05-12 03:12:08.246219 | 2026-05-12 03:12:08.246758 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-05-12 03:12:08.757455 | debian-bookworm -> localhost | changed 2026-05-12 03:12:08.773051 | 2026-05-12 03:12:08.773197 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-05-12 03:12:09.983344 | debian-bookworm -> localhost | changed 2026-05-12 03:12:09.994066 | 2026-05-12 03:12:09.994171 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-05-12 03:12:10.311406 | debian-bookworm -> localhost | ok 2026-05-12 03:12:10.317479 | 2026-05-12 03:12:10.317598 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-05-12 03:12:10.358599 | debian-bookworm | ok 2026-05-12 03:12:10.385482 | debian-bookworm | included: /var/lib/zuul/builds/b72076a9d7954601acd5d4765e7baf13/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-05-12 03:12:10.403340 | 2026-05-12 03:12:10.403468 | TASK [add-build-sshkey : Create Temp SSH key] 2026-05-12 03:12:11.966156 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-05-12 03:12:11.966375 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/b72076a9d7954601acd5d4765e7baf13/work/b72076a9d7954601acd5d4765e7baf13_id_rsa 2026-05-12 03:12:11.966416 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/b72076a9d7954601acd5d4765e7baf13/work/b72076a9d7954601acd5d4765e7baf13_id_rsa.pub 2026-05-12 03:12:11.966443 | debian-bookworm -> localhost | The key fingerprint is: 2026-05-12 03:12:11.966467 | debian-bookworm -> localhost | SHA256:fWUO+koHwWAtD+ru1117SUaYudQh8twCVluFEF2RgtQ zuul-build-sshkey 2026-05-12 03:12:11.966489 | debian-bookworm -> localhost | The key's randomart image is: 2026-05-12 03:12:11.966521 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-05-12 03:12:11.966542 | debian-bookworm -> localhost | | o...*= =*| 2026-05-12 03:12:11.966563 | debian-bookworm -> localhost | | .oo.= E=o | 2026-05-12 03:12:11.966583 | debian-bookworm -> localhost | | . ++ *.@ .| 2026-05-12 03:12:11.966603 | debian-bookworm -> localhost | | . ..o & + | 2026-05-12 03:12:11.966621 | debian-bookworm -> localhost | | . S + o = | 2026-05-12 03:12:11.966646 | debian-bookworm -> localhost | | . + ..o | 2026-05-12 03:12:11.966676 | debian-bookworm -> localhost | | . ...o.o..| 2026-05-12 03:12:11.966709 | debian-bookworm -> localhost | | . ...o. ...| 2026-05-12 03:12:11.966745 | debian-bookworm -> localhost | | ... . . | 2026-05-12 03:12:11.966780 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-05-12 03:12:11.966848 | debian-bookworm -> localhost | ok: Runtime: 0:00:01.053839 2026-05-12 03:12:11.973169 | 2026-05-12 03:12:11.973263 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-05-12 03:12:11.991750 | debian-bookworm | ok 2026-05-12 03:12:12.001093 | debian-bookworm | included: /var/lib/zuul/builds/b72076a9d7954601acd5d4765e7baf13/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-05-12 03:12:12.009949 | 2026-05-12 03:12:12.010053 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-05-12 03:12:12.023229 | debian-bookworm | skipping: Conditional result was False 2026-05-12 03:12:12.029028 | 2026-05-12 03:12:12.029127 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-05-12 03:12:12.676002 | debian-bookworm | changed 2026-05-12 03:12:12.684004 | 2026-05-12 03:12:12.684156 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-05-12 03:12:12.916148 | debian-bookworm | ok 2026-05-12 03:12:12.923741 | 2026-05-12 03:12:12.923880 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-05-12 03:12:13.512237 | debian-bookworm | changed 2026-05-12 03:12:13.519655 | 2026-05-12 03:12:13.519778 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-05-12 03:12:14.135205 | debian-bookworm | changed 2026-05-12 03:12:14.144197 | 2026-05-12 03:12:14.144396 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-05-12 03:12:14.179762 | debian-bookworm | skipping: Conditional result was False 2026-05-12 03:12:14.192793 | 2026-05-12 03:12:14.192976 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-05-12 03:12:14.979983 | debian-bookworm -> localhost | changed 2026-05-12 03:12:15.013471 | 2026-05-12 03:12:15.014316 | TASK [add-build-sshkey : Add back temp key] 2026-05-12 03:12:15.821736 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/b72076a9d7954601acd5d4765e7baf13/work/b72076a9d7954601acd5d4765e7baf13_id_rsa (zuul-build-sshkey) 2026-05-12 03:12:15.822146 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.012046 2026-05-12 03:12:15.837445 | 2026-05-12 03:12:15.838933 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-05-12 03:12:16.352064 | debian-bookworm | ok 2026-05-12 03:12:16.361657 | 2026-05-12 03:12:16.361790 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-05-12 03:12:16.409731 | debian-bookworm | skipping: Conditional result was False 2026-05-12 03:12:16.425648 | 2026-05-12 03:12:16.425790 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-05-12 03:12:17.117122 | debian-bookworm | ok 2026-05-12 03:12:17.160274 | 2026-05-12 03:12:17.161388 | TASK [validate-host : Define zuul_info_dir fact] 2026-05-12 03:12:17.239568 | debian-bookworm | ok 2026-05-12 03:12:17.257180 | 2026-05-12 03:12:17.259132 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-05-12 03:12:18.220715 | debian-bookworm -> localhost | ok 2026-05-12 03:12:18.226270 | 2026-05-12 03:12:18.226366 | TASK [validate-host : Collect information about the host] 2026-05-12 03:12:19.502397 | debian-bookworm | ok 2026-05-12 03:12:19.522334 | 2026-05-12 03:12:19.522496 | TASK [validate-host : Sanitize hostname] 2026-05-12 03:12:19.612814 | debian-bookworm | ok 2026-05-12 03:12:19.648605 | 2026-05-12 03:12:19.648716 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-05-12 03:12:20.440974 | debian-bookworm -> localhost | changed 2026-05-12 03:12:20.448429 | 2026-05-12 03:12:20.448548 | TASK [validate-host : Collect information about zuul worker] 2026-05-12 03:12:20.905088 | debian-bookworm | ok 2026-05-12 03:12:20.911832 | 2026-05-12 03:12:20.911939 | TASK [validate-host : Write out all zuul information for each host] 2026-05-12 03:12:21.567392 | debian-bookworm -> localhost | changed 2026-05-12 03:12:21.584630 | 2026-05-12 03:12:21.584737 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-05-12 03:12:21.849550 | debian-bookworm | ok 2026-05-12 03:12:21.856073 | 2026-05-12 03:12:21.856202 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-05-12 03:12:23.573303 | debian-bookworm | changed: 2026-05-12 03:12:23.573473 | debian-bookworm | cd+++++++++ src/ 2026-05-12 03:12:23.573507 | debian-bookworm | cd+++++++++ src/github.com/ 2026-05-12 03:12:23.573531 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-05-12 03:12:23.573553 | debian-bookworm | cd+++++++++ src/github.com/osism/node-image/ 2026-05-12 03:12:23.573573 | debian-bookworm | Containerfile 2026-05-12 03:12:23.573702 | debian-bookworm | localhost | ok: "/var/lib/zuul/builds/b72076a9d7954601acd5d4765e7baf13/work/logs" 2026-05-12 03:12:47.857655 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/b72076a9d7954601acd5d4765e7baf13/work/artifacts" 2026-05-12 03:12:48.425261 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/b72076a9d7954601acd5d4765e7baf13/work/docs" 2026-05-12 03:12:48.435059 | 2026-05-12 03:12:48.435162 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-05-12 03:12:49.404792 | debian-bookworm | changed: 2026-05-12 03:12:49.404980 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-05-12 03:12:49.405015 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-05-12 03:12:49.405044 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-05-12 03:12:49.405087 | debian-bookworm | changed: All items complete 2026-05-12 03:12:49.405113 | 2026-05-12 03:12:49.952408 | debian-bookworm | changed: .d..t...... ./ 2026-05-12 03:12:50.601920 | debian-bookworm | changed: .d..t...... ./ 2026-05-12 03:12:50.616516 | 2026-05-12 03:12:50.616644 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-05-12 03:12:50.680022 | debian-bookworm | skipping: Conditional result was False 2026-05-12 03:12:50.701512 | debian-bookworm | skipping: Conditional result was False 2026-05-12 03:12:50.718991 | 2026-05-12 03:12:50.719084 | PLAY RECAP 2026-05-12 03:12:50.719135 | debian-bookworm | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0 2026-05-12 03:12:50.719159 | 2026-05-12 03:12:51.108994 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-05-12 03:12:51.109866 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-12 03:12:51.970904 | 2026-05-12 03:12:51.971078 | PLAY [Base post] 2026-05-12 03:12:52.017213 | 2026-05-12 03:12:52.017447 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-05-12 03:12:52.745604 | debian-bookworm | changed 2026-05-12 03:12:52.761993 | 2026-05-12 03:12:52.762130 | PLAY RECAP 2026-05-12 03:12:52.762201 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-05-12 03:12:52.762276 | 2026-05-12 03:12:52.975942 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-12 03:12:52.976830 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-05-12 03:12:54.364297 | 2026-05-12 03:12:54.364489 | PLAY [Base post-logs] 2026-05-12 03:12:54.382684 | 2026-05-12 03:12:54.382902 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-05-12 03:12:55.212637 | localhost | changed 2026-05-12 03:12:55.229653 | 2026-05-12 03:12:55.229852 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-05-12 03:12:55.289128 | localhost | ok 2026-05-12 03:12:55.297272 | 2026-05-12 03:12:55.297543 | TASK [Set zuul-log-path fact] 2026-05-12 03:12:55.327590 | localhost | ok 2026-05-12 03:12:55.337420 | 2026-05-12 03:12:55.337563 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-12 03:12:55.365149 | localhost | ok 2026-05-12 03:12:55.368478 | 2026-05-12 03:12:55.368596 | TASK [upload-logs : Create log directories] 2026-05-12 03:12:56.223965 | localhost | changed 2026-05-12 03:12:56.229803 | 2026-05-12 03:12:56.229940 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-05-12 03:12:56.971268 | localhost -> localhost | ok: Runtime: 0:00:00.006517 2026-05-12 03:12:56.977563 | 2026-05-12 03:12:56.977703 | TASK [upload-logs : Upload logs to log server] 2026-05-12 03:12:58.111057 | localhost | Output suppressed because no_log was given 2026-05-12 03:12:58.113489 | 2026-05-12 03:12:58.113577 | LOOP [upload-logs : Compress console log and json output] 2026-05-12 03:12:58.241317 | localhost | skipping: Conditional result was False 2026-05-12 03:12:58.259048 | localhost | skipping: Conditional result was False 2026-05-12 03:12:58.271691 | 2026-05-12 03:12:58.271789 | LOOP [upload-logs : Upload compressed console log and json output] 2026-05-12 03:12:58.346940 | localhost | skipping: Conditional result was False 2026-05-12 03:12:58.347194 | 2026-05-12 03:12:58.376635 | localhost | skipping: Conditional result was False 2026-05-12 03:12:58.384041 | 2026-05-12 03:12:58.384184 | LOOP [upload-logs : Upload console log and json output]