2026-09-24 03:20:07.517073 | Job console starting 2026-09-24 03:20:07.525241 | Updating git repos 2026-09-24 03:20:07.578002 | Cloning repos into workspace 2026-09-24 03:20:07.623805 | Restoring repo states 2026-09-24 03:20:07.650347 | Merging changes 2026-09-24 03:20:07.650362 | Checking out repos 2026-09-24 03:20:07.976303 | Preparing playbooks 2026-09-24 03:20:09.087044 | Running Ansible setup 2026-09-24 03:20:13.303446 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-09-24 03:20:14.225066 | 2026-09-24 03:20:14.225189 | PLAY [Base pre] 2026-09-24 03:20:14.266748 | 2026-09-24 03:20:14.266870 | TASK [Setup log path fact] 2026-09-24 03:20:14.299086 | debian-bookworm | ok 2026-09-24 03:20:14.337169 | 2026-09-24 03:20:14.337306 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-09-24 03:20:14.393473 | debian-bookworm | ok 2026-09-24 03:20:14.403319 | 2026-09-24 03:20:14.403445 | TASK [emit-job-header : Print job information] 2026-09-24 03:20:14.444426 | # Job Information 2026-09-24 03:20:14.444612 | Ansible Version: 2.16.14 2026-09-24 03:20:14.444649 | Job: python-osism-test-setup 2026-09-24 03:20:14.444682 | Pipeline: periodic-daily 2026-09-24 03:20:14.444705 | Executor: 521e9411259a 2026-09-24 03:20:14.444725 | Triggered by: https://github.com/osism/python-osism 2026-09-24 03:20:14.444748 | Event ID: a3067c4b3c454c91bd61840ec0c7a502 2026-09-24 03:20:14.449573 | 2026-09-24 03:20:14.449671 | LOOP [emit-job-header : Print node information] 2026-09-24 03:20:14.602297 | debian-bookworm | ok: 2026-09-24 03:20:14.602487 | debian-bookworm | # Node Information 2026-09-24 03:20:14.602522 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-09-24 03:20:14.602547 | debian-bookworm | Hostname: debian 2026-09-24 03:20:14.602567 | debian-bookworm | Username: zuul 2026-09-24 03:20:14.602587 | debian-bookworm | Distro: Debian 12.15 2026-09-24 03:20:14.602610 | debian-bookworm | Provider: regiocloud-a 2026-09-24 03:20:14.602631 | debian-bookworm | Region: 2026-09-24 03:20:14.602652 | debian-bookworm | Label: debian-bookworm 2026-09-24 03:20:14.602670 | debian-bookworm | Product Name: OpenStack Nova 2026-09-24 03:20:14.602689 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe8c:ea93 2026-09-24 03:20:14.613499 | 2026-09-24 03:20:14.614039 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-09-24 03:20:15.203984 | debian-bookworm -> localhost | changed 2026-09-24 03:20:15.210850 | 2026-09-24 03:20:15.210946 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-09-24 03:20:16.219591 | debian-bookworm -> localhost | changed 2026-09-24 03:20:16.230824 | 2026-09-24 03:20:16.230918 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-09-24 03:20:16.617385 | debian-bookworm -> localhost | ok 2026-09-24 03:20:16.624785 | 2026-09-24 03:20:16.624874 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-09-24 03:20:16.643910 | debian-bookworm | ok 2026-09-24 03:20:16.658577 | debian-bookworm | included: /var/lib/zuul/builds/51616d90027f48d8aaa3d6a785243dd6/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-09-24 03:20:16.664409 | 2026-09-24 03:20:16.664491 | TASK [add-build-sshkey : Create Temp SSH key] 2026-09-24 03:20:20.371347 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-09-24 03:20:20.371536 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/51616d90027f48d8aaa3d6a785243dd6/work/51616d90027f48d8aaa3d6a785243dd6_id_rsa 2026-09-24 03:20:20.371573 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/51616d90027f48d8aaa3d6a785243dd6/work/51616d90027f48d8aaa3d6a785243dd6_id_rsa.pub 2026-09-24 03:20:20.371599 | debian-bookworm -> localhost | The key fingerprint is: 2026-09-24 03:20:20.371623 | debian-bookworm -> localhost | SHA256:Y/Kl1pX6+o1W0McxBvmbcZQy+Og1dMOfBAxudglSAiI zuul-build-sshkey 2026-09-24 03:20:20.371645 | debian-bookworm -> localhost | The key's randomart image is: 2026-09-24 03:20:20.371675 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-09-24 03:20:20.371697 | debian-bookworm -> localhost | | E . ..o.++o= .| 2026-09-24 03:20:20.371719 | debian-bookworm -> localhost | | . . +..*.X.| 2026-09-24 03:20:20.371739 | debian-bookworm -> localhost | | +++Bo*| 2026-09-24 03:20:20.371758 | debian-bookworm -> localhost | | o.o=.+=| 2026-09-24 03:20:20.371778 | debian-bookworm -> localhost | | . S o +...=| 2026-09-24 03:20:20.371801 | debian-bookworm -> localhost | | + = + .o | 2026-09-24 03:20:20.371822 | debian-bookworm -> localhost | | + o . | 2026-09-24 03:20:20.371841 | debian-bookworm -> localhost | | . ..o | 2026-09-24 03:20:20.371861 | debian-bookworm -> localhost | | .++ . | 2026-09-24 03:20:20.371880 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-09-24 03:20:20.371926 | debian-bookworm -> localhost | ok: Runtime: 0:00:03.227675 2026-09-24 03:20:20.378280 | 2026-09-24 03:20:20.378384 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-09-24 03:20:20.438512 | debian-bookworm | ok 2026-09-24 03:20:20.457995 | debian-bookworm | included: /var/lib/zuul/builds/51616d90027f48d8aaa3d6a785243dd6/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-09-24 03:20:20.478239 | 2026-09-24 03:20:20.478372 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-09-24 03:20:20.504859 | debian-bookworm | skipping: Conditional result was False 2026-09-24 03:20:20.512203 | 2026-09-24 03:20:20.512424 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-09-24 03:20:21.426649 | debian-bookworm | changed 2026-09-24 03:20:21.436933 | 2026-09-24 03:20:21.437047 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-09-24 03:20:21.710140 | debian-bookworm | ok 2026-09-24 03:20:21.718755 | 2026-09-24 03:20:21.718926 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-09-24 03:20:22.466691 | debian-bookworm | changed 2026-09-24 03:20:22.485097 | 2026-09-24 03:20:22.485237 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-09-24 03:20:23.297230 | debian-bookworm | changed 2026-09-24 03:20:23.304014 | 2026-09-24 03:20:23.304105 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-09-24 03:20:23.316954 | debian-bookworm | skipping: Conditional result was False 2026-09-24 03:20:23.322662 | 2026-09-24 03:20:23.322753 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-09-24 03:20:23.843698 | debian-bookworm -> localhost | changed 2026-09-24 03:20:23.856908 | 2026-09-24 03:20:23.857053 | TASK [add-build-sshkey : Add back temp key] 2026-09-24 03:20:24.214456 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/51616d90027f48d8aaa3d6a785243dd6/work/51616d90027f48d8aaa3d6a785243dd6_id_rsa (zuul-build-sshkey) 2026-09-24 03:20:24.214686 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.009345 2026-09-24 03:20:24.222549 | 2026-09-24 03:20:24.222641 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-09-24 03:20:24.615373 | debian-bookworm | ok 2026-09-24 03:20:24.620531 | 2026-09-24 03:20:24.620623 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-09-24 03:20:24.644230 | debian-bookworm | skipping: Conditional result was False 2026-09-24 03:20:24.654089 | 2026-09-24 03:20:24.654179 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-09-24 03:20:25.065489 | debian-bookworm | ok 2026-09-24 03:20:25.086735 | 2026-09-24 03:20:25.086837 | TASK [validate-host : Define zuul_info_dir fact] 2026-09-24 03:20:25.136723 | debian-bookworm | ok 2026-09-24 03:20:25.142088 | 2026-09-24 03:20:25.142174 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-09-24 03:20:25.484161 | debian-bookworm -> localhost | ok 2026-09-24 03:20:25.490742 | 2026-09-24 03:20:25.490834 | TASK [validate-host : Collect information about the host] 2026-09-24 03:20:26.602372 | debian-bookworm | ok 2026-09-24 03:20:26.613538 | 2026-09-24 03:20:26.613732 | TASK [validate-host : Sanitize hostname] 2026-09-24 03:20:26.720089 | debian-bookworm | ok 2026-09-24 03:20:26.724249 | 2026-09-24 03:20:26.724359 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-09-24 03:20:27.510419 | debian-bookworm -> localhost | changed 2026-09-24 03:20:27.517244 | 2026-09-24 03:20:27.517374 | TASK [validate-host : Collect information about zuul worker] 2026-09-24 03:20:28.147512 | debian-bookworm | ok 2026-09-24 03:20:28.152496 | 2026-09-24 03:20:28.152587 | TASK [validate-host : Write out all zuul information for each host] 2026-09-24 03:20:28.988262 | debian-bookworm -> localhost | changed 2026-09-24 03:20:29.007274 | 2026-09-24 03:20:29.007423 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-09-24 03:20:29.351139 | debian-bookworm | ok 2026-09-24 03:20:29.366782 | 2026-09-24 03:20:29.366902 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-09-24 03:20:31.734665 | debian-bookworm | changed: 2026-09-24 03:20:31.734883 | debian-bookworm | cd+++++++++ src/ 2026-09-24 03:20:31.734931 | debian-bookworm | cd+++++++++ src/github.com/ 2026-09-24 03:20:31.734965 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-09-24 03:20:31.734995 | debian-bookworm | cd+++++++++ src/github.com/osism/python-osism/ 2026-09-24 03:20:31.735023 | debian-bookworm | Containerfile 2026-09-24 03:20:31.735209 | debian-bookworm | Containerfile 2026-09-24 03:20:31.753937 | debian-bookworm | localhost | ok: "/var/lib/zuul/builds/51616d90027f48d8aaa3d6a785243dd6/work/logs" 2026-09-24 03:23:13.658924 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/51616d90027f48d8aaa3d6a785243dd6/work/artifacts" 2026-09-24 03:23:13.924918 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/51616d90027f48d8aaa3d6a785243dd6/work/docs" 2026-09-24 03:23:13.944983 | 2026-09-24 03:23:13.945146 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-09-24 03:23:14.756669 | debian-bookworm | changed: 2026-09-24 03:23:14.757021 | debian-bookworm | .d..t...... ./ 2026-09-24 03:23:14.757083 | debian-bookworm | >f+++++++++ net-debug.post.txt 2026-09-24 03:23:14.757125 | debian-bookworm | >f+++++++++ net-debug.pre.txt 2026-09-24 03:23:14.757163 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-09-24 03:23:14.757200 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-09-24 03:23:14.757235 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-09-24 03:23:14.757290 | debian-bookworm | changed: All items complete 2026-09-24 03:23:14.757332 | 2026-09-24 03:23:15.352377 | debian-bookworm | changed: .d..t...... ./ 2026-09-24 03:23:15.914905 | debian-bookworm | changed: .d..t...... ./ 2026-09-24 03:23:15.938414 | 2026-09-24 03:23:15.938558 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-09-24 03:23:15.968295 | debian-bookworm | skipping: Conditional result was False 2026-09-24 03:23:15.974466 | debian-bookworm | skipping: Conditional result was False 2026-09-24 03:23:15.997318 | 2026-09-24 03:23:15.997454 | PLAY RECAP 2026-09-24 03:23:15.997530 | debian-bookworm | ok: 7 changed: 2 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 0 2026-09-24 03:23:15.997599 | 2026-09-24 03:23:16.135424 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-09-24 03:23:16.136455 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-09-24 03:23:16.868159 | 2026-09-24 03:23:16.868351 | PLAY [Base post] 2026-09-24 03:23:16.881783 | 2026-09-24 03:23:16.881907 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-09-24 03:23:17.688485 | debian-bookworm | changed 2026-09-24 03:23:17.696300 | 2026-09-24 03:23:17.696412 | PLAY RECAP 2026-09-24 03:23:17.696477 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-09-24 03:23:17.696551 | 2026-09-24 03:23:17.822695 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-09-24 03:23:17.824973 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-09-24 03:23:18.595820 | 2026-09-24 03:23:18.595987 | PLAY [Base post-logs] 2026-09-24 03:23:18.606420 | 2026-09-24 03:23:18.606560 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-09-24 03:23:19.050677 | localhost | changed 2026-09-24 03:23:19.060918 | 2026-09-24 03:23:19.061065 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-09-24 03:23:19.096809 | localhost | ok 2026-09-24 03:23:19.100916 | 2026-09-24 03:23:19.101037 | TASK [Set zuul-log-path fact] 2026-09-24 03:23:19.117061 | localhost | ok 2026-09-24 03:23:19.128172 | 2026-09-24 03:23:19.128406 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-09-24 03:23:19.165470 | localhost | ok 2026-09-24 03:23:19.171140 | 2026-09-24 03:23:19.171293 | TASK [upload-logs : Create log directories] 2026-09-24 03:23:19.705152 | localhost | changed 2026-09-24 03:23:19.708276 | 2026-09-24 03:23:19.708390 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-09-24 03:23:20.220863 | localhost -> localhost | ok: Runtime: 0:00:00.008075 2026-09-24 03:23:20.233506 | 2026-09-24 03:23:20.233725 | TASK [upload-logs : Upload logs to log server] 2026-09-24 03:23:20.799223 | localhost | Output suppressed because no_log was given 2026-09-24 03:23:20.802263 | 2026-09-24 03:23:20.802421 | LOOP [upload-logs : Compress console log and json output] 2026-09-24 03:23:20.863954 | localhost | skipping: Conditional result was False 2026-09-24 03:23:20.868348 | localhost | skipping: Conditional result was False 2026-09-24 03:23:20.874455 | 2026-09-24 03:23:20.874641 | LOOP [upload-logs : Upload compressed console log and json output] 2026-09-24 03:23:20.919770 | localhost | skipping: Conditional result was False 2026-09-24 03:23:20.920367 | 2026-09-24 03:23:20.923883 | localhost | skipping: Conditional result was False 2026-09-24 03:23:20.937312 | 2026-09-24 03:23:20.937534 | LOOP [upload-logs : Upload console log and json output]