2026-05-25 03:11:53.575341 | Job console starting 2026-05-25 03:11:53.599372 | Updating git repos 2026-05-25 03:11:53.649772 | Cloning repos into workspace 2026-05-25 03:11:53.813626 | Restoring repo states 2026-05-25 03:11:53.833245 | Merging changes 2026-05-25 03:11:53.833271 | Checking out repos 2026-05-25 03:11:54.003197 | Preparing playbooks 2026-05-25 03:11:56.335458 | Running Ansible setup 2026-05-25 03:12:02.640215 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-05-25 03:12:04.072002 | 2026-05-25 03:12:04.072157 | PLAY [Base pre] 2026-05-25 03:12:04.109247 | 2026-05-25 03:12:04.109394 | TASK [Setup log path fact] 2026-05-25 03:12:04.152495 | debian-bookworm | ok 2026-05-25 03:12:04.188689 | 2026-05-25 03:12:04.188836 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-25 03:12:04.257638 | debian-bookworm | ok 2026-05-25 03:12:04.269810 | 2026-05-25 03:12:04.269924 | TASK [emit-job-header : Print job information] 2026-05-25 03:12:04.346410 | # Job Information 2026-05-25 03:12:04.346561 | Ansible Version: 2.16.14 2026-05-25 03:12:04.346591 | Job: python-black 2026-05-25 03:12:04.346619 | Pipeline: periodic-daily 2026-05-25 03:12:04.346638 | Executor: 521e9411259a 2026-05-25 03:12:04.346655 | Triggered by: https://github.com/osism/cfg-cookiecutter 2026-05-25 03:12:04.346673 | Event ID: ba79d702c1054b86ab942e910f5346f1 2026-05-25 03:12:04.350694 | 2026-05-25 03:12:04.350785 | LOOP [emit-job-header : Print node information] 2026-05-25 03:12:04.529255 | debian-bookworm | ok: 2026-05-25 03:12:04.529585 | debian-bookworm | # Node Information 2026-05-25 03:12:04.529621 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-05-25 03:12:04.529647 | debian-bookworm | Hostname: debian 2026-05-25 03:12:04.529669 | debian-bookworm | Username: zuul 2026-05-25 03:12:04.529690 | debian-bookworm | Distro: Debian 12.14 2026-05-25 03:12:04.529715 | debian-bookworm | Provider: regiocloud-a 2026-05-25 03:12:04.529735 | debian-bookworm | Region: 2026-05-25 03:12:04.529756 | debian-bookworm | Label: debian-bookworm 2026-05-25 03:12:04.529776 | debian-bookworm | Product Name: OpenStack Nova 2026-05-25 03:12:04.529795 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe5c:de64 2026-05-25 03:12:04.541883 | 2026-05-25 03:12:04.541991 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-05-25 03:12:05.956824 | debian-bookworm -> localhost | changed 2026-05-25 03:12:05.964123 | 2026-05-25 03:12:05.964244 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-05-25 03:12:08.913374 | debian-bookworm -> localhost | changed 2026-05-25 03:12:08.924628 | 2026-05-25 03:12:08.924760 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-05-25 03:12:10.086841 | debian-bookworm -> localhost | ok 2026-05-25 03:12:10.092993 | 2026-05-25 03:12:10.093129 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-05-25 03:12:10.133468 | debian-bookworm | ok 2026-05-25 03:12:10.177060 | debian-bookworm | included: /var/lib/zuul/builds/efcb862d261c4f79b02c635e1f29e1a3/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-05-25 03:12:10.204471 | 2026-05-25 03:12:10.204599 | TASK [add-build-sshkey : Create Temp SSH key] 2026-05-25 03:12:13.095050 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-05-25 03:12:13.095271 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/efcb862d261c4f79b02c635e1f29e1a3/work/efcb862d261c4f79b02c635e1f29e1a3_id_rsa 2026-05-25 03:12:13.095309 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/efcb862d261c4f79b02c635e1f29e1a3/work/efcb862d261c4f79b02c635e1f29e1a3_id_rsa.pub 2026-05-25 03:12:13.095334 | debian-bookworm -> localhost | The key fingerprint is: 2026-05-25 03:12:13.095357 | debian-bookworm -> localhost | SHA256:MfV1IQdy5mM8MzztnyEhTLWC+072jb5RWLyYdLZDgfo zuul-build-sshkey 2026-05-25 03:12:13.095380 | debian-bookworm -> localhost | The key's randomart image is: 2026-05-25 03:12:13.095414 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-05-25 03:12:13.095436 | debian-bookworm -> localhost | | .o.*++o| 2026-05-25 03:12:13.095458 | debian-bookworm -> localhost | | .+.Bo*..| 2026-05-25 03:12:13.095478 | debian-bookworm -> localhost | | o. +o# B | 2026-05-25 03:12:13.095497 | debian-bookworm -> localhost | | o..= ^ o| 2026-05-25 03:12:13.095516 | debian-bookworm -> localhost | | S. .= B | 2026-05-25 03:12:13.095541 | debian-bookworm -> localhost | | . Eo =| 2026-05-25 03:12:13.095561 | debian-bookworm -> localhost | | + . ..| 2026-05-25 03:12:13.095580 | debian-bookworm -> localhost | | + . + | 2026-05-25 03:12:13.095600 | debian-bookworm -> localhost | | ..=.. | 2026-05-25 03:12:13.095620 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-05-25 03:12:13.095669 | debian-bookworm -> localhost | ok: Runtime: 0:00:01.365641 2026-05-25 03:12:13.101813 | 2026-05-25 03:12:13.101903 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-05-25 03:12:13.143158 | debian-bookworm | ok 2026-05-25 03:12:13.190704 | debian-bookworm | included: /var/lib/zuul/builds/efcb862d261c4f79b02c635e1f29e1a3/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-05-25 03:12:13.214741 | 2026-05-25 03:12:13.237485 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-05-25 03:12:13.283896 | debian-bookworm | skipping: Conditional result was False 2026-05-25 03:12:13.289351 | 2026-05-25 03:12:13.289456 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-05-25 03:12:14.156145 | debian-bookworm | changed 2026-05-25 03:12:14.179689 | 2026-05-25 03:12:14.179806 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-05-25 03:12:14.465384 | debian-bookworm | ok 2026-05-25 03:12:14.469932 | 2026-05-25 03:12:14.470014 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-05-25 03:12:15.263778 | debian-bookworm | changed 2026-05-25 03:12:15.276563 | 2026-05-25 03:12:15.276689 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-05-25 03:12:16.046341 | debian-bookworm | changed 2026-05-25 03:12:16.051379 | 2026-05-25 03:12:16.051480 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-05-25 03:12:16.100776 | debian-bookworm | skipping: Conditional result was False 2026-05-25 03:12:16.106018 | 2026-05-25 03:12:16.106140 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-05-25 03:12:17.527691 | debian-bookworm -> localhost | changed 2026-05-25 03:12:17.538694 | 2026-05-25 03:12:17.538799 | TASK [add-build-sshkey : Add back temp key] 2026-05-25 03:12:18.316252 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/efcb862d261c4f79b02c635e1f29e1a3/work/efcb862d261c4f79b02c635e1f29e1a3_id_rsa (zuul-build-sshkey) 2026-05-25 03:12:18.316460 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.024623 2026-05-25 03:12:18.323857 | 2026-05-25 03:12:18.323957 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-05-25 03:12:18.872842 | debian-bookworm | ok 2026-05-25 03:12:18.877298 | 2026-05-25 03:12:18.877385 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-05-25 03:12:18.942975 | debian-bookworm | skipping: Conditional result was False 2026-05-25 03:12:18.953150 | 2026-05-25 03:12:18.953242 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-05-25 03:12:19.919562 | debian-bookworm | ok 2026-05-25 03:12:19.956421 | 2026-05-25 03:12:19.956543 | TASK [validate-host : Define zuul_info_dir fact] 2026-05-25 03:12:20.022019 | debian-bookworm | ok 2026-05-25 03:12:20.059467 | 2026-05-25 03:12:20.059585 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-05-25 03:12:20.962649 | debian-bookworm -> localhost | ok 2026-05-25 03:12:20.970286 | 2026-05-25 03:12:20.970392 | TASK [validate-host : Collect information about the host] 2026-05-25 03:12:23.168336 | debian-bookworm | ok 2026-05-25 03:12:23.192144 | 2026-05-25 03:12:23.192268 | TASK [validate-host : Sanitize hostname] 2026-05-25 03:12:23.621139 | debian-bookworm | ok 2026-05-25 03:12:23.863935 | 2026-05-25 03:12:23.864066 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-05-25 03:12:25.841618 | debian-bookworm -> localhost | changed 2026-05-25 03:12:25.846196 | 2026-05-25 03:12:25.846277 | TASK [validate-host : Collect information about zuul worker] 2026-05-25 03:12:26.660190 | debian-bookworm | ok 2026-05-25 03:12:26.664036 | 2026-05-25 03:12:26.664148 | TASK [validate-host : Write out all zuul information for each host] 2026-05-25 03:12:28.599580 | debian-bookworm -> localhost | changed 2026-05-25 03:12:28.607738 | 2026-05-25 03:12:28.607824 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-05-25 03:12:28.879642 | debian-bookworm | ok 2026-05-25 03:12:28.885177 | 2026-05-25 03:12:28.885264 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-05-25 03:12:31.404013 | debian-bookworm | changed: 2026-05-25 03:12:31.404185 | debian-bookworm | cd+++++++++ src/ 2026-05-25 03:12:31.404217 | debian-bookworm | cd+++++++++ src/github.com/ 2026-05-25 03:12:31.404237 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-05-25 03:12:31.404255 | debian-bookworm | cd+++++++++ src/github.com/osism/cfg-cookiecutter/ 2026-05-25 03:12:31.404272 | debian-bookworm | Containerfile 2026-05-25 03:12:31.404377 | debian-bookworm | localhost | ok: "/var/lib/zuul/builds/efcb862d261c4f79b02c635e1f29e1a3/work/logs" 2026-05-25 03:13:01.239869 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/efcb862d261c4f79b02c635e1f29e1a3/work/artifacts" 2026-05-25 03:13:01.481582 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/efcb862d261c4f79b02c635e1f29e1a3/work/docs" 2026-05-25 03:13:01.493193 | 2026-05-25 03:13:01.493317 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-05-25 03:13:02.284888 | debian-bookworm | changed: 2026-05-25 03:13:02.285110 | debian-bookworm | .d..t...... ./ 2026-05-25 03:13:02.285144 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-05-25 03:13:02.285175 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-05-25 03:13:02.285200 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-05-25 03:13:02.285236 | debian-bookworm | changed: All items complete 2026-05-25 03:13:02.285259 | 2026-05-25 03:13:02.879082 | debian-bookworm | changed: .d..t...... ./ 2026-05-25 03:13:03.534761 | debian-bookworm | changed: .d..t...... ./ 2026-05-25 03:13:03.547379 | 2026-05-25 03:13:03.547503 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-05-25 03:13:03.580293 | debian-bookworm | skipping: Conditional result was False 2026-05-25 03:13:03.583976 | debian-bookworm | skipping: Conditional result was False 2026-05-25 03:13:03.614568 | 2026-05-25 03:13:03.614780 | PLAY RECAP 2026-05-25 03:13:03.614918 | debian-bookworm | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0 2026-05-25 03:13:03.614972 | 2026-05-25 03:13:03.736901 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-05-25 03:13:03.737770 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-25 03:13:04.554345 | 2026-05-25 03:13:04.554555 | PLAY [Base post] 2026-05-25 03:13:04.589763 | 2026-05-25 03:13:04.589962 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-05-25 03:13:05.194023 | debian-bookworm | changed 2026-05-25 03:13:05.200371 | 2026-05-25 03:13:05.200827 | PLAY RECAP 2026-05-25 03:13:05.200944 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-05-25 03:13:05.201707 | 2026-05-25 03:13:05.415544 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-25 03:13:05.416583 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-05-25 03:13:06.522363 | 2026-05-25 03:13:06.522585 | PLAY [Base post-logs] 2026-05-25 03:13:06.537583 | 2026-05-25 03:13:06.537771 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-05-25 03:13:07.165374 | localhost | changed 2026-05-25 03:13:07.178890 | 2026-05-25 03:13:07.179114 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-05-25 03:13:07.244771 | localhost | ok 2026-05-25 03:13:07.252305 | 2026-05-25 03:13:07.252443 | TASK [Set zuul-log-path fact] 2026-05-25 03:13:07.270608 | localhost | ok 2026-05-25 03:13:07.291820 | 2026-05-25 03:13:07.292012 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-25 03:13:07.343383 | localhost | ok 2026-05-25 03:13:07.347177 | 2026-05-25 03:13:07.347543 | TASK [upload-logs : Create log directories] 2026-05-25 03:13:08.214027 | localhost | changed 2026-05-25 03:13:08.217405 | 2026-05-25 03:13:08.217547 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-05-25 03:13:08.789749 | localhost -> localhost | ok: Runtime: 0:00:00.007510 2026-05-25 03:13:08.794643 | 2026-05-25 03:13:08.794798 | TASK [upload-logs : Upload logs to log server] 2026-05-25 03:13:09.435635 | localhost | Output suppressed because no_log was given 2026-05-25 03:13:09.437793 | 2026-05-25 03:13:09.437912 | LOOP [upload-logs : Compress console log and json output] 2026-05-25 03:13:09.499114 | localhost | skipping: Conditional result was False 2026-05-25 03:13:09.507559 | localhost | skipping: Conditional result was False 2026-05-25 03:13:09.516612 | 2026-05-25 03:13:09.516786 | LOOP [upload-logs : Upload compressed console log and json output] 2026-05-25 03:13:09.580085 | localhost | skipping: Conditional result was False 2026-05-25 03:13:09.580414 | 2026-05-25 03:13:09.585861 | localhost | skipping: Conditional result was False 2026-05-25 03:13:09.595402 | 2026-05-25 03:13:09.595593 | LOOP [upload-logs : Upload console log and json output]