2026-06-19 16:26:31.134438 | Job console starting 2026-06-19 16:26:31.143111 | Updating git repos 2026-06-19 16:26:31.173435 | Cloning repos into workspace 2026-06-19 16:26:31.240488 | Restoring repo states 2026-06-19 16:26:31.263904 | Merging changes 2026-06-19 16:26:32.351641 | Checking out repos 2026-06-19 16:26:32.394294 | Preparing playbooks 2026-06-19 16:26:33.522333 | Running Ansible setup 2026-06-19 16:26:38.739177 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-06-19 16:26:39.759259 | 2026-06-19 16:26:39.759516 | PLAY [Base pre] 2026-06-19 16:26:39.779036 | 2026-06-19 16:26:39.779432 | TASK [Setup log path fact] 2026-06-19 16:26:39.821512 | debian-bookworm | ok 2026-06-19 16:26:39.839807 | 2026-06-19 16:26:39.840015 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-06-19 16:26:39.883175 | debian-bookworm | ok 2026-06-19 16:26:39.902320 | 2026-06-19 16:26:39.902529 | TASK [emit-job-header : Print job information] 2026-06-19 16:26:39.945150 | # Job Information 2026-06-19 16:26:39.945501 | Ansible Version: 2.16.14 2026-06-19 16:26:39.945548 | Job: python-osism-test-setup 2026-06-19 16:26:39.945580 | Pipeline: check 2026-06-19 16:26:39.945604 | Executor: 521e9411259a 2026-06-19 16:26:39.945625 | Triggered by: https://github.com/osism/python-osism/pull/2322 2026-06-19 16:26:39.945647 | Event ID: c8bd7670-6bf7-11f1-9e90-82962a1d460b 2026-06-19 16:26:39.951357 | 2026-06-19 16:26:39.951552 | LOOP [emit-job-header : Print node information] 2026-06-19 16:26:40.075597 | debian-bookworm | ok: 2026-06-19 16:26:40.075913 | debian-bookworm | # Node Information 2026-06-19 16:26:40.075953 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-06-19 16:26:40.075979 | debian-bookworm | Hostname: debian 2026-06-19 16:26:40.076001 | debian-bookworm | Username: zuul 2026-06-19 16:26:40.076024 | debian-bookworm | Distro: Debian 12.14 2026-06-19 16:26:40.076047 | debian-bookworm | Provider: regiocloud-a 2026-06-19 16:26:40.076068 | debian-bookworm | Region: 2026-06-19 16:26:40.076089 | debian-bookworm | Label: debian-bookworm 2026-06-19 16:26:40.076109 | debian-bookworm | Product Name: OpenStack Nova 2026-06-19 16:26:40.076129 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe02:4583 2026-06-19 16:26:40.092248 | 2026-06-19 16:26:40.092399 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-06-19 16:26:40.957864 | debian-bookworm -> localhost | changed 2026-06-19 16:26:40.966073 | 2026-06-19 16:26:40.966231 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-06-19 16:26:42.389548 | debian-bookworm -> localhost | changed 2026-06-19 16:26:42.401609 | 2026-06-19 16:26:42.401760 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-06-19 16:26:42.712082 | debian-bookworm -> localhost | ok 2026-06-19 16:26:42.719392 | 2026-06-19 16:26:42.719548 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-06-19 16:26:42.752961 | debian-bookworm | ok 2026-06-19 16:26:42.769584 | debian-bookworm | included: /var/lib/zuul/builds/488e418420a347a8a77c3477a62ad41c/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-06-19 16:26:42.776376 | 2026-06-19 16:26:42.776529 | TASK [add-build-sshkey : Create Temp SSH key] 2026-06-19 16:26:43.771347 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-06-19 16:26:43.771754 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/488e418420a347a8a77c3477a62ad41c/work/488e418420a347a8a77c3477a62ad41c_id_rsa 2026-06-19 16:26:43.771812 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/488e418420a347a8a77c3477a62ad41c/work/488e418420a347a8a77c3477a62ad41c_id_rsa.pub 2026-06-19 16:26:43.771846 | debian-bookworm -> localhost | The key fingerprint is: 2026-06-19 16:26:43.771878 | debian-bookworm -> localhost | SHA256:tVezCHSfqOyiqlvYSHRmPxsFeo1V9MppvK6GBSaRkTc zuul-build-sshkey 2026-06-19 16:26:43.771907 | debian-bookworm -> localhost | The key's randomart image is: 2026-06-19 16:26:43.771950 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-06-19 16:26:43.771978 | debian-bookworm -> localhost | | .+. .o+ . | 2026-06-19 16:26:43.772005 | debian-bookworm -> localhost | | +.E= . o o . | 2026-06-19 16:26:43.772031 | debian-bookworm -> localhost | | . =oo.o o o = | 2026-06-19 16:26:43.772055 | debian-bookworm -> localhost | | . +.oo. = * o o | 2026-06-19 16:26:43.772080 | debian-bookworm -> localhost | | . o+.S X o . | 2026-06-19 16:26:43.772117 | debian-bookworm -> localhost | | . + +.o o | 2026-06-19 16:26:43.772143 | debian-bookworm -> localhost | | o o .o. o | 2026-06-19 16:26:43.772167 | debian-bookworm -> localhost | | . ...o | 2026-06-19 16:26:43.772194 | debian-bookworm -> localhost | | oo....... | 2026-06-19 16:26:43.772220 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-06-19 16:26:43.772292 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.438755 2026-06-19 16:26:43.781205 | 2026-06-19 16:26:43.781353 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-06-19 16:26:43.832255 | debian-bookworm | ok 2026-06-19 16:26:43.844309 | debian-bookworm | included: /var/lib/zuul/builds/488e418420a347a8a77c3477a62ad41c/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-06-19 16:26:43.853140 | 2026-06-19 16:26:43.853262 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-06-19 16:26:43.879121 | debian-bookworm | skipping: Conditional result was False 2026-06-19 16:26:43.886161 | 2026-06-19 16:26:43.886276 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-06-19 16:26:44.606046 | debian-bookworm | changed 2026-06-19 16:26:44.613153 | 2026-06-19 16:26:44.613296 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-06-19 16:26:44.904205 | debian-bookworm | ok 2026-06-19 16:26:44.913323 | 2026-06-19 16:26:44.913499 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-06-19 16:26:45.628060 | debian-bookworm | changed 2026-06-19 16:26:45.647855 | 2026-06-19 16:26:45.648004 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-06-19 16:26:46.346215 | debian-bookworm | changed 2026-06-19 16:26:46.352356 | 2026-06-19 16:26:46.352524 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-06-19 16:26:46.376943 | debian-bookworm | skipping: Conditional result was False 2026-06-19 16:26:46.384883 | 2026-06-19 16:26:46.385020 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-06-19 16:26:46.924175 | debian-bookworm -> localhost | changed 2026-06-19 16:26:46.938146 | 2026-06-19 16:26:46.938290 | TASK [add-build-sshkey : Add back temp key] 2026-06-19 16:26:47.337126 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/488e418420a347a8a77c3477a62ad41c/work/488e418420a347a8a77c3477a62ad41c_id_rsa (zuul-build-sshkey) 2026-06-19 16:26:47.337444 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.014972 2026-06-19 16:26:47.366517 | 2026-06-19 16:26:47.366671 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-06-19 16:26:47.812528 | debian-bookworm | ok 2026-06-19 16:26:47.822668 | 2026-06-19 16:26:47.822823 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-06-19 16:26:47.852827 | debian-bookworm | skipping: Conditional result was False 2026-06-19 16:26:47.866783 | 2026-06-19 16:26:47.866954 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-06-19 16:26:48.365262 | debian-bookworm | ok 2026-06-19 16:26:48.380242 | 2026-06-19 16:26:48.380414 | TASK [validate-host : Define zuul_info_dir fact] 2026-06-19 16:26:48.442135 | debian-bookworm | ok 2026-06-19 16:26:48.454455 | 2026-06-19 16:26:48.454653 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-06-19 16:26:48.847936 | debian-bookworm -> localhost | ok 2026-06-19 16:26:48.859672 | 2026-06-19 16:26:48.859846 | TASK [validate-host : Collect information about the host] 2026-06-19 16:26:50.169748 | debian-bookworm | ok 2026-06-19 16:26:50.183787 | 2026-06-19 16:26:50.183928 | TASK [validate-host : Sanitize hostname] 2026-06-19 16:26:50.348921 | debian-bookworm | ok 2026-06-19 16:26:50.353950 | 2026-06-19 16:26:50.354065 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-06-19 16:26:51.080335 | debian-bookworm -> localhost | changed 2026-06-19 16:26:51.086826 | 2026-06-19 16:26:51.086988 | TASK [validate-host : Collect information about zuul worker] 2026-06-19 16:26:51.552197 | debian-bookworm | ok 2026-06-19 16:26:51.557384 | 2026-06-19 16:26:51.557537 | TASK [validate-host : Write out all zuul information for each host] 2026-06-19 16:26:52.165831 | debian-bookworm -> localhost | changed 2026-06-19 16:26:52.180930 | 2026-06-19 16:26:52.181085 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-06-19 16:26:52.462735 | debian-bookworm | ok 2026-06-19 16:26:52.473638 | 2026-06-19 16:26:52.473906 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-06-19 16:26:53.665831 | debian-bookworm | changed: 2026-06-19 16:26:53.666085 | debian-bookworm | cd+++++++++ src/ 2026-06-19 16:26:53.666122 | debian-bookworm | cd+++++++++ src/github.com/ 2026-06-19 16:26:53.666146 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-06-19 16:26:53.666167 | debian-bookworm | cd+++++++++ src/github.com/osism/python-osism/ 2026-06-19 16:26:53.666187 | debian-bookworm | Containerfile 2026-06-19 16:26:53.666316 | debian-bookworm | Containerfile 2026-06-19 16:26:53.680871 | debian-bookworm | localhost | ok: "/var/lib/zuul/builds/488e418420a347a8a77c3477a62ad41c/work/logs" 2026-06-19 16:30:03.668866 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/488e418420a347a8a77c3477a62ad41c/work/artifacts" 2026-06-19 16:30:04.201973 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/488e418420a347a8a77c3477a62ad41c/work/docs" 2026-06-19 16:30:04.218661 | 2026-06-19 16:30:04.218984 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-06-19 16:30:05.289958 | debian-bookworm | changed: 2026-06-19 16:30:05.290258 | debian-bookworm | .d..t...... ./ 2026-06-19 16:30:05.290295 | debian-bookworm | >f+++++++++ net-debug.post.txt 2026-06-19 16:30:05.290319 | debian-bookworm | >f+++++++++ net-debug.pre.txt 2026-06-19 16:30:05.290340 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-06-19 16:30:05.290378 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-06-19 16:30:05.290399 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-06-19 16:30:05.290448 | debian-bookworm | changed: All items complete 2026-06-19 16:30:05.290475 | 2026-06-19 16:30:06.023912 | debian-bookworm | changed: .d..t...... ./ 2026-06-19 16:30:06.586488 | debian-bookworm | changed: .d..t...... ./ 2026-06-19 16:30:06.611521 | 2026-06-19 16:30:06.611706 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-06-19 16:30:07.029387 | debian-bookworm -> localhost | ok: Item: artifacts Runtime: 0:00:00.029323 2026-06-19 16:30:07.403165 | debian-bookworm -> localhost | ok: Item: docs Runtime: 0:00:00.006086 2026-06-19 16:30:07.421453 | 2026-06-19 16:30:07.421619 | PLAY RECAP 2026-06-19 16:30:07.421682 | debian-bookworm | ok: 8 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-06-19 16:30:07.421708 | 2026-06-19 16:30:07.596269 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-06-19 16:30:07.597831 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-06-19 16:30:08.394180 | 2026-06-19 16:30:08.394366 | PLAY [Base post] 2026-06-19 16:30:08.410397 | 2026-06-19 16:30:08.410669 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-06-19 16:30:09.195211 | debian-bookworm | changed 2026-06-19 16:30:09.204607 | 2026-06-19 16:30:09.204753 | PLAY RECAP 2026-06-19 16:30:09.204832 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-06-19 16:30:09.204908 | 2026-06-19 16:30:09.388737 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-06-19 16:30:09.389701 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-06-19 16:30:10.254704 | 2026-06-19 16:30:10.254917 | PLAY [Base post-logs] 2026-06-19 16:30:10.266128 | 2026-06-19 16:30:10.266276 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-06-19 16:30:10.799893 | localhost | changed 2026-06-19 16:30:10.814015 | 2026-06-19 16:30:10.814203 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-06-19 16:30:10.840382 | localhost | ok 2026-06-19 16:30:10.843935 | 2026-06-19 16:30:10.844047 | TASK [Set zuul-log-path fact] 2026-06-19 16:30:10.859544 | localhost | ok 2026-06-19 16:30:10.868534 | 2026-06-19 16:30:10.868715 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-06-19 16:30:10.894856 | localhost | ok 2026-06-19 16:30:10.898047 | 2026-06-19 16:30:10.898153 | TASK [upload-logs : Create log directories] 2026-06-19 16:30:11.484767 | localhost | changed 2026-06-19 16:30:11.491270 | 2026-06-19 16:30:11.491396 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-06-19 16:30:12.122171 | localhost -> localhost | ok: Runtime: 0:00:00.007781 2026-06-19 16:30:12.137333 | 2026-06-19 16:30:12.137513 | TASK [upload-logs : Upload logs to log server] 2026-06-19 16:30:13.131797 | localhost | Output suppressed because no_log was given 2026-06-19 16:30:13.133913 | 2026-06-19 16:30:13.134037 | LOOP [upload-logs : Compress console log and json output] 2026-06-19 16:30:13.188381 | localhost | skipping: Conditional result was False 2026-06-19 16:30:13.195230 | localhost | skipping: Conditional result was False 2026-06-19 16:30:13.204944 | 2026-06-19 16:30:13.205128 | LOOP [upload-logs : Upload compressed console log and json output] 2026-06-19 16:30:13.257714 | localhost | skipping: Conditional result was False 2026-06-19 16:30:13.258346 | 2026-06-19 16:30:13.263705 | localhost | skipping: Conditional result was False 2026-06-19 16:30:13.273515 | 2026-06-19 16:30:13.273711 | LOOP [upload-logs : Upload console log and json output]