2026-08-30 16:48:08.422059 | Job console starting 2026-08-30 16:48:08.429603 | Updating git repos 2026-08-30 16:48:08.484596 | Cloning repos into workspace 2026-08-30 16:48:08.585366 | Restoring repo states 2026-08-30 16:48:08.615622 | Merging changes 2026-08-30 16:48:09.719634 | Checking out repos 2026-08-30 16:48:09.771285 | Preparing playbooks 2026-08-30 16:48:11.225272 | Running Ansible setup 2026-08-30 16:48:17.567802 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-08-30 16:48:19.155113 | 2026-08-30 16:48:19.155249 | PLAY [Base pre] 2026-08-30 16:48:19.188464 | 2026-08-30 16:48:19.188601 | TASK [Setup log path fact] 2026-08-30 16:48:19.216081 | debian-bookworm | ok 2026-08-30 16:48:19.232224 | 2026-08-30 16:48:19.232355 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-08-30 16:48:19.259937 | debian-bookworm | ok 2026-08-30 16:48:19.272165 | 2026-08-30 16:48:19.272265 | TASK [emit-job-header : Print job information] 2026-08-30 16:48:19.320076 | # Job Information 2026-08-30 16:48:19.320228 | Ansible Version: 2.16.14 2026-08-30 16:48:19.320257 | Job: python-osism-test-setup 2026-08-30 16:48:19.320283 | Pipeline: check 2026-08-30 16:48:19.320302 | Executor: 521e9411259a 2026-08-30 16:48:19.320319 | Triggered by: https://github.com/osism/python-osism/pull/2640 2026-08-30 16:48:19.320337 | Event ID: 862d4410-a492-11f1-9022-b0377e70c450 2026-08-30 16:48:19.324430 | 2026-08-30 16:48:19.324526 | LOOP [emit-job-header : Print node information] 2026-08-30 16:48:19.678293 | debian-bookworm | ok: 2026-08-30 16:48:19.678445 | debian-bookworm | # Node Information 2026-08-30 16:48:19.678474 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-08-30 16:48:19.678495 | debian-bookworm | Hostname: debian 2026-08-30 16:48:19.678512 | debian-bookworm | Username: zuul 2026-08-30 16:48:19.678529 | debian-bookworm | Distro: Debian 12.15 2026-08-30 16:48:19.678549 | debian-bookworm | Provider: regiocloud-a 2026-08-30 16:48:19.678566 | debian-bookworm | Region: 2026-08-30 16:48:19.678584 | debian-bookworm | Label: debian-bookworm 2026-08-30 16:48:19.678601 | debian-bookworm | Product Name: OpenStack Nova 2026-08-30 16:48:19.678617 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe56:3447 2026-08-30 16:48:19.698612 | 2026-08-30 16:48:19.698718 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-08-30 16:48:20.724974 | debian-bookworm -> localhost | changed 2026-08-30 16:48:20.731123 | 2026-08-30 16:48:20.731221 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-08-30 16:48:22.525357 | debian-bookworm -> localhost | changed 2026-08-30 16:48:22.553844 | 2026-08-30 16:48:22.553970 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-08-30 16:48:23.325789 | debian-bookworm -> localhost | ok 2026-08-30 16:48:23.331017 | 2026-08-30 16:48:23.331108 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-08-30 16:48:23.369976 | debian-bookworm | ok 2026-08-30 16:48:23.403511 | debian-bookworm | included: /var/lib/zuul/builds/4c2d17567e864a25a81fa3751a695cc6/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-08-30 16:48:23.417736 | 2026-08-30 16:48:23.417845 | TASK [add-build-sshkey : Create Temp SSH key] 2026-08-30 16:48:25.511995 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-08-30 16:48:25.512175 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/4c2d17567e864a25a81fa3751a695cc6/work/4c2d17567e864a25a81fa3751a695cc6_id_rsa 2026-08-30 16:48:25.512207 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/4c2d17567e864a25a81fa3751a695cc6/work/4c2d17567e864a25a81fa3751a695cc6_id_rsa.pub 2026-08-30 16:48:25.512230 | debian-bookworm -> localhost | The key fingerprint is: 2026-08-30 16:48:25.512251 | debian-bookworm -> localhost | SHA256:2sf/558mDjtl/io1tohmSsxk1BC3sen2ASRkuFRWwUQ zuul-build-sshkey 2026-08-30 16:48:25.512271 | debian-bookworm -> localhost | The key's randomart image is: 2026-08-30 16:48:25.512298 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-08-30 16:48:25.512317 | debian-bookworm -> localhost | | +O*E. | 2026-08-30 16:48:25.512335 | debian-bookworm -> localhost | | oo *.= | 2026-08-30 16:48:25.512351 | debian-bookworm -> localhost | | . .. * | 2026-08-30 16:48:25.512368 | debian-bookworm -> localhost | | .. . . | 2026-08-30 16:48:25.512385 | debian-bookworm -> localhost | | oSo . | 2026-08-30 16:48:25.512407 | debian-bookworm -> localhost | | =o... .* | 2026-08-30 16:48:25.512424 | debian-bookworm -> localhost | | .+. +oB o | 2026-08-30 16:48:25.512441 | debian-bookworm -> localhost | | . = =o+ .o| 2026-08-30 16:48:25.512458 | debian-bookworm -> localhost | | .+ .=+*=+| 2026-08-30 16:48:25.512474 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-08-30 16:48:25.512511 | debian-bookworm -> localhost | ok: Runtime: 0:00:01.179136 2026-08-30 16:48:25.517723 | 2026-08-30 16:48:25.517802 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-08-30 16:48:25.555257 | debian-bookworm | ok 2026-08-30 16:48:25.569032 | debian-bookworm | included: /var/lib/zuul/builds/4c2d17567e864a25a81fa3751a695cc6/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-08-30 16:48:25.580678 | 2026-08-30 16:48:25.580775 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-08-30 16:48:25.619815 | debian-bookworm | skipping: Conditional result was False 2026-08-30 16:48:25.624765 | 2026-08-30 16:48:25.624861 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-08-30 16:48:26.518285 | debian-bookworm | changed 2026-08-30 16:48:26.523640 | 2026-08-30 16:48:26.523734 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-08-30 16:48:26.862043 | debian-bookworm | ok 2026-08-30 16:48:26.873206 | 2026-08-30 16:48:26.873300 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-08-30 16:48:27.585646 | debian-bookworm | changed 2026-08-30 16:48:27.591833 | 2026-08-30 16:48:27.591964 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-08-30 16:48:28.289127 | debian-bookworm | changed 2026-08-30 16:48:28.295417 | 2026-08-30 16:48:28.295526 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-08-30 16:48:28.340199 | debian-bookworm | skipping: Conditional result was False 2026-08-30 16:48:28.347280 | 2026-08-30 16:48:28.347440 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-08-30 16:48:28.827295 | debian-bookworm -> localhost | changed 2026-08-30 16:48:28.842100 | 2026-08-30 16:48:28.842207 | TASK [add-build-sshkey : Add back temp key] 2026-08-30 16:48:29.183710 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/4c2d17567e864a25a81fa3751a695cc6/work/4c2d17567e864a25a81fa3751a695cc6_id_rsa (zuul-build-sshkey) 2026-08-30 16:48:29.184000 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.013777 2026-08-30 16:48:29.196663 | 2026-08-30 16:48:29.196760 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-08-30 16:48:29.668254 | debian-bookworm | ok 2026-08-30 16:48:29.673214 | 2026-08-30 16:48:29.673294 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-08-30 16:48:29.697098 | debian-bookworm | skipping: Conditional result was False 2026-08-30 16:48:29.706643 | 2026-08-30 16:48:29.706731 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-08-30 16:48:30.134042 | debian-bookworm | ok 2026-08-30 16:48:30.148149 | 2026-08-30 16:48:30.148276 | TASK [validate-host : Define zuul_info_dir fact] 2026-08-30 16:48:30.177921 | debian-bookworm | ok 2026-08-30 16:48:30.183185 | 2026-08-30 16:48:30.183286 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-08-30 16:48:30.597521 | debian-bookworm -> localhost | ok 2026-08-30 16:48:30.604067 | 2026-08-30 16:48:30.604159 | TASK [validate-host : Collect information about the host] 2026-08-30 16:48:31.779649 | debian-bookworm | ok 2026-08-30 16:48:31.792471 | 2026-08-30 16:48:31.792606 | TASK [validate-host : Sanitize hostname] 2026-08-30 16:48:31.999312 | debian-bookworm | ok 2026-08-30 16:48:32.008289 | 2026-08-30 16:48:32.008419 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-08-30 16:48:32.702563 | debian-bookworm -> localhost | changed 2026-08-30 16:48:32.711338 | 2026-08-30 16:48:32.711468 | TASK [validate-host : Collect information about zuul worker] 2026-08-30 16:48:33.198303 | debian-bookworm | ok 2026-08-30 16:48:33.204823 | 2026-08-30 16:48:33.204977 | TASK [validate-host : Write out all zuul information for each host] 2026-08-30 16:48:33.920130 | debian-bookworm -> localhost | changed 2026-08-30 16:48:33.937749 | 2026-08-30 16:48:33.937915 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-08-30 16:48:34.240026 | debian-bookworm | ok 2026-08-30 16:48:34.258989 | 2026-08-30 16:48:34.259211 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-08-30 16:48:36.053450 | debian-bookworm | changed: 2026-08-30 16:48:36.053705 | debian-bookworm | cd+++++++++ src/ 2026-08-30 16:48:36.053740 | debian-bookworm | cd+++++++++ src/github.com/ 2026-08-30 16:48:36.053764 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-08-30 16:48:36.053785 | debian-bookworm | cd+++++++++ src/github.com/osism/python-osism/ 2026-08-30 16:48:36.053805 | debian-bookworm | Containerfile 2026-08-30 16:48:36.053983 | debian-bookworm | Containerfile 2026-08-30 16:48:36.073004 | debian-bookworm | localhost | ok: "/var/lib/zuul/builds/4c2d17567e864a25a81fa3751a695cc6/work/logs" 2026-08-30 16:51:24.237246 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/4c2d17567e864a25a81fa3751a695cc6/work/artifacts" 2026-08-30 16:51:24.537048 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/4c2d17567e864a25a81fa3751a695cc6/work/docs" 2026-08-30 16:51:24.549686 | 2026-08-30 16:51:24.550041 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-08-30 16:51:25.400277 | debian-bookworm | changed: 2026-08-30 16:51:25.400649 | debian-bookworm | .d..t...... ./ 2026-08-30 16:51:25.400711 | debian-bookworm | >f+++++++++ net-debug.post.txt 2026-08-30 16:51:25.400755 | debian-bookworm | >f+++++++++ net-debug.pre.txt 2026-08-30 16:51:25.400815 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-08-30 16:51:25.400854 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-08-30 16:51:25.400892 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-08-30 16:51:25.400971 | debian-bookworm | changed: All items complete 2026-08-30 16:51:25.401166 | 2026-08-30 16:51:25.969456 | debian-bookworm | changed: .d..t...... ./ 2026-08-30 16:51:26.558660 | debian-bookworm | changed: .d..t...... ./ 2026-08-30 16:51:26.583232 | 2026-08-30 16:51:26.583381 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-08-30 16:51:26.956121 | debian-bookworm -> localhost | ok: Item: artifacts Runtime: 0:00:00.009931 2026-08-30 16:51:27.277092 | debian-bookworm -> localhost | ok: Item: docs Runtime: 0:00:00.011005 2026-08-30 16:51:27.304162 | 2026-08-30 16:51:27.304471 | PLAY RECAP 2026-08-30 16:51:27.304707 | debian-bookworm | ok: 8 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-08-30 16:51:27.304809 | 2026-08-30 16:51:27.505889 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-08-30 16:51:27.508145 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-08-30 16:51:28.321978 | 2026-08-30 16:51:28.322180 | PLAY [Base post] 2026-08-30 16:51:28.336966 | 2026-08-30 16:51:28.337139 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-08-30 16:51:29.195929 | debian-bookworm | changed 2026-08-30 16:51:29.205282 | 2026-08-30 16:51:29.205416 | PLAY RECAP 2026-08-30 16:51:29.205489 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-08-30 16:51:29.205564 | 2026-08-30 16:51:29.376501 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-08-30 16:51:29.377576 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-08-30 16:51:30.230525 | 2026-08-30 16:51:30.230724 | PLAY [Base post-logs] 2026-08-30 16:51:30.242589 | 2026-08-30 16:51:30.242744 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-08-30 16:51:30.746239 | localhost | changed 2026-08-30 16:51:30.757428 | 2026-08-30 16:51:30.757625 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-08-30 16:51:30.787345 | localhost | ok 2026-08-30 16:51:30.794343 | 2026-08-30 16:51:30.794527 | TASK [Set zuul-log-path fact] 2026-08-30 16:51:30.813782 | localhost | ok 2026-08-30 16:51:30.826785 | 2026-08-30 16:51:30.826982 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-08-30 16:51:30.853909 | localhost | ok 2026-08-30 16:51:30.857382 | 2026-08-30 16:51:30.857500 | TASK [upload-logs : Create log directories] 2026-08-30 16:51:31.372745 | localhost | changed 2026-08-30 16:51:31.376245 | 2026-08-30 16:51:31.376370 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-08-30 16:51:31.887898 | localhost -> localhost | ok: Runtime: 0:00:00.008040 2026-08-30 16:51:31.894742 | 2026-08-30 16:51:31.895037 | TASK [upload-logs : Upload logs to log server] 2026-08-30 16:51:32.481626 | localhost | Output suppressed because no_log was given 2026-08-30 16:51:32.487110 | 2026-08-30 16:51:32.487288 | LOOP [upload-logs : Compress console log and json output] 2026-08-30 16:51:32.537425 | localhost | skipping: Conditional result was False 2026-08-30 16:51:32.547178 | localhost | skipping: Conditional result was False 2026-08-30 16:51:32.555863 | 2026-08-30 16:51:32.556010 | LOOP [upload-logs : Upload compressed console log and json output] 2026-08-30 16:51:32.603683 | localhost | skipping: Conditional result was False 2026-08-30 16:51:32.604009 | 2026-08-30 16:51:32.609275 | localhost | skipping: Conditional result was False 2026-08-30 16:51:32.616375 | 2026-08-30 16:51:32.616692 | LOOP [upload-logs : Upload console log and json output]