2026-07-27 21:15:49.679682 | Job console starting 2026-07-27 21:15:49.747692 | Updating git repos 2026-07-27 21:15:49.865472 | Cloning repos into workspace 2026-07-27 21:15:50.021992 | Restoring repo states 2026-07-27 21:15:50.062024 | Merging changes 2026-07-27 21:15:50.986558 | Checking out repos 2026-07-27 21:15:51.244597 | Preparing playbooks 2026-07-27 21:15:54.068571 | Running Ansible setup 2026-07-27 21:15:59.545015 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-07-27 21:16:01.527322 | 2026-07-27 21:16:01.527576 | PLAY [Base pre] 2026-07-27 21:16:01.569489 | 2026-07-27 21:16:01.569616 | TASK [Setup log path fact] 2026-07-27 21:16:01.597857 | debian-bookworm | ok 2026-07-27 21:16:01.639583 | 2026-07-27 21:16:01.639739 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-07-27 21:16:01.687107 | debian-bookworm | ok 2026-07-27 21:16:01.695504 | 2026-07-27 21:16:01.695610 | TASK [emit-job-header : Print job information] 2026-07-27 21:16:01.811868 | # Job Information 2026-07-27 21:16:01.812052 | Ansible Version: 2.16.14 2026-07-27 21:16:01.812083 | Job: python-osism-test-setup 2026-07-27 21:16:01.812110 | Pipeline: check 2026-07-27 21:16:01.812129 | Executor: 521e9411259a 2026-07-27 21:16:01.812146 | Triggered by: https://github.com/osism/python-osism/pull/2526 2026-07-27 21:16:01.812165 | Event ID: 4ddbf590-8a00-11f1-8cd0-d48f1844ffb7 2026-07-27 21:16:01.816297 | 2026-07-27 21:16:01.816391 | LOOP [emit-job-header : Print node information] 2026-07-27 21:16:01.983801 | debian-bookworm | ok: 2026-07-27 21:16:01.983981 | debian-bookworm | # Node Information 2026-07-27 21:16:01.984009 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-07-27 21:16:01.984030 | debian-bookworm | Hostname: debian 2026-07-27 21:16:01.984047 | debian-bookworm | Username: zuul 2026-07-27 21:16:01.984064 | debian-bookworm | Distro: Debian 12.15 2026-07-27 21:16:01.984083 | debian-bookworm | Provider: regiocloud-a 2026-07-27 21:16:01.984101 | debian-bookworm | Region: 2026-07-27 21:16:01.984119 | debian-bookworm | Label: debian-bookworm 2026-07-27 21:16:01.984135 | debian-bookworm | Product Name: OpenStack Nova 2026-07-27 21:16:01.984151 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe4d:2440 2026-07-27 21:16:01.992264 | 2026-07-27 21:16:01.992362 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-07-27 21:16:03.297285 | debian-bookworm -> localhost | changed 2026-07-27 21:16:03.303226 | 2026-07-27 21:16:03.303318 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-07-27 21:16:05.217453 | debian-bookworm -> localhost | changed 2026-07-27 21:16:05.227414 | 2026-07-27 21:16:05.227557 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-07-27 21:16:05.996623 | debian-bookworm -> localhost | ok 2026-07-27 21:16:06.001898 | 2026-07-27 21:16:06.002003 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-07-27 21:16:06.050414 | debian-bookworm | ok 2026-07-27 21:16:06.084825 | debian-bookworm | included: /var/lib/zuul/builds/499b650164d54101a11622855c354461/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-07-27 21:16:06.089892 | 2026-07-27 21:16:06.089991 | TASK [add-build-sshkey : Create Temp SSH key] 2026-07-27 21:16:08.194423 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-07-27 21:16:08.194612 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/499b650164d54101a11622855c354461/work/499b650164d54101a11622855c354461_id_rsa 2026-07-27 21:16:08.194645 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/499b650164d54101a11622855c354461/work/499b650164d54101a11622855c354461_id_rsa.pub 2026-07-27 21:16:08.194668 | debian-bookworm -> localhost | The key fingerprint is: 2026-07-27 21:16:08.194690 | debian-bookworm -> localhost | SHA256:wIE9IfwJpQGr2k9xH7ldSFjoCAiH6Ald1k9pPNURH/Y zuul-build-sshkey 2026-07-27 21:16:08.194710 | debian-bookworm -> localhost | The key's randomart image is: 2026-07-27 21:16:08.194736 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-07-27 21:16:08.194754 | debian-bookworm -> localhost | |o+o===+o +o.ooo | 2026-07-27 21:16:08.194772 | debian-bookworm -> localhost | |+.oo==+.Bo .o o | 2026-07-27 21:16:08.194789 | debian-bookworm -> localhost | |o o .+oO... . E| 2026-07-27 21:16:08.194806 | debian-bookworm -> localhost | | + +.oo . | 2026-07-27 21:16:08.194823 | debian-bookworm -> localhost | |. . . S . . | 2026-07-27 21:16:08.194856 | debian-bookworm -> localhost | |.. o . + . | 2026-07-27 21:16:08.194875 | debian-bookworm -> localhost | |. . . o . | 2026-07-27 21:16:08.194891 | debian-bookworm -> localhost | | o | 2026-07-27 21:16:08.194909 | debian-bookworm -> localhost | | . | 2026-07-27 21:16:08.194926 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-07-27 21:16:08.194975 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.997003 2026-07-27 21:16:08.200515 | 2026-07-27 21:16:08.200596 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-07-27 21:16:08.217192 | debian-bookworm | ok 2026-07-27 21:16:08.226068 | debian-bookworm | included: /var/lib/zuul/builds/499b650164d54101a11622855c354461/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-07-27 21:16:08.232580 | 2026-07-27 21:16:08.232648 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-07-27 21:16:08.244922 | debian-bookworm | skipping: Conditional result was False 2026-07-27 21:16:08.249509 | 2026-07-27 21:16:08.249581 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-07-27 21:16:08.940748 | debian-bookworm | changed 2026-07-27 21:16:08.948906 | 2026-07-27 21:16:08.949085 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-07-27 21:16:09.222620 | debian-bookworm | ok 2026-07-27 21:16:09.231698 | 2026-07-27 21:16:09.231820 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-07-27 21:16:09.903550 | debian-bookworm | changed 2026-07-27 21:16:09.911790 | 2026-07-27 21:16:09.911915 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-07-27 21:16:10.639597 | debian-bookworm | changed 2026-07-27 21:16:10.645125 | 2026-07-27 21:16:10.645225 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-07-27 21:16:10.670156 | debian-bookworm | skipping: Conditional result was False 2026-07-27 21:16:10.676540 | 2026-07-27 21:16:10.676647 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-07-27 21:16:11.408440 | debian-bookworm -> localhost | changed 2026-07-27 21:16:11.420233 | 2026-07-27 21:16:11.420350 | TASK [add-build-sshkey : Add back temp key] 2026-07-27 21:16:12.068774 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/499b650164d54101a11622855c354461/work/499b650164d54101a11622855c354461_id_rsa (zuul-build-sshkey) 2026-07-27 21:16:12.069078 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.039566 2026-07-27 21:16:12.078610 | 2026-07-27 21:16:12.078770 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-07-27 21:16:12.575243 | debian-bookworm | ok 2026-07-27 21:16:12.582691 | 2026-07-27 21:16:12.590970 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-07-27 21:16:12.682877 | debian-bookworm | skipping: Conditional result was False 2026-07-27 21:16:12.703228 | 2026-07-27 21:16:12.703437 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-07-27 21:16:13.178923 | debian-bookworm | ok 2026-07-27 21:16:13.207233 | 2026-07-27 21:16:13.207370 | TASK [validate-host : Define zuul_info_dir fact] 2026-07-27 21:16:13.246328 | debian-bookworm | ok 2026-07-27 21:16:13.252232 | 2026-07-27 21:16:13.252339 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-07-27 21:16:13.796597 | debian-bookworm -> localhost | ok 2026-07-27 21:16:13.803135 | 2026-07-27 21:16:13.803235 | TASK [validate-host : Collect information about the host] 2026-07-27 21:16:14.907129 | debian-bookworm | ok 2026-07-27 21:16:14.923152 | 2026-07-27 21:16:14.923272 | TASK [validate-host : Sanitize hostname] 2026-07-27 21:16:15.224119 | debian-bookworm | ok 2026-07-27 21:16:15.228907 | 2026-07-27 21:16:15.229031 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-07-27 21:16:15.963089 | debian-bookworm -> localhost | changed 2026-07-27 21:16:15.969203 | 2026-07-27 21:16:15.969305 | TASK [validate-host : Collect information about zuul worker] 2026-07-27 21:16:16.386584 | debian-bookworm | ok 2026-07-27 21:16:16.391360 | 2026-07-27 21:16:16.391461 | TASK [validate-host : Write out all zuul information for each host] 2026-07-27 21:16:17.447559 | debian-bookworm -> localhost | changed 2026-07-27 21:16:17.456966 | 2026-07-27 21:16:17.457068 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-07-27 21:16:17.726437 | debian-bookworm | ok 2026-07-27 21:16:17.732675 | 2026-07-27 21:16:17.732769 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-07-27 21:16:19.030275 | debian-bookworm | changed: 2026-07-27 21:16:19.030459 | debian-bookworm | cd+++++++++ src/ 2026-07-27 21:16:19.030493 | debian-bookworm | cd+++++++++ src/github.com/ 2026-07-27 21:16:19.030517 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-07-27 21:16:19.030538 | debian-bookworm | cd+++++++++ src/github.com/osism/python-osism/ 2026-07-27 21:16:19.030559 | debian-bookworm | Containerfile 2026-07-27 21:16:19.030689 | debian-bookworm | Containerfile 2026-07-27 21:16:19.150050 | debian-bookworm | localhost | ok: "/var/lib/zuul/builds/499b650164d54101a11622855c354461/work/logs" 2026-07-27 21:18:56.711631 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/499b650164d54101a11622855c354461/work/artifacts" 2026-07-27 21:18:56.974401 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/499b650164d54101a11622855c354461/work/docs" 2026-07-27 21:18:56.984866 | 2026-07-27 21:18:56.985006 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-07-27 21:18:57.914705 | debian-bookworm | changed: 2026-07-27 21:18:57.915051 | debian-bookworm | .d..t...... ./ 2026-07-27 21:18:57.915095 | debian-bookworm | >f+++++++++ net-debug.post.txt 2026-07-27 21:18:57.915119 | debian-bookworm | >f+++++++++ net-debug.pre.txt 2026-07-27 21:18:57.915141 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-07-27 21:18:57.915161 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-07-27 21:18:57.915181 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-07-27 21:18:57.915231 | debian-bookworm | changed: All items complete 2026-07-27 21:18:57.915257 | 2026-07-27 21:18:58.530537 | debian-bookworm | changed: .d..t...... ./ 2026-07-27 21:18:59.123430 | debian-bookworm | changed: .d..t...... ./ 2026-07-27 21:18:59.145032 | 2026-07-27 21:18:59.145179 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-07-27 21:18:59.566380 | debian-bookworm -> localhost | ok: Item: artifacts Runtime: 0:00:00.010395 2026-07-27 21:18:59.891746 | debian-bookworm -> localhost | ok: Item: docs Runtime: 0:00:00.009216 2026-07-27 21:18:59.911809 | 2026-07-27 21:18:59.912124 | PLAY RECAP 2026-07-27 21:18:59.912214 | debian-bookworm | ok: 8 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-07-27 21:18:59.912255 | 2026-07-27 21:19:00.094306 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-07-27 21:19:00.096003 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-07-27 21:19:00.838140 | 2026-07-27 21:19:00.838311 | PLAY [Base post] 2026-07-27 21:19:00.852716 | 2026-07-27 21:19:00.852880 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-07-27 21:19:01.679492 | debian-bookworm | changed 2026-07-27 21:19:01.689421 | 2026-07-27 21:19:01.689573 | PLAY RECAP 2026-07-27 21:19:01.689651 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-07-27 21:19:01.689726 | 2026-07-27 21:19:01.824756 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-07-27 21:19:01.826892 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-07-27 21:19:02.675610 | 2026-07-27 21:19:02.675815 | PLAY [Base post-logs] 2026-07-27 21:19:02.687567 | 2026-07-27 21:19:02.687736 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-07-27 21:19:03.211971 | localhost | changed 2026-07-27 21:19:03.223775 | 2026-07-27 21:19:03.223929 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-07-27 21:19:03.263980 | localhost | ok 2026-07-27 21:19:03.272259 | 2026-07-27 21:19:03.272456 | TASK [Set zuul-log-path fact] 2026-07-27 21:19:03.298923 | localhost | ok 2026-07-27 21:19:03.308107 | 2026-07-27 21:19:03.308230 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-07-27 21:19:03.346427 | localhost | ok 2026-07-27 21:19:03.353951 | 2026-07-27 21:19:03.354167 | TASK [upload-logs : Create log directories] 2026-07-27 21:19:03.884959 | localhost | changed 2026-07-27 21:19:03.890794 | 2026-07-27 21:19:03.890984 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-07-27 21:19:04.480398 | localhost -> localhost | ok: Runtime: 0:00:00.012103 2026-07-27 21:19:04.490794 | 2026-07-27 21:19:04.491139 | TASK [upload-logs : Upload logs to log server] 2026-07-27 21:19:05.115118 | localhost | Output suppressed because no_log was given 2026-07-27 21:19:05.119089 | 2026-07-27 21:19:05.119264 | LOOP [upload-logs : Compress console log and json output] 2026-07-27 21:19:05.180862 | localhost | skipping: Conditional result was False 2026-07-27 21:19:05.186481 | localhost | skipping: Conditional result was False 2026-07-27 21:19:05.195006 | 2026-07-27 21:19:05.195315 | LOOP [upload-logs : Upload compressed console log and json output] 2026-07-27 21:19:05.254779 | localhost | skipping: Conditional result was False 2026-07-27 21:19:05.255209 | 2026-07-27 21:19:05.269804 | localhost | skipping: Conditional result was False 2026-07-27 21:19:05.277171 | 2026-07-27 21:19:05.277406 | LOOP [upload-logs : Upload console log and json output]