2026-09-07 03:10:46.137114 | Job console starting 2026-09-07 03:10:46.152098 | Updating git repos 2026-09-07 03:10:46.173135 | Cloning repos into workspace 2026-09-07 03:10:46.218181 | Restoring repo states 2026-09-07 03:10:46.221938 | Merging changes 2026-09-07 03:10:46.221962 | Checking out repos 2026-09-07 03:10:46.304252 | Preparing playbooks 2026-09-07 03:10:47.162211 | Running Ansible setup 2026-09-07 03:10:51.389341 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-09-07 03:10:52.118609 | 2026-09-07 03:10:52.118798 | PLAY [Base pre] 2026-09-07 03:10:52.147059 | 2026-09-07 03:10:52.147174 | TASK [Setup log path fact] 2026-09-07 03:10:52.165345 | debian-bookworm | ok 2026-09-07 03:10:52.182166 | 2026-09-07 03:10:52.182273 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-09-07 03:10:52.209913 | debian-bookworm | ok 2026-09-07 03:10:52.219276 | 2026-09-07 03:10:52.219364 | TASK [emit-job-header : Print job information] 2026-09-07 03:10:52.248169 | # Job Information 2026-09-07 03:10:52.248300 | Ansible Version: 2.16.14 2026-09-07 03:10:52.248335 | Job: python-black 2026-09-07 03:10:52.248368 | Pipeline: periodic-daily 2026-09-07 03:10:52.248393 | Executor: 521e9411259a 2026-09-07 03:10:52.248415 | Triggered by: https://github.com/osism/testbed 2026-09-07 03:10:52.248439 | Event ID: 0eea77c1f2ab45c0bd121ee5d904d382 2026-09-07 03:10:52.253318 | 2026-09-07 03:10:52.253404 | LOOP [emit-job-header : Print node information] 2026-09-07 03:10:52.357764 | debian-bookworm | ok: 2026-09-07 03:10:52.358186 | debian-bookworm | # Node Information 2026-09-07 03:10:52.358243 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-09-07 03:10:52.358285 | debian-bookworm | Hostname: debian 2026-09-07 03:10:52.358321 | debian-bookworm | Username: zuul 2026-09-07 03:10:52.358356 | debian-bookworm | Distro: Debian 12.15 2026-09-07 03:10:52.358395 | debian-bookworm | Provider: regiocloud-a 2026-09-07 03:10:52.358429 | debian-bookworm | Region: 2026-09-07 03:10:52.358464 | debian-bookworm | Label: debian-bookworm 2026-09-07 03:10:52.358497 | debian-bookworm | Product Name: OpenStack Nova 2026-09-07 03:10:52.358529 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe7b:1c57 2026-09-07 03:10:52.386716 | 2026-09-07 03:10:52.386890 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-09-07 03:10:52.861653 | debian-bookworm -> localhost | changed 2026-09-07 03:10:52.869158 | 2026-09-07 03:10:52.869264 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-09-07 03:10:53.945575 | debian-bookworm -> localhost | changed 2026-09-07 03:10:53.955647 | 2026-09-07 03:10:53.955737 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-09-07 03:10:54.221322 | debian-bookworm -> localhost | ok 2026-09-07 03:10:54.227228 | 2026-09-07 03:10:54.227325 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-09-07 03:10:54.246030 | debian-bookworm | ok 2026-09-07 03:10:54.270710 | debian-bookworm | included: /var/lib/zuul/builds/2920735f535a4e10bc3262718f55dc8f/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-09-07 03:10:54.286726 | 2026-09-07 03:10:54.286827 | TASK [add-build-sshkey : Create Temp SSH key] 2026-09-07 03:10:55.327369 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-09-07 03:10:55.327552 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/2920735f535a4e10bc3262718f55dc8f/work/2920735f535a4e10bc3262718f55dc8f_id_rsa 2026-09-07 03:10:55.327589 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/2920735f535a4e10bc3262718f55dc8f/work/2920735f535a4e10bc3262718f55dc8f_id_rsa.pub 2026-09-07 03:10:55.327615 | debian-bookworm -> localhost | The key fingerprint is: 2026-09-07 03:10:55.327639 | debian-bookworm -> localhost | SHA256:RO7diwXSdxu/BWSaZ5FUN/ReArpo9fyehF4DxkKUojw zuul-build-sshkey 2026-09-07 03:10:55.327661 | debian-bookworm -> localhost | The key's randomart image is: 2026-09-07 03:10:55.327691 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-09-07 03:10:55.327712 | debian-bookworm -> localhost | | . ....*=o| 2026-09-07 03:10:55.327734 | debian-bookworm -> localhost | | o.o.. *..+| 2026-09-07 03:10:55.327754 | debian-bookworm -> localhost | | . .+.* + B o| 2026-09-07 03:10:55.327773 | debian-bookworm -> localhost | | Eo * O + B.| 2026-09-07 03:10:55.327793 | debian-bookworm -> localhost | | .S + O . +| 2026-09-07 03:10:55.327817 | debian-bookworm -> localhost | | . = = o| 2026-09-07 03:10:55.327837 | debian-bookworm -> localhost | | . o =. | 2026-09-07 03:10:55.327855 | debian-bookworm -> localhost | | . + o | 2026-09-07 03:10:55.327875 | debian-bookworm -> localhost | | . o | 2026-09-07 03:10:55.327895 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-09-07 03:10:55.327983 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.577572 2026-09-07 03:10:55.342862 | 2026-09-07 03:10:55.342988 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-09-07 03:10:55.361541 | debian-bookworm | ok 2026-09-07 03:10:55.372911 | debian-bookworm | included: /var/lib/zuul/builds/2920735f535a4e10bc3262718f55dc8f/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-09-07 03:10:55.381681 | 2026-09-07 03:10:55.381760 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-09-07 03:10:55.395817 | debian-bookworm | skipping: Conditional result was False 2026-09-07 03:10:55.401376 | 2026-09-07 03:10:55.401459 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-09-07 03:10:56.028919 | debian-bookworm | changed 2026-09-07 03:10:56.035812 | 2026-09-07 03:10:56.035906 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-09-07 03:10:56.281447 | debian-bookworm | ok 2026-09-07 03:10:56.287661 | 2026-09-07 03:10:56.287754 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-09-07 03:10:56.905708 | debian-bookworm | changed 2026-09-07 03:10:56.912031 | 2026-09-07 03:10:56.912124 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-09-07 03:10:57.497489 | debian-bookworm | changed 2026-09-07 03:10:57.503030 | 2026-09-07 03:10:57.503117 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-09-07 03:10:57.526959 | debian-bookworm | skipping: Conditional result was False 2026-09-07 03:10:57.532644 | 2026-09-07 03:10:57.532729 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-09-07 03:10:57.920712 | debian-bookworm -> localhost | changed 2026-09-07 03:10:57.931563 | 2026-09-07 03:10:57.931659 | TASK [add-build-sshkey : Add back temp key] 2026-09-07 03:10:58.261902 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/2920735f535a4e10bc3262718f55dc8f/work/2920735f535a4e10bc3262718f55dc8f_id_rsa (zuul-build-sshkey) 2026-09-07 03:10:58.262430 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.015811 2026-09-07 03:10:58.280892 | 2026-09-07 03:10:58.281131 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-09-07 03:10:58.691595 | debian-bookworm | ok 2026-09-07 03:10:58.700796 | 2026-09-07 03:10:58.700911 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-09-07 03:10:58.735132 | debian-bookworm | skipping: Conditional result was False 2026-09-07 03:10:58.758605 | 2026-09-07 03:10:58.758870 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-09-07 03:10:59.188712 | debian-bookworm | ok 2026-09-07 03:10:59.201780 | 2026-09-07 03:10:59.201879 | TASK [validate-host : Define zuul_info_dir fact] 2026-09-07 03:10:59.229559 | debian-bookworm | ok 2026-09-07 03:10:59.234542 | 2026-09-07 03:10:59.234625 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-09-07 03:10:59.490377 | debian-bookworm -> localhost | ok 2026-09-07 03:10:59.496955 | 2026-09-07 03:10:59.497046 | TASK [validate-host : Collect information about the host] 2026-09-07 03:11:00.607454 | debian-bookworm | ok 2026-09-07 03:11:00.622713 | 2026-09-07 03:11:00.622796 | TASK [validate-host : Sanitize hostname] 2026-09-07 03:11:00.734418 | debian-bookworm | ok 2026-09-07 03:11:00.737876 | 2026-09-07 03:11:00.737960 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-09-07 03:11:01.237445 | debian-bookworm -> localhost | changed 2026-09-07 03:11:01.242772 | 2026-09-07 03:11:01.242900 | TASK [validate-host : Collect information about zuul worker] 2026-09-07 03:11:01.640621 | debian-bookworm | ok 2026-09-07 03:11:01.652734 | 2026-09-07 03:11:01.652843 | TASK [validate-host : Write out all zuul information for each host] 2026-09-07 03:11:02.317586 | debian-bookworm -> localhost | changed 2026-09-07 03:11:02.326713 | 2026-09-07 03:11:02.326799 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-09-07 03:11:02.565307 | debian-bookworm | ok 2026-09-07 03:11:02.570552 | 2026-09-07 03:11:02.570640 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-09-07 03:11:03.763604 | debian-bookworm | changed: 2026-09-07 03:11:03.763764 | debian-bookworm | cd+++++++++ src/ 2026-09-07 03:11:03.763799 | debian-bookworm | cd+++++++++ src/github.com/ 2026-09-07 03:11:03.763822 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-09-07 03:11:03.763844 | debian-bookworm | cd+++++++++ src/github.com/osism/testbed/ 2026-09-07 03:11:03.763864 | debian-bookworm | localhost | ok: "/var/lib/zuul/builds/2920735f535a4e10bc3262718f55dc8f/work/logs" 2026-09-07 03:12:06.835496 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/2920735f535a4e10bc3262718f55dc8f/work/artifacts" 2026-09-07 03:12:07.100271 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/2920735f535a4e10bc3262718f55dc8f/work/docs" 2026-09-07 03:12:07.115773 | 2026-09-07 03:12:07.115904 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-09-07 03:12:07.829706 | debian-bookworm | changed: 2026-09-07 03:12:07.829906 | debian-bookworm | .d..t...... ./ 2026-09-07 03:12:07.829941 | debian-bookworm | >f+++++++++ net-debug.post.txt 2026-09-07 03:12:07.829966 | debian-bookworm | >f+++++++++ net-debug.pre.txt 2026-09-07 03:12:07.829988 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-09-07 03:12:07.830010 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-09-07 03:12:07.830031 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-09-07 03:12:07.830070 | debian-bookworm | changed: All items complete 2026-09-07 03:12:07.830092 | 2026-09-07 03:12:08.354758 | debian-bookworm | changed: .d..t...... ./ 2026-09-07 03:12:08.900848 | debian-bookworm | changed: .d..t...... ./ 2026-09-07 03:12:08.912372 | 2026-09-07 03:12:08.912463 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-09-07 03:12:08.941203 | debian-bookworm | skipping: Conditional result was False 2026-09-07 03:12:08.949684 | debian-bookworm | skipping: Conditional result was False 2026-09-07 03:12:08.965413 | 2026-09-07 03:12:08.965498 | PLAY RECAP 2026-09-07 03:12:08.965563 | debian-bookworm | ok: 7 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0 2026-09-07 03:12:08.965598 | 2026-09-07 03:12:09.051182 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-09-07 03:12:09.051919 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-09-07 03:12:09.733522 | 2026-09-07 03:12:09.733643 | PLAY [Base post] 2026-09-07 03:12:09.746058 | 2026-09-07 03:12:09.746167 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-09-07 03:12:10.377430 | debian-bookworm | changed 2026-09-07 03:12:10.389487 | 2026-09-07 03:12:10.389612 | PLAY RECAP 2026-09-07 03:12:10.389677 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-09-07 03:12:10.389740 | 2026-09-07 03:12:10.528258 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-09-07 03:12:10.529616 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-09-07 03:12:11.356549 | 2026-09-07 03:12:11.356707 | PLAY [Base post-logs] 2026-09-07 03:12:11.374410 | 2026-09-07 03:12:11.374561 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-09-07 03:12:11.806420 | localhost | changed 2026-09-07 03:12:11.818183 | 2026-09-07 03:12:11.818332 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-09-07 03:12:11.851011 | localhost | ok 2026-09-07 03:12:11.854142 | 2026-09-07 03:12:11.854239 | TASK [Set zuul-log-path fact] 2026-09-07 03:12:11.879008 | localhost | ok 2026-09-07 03:12:11.886937 | 2026-09-07 03:12:11.887049 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-09-07 03:12:11.922980 | localhost | ok 2026-09-07 03:12:11.928895 | 2026-09-07 03:12:11.929052 | TASK [upload-logs : Create log directories] 2026-09-07 03:12:12.423902 | localhost | changed 2026-09-07 03:12:12.427084 | 2026-09-07 03:12:12.427254 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-09-07 03:12:12.938372 | localhost -> localhost | ok: Runtime: 0:00:00.007790 2026-09-07 03:12:12.949345 | 2026-09-07 03:12:12.949533 | TASK [upload-logs : Upload logs to log server] 2026-09-07 03:12:13.550982 | localhost | Output suppressed because no_log was given 2026-09-07 03:12:13.553576 | 2026-09-07 03:12:13.553732 | LOOP [upload-logs : Compress console log and json output] 2026-09-07 03:12:13.615217 | localhost | skipping: Conditional result was False 2026-09-07 03:12:13.620475 | localhost | skipping: Conditional result was False 2026-09-07 03:12:13.628038 | 2026-09-07 03:12:13.628245 | LOOP [upload-logs : Upload compressed console log and json output] 2026-09-07 03:12:13.674067 | localhost | skipping: Conditional result was False 2026-09-07 03:12:13.674603 | 2026-09-07 03:12:13.678203 | localhost | skipping: Conditional result was False 2026-09-07 03:12:13.692180 | 2026-09-07 03:12:13.692412 | LOOP [upload-logs : Upload console log and json output]