2025-12-05 08:16:08.897726 | Job console starting 2025-12-05 08:16:08.914029 | Updating git repos 2025-12-05 08:16:08.990414 | Cloning repos into workspace 2025-12-05 08:16:09.103637 | Restoring repo states 2025-12-05 08:16:09.142031 | Merging changes 2025-12-05 08:16:10.031941 | Checking out repos 2025-12-05 08:16:10.153847 | Preparing playbooks 2025-12-05 08:16:11.456822 | Running Ansible setup 2025-12-05 08:16:18.163752 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-12-05 08:16:19.682583 | 2025-12-05 08:16:19.682764 | PLAY [Base pre] 2025-12-05 08:16:19.720541 | 2025-12-05 08:16:19.720723 | TASK [Setup log path fact] 2025-12-05 08:16:19.765696 | debian-bookworm | ok 2025-12-05 08:16:19.794314 | 2025-12-05 08:16:19.794491 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-12-05 08:16:19.879715 | debian-bookworm | ok 2025-12-05 08:16:19.900443 | 2025-12-05 08:16:19.900591 | TASK [emit-job-header : Print job information] 2025-12-05 08:16:19.961745 | # Job Information 2025-12-05 08:16:19.967212 | Ansible Version: 2.16.14 2025-12-05 08:16:19.967344 | Job: python-black 2025-12-05 08:16:19.967438 | Pipeline: check 2025-12-05 08:16:19.967503 | Executor: 521e9411259a 2025-12-05 08:16:19.967558 | Triggered by: https://github.com/osism/python-osism/pull/1898 2025-12-05 08:16:19.967617 | Event ID: a1f0ebb0-d1b2-11f0-9a26-8a72fd1aa6a3 2025-12-05 08:16:19.987563 | 2025-12-05 08:16:19.987724 | LOOP [emit-job-header : Print node information] 2025-12-05 08:16:20.217200 | debian-bookworm | ok: 2025-12-05 08:16:20.217785 | debian-bookworm | # Node Information 2025-12-05 08:16:20.217830 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-12-05 08:16:20.217857 | debian-bookworm | Hostname: debian 2025-12-05 08:16:20.217880 | debian-bookworm | Username: zuul 2025-12-05 08:16:20.217902 | debian-bookworm | Distro: Debian 12.12 2025-12-05 08:16:20.217926 | debian-bookworm | Provider: regiocloud-a 2025-12-05 08:16:20.217947 | debian-bookworm | Region: 2025-12-05 08:16:20.217987 | debian-bookworm | Label: debian-bookworm 2025-12-05 08:16:20.218009 | debian-bookworm | Product Name: OpenStack Nova 2025-12-05 08:16:20.218029 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe10:301e 2025-12-05 08:16:20.242146 | 2025-12-05 08:16:20.242316 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-12-05 08:16:21.260553 | debian-bookworm -> localhost | changed 2025-12-05 08:16:21.268260 | 2025-12-05 08:16:21.268422 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-12-05 08:16:23.366413 | debian-bookworm -> localhost | changed 2025-12-05 08:16:23.379175 | 2025-12-05 08:16:23.379370 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-12-05 08:16:23.954677 | debian-bookworm -> localhost | ok 2025-12-05 08:16:23.963681 | 2025-12-05 08:16:23.963813 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-12-05 08:16:24.003249 | debian-bookworm | ok 2025-12-05 08:16:24.045285 | debian-bookworm | included: /var/lib/zuul/builds/a186690c89284820b0d2886f307cbc4d/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-12-05 08:16:24.063480 | 2025-12-05 08:16:24.063635 | TASK [add-build-sshkey : Create Temp SSH key] 2025-12-05 08:16:26.041151 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-12-05 08:16:26.041434 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/a186690c89284820b0d2886f307cbc4d/work/a186690c89284820b0d2886f307cbc4d_id_rsa 2025-12-05 08:16:26.041477 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/a186690c89284820b0d2886f307cbc4d/work/a186690c89284820b0d2886f307cbc4d_id_rsa.pub 2025-12-05 08:16:26.041505 | debian-bookworm -> localhost | The key fingerprint is: 2025-12-05 08:16:26.041528 | debian-bookworm -> localhost | SHA256:0oqwOK6+Y7L/acyXuHbLb1MSkatN/6cOUt9/BESfOS0 zuul-build-sshkey 2025-12-05 08:16:26.041550 | debian-bookworm -> localhost | The key's randomart image is: 2025-12-05 08:16:26.041582 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-12-05 08:16:26.041604 | debian-bookworm -> localhost | | . .. | 2025-12-05 08:16:26.041626 | debian-bookworm -> localhost | | o ..+| 2025-12-05 08:16:26.041646 | debian-bookworm -> localhost | | o .E+o| 2025-12-05 08:16:26.041666 | debian-bookworm -> localhost | | .+ ...| 2025-12-05 08:16:26.041685 | debian-bookworm -> localhost | | . .+So. . | 2025-12-05 08:16:26.041708 | debian-bookworm -> localhost | | . o ..oo.o. . .| 2025-12-05 08:16:26.041728 | debian-bookworm -> localhost | |o .o.....o... .. | 2025-12-05 08:16:26.041748 | debian-bookworm -> localhost | |o+ *o+ o. .. ...| 2025-12-05 08:16:26.041770 | debian-bookworm -> localhost | |B*++++o+.. .oo o| 2025-12-05 08:16:26.041790 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-12-05 08:16:26.041844 | debian-bookworm -> localhost | ok: Runtime: 0:00:01.236542 2025-12-05 08:16:26.048602 | 2025-12-05 08:16:26.048725 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-12-05 08:16:26.077337 | debian-bookworm | ok 2025-12-05 08:16:26.098391 | debian-bookworm | included: /var/lib/zuul/builds/a186690c89284820b0d2886f307cbc4d/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-12-05 08:16:26.127556 | 2025-12-05 08:16:26.127686 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-12-05 08:16:26.161754 | debian-bookworm | skipping: Conditional result was False 2025-12-05 08:16:26.175391 | 2025-12-05 08:16:26.175520 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-12-05 08:16:27.032462 | debian-bookworm | changed 2025-12-05 08:16:27.038509 | 2025-12-05 08:16:27.038682 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-12-05 08:16:27.325783 | debian-bookworm | ok 2025-12-05 08:16:27.335408 | 2025-12-05 08:16:27.335580 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-12-05 08:16:28.007135 | debian-bookworm | changed 2025-12-05 08:16:28.014533 | 2025-12-05 08:16:28.014665 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-12-05 08:16:28.687236 | debian-bookworm | changed 2025-12-05 08:16:28.696617 | 2025-12-05 08:16:28.696747 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-12-05 08:16:28.720906 | debian-bookworm | skipping: Conditional result was False 2025-12-05 08:16:28.727495 | 2025-12-05 08:16:28.727619 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-12-05 08:16:29.292763 | debian-bookworm -> localhost | changed 2025-12-05 08:16:29.304739 | 2025-12-05 08:16:29.304896 | TASK [add-build-sshkey : Add back temp key] 2025-12-05 08:16:29.889144 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/a186690c89284820b0d2886f307cbc4d/work/a186690c89284820b0d2886f307cbc4d_id_rsa (zuul-build-sshkey) 2025-12-05 08:16:29.889401 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.018724 2025-12-05 08:16:29.897861 | 2025-12-05 08:16:29.898009 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-12-05 08:16:30.484203 | debian-bookworm | ok 2025-12-05 08:16:30.491550 | 2025-12-05 08:16:30.491693 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-12-05 08:16:30.515869 | debian-bookworm | skipping: Conditional result was False 2025-12-05 08:16:30.527439 | 2025-12-05 08:16:30.527586 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-12-05 08:16:31.019551 | debian-bookworm | ok 2025-12-05 08:16:31.043924 | 2025-12-05 08:16:31.044096 | TASK [validate-host : Define zuul_info_dir fact] 2025-12-05 08:16:31.094563 | debian-bookworm | ok 2025-12-05 08:16:31.100306 | 2025-12-05 08:16:31.100434 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-12-05 08:16:31.463770 | debian-bookworm -> localhost | ok 2025-12-05 08:16:31.470531 | 2025-12-05 08:16:31.470649 | TASK [validate-host : Collect information about the host] 2025-12-05 08:16:32.880088 | debian-bookworm | ok 2025-12-05 08:16:32.904999 | 2025-12-05 08:16:32.905150 | TASK [validate-host : Sanitize hostname] 2025-12-05 08:16:33.017484 | debian-bookworm | ok 2025-12-05 08:16:33.069187 | 2025-12-05 08:16:33.069336 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-12-05 08:16:33.903173 | debian-bookworm -> localhost | changed 2025-12-05 08:16:33.909645 | 2025-12-05 08:16:33.909775 | TASK [validate-host : Collect information about zuul worker] 2025-12-05 08:16:34.448535 | debian-bookworm | ok 2025-12-05 08:16:34.453675 | 2025-12-05 08:16:34.453811 | TASK [validate-host : Write out all zuul information for each host] 2025-12-05 08:16:35.499935 | debian-bookworm -> localhost | changed 2025-12-05 08:16:35.531200 | 2025-12-05 08:16:35.531345 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-12-05 08:16:35.818202 | debian-bookworm | ok 2025-12-05 08:16:35.824218 | 2025-12-05 08:16:35.824346 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-12-05 08:16:37.216730 | debian-bookworm | changed: 2025-12-05 08:16:37.217713 | debian-bookworm | cd+++++++++ src/ 2025-12-05 08:16:37.217809 | debian-bookworm | cd+++++++++ src/github.com/ 2025-12-05 08:16:37.218324 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-12-05 08:16:37.218367 | debian-bookworm | cd+++++++++ src/github.com/osism/python-osism/ 2025-12-05 08:16:37.218391 | debian-bookworm | Containerfile 2025-12-05 08:16:37.218584 | debian-bookworm | Containerfile 2025-12-05 08:16:37.305911 | debian-bookworm | localhost | ok: Runtime: 0:00:00.006085 2025-12-05 08:17:06.546615 | 2025-12-05 08:17:06.546779 | TASK [upload-logs : Upload logs to log server] 2025-12-05 08:17:07.161121 | localhost | Output suppressed because no_log was given 2025-12-05 08:17:07.165638 | 2025-12-05 08:17:07.165846 | LOOP [upload-logs : Compress console log and json output] 2025-12-05 08:17:07.246635 | localhost | skipping: Conditional result was False 2025-12-05 08:17:07.255567 | localhost | skipping: Conditional result was False 2025-12-05 08:17:07.268120 | 2025-12-05 08:17:07.268311 | LOOP [upload-logs : Upload compressed console log and json output] 2025-12-05 08:17:07.321093 | localhost | skipping: Conditional result was False 2025-12-05 08:17:07.321471 | 2025-12-05 08:17:07.328847 | localhost | skipping: Conditional result was False 2025-12-05 08:17:07.337061 | 2025-12-05 08:17:07.337198 | LOOP [upload-logs : Upload console log and json output]