2026-05-06 03:08:57.476348 | Job console starting 2026-05-06 03:08:57.485570 | Updating git repos 2026-05-06 03:08:57.513255 | Cloning repos into workspace 2026-05-06 03:08:57.575166 | Restoring repo states 2026-05-06 03:08:57.598262 | Merging changes 2026-05-06 03:08:57.598280 | Checking out repos 2026-05-06 03:08:57.672209 | Preparing playbooks 2026-05-06 03:08:58.549480 | Running Ansible setup 2026-05-06 03:09:03.047806 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-05-06 03:09:04.510988 | 2026-05-06 03:09:04.511461 | PLAY [Base pre] 2026-05-06 03:09:04.536070 | 2026-05-06 03:09:04.536257 | TASK [Setup log path fact] 2026-05-06 03:09:04.560907 | debian-bookworm | ok 2026-05-06 03:09:04.582032 | 2026-05-06 03:09:04.582221 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-06 03:09:04.634924 | debian-bookworm | ok 2026-05-06 03:09:04.645590 | 2026-05-06 03:09:04.645738 | TASK [emit-job-header : Print job information] 2026-05-06 03:09:04.696340 | # Job Information 2026-05-06 03:09:04.696534 | Ansible Version: 2.16.14 2026-05-06 03:09:04.696586 | Job: python-osism-test-setup 2026-05-06 03:09:04.696618 | Pipeline: periodic-daily 2026-05-06 03:09:04.696641 | Executor: 521e9411259a 2026-05-06 03:09:04.696662 | Triggered by: https://github.com/osism/python-osism 2026-05-06 03:09:04.696684 | Event ID: c356d19ef1984738b2ddc07046d5ee1f 2026-05-06 03:09:04.703238 | 2026-05-06 03:09:04.703367 | LOOP [emit-job-header : Print node information] 2026-05-06 03:09:04.842989 | debian-bookworm | ok: 2026-05-06 03:09:04.843199 | debian-bookworm | # Node Information 2026-05-06 03:09:04.843234 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-05-06 03:09:04.843259 | debian-bookworm | Hostname: debian 2026-05-06 03:09:04.843281 | debian-bookworm | Username: zuul 2026-05-06 03:09:04.843302 | debian-bookworm | Distro: Debian 12.13 2026-05-06 03:09:04.843325 | debian-bookworm | Provider: regiocloud-a 2026-05-06 03:09:04.843346 | debian-bookworm | Region: 2026-05-06 03:09:04.843367 | debian-bookworm | Label: debian-bookworm 2026-05-06 03:09:04.843387 | debian-bookworm | Product Name: OpenStack Nova 2026-05-06 03:09:04.843407 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe1c:5c3c 2026-05-06 03:09:04.885392 | 2026-05-06 03:09:04.885594 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-05-06 03:09:05.467237 | debian-bookworm -> localhost | changed 2026-05-06 03:09:05.476306 | 2026-05-06 03:09:05.476469 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-05-06 03:09:07.689923 | debian-bookworm -> localhost | changed 2026-05-06 03:09:07.715113 | 2026-05-06 03:09:07.716656 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-05-06 03:09:08.357613 | debian-bookworm -> localhost | ok 2026-05-06 03:09:08.363489 | 2026-05-06 03:09:08.363610 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-05-06 03:09:08.394623 | debian-bookworm | ok 2026-05-06 03:09:08.428914 | debian-bookworm | included: /var/lib/zuul/builds/68c9c8ab06e14006b681d4fc1c44f99e/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-05-06 03:09:08.441063 | 2026-05-06 03:09:08.441167 | TASK [add-build-sshkey : Create Temp SSH key] 2026-05-06 03:09:10.135228 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-05-06 03:09:10.135395 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/68c9c8ab06e14006b681d4fc1c44f99e/work/68c9c8ab06e14006b681d4fc1c44f99e_id_rsa 2026-05-06 03:09:10.135427 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/68c9c8ab06e14006b681d4fc1c44f99e/work/68c9c8ab06e14006b681d4fc1c44f99e_id_rsa.pub 2026-05-06 03:09:10.135449 | debian-bookworm -> localhost | The key fingerprint is: 2026-05-06 03:09:10.135470 | debian-bookworm -> localhost | SHA256:+owFMbd9d7jQQPJ6SWTGtkC2vBKJrscdPvwoQWAvROo zuul-build-sshkey 2026-05-06 03:09:10.135488 | debian-bookworm -> localhost | The key's randomart image is: 2026-05-06 03:09:10.135512 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-05-06 03:09:10.135530 | debian-bookworm -> localhost | | .. .+.= | 2026-05-06 03:09:10.135574 | debian-bookworm -> localhost | | .+ . +.Oo | 2026-05-06 03:09:10.135594 | debian-bookworm -> localhost | | .o o+ + oo+. | 2026-05-06 03:09:10.135611 | debian-bookworm -> localhost | | . ..o+ + +.+ . | 2026-05-06 03:09:10.135627 | debian-bookworm -> localhost | | E oo S + = + .| 2026-05-06 03:09:10.135648 | debian-bookworm -> localhost | | o.* o o o o | 2026-05-06 03:09:10.135665 | debian-bookworm -> localhost | | . +.* . | 2026-05-06 03:09:10.135681 | debian-bookworm -> localhost | | ..= + | 2026-05-06 03:09:10.135698 | debian-bookworm -> localhost | | ..+ . | 2026-05-06 03:09:10.135714 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-05-06 03:09:10.135762 | debian-bookworm -> localhost | ok: Runtime: 0:00:01.166080 2026-05-06 03:09:10.142154 | 2026-05-06 03:09:10.142241 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-05-06 03:09:10.169303 | debian-bookworm | ok 2026-05-06 03:09:10.186154 | debian-bookworm | included: /var/lib/zuul/builds/68c9c8ab06e14006b681d4fc1c44f99e/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-05-06 03:09:10.193831 | 2026-05-06 03:09:10.193918 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-05-06 03:09:10.217018 | debian-bookworm | skipping: Conditional result was False 2026-05-06 03:09:10.222071 | 2026-05-06 03:09:10.222167 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-05-06 03:09:10.872261 | debian-bookworm | changed 2026-05-06 03:09:10.879791 | 2026-05-06 03:09:10.880057 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-05-06 03:09:11.148853 | debian-bookworm | ok 2026-05-06 03:09:11.155487 | 2026-05-06 03:09:11.155633 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-05-06 03:09:11.820210 | debian-bookworm | changed 2026-05-06 03:09:11.826023 | 2026-05-06 03:09:11.826117 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-05-06 03:09:12.484070 | debian-bookworm | changed 2026-05-06 03:09:12.494602 | 2026-05-06 03:09:12.494739 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-05-06 03:09:12.519194 | debian-bookworm | skipping: Conditional result was False 2026-05-06 03:09:12.525475 | 2026-05-06 03:09:12.525620 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-05-06 03:09:12.943793 | debian-bookworm -> localhost | changed 2026-05-06 03:09:12.957760 | 2026-05-06 03:09:12.957866 | TASK [add-build-sshkey : Add back temp key] 2026-05-06 03:09:13.293767 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/68c9c8ab06e14006b681d4fc1c44f99e/work/68c9c8ab06e14006b681d4fc1c44f99e_id_rsa (zuul-build-sshkey) 2026-05-06 03:09:13.293978 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.014793 2026-05-06 03:09:13.301538 | 2026-05-06 03:09:13.301660 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-05-06 03:09:13.740953 | debian-bookworm | ok 2026-05-06 03:09:13.746711 | 2026-05-06 03:09:13.746812 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-05-06 03:09:13.772581 | debian-bookworm | skipping: Conditional result was False 2026-05-06 03:09:13.782759 | 2026-05-06 03:09:13.782880 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-05-06 03:09:14.250229 | debian-bookworm | ok 2026-05-06 03:09:14.270117 | 2026-05-06 03:09:14.270228 | TASK [validate-host : Define zuul_info_dir fact] 2026-05-06 03:09:14.297808 | debian-bookworm | ok 2026-05-06 03:09:14.302966 | 2026-05-06 03:09:14.303065 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-05-06 03:09:14.578758 | debian-bookworm -> localhost | ok 2026-05-06 03:09:14.584981 | 2026-05-06 03:09:14.585083 | TASK [validate-host : Collect information about the host] 2026-05-06 03:09:15.700479 | debian-bookworm | ok 2026-05-06 03:09:15.726503 | 2026-05-06 03:09:15.726710 | TASK [validate-host : Sanitize hostname] 2026-05-06 03:09:15.871867 | debian-bookworm | ok 2026-05-06 03:09:15.918657 | 2026-05-06 03:09:15.918806 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-05-06 03:09:16.596262 | debian-bookworm -> localhost | changed 2026-05-06 03:09:16.622077 | 2026-05-06 03:09:16.622254 | TASK [validate-host : Collect information about zuul worker] 2026-05-06 03:09:17.144538 | debian-bookworm | ok 2026-05-06 03:09:17.161194 | 2026-05-06 03:09:17.164777 | TASK [validate-host : Write out all zuul information for each host] 2026-05-06 03:09:17.874457 | debian-bookworm -> localhost | changed 2026-05-06 03:09:17.884892 | 2026-05-06 03:09:17.885163 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-05-06 03:09:18.192118 | debian-bookworm | ok 2026-05-06 03:09:18.203295 | 2026-05-06 03:09:18.203472 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-05-06 03:09:19.449973 | debian-bookworm | changed: 2026-05-06 03:09:19.450255 | debian-bookworm | cd+++++++++ src/ 2026-05-06 03:09:19.450308 | debian-bookworm | cd+++++++++ src/github.com/ 2026-05-06 03:09:19.450345 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-05-06 03:09:19.450378 | debian-bookworm | cd+++++++++ src/github.com/osism/python-osism/ 2026-05-06 03:09:19.450409 | debian-bookworm | Containerfile 2026-05-06 03:09:19.450630 | debian-bookworm | Containerfile 2026-05-06 03:09:19.478728 | debian-bookworm | localhost | ok: "/var/lib/zuul/builds/68c9c8ab06e14006b681d4fc1c44f99e/work/logs" 2026-05-06 03:22:31.136377 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/68c9c8ab06e14006b681d4fc1c44f99e/work/artifacts" 2026-05-06 03:22:31.429872 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/68c9c8ab06e14006b681d4fc1c44f99e/work/docs" 2026-05-06 03:22:31.445858 | 2026-05-06 03:22:31.445990 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-05-06 03:22:32.311992 | debian-bookworm | changed: 2026-05-06 03:22:32.312269 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-05-06 03:22:32.312312 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-05-06 03:22:32.312349 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-05-06 03:22:32.312403 | debian-bookworm | changed: All items complete 2026-05-06 03:22:32.312454 | 2026-05-06 03:22:32.935269 | debian-bookworm | changed: .d..t...... ./ 2026-05-06 03:22:33.584164 | debian-bookworm | changed: .d..t...... ./ 2026-05-06 03:22:33.598125 | 2026-05-06 03:22:33.598269 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-05-06 03:22:33.627368 | debian-bookworm | skipping: Conditional result was False 2026-05-06 03:22:33.631359 | debian-bookworm | skipping: Conditional result was False 2026-05-06 03:22:33.641735 | 2026-05-06 03:22:33.641836 | PLAY RECAP 2026-05-06 03:22:33.641889 | debian-bookworm | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0 2026-05-06 03:22:33.641914 | 2026-05-06 03:22:33.791165 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-05-06 03:22:33.793266 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-06 03:22:34.542285 | 2026-05-06 03:22:34.542474 | PLAY [Base post] 2026-05-06 03:22:34.556488 | 2026-05-06 03:22:34.556635 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-05-06 03:22:35.465008 | debian-bookworm | changed 2026-05-06 03:22:35.474619 | 2026-05-06 03:22:35.474919 | PLAY RECAP 2026-05-06 03:22:35.475067 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-05-06 03:22:35.475208 | 2026-05-06 03:22:35.620730 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-06 03:22:35.622564 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-05-06 03:22:36.426189 | 2026-05-06 03:22:36.426362 | PLAY [Base post-logs] 2026-05-06 03:22:36.437392 | 2026-05-06 03:22:36.437550 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-05-06 03:22:36.950009 | localhost | changed 2026-05-06 03:22:36.960208 | 2026-05-06 03:22:36.960378 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-05-06 03:22:36.996827 | localhost | ok 2026-05-06 03:22:37.001708 | 2026-05-06 03:22:37.001842 | TASK [Set zuul-log-path fact] 2026-05-06 03:22:37.022361 | localhost | ok 2026-05-06 03:22:37.038274 | 2026-05-06 03:22:37.038472 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-06 03:22:37.078135 | localhost | ok 2026-05-06 03:22:37.087730 | 2026-05-06 03:22:37.087909 | TASK [upload-logs : Create log directories] 2026-05-06 03:22:37.651627 | localhost | changed 2026-05-06 03:22:37.657384 | 2026-05-06 03:22:37.657615 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-05-06 03:22:38.182173 | localhost -> localhost | ok: Runtime: 0:00:00.007861 2026-05-06 03:22:38.186724 | 2026-05-06 03:22:38.186894 | TASK [upload-logs : Upload logs to log server] 2026-05-06 03:22:38.805739 | localhost | Output suppressed because no_log was given 2026-05-06 03:22:38.808162 | 2026-05-06 03:22:38.808296 | LOOP [upload-logs : Compress console log and json output] 2026-05-06 03:22:38.863204 | localhost | skipping: Conditional result was False 2026-05-06 03:22:38.868120 | localhost | skipping: Conditional result was False 2026-05-06 03:22:38.881693 | 2026-05-06 03:22:38.881958 | LOOP [upload-logs : Upload compressed console log and json output] 2026-05-06 03:22:38.936058 | localhost | skipping: Conditional result was False 2026-05-06 03:22:38.936691 | 2026-05-06 03:22:38.942455 | localhost | skipping: Conditional result was False 2026-05-06 03:22:38.950587 | 2026-05-06 03:22:38.950781 | LOOP [upload-logs : Upload console log and json output]