2026-05-15 03:15:19.000973 | Job console starting 2026-05-15 03:15:19.011534 | Updating git repos 2026-05-15 03:15:19.053826 | Cloning repos into workspace 2026-05-15 03:15:19.129413 | Restoring repo states 2026-05-15 03:15:19.137450 | Merging changes 2026-05-15 03:15:19.137501 | Checking out repos 2026-05-15 03:15:19.214296 | Preparing playbooks 2026-05-15 03:15:20.044808 | Running Ansible setup 2026-05-15 03:15:24.986299 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-05-15 03:15:25.793720 | 2026-05-15 03:15:25.793856 | PLAY [Base pre] 2026-05-15 03:15:25.809961 | 2026-05-15 03:15:25.810088 | TASK [Setup log path fact] 2026-05-15 03:15:25.828222 | debian-bookworm | ok 2026-05-15 03:15:25.843570 | 2026-05-15 03:15:25.843694 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-15 03:15:25.871918 | debian-bookworm | ok 2026-05-15 03:15:25.884279 | 2026-05-15 03:15:25.884387 | TASK [emit-job-header : Print job information] 2026-05-15 03:15:25.927107 | # Job Information 2026-05-15 03:15:25.927406 | Ansible Version: 2.16.14 2026-05-15 03:15:25.927449 | Job: python-black 2026-05-15 03:15:25.927483 | Pipeline: periodic-daily 2026-05-15 03:15:25.927506 | Executor: 521e9411259a 2026-05-15 03:15:25.927527 | Triggered by: https://github.com/osism/openstack-image-manager 2026-05-15 03:15:25.927549 | Event ID: 0bc7489cdb61474cafd81d47831605ee 2026-05-15 03:15:25.932680 | 2026-05-15 03:15:25.932777 | LOOP [emit-job-header : Print node information] 2026-05-15 03:15:26.033903 | debian-bookworm | ok: 2026-05-15 03:15:26.034102 | debian-bookworm | # Node Information 2026-05-15 03:15:26.034140 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-05-15 03:15:26.034166 | debian-bookworm | Hostname: debian 2026-05-15 03:15:26.034189 | debian-bookworm | Username: zuul 2026-05-15 03:15:26.034210 | debian-bookworm | Distro: Debian 12.13 2026-05-15 03:15:26.034235 | debian-bookworm | Provider: regiocloud-a 2026-05-15 03:15:26.034257 | debian-bookworm | Region: 2026-05-15 03:15:26.034278 | debian-bookworm | Label: debian-bookworm 2026-05-15 03:15:26.034298 | debian-bookworm | Product Name: OpenStack Nova 2026-05-15 03:15:26.034317 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fee7:6732 2026-05-15 03:15:26.059669 | 2026-05-15 03:15:26.059800 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-05-15 03:15:26.628512 | debian-bookworm -> localhost | changed 2026-05-15 03:15:26.635492 | 2026-05-15 03:15:26.635600 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-05-15 03:15:28.075311 | debian-bookworm -> localhost | changed 2026-05-15 03:15:28.086706 | 2026-05-15 03:15:28.086872 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-05-15 03:15:28.497677 | debian-bookworm -> localhost | ok 2026-05-15 03:15:28.508977 | 2026-05-15 03:15:28.509154 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-05-15 03:15:28.566339 | debian-bookworm | ok 2026-05-15 03:15:28.582998 | debian-bookworm | included: /var/lib/zuul/builds/e2b9e9d23a074eb1bd17cb1a8a0d8ffc/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-05-15 03:15:28.589727 | 2026-05-15 03:15:28.589842 | TASK [add-build-sshkey : Create Temp SSH key] 2026-05-15 03:15:29.636560 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-05-15 03:15:29.636813 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/e2b9e9d23a074eb1bd17cb1a8a0d8ffc/work/e2b9e9d23a074eb1bd17cb1a8a0d8ffc_id_rsa 2026-05-15 03:15:29.636853 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/e2b9e9d23a074eb1bd17cb1a8a0d8ffc/work/e2b9e9d23a074eb1bd17cb1a8a0d8ffc_id_rsa.pub 2026-05-15 03:15:29.636880 | debian-bookworm -> localhost | The key fingerprint is: 2026-05-15 03:15:29.636905 | debian-bookworm -> localhost | SHA256:HDI/5T9+NoiI8U9fdBlpwkmg4dn3JBy//qxw8hZz23s zuul-build-sshkey 2026-05-15 03:15:29.636927 | debian-bookworm -> localhost | The key's randomart image is: 2026-05-15 03:15:29.636963 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-05-15 03:15:29.636985 | debian-bookworm -> localhost | | . ..o | 2026-05-15 03:15:29.637007 | debian-bookworm -> localhost | | . = + + .| 2026-05-15 03:15:29.637028 | debian-bookworm -> localhost | | o .+.. B * | 2026-05-15 03:15:29.637049 | debian-bookworm -> localhost | | = + . * +| 2026-05-15 03:15:29.637109 | debian-bookworm -> localhost | | S . .+.| 2026-05-15 03:15:29.637140 | debian-bookworm -> localhost | | . . . .+..| 2026-05-15 03:15:29.637161 | debian-bookworm -> localhost | | + ...=.o=o| 2026-05-15 03:15:29.637181 | debian-bookworm -> localhost | | . o..o.B=oE| 2026-05-15 03:15:29.637202 | debian-bookworm -> localhost | | .. o+o+=| 2026-05-15 03:15:29.637222 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-05-15 03:15:29.637287 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.362755 2026-05-15 03:15:29.644107 | 2026-05-15 03:15:29.644236 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-05-15 03:15:29.685594 | debian-bookworm | ok 2026-05-15 03:15:29.705795 | debian-bookworm | included: /var/lib/zuul/builds/e2b9e9d23a074eb1bd17cb1a8a0d8ffc/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-05-15 03:15:29.725317 | 2026-05-15 03:15:29.725457 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-05-15 03:15:29.770252 | debian-bookworm | skipping: Conditional result was False 2026-05-15 03:15:29.777439 | 2026-05-15 03:15:29.777580 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-05-15 03:15:30.584886 | debian-bookworm | changed 2026-05-15 03:15:30.598433 | 2026-05-15 03:15:30.598589 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-05-15 03:15:30.919339 | debian-bookworm | ok 2026-05-15 03:15:30.925054 | 2026-05-15 03:15:30.925190 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-05-15 03:15:31.784249 | debian-bookworm | changed 2026-05-15 03:15:31.792246 | 2026-05-15 03:15:31.792605 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-05-15 03:15:32.674719 | debian-bookworm | changed 2026-05-15 03:15:32.680511 | 2026-05-15 03:15:32.680605 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-05-15 03:15:32.728277 | debian-bookworm | skipping: Conditional result was False 2026-05-15 03:15:32.741478 | 2026-05-15 03:15:32.742991 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-05-15 03:15:33.728770 | debian-bookworm -> localhost | changed 2026-05-15 03:15:33.740613 | 2026-05-15 03:15:33.740722 | TASK [add-build-sshkey : Add back temp key] 2026-05-15 03:15:34.116397 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/e2b9e9d23a074eb1bd17cb1a8a0d8ffc/work/e2b9e9d23a074eb1bd17cb1a8a0d8ffc_id_rsa (zuul-build-sshkey) 2026-05-15 03:15:34.117065 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.009472 2026-05-15 03:15:34.132901 | 2026-05-15 03:15:34.133047 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-05-15 03:15:34.571231 | debian-bookworm | ok 2026-05-15 03:15:34.580332 | 2026-05-15 03:15:34.580567 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-05-15 03:15:34.604457 | debian-bookworm | skipping: Conditional result was False 2026-05-15 03:15:34.615112 | 2026-05-15 03:15:34.615229 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-05-15 03:15:35.030284 | debian-bookworm | ok 2026-05-15 03:15:35.050426 | 2026-05-15 03:15:35.050538 | TASK [validate-host : Define zuul_info_dir fact] 2026-05-15 03:15:35.115343 | debian-bookworm | ok 2026-05-15 03:15:35.122249 | 2026-05-15 03:15:35.122349 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-05-15 03:15:35.591260 | debian-bookworm -> localhost | ok 2026-05-15 03:15:35.599774 | 2026-05-15 03:15:35.599888 | TASK [validate-host : Collect information about the host] 2026-05-15 03:15:36.974590 | debian-bookworm | ok 2026-05-15 03:15:36.985871 | 2026-05-15 03:15:36.985972 | TASK [validate-host : Sanitize hostname] 2026-05-15 03:15:37.077113 | debian-bookworm | ok 2026-05-15 03:15:37.115334 | 2026-05-15 03:15:37.115440 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-05-15 03:15:38.132774 | debian-bookworm -> localhost | changed 2026-05-15 03:15:38.138349 | 2026-05-15 03:15:38.138443 | TASK [validate-host : Collect information about zuul worker] 2026-05-15 03:15:38.562152 | debian-bookworm | ok 2026-05-15 03:15:38.567352 | 2026-05-15 03:15:38.567456 | TASK [validate-host : Write out all zuul information for each host] 2026-05-15 03:15:39.198899 | debian-bookworm -> localhost | changed 2026-05-15 03:15:39.208473 | 2026-05-15 03:15:39.208575 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-05-15 03:15:39.538749 | debian-bookworm | ok 2026-05-15 03:15:39.543935 | 2026-05-15 03:15:39.544014 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-05-15 03:15:40.812416 | debian-bookworm | changed: 2026-05-15 03:15:40.812764 | debian-bookworm | cd+++++++++ src/ 2026-05-15 03:15:40.812811 | debian-bookworm | cd+++++++++ src/github.com/ 2026-05-15 03:15:40.812836 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-05-15 03:15:40.812859 | debian-bookworm | cd+++++++++ src/github.com/osism/openstack-image-manager/ 2026-05-15 03:15:40.812880 | debian-bookworm | localhost | ok: "/var/lib/zuul/builds/e2b9e9d23a074eb1bd17cb1a8a0d8ffc/work/logs" 2026-05-15 03:16:08.497073 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/e2b9e9d23a074eb1bd17cb1a8a0d8ffc/work/artifacts" 2026-05-15 03:16:08.743529 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/e2b9e9d23a074eb1bd17cb1a8a0d8ffc/work/docs" 2026-05-15 03:16:08.760723 | 2026-05-15 03:16:08.760824 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-05-15 03:16:09.664970 | debian-bookworm | changed: 2026-05-15 03:16:09.665238 | debian-bookworm | .d..t...... ./ 2026-05-15 03:16:09.665274 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-05-15 03:16:09.665305 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-05-15 03:16:09.665331 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-05-15 03:16:09.665375 | debian-bookworm | changed: All items complete 2026-05-15 03:16:09.665402 | 2026-05-15 03:16:10.382996 | debian-bookworm | changed: .d..t...... ./ 2026-05-15 03:16:11.001755 | debian-bookworm | changed: .d..t...... ./ 2026-05-15 03:16:11.030504 | 2026-05-15 03:16:11.030618 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-05-15 03:16:11.076082 | debian-bookworm | skipping: Conditional result was False 2026-05-15 03:16:11.092539 | debian-bookworm | skipping: Conditional result was False 2026-05-15 03:16:11.104594 | 2026-05-15 03:16:11.104688 | PLAY RECAP 2026-05-15 03:16:11.104742 | debian-bookworm | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0 2026-05-15 03:16:11.104768 | 2026-05-15 03:16:11.231202 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-05-15 03:16:11.231941 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-15 03:16:12.597578 | 2026-05-15 03:16:12.597718 | PLAY [Base post] 2026-05-15 03:16:12.629079 | 2026-05-15 03:16:12.629225 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-05-15 03:16:13.516603 | debian-bookworm | changed 2026-05-15 03:16:13.539688 | 2026-05-15 03:16:13.539801 | PLAY RECAP 2026-05-15 03:16:13.539865 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-05-15 03:16:13.539926 | 2026-05-15 03:16:13.820440 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-15 03:16:13.821377 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-05-15 03:16:15.493503 | 2026-05-15 03:16:15.493634 | PLAY [Base post-logs] 2026-05-15 03:16:15.521492 | 2026-05-15 03:16:15.521607 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-05-15 03:16:16.511578 | localhost | changed 2026-05-15 03:16:16.519454 | 2026-05-15 03:16:16.519569 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-05-15 03:16:16.545008 | localhost | ok 2026-05-15 03:16:16.548345 | 2026-05-15 03:16:16.548498 | TASK [Set zuul-log-path fact] 2026-05-15 03:16:16.563273 | localhost | ok 2026-05-15 03:16:16.569561 | 2026-05-15 03:16:16.569645 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-15 03:16:16.614395 | localhost | ok 2026-05-15 03:16:16.622066 | 2026-05-15 03:16:16.622160 | TASK [upload-logs : Create log directories] 2026-05-15 03:16:17.288379 | localhost | changed 2026-05-15 03:16:17.290453 | 2026-05-15 03:16:17.290525 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-05-15 03:16:18.195204 | localhost -> localhost | ok: Runtime: 0:00:00.034353 2026-05-15 03:16:18.199203 | 2026-05-15 03:16:18.199324 | TASK [upload-logs : Upload logs to log server] 2026-05-15 03:16:18.849021 | localhost | Output suppressed because no_log was given 2026-05-15 03:16:18.850755 | 2026-05-15 03:16:18.850964 | LOOP [upload-logs : Compress console log and json output] 2026-05-15 03:16:18.918311 | localhost | skipping: Conditional result was False 2026-05-15 03:16:18.923049 | localhost | skipping: Conditional result was False 2026-05-15 03:16:18.933717 | 2026-05-15 03:16:18.933859 | LOOP [upload-logs : Upload compressed console log and json output] 2026-05-15 03:16:18.990423 | localhost | skipping: Conditional result was False 2026-05-15 03:16:18.990649 | 2026-05-15 03:16:19.005263 | localhost | skipping: Conditional result was False 2026-05-15 03:16:19.010680 | 2026-05-15 03:16:19.010778 | LOOP [upload-logs : Upload console log and json output]