2026-09-17 14:14:51.120952 | Job console starting 2026-09-17 14:14:51.153816 | Updating git repos 2026-09-17 14:14:51.226619 | Cloning repos into workspace 2026-09-17 14:14:51.305495 | Restoring repo states 2026-09-17 14:14:51.350130 | Merging changes 2026-09-17 14:14:52.949860 | Checking out repos 2026-09-17 14:14:53.068048 | Preparing playbooks 2026-09-17 14:14:55.723549 | Running Ansible setup 2026-09-17 14:15:02.655143 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-09-17 14:15:04.493443 | 2026-09-17 14:15:04.493581 | PLAY [Base pre] 2026-09-17 14:15:04.529846 | 2026-09-17 14:15:04.529959 | TASK [Setup log path fact] 2026-09-17 14:15:04.556643 | debian-bookworm | ok 2026-09-17 14:15:04.570350 | 2026-09-17 14:15:04.570453 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-09-17 14:15:04.596804 | debian-bookworm | ok 2026-09-17 14:15:04.604699 | 2026-09-17 14:15:04.604786 | TASK [emit-job-header : Print job information] 2026-09-17 14:15:04.716530 | # Job Information 2026-09-17 14:15:04.716662 | Ansible Version: 2.16.14 2026-09-17 14:15:04.716690 | Job: python-osism-test-setup 2026-09-17 14:15:04.716718 | Pipeline: check 2026-09-17 14:15:04.716737 | Executor: 521e9411259a 2026-09-17 14:15:04.716755 | Triggered by: https://github.com/osism/python-osism/pull/2707 2026-09-17 14:15:04.716773 | Event ID: 1e1c1900-b2a2-11f1-821a-cb2acf928794 2026-09-17 14:15:04.720666 | 2026-09-17 14:15:04.720741 | LOOP [emit-job-header : Print node information] 2026-09-17 14:15:04.893211 | debian-bookworm | ok: 2026-09-17 14:15:04.893369 | debian-bookworm | # Node Information 2026-09-17 14:15:04.893398 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-09-17 14:15:04.893420 | debian-bookworm | Hostname: debian 2026-09-17 14:15:04.893439 | debian-bookworm | Username: zuul 2026-09-17 14:15:04.893456 | debian-bookworm | Distro: Debian 12.15 2026-09-17 14:15:04.893476 | debian-bookworm | Provider: regiocloud-a 2026-09-17 14:15:04.893494 | debian-bookworm | Region: 2026-09-17 14:15:04.893511 | debian-bookworm | Label: debian-bookworm 2026-09-17 14:15:04.893528 | debian-bookworm | Product Name: OpenStack Nova 2026-09-17 14:15:04.893545 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fed1:de83 2026-09-17 14:15:04.911553 | 2026-09-17 14:15:04.911654 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-09-17 14:15:05.524987 | debian-bookworm -> localhost | changed 2026-09-17 14:15:05.530682 | 2026-09-17 14:15:05.530766 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-09-17 14:15:07.279015 | debian-bookworm -> localhost | changed 2026-09-17 14:15:07.287660 | 2026-09-17 14:15:07.287746 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-09-17 14:15:07.839386 | debian-bookworm -> localhost | ok 2026-09-17 14:15:07.844437 | 2026-09-17 14:15:07.844525 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-09-17 14:15:07.873304 | debian-bookworm | ok 2026-09-17 14:15:07.903773 | debian-bookworm | included: /var/lib/zuul/builds/bfa289da6df24a8488109c2f02865aba/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-09-17 14:15:07.920886 | 2026-09-17 14:15:07.920975 | TASK [add-build-sshkey : Create Temp SSH key] 2026-09-17 14:15:09.722774 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-09-17 14:15:09.722939 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/bfa289da6df24a8488109c2f02865aba/work/bfa289da6df24a8488109c2f02865aba_id_rsa 2026-09-17 14:15:09.722970 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/bfa289da6df24a8488109c2f02865aba/work/bfa289da6df24a8488109c2f02865aba_id_rsa.pub 2026-09-17 14:15:09.722992 | debian-bookworm -> localhost | The key fingerprint is: 2026-09-17 14:15:09.723024 | debian-bookworm -> localhost | SHA256:jE1vC58KanQL1uyydDUAM+7V/UYaqfVDdy8wNlU6Orc zuul-build-sshkey 2026-09-17 14:15:09.723044 | debian-bookworm -> localhost | The key's randomart image is: 2026-09-17 14:15:09.723074 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-09-17 14:15:09.723093 | debian-bookworm -> localhost | | + ...| 2026-09-17 14:15:09.723111 | debian-bookworm -> localhost | | . + . . . . . | 2026-09-17 14:15:09.723128 | debian-bookworm -> localhost | | . o o = B + .| 2026-09-17 14:15:09.723145 | debian-bookworm -> localhost | | . . * + O * o.| 2026-09-17 14:15:09.723162 | debian-bookworm -> localhost | | .o. S + B o .| 2026-09-17 14:15:09.723182 | debian-bookworm -> localhost | | + +. = + + o | 2026-09-17 14:15:09.723200 | debian-bookworm -> localhost | | o.+o. + E | 2026-09-17 14:15:09.723216 | debian-bookworm -> localhost | | .ooo. . | 2026-09-17 14:15:09.723234 | debian-bookworm -> localhost | | .oo . | 2026-09-17 14:15:09.723251 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-09-17 14:15:09.723292 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.689669 2026-09-17 14:15:09.728414 | 2026-09-17 14:15:09.728491 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-09-17 14:15:09.754692 | debian-bookworm | ok 2026-09-17 14:15:09.781645 | debian-bookworm | included: /var/lib/zuul/builds/bfa289da6df24a8488109c2f02865aba/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-09-17 14:15:09.796987 | 2026-09-17 14:15:09.797069 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-09-17 14:15:09.841693 | debian-bookworm | skipping: Conditional result was False 2026-09-17 14:15:09.846371 | 2026-09-17 14:15:09.846440 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-09-17 14:15:10.660193 | debian-bookworm | changed 2026-09-17 14:15:10.672311 | 2026-09-17 14:15:10.672384 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-09-17 14:15:10.965191 | debian-bookworm | ok 2026-09-17 14:15:10.969780 | 2026-09-17 14:15:10.969857 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-09-17 14:15:11.737654 | debian-bookworm | changed 2026-09-17 14:15:11.751784 | 2026-09-17 14:15:11.751876 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-09-17 14:15:12.415337 | debian-bookworm | changed 2026-09-17 14:15:12.421540 | 2026-09-17 14:15:12.421654 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-09-17 14:15:12.444574 | debian-bookworm | skipping: Conditional result was False 2026-09-17 14:15:12.450524 | 2026-09-17 14:15:12.450623 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-09-17 14:15:12.956196 | debian-bookworm -> localhost | changed 2026-09-17 14:15:12.966648 | 2026-09-17 14:15:12.966734 | TASK [add-build-sshkey : Add back temp key] 2026-09-17 14:15:13.264584 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/bfa289da6df24a8488109c2f02865aba/work/bfa289da6df24a8488109c2f02865aba_id_rsa (zuul-build-sshkey) 2026-09-17 14:15:13.264750 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.011012 2026-09-17 14:15:13.270960 | 2026-09-17 14:15:13.271097 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-09-17 14:15:13.706897 | debian-bookworm | ok 2026-09-17 14:15:13.711266 | 2026-09-17 14:15:13.711344 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-09-17 14:15:13.756105 | debian-bookworm | skipping: Conditional result was False 2026-09-17 14:15:13.765743 | 2026-09-17 14:15:13.765827 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-09-17 14:15:14.276214 | debian-bookworm | ok 2026-09-17 14:15:14.289373 | 2026-09-17 14:15:14.289462 | TASK [validate-host : Define zuul_info_dir fact] 2026-09-17 14:15:14.340869 | debian-bookworm | ok 2026-09-17 14:15:14.356492 | 2026-09-17 14:15:14.356577 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-09-17 14:15:14.881210 | debian-bookworm -> localhost | ok 2026-09-17 14:15:14.898496 | 2026-09-17 14:15:14.898587 | TASK [validate-host : Collect information about the host] 2026-09-17 14:15:16.453676 | debian-bookworm | ok 2026-09-17 14:15:16.470569 | 2026-09-17 14:15:16.470669 | TASK [validate-host : Sanitize hostname] 2026-09-17 14:15:16.585735 | debian-bookworm | ok 2026-09-17 14:15:16.589487 | 2026-09-17 14:15:16.589581 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-09-17 14:15:17.444666 | debian-bookworm -> localhost | changed 2026-09-17 14:15:17.449219 | 2026-09-17 14:15:17.449302 | TASK [validate-host : Collect information about zuul worker] 2026-09-17 14:15:18.061273 | debian-bookworm | ok 2026-09-17 14:15:18.072246 | 2026-09-17 14:15:18.072361 | TASK [validate-host : Write out all zuul information for each host] 2026-09-17 14:15:18.873896 | debian-bookworm -> localhost | changed 2026-09-17 14:15:18.885736 | 2026-09-17 14:15:18.885877 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-09-17 14:15:19.168167 | debian-bookworm | ok 2026-09-17 14:15:19.176052 | 2026-09-17 14:15:19.176147 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-09-17 14:15:22.276083 | debian-bookworm | changed: 2026-09-17 14:15:22.276234 | debian-bookworm | cd+++++++++ src/ 2026-09-17 14:15:22.276263 | debian-bookworm | cd+++++++++ src/github.com/ 2026-09-17 14:15:22.276283 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-09-17 14:15:22.276301 | debian-bookworm | cd+++++++++ src/github.com/osism/python-osism/ 2026-09-17 14:15:22.276317 | debian-bookworm | Containerfile 2026-09-17 14:15:22.276423 | debian-bookworm | Containerfile 2026-09-17 14:15:22.346645 | debian-bookworm | localhost | ok: "/var/lib/zuul/builds/bfa289da6df24a8488109c2f02865aba/work/logs" 2026-09-17 14:18:02.892066 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/bfa289da6df24a8488109c2f02865aba/work/artifacts" 2026-09-17 14:18:03.138514 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/bfa289da6df24a8488109c2f02865aba/work/docs" 2026-09-17 14:18:03.156230 | 2026-09-17 14:18:03.156371 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-09-17 14:18:03.947065 | debian-bookworm | changed: 2026-09-17 14:18:03.947436 | debian-bookworm | .d..t...... ./ 2026-09-17 14:18:03.947488 | debian-bookworm | >f+++++++++ net-debug.post.txt 2026-09-17 14:18:03.947516 | debian-bookworm | >f+++++++++ net-debug.pre.txt 2026-09-17 14:18:03.947539 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-09-17 14:18:03.947562 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-09-17 14:18:03.947584 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-09-17 14:18:03.947626 | debian-bookworm | changed: All items complete 2026-09-17 14:18:03.947653 | 2026-09-17 14:18:04.499307 | debian-bookworm | changed: .d..t...... ./ 2026-09-17 14:18:05.066179 | debian-bookworm | changed: .d..t...... ./ 2026-09-17 14:18:05.086737 | 2026-09-17 14:18:05.086868 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-09-17 14:18:05.443234 | debian-bookworm -> localhost | ok: Item: artifacts Runtime: 0:00:00.009756 2026-09-17 14:18:05.773499 | debian-bookworm -> localhost | ok: Item: docs Runtime: 0:00:00.014883 2026-09-17 14:18:05.809494 | 2026-09-17 14:18:05.809709 | PLAY RECAP 2026-09-17 14:18:05.809823 | debian-bookworm | ok: 8 changed: 3 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-09-17 14:18:05.809884 | 2026-09-17 14:18:05.969656 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-09-17 14:18:05.970520 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-09-17 14:18:06.698154 | 2026-09-17 14:18:06.698337 | PLAY [Base post] 2026-09-17 14:18:06.711875 | 2026-09-17 14:18:06.712002 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-09-17 14:18:07.510884 | debian-bookworm | changed 2026-09-17 14:18:07.517113 | 2026-09-17 14:18:07.517247 | PLAY RECAP 2026-09-17 14:18:07.517312 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-09-17 14:18:07.517374 | 2026-09-17 14:18:07.637077 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-09-17 14:18:07.640136 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-09-17 14:18:08.418115 | 2026-09-17 14:18:08.418306 | PLAY [Base post-logs] 2026-09-17 14:18:08.428833 | 2026-09-17 14:18:08.428969 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-09-17 14:18:08.871444 | localhost | changed 2026-09-17 14:18:08.883914 | 2026-09-17 14:18:08.884060 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-09-17 14:18:08.913168 | localhost | ok 2026-09-17 14:18:08.916341 | 2026-09-17 14:18:08.916447 | TASK [Set zuul-log-path fact] 2026-09-17 14:18:08.931487 | localhost | ok 2026-09-17 14:18:08.941739 | 2026-09-17 14:18:08.941849 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-09-17 14:18:08.966695 | localhost | ok 2026-09-17 14:18:08.969626 | 2026-09-17 14:18:08.969731 | TASK [upload-logs : Create log directories] 2026-09-17 14:18:09.489832 | localhost | changed 2026-09-17 14:18:09.495171 | 2026-09-17 14:18:09.495352 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-09-17 14:18:09.988930 | localhost -> localhost | ok: Runtime: 0:00:00.006852 2026-09-17 14:18:09.997996 | 2026-09-17 14:18:09.998197 | TASK [upload-logs : Upload logs to log server] 2026-09-17 14:18:10.559642 | localhost | Output suppressed because no_log was given 2026-09-17 14:18:10.562664 | 2026-09-17 14:18:10.562840 | LOOP [upload-logs : Compress console log and json output] 2026-09-17 14:18:10.610033 | localhost | skipping: Conditional result was False 2026-09-17 14:18:10.615837 | localhost | skipping: Conditional result was False 2026-09-17 14:18:10.627315 | 2026-09-17 14:18:10.627682 | LOOP [upload-logs : Upload compressed console log and json output] 2026-09-17 14:18:10.672268 | localhost | skipping: Conditional result was False 2026-09-17 14:18:10.672611 | 2026-09-17 14:18:10.677181 | localhost | skipping: Conditional result was False 2026-09-17 14:18:10.689891 | 2026-09-17 14:18:10.690150 | LOOP [upload-logs : Upload console log and json output]