2026-07-24 16:08:08.752154 | Job console starting 2026-07-24 16:08:08.768801 | Updating git repos 2026-07-24 16:08:08.816521 | Cloning repos into workspace 2026-07-24 16:08:08.907706 | Restoring repo states 2026-07-24 16:08:08.939819 | Merging changes 2026-07-24 16:08:09.908258 | Checking out repos 2026-07-24 16:08:10.043504 | Preparing playbooks 2026-07-24 16:08:12.051697 | Running Ansible setup 2026-07-24 16:08:17.339582 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-07-24 16:08:18.193194 | 2026-07-24 16:08:18.193338 | PLAY [Base pre] 2026-07-24 16:08:18.220693 | 2026-07-24 16:08:18.220827 | TASK [Setup log path fact] 2026-07-24 16:08:18.249238 | debian-bookworm | ok 2026-07-24 16:08:18.276813 | 2026-07-24 16:08:18.276959 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-07-24 16:08:18.337645 | debian-bookworm | ok 2026-07-24 16:08:18.364991 | 2026-07-24 16:08:18.365118 | TASK [emit-job-header : Print job information] 2026-07-24 16:08:18.424225 | # Job Information 2026-07-24 16:08:18.424412 | Ansible Version: 2.16.14 2026-07-24 16:08:18.424449 | Job: python-osism-test-setup 2026-07-24 16:08:18.424483 | Pipeline: check 2026-07-24 16:08:18.424506 | Executor: 521e9411259a 2026-07-24 16:08:18.424539 | Triggered by: https://github.com/osism/python-osism/pull/2450 2026-07-24 16:08:18.424564 | Event ID: 5145d680-8779-11f1-9322-084c6636d649 2026-07-24 16:08:18.429834 | 2026-07-24 16:08:18.429942 | LOOP [emit-job-header : Print node information] 2026-07-24 16:08:18.670057 | debian-bookworm | ok: 2026-07-24 16:08:18.670244 | debian-bookworm | # Node Information 2026-07-24 16:08:18.670277 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-07-24 16:08:18.670302 | debian-bookworm | Hostname: debian 2026-07-24 16:08:18.670324 | debian-bookworm | Username: zuul 2026-07-24 16:08:18.670345 | debian-bookworm | Distro: Debian 12.15 2026-07-24 16:08:18.670368 | debian-bookworm | Provider: regiocloud-a 2026-07-24 16:08:18.670388 | debian-bookworm | Region: 2026-07-24 16:08:18.670409 | debian-bookworm | Label: debian-bookworm 2026-07-24 16:08:18.670428 | debian-bookworm | Product Name: OpenStack Nova 2026-07-24 16:08:18.670447 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe47:e54e 2026-07-24 16:08:18.680339 | 2026-07-24 16:08:18.680452 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-07-24 16:08:19.343758 | debian-bookworm -> localhost | changed 2026-07-24 16:08:19.360394 | 2026-07-24 16:08:19.360579 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-07-24 16:08:21.227773 | debian-bookworm -> localhost | changed 2026-07-24 16:08:21.244756 | 2026-07-24 16:08:21.244940 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-07-24 16:08:21.855694 | debian-bookworm -> localhost | ok 2026-07-24 16:08:21.862982 | 2026-07-24 16:08:21.863155 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-07-24 16:08:21.895320 | debian-bookworm | ok 2026-07-24 16:08:21.938849 | debian-bookworm | included: /var/lib/zuul/builds/87246a919d4943c28630f570bc3613b4/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-07-24 16:08:21.955735 | 2026-07-24 16:08:21.955890 | TASK [add-build-sshkey : Create Temp SSH key] 2026-07-24 16:08:23.317836 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-07-24 16:08:23.318043 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/87246a919d4943c28630f570bc3613b4/work/87246a919d4943c28630f570bc3613b4_id_rsa 2026-07-24 16:08:23.318081 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/87246a919d4943c28630f570bc3613b4/work/87246a919d4943c28630f570bc3613b4_id_rsa.pub 2026-07-24 16:08:23.318109 | debian-bookworm -> localhost | The key fingerprint is: 2026-07-24 16:08:23.318135 | debian-bookworm -> localhost | SHA256:xxuKax++opI9Bqx/CqrIz/b/43lM5gbHnVtyuRjlvyM zuul-build-sshkey 2026-07-24 16:08:23.318157 | debian-bookworm -> localhost | The key's randomart image is: 2026-07-24 16:08:23.318188 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-07-24 16:08:23.318210 | debian-bookworm -> localhost | | | 2026-07-24 16:08:23.318231 | debian-bookworm -> localhost | | | 2026-07-24 16:08:23.318251 | debian-bookworm -> localhost | | | 2026-07-24 16:08:23.318271 | debian-bookworm -> localhost | | . . | 2026-07-24 16:08:23.318291 | debian-bookworm -> localhost | | . S = . + .| 2026-07-24 16:08:23.318318 | debian-bookworm -> localhost | | o . + B = = | 2026-07-24 16:08:23.318340 | debian-bookworm -> localhost | |.. + . o O B o| 2026-07-24 16:08:23.318360 | debian-bookworm -> localhost | |=.+.= oo o.= E o.| 2026-07-24 16:08:23.318380 | debian-bookworm -> localhost | |=o=Bo=o+*=+ ..o| 2026-07-24 16:08:23.318401 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-07-24 16:08:23.318457 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.658276 2026-07-24 16:08:23.324966 | 2026-07-24 16:08:23.325074 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-07-24 16:08:23.383568 | debian-bookworm | ok 2026-07-24 16:08:23.401691 | debian-bookworm | included: /var/lib/zuul/builds/87246a919d4943c28630f570bc3613b4/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-07-24 16:08:23.429932 | 2026-07-24 16:08:23.430057 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-07-24 16:08:23.493746 | debian-bookworm | skipping: Conditional result was False 2026-07-24 16:08:23.499857 | 2026-07-24 16:08:23.499963 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-07-24 16:08:24.167605 | debian-bookworm | changed 2026-07-24 16:08:24.172203 | 2026-07-24 16:08:24.172281 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-07-24 16:08:24.506256 | debian-bookworm | ok 2026-07-24 16:08:24.512121 | 2026-07-24 16:08:24.512234 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-07-24 16:08:25.275945 | debian-bookworm | changed 2026-07-24 16:08:25.280418 | 2026-07-24 16:08:25.280517 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-07-24 16:08:25.997691 | debian-bookworm | changed 2026-07-24 16:08:26.002275 | 2026-07-24 16:08:26.002368 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-07-24 16:08:26.016910 | debian-bookworm | skipping: Conditional result was False 2026-07-24 16:08:26.032871 | 2026-07-24 16:08:26.035289 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-07-24 16:08:26.709658 | debian-bookworm -> localhost | changed 2026-07-24 16:08:26.718392 | 2026-07-24 16:08:26.718491 | TASK [add-build-sshkey : Add back temp key] 2026-07-24 16:08:27.243249 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/87246a919d4943c28630f570bc3613b4/work/87246a919d4943c28630f570bc3613b4_id_rsa (zuul-build-sshkey) 2026-07-24 16:08:27.243447 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.031972 2026-07-24 16:08:27.263566 | 2026-07-24 16:08:27.263675 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-07-24 16:08:27.743915 | debian-bookworm | ok 2026-07-24 16:08:27.752085 | 2026-07-24 16:08:27.752205 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-07-24 16:08:27.766171 | debian-bookworm | skipping: Conditional result was False 2026-07-24 16:08:27.788156 | 2026-07-24 16:08:27.788252 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-07-24 16:08:28.217913 | debian-bookworm | ok 2026-07-24 16:08:28.230996 | 2026-07-24 16:08:28.231096 | TASK [validate-host : Define zuul_info_dir fact] 2026-07-24 16:08:28.272662 | debian-bookworm | ok 2026-07-24 16:08:28.277580 | 2026-07-24 16:08:28.277663 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-07-24 16:08:28.546853 | debian-bookworm -> localhost | ok 2026-07-24 16:08:28.560446 | 2026-07-24 16:08:28.560574 | TASK [validate-host : Collect information about the host] 2026-07-24 16:08:29.674609 | debian-bookworm | ok 2026-07-24 16:08:29.684248 | 2026-07-24 16:08:29.684346 | TASK [validate-host : Sanitize hostname] 2026-07-24 16:08:29.849670 | debian-bookworm | ok 2026-07-24 16:08:29.854263 | 2026-07-24 16:08:29.854359 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-07-24 16:08:30.824991 | debian-bookworm -> localhost | changed 2026-07-24 16:08:30.832324 | 2026-07-24 16:08:30.832416 | TASK [validate-host : Collect information about zuul worker] 2026-07-24 16:08:31.427752 | debian-bookworm | ok 2026-07-24 16:08:31.432427 | 2026-07-24 16:08:31.432533 | TASK [validate-host : Write out all zuul information for each host] 2026-07-24 16:08:32.376323 | debian-bookworm -> localhost | changed 2026-07-24 16:08:32.385548 | 2026-07-24 16:08:32.385648 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-07-24 16:08:32.725951 | debian-bookworm | ok 2026-07-24 16:08:32.730584 | 2026-07-24 16:08:32.730673 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-07-24 16:08:34.011710 | debian-bookworm | changed: 2026-07-24 16:08:34.012275 | debian-bookworm | cd+++++++++ src/ 2026-07-24 16:08:34.012306 | debian-bookworm | cd+++++++++ src/github.com/ 2026-07-24 16:08:34.012325 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-07-24 16:08:34.012580 | debian-bookworm | cd+++++++++ src/github.com/osism/python-osism/ 2026-07-24 16:08:34.012851 | debian-bookworm | Containerfile 2026-07-24 16:08:34.012977 | debian-bookworm | Containerfile 2026-07-24 16:08:34.047785 | debian-bookworm | localhost | ok: "/var/lib/zuul/builds/87246a919d4943c28630f570bc3613b4/work/logs" 2026-07-24 16:11:07.389734 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/87246a919d4943c28630f570bc3613b4/work/artifacts" 2026-07-24 16:11:07.688860 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/87246a919d4943c28630f570bc3613b4/work/docs" 2026-07-24 16:11:07.718024 | 2026-07-24 16:11:07.718310 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-07-24 16:11:08.538602 | debian-bookworm | changed: 2026-07-24 16:11:08.538956 | debian-bookworm | .d..t...... ./ 2026-07-24 16:11:08.538995 | debian-bookworm | >f+++++++++ net-debug.post.txt 2026-07-24 16:11:08.539019 | debian-bookworm | >f+++++++++ net-debug.pre.txt 2026-07-24 16:11:08.539040 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-07-24 16:11:08.539062 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-07-24 16:11:08.539083 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-07-24 16:11:08.539124 | debian-bookworm | changed: All items complete 2026-07-24 16:11:08.539147 | 2026-07-24 16:11:09.140822 | debian-bookworm | changed: .d..t...... ./ 2026-07-24 16:11:09.730088 | debian-bookworm | changed: .d..t...... ./ 2026-07-24 16:11:09.752535 | 2026-07-24 16:11:09.752683 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-07-24 16:11:10.102538 | debian-bookworm -> localhost | ok: Item: artifacts Runtime: 0:00:00.011499 2026-07-24 16:11:10.404527 | debian-bookworm -> localhost | ok: Item: docs Runtime: 0:00:00.010481 2026-07-24 16:11:10.424799 | 2026-07-24 16:11:10.424925 | PLAY RECAP 2026-07-24 16:11:10.425003 | debian-bookworm | ok: 8 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-07-24 16:11:10.425044 | 2026-07-24 16:11:10.563666 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-07-24 16:11:10.565829 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-07-24 16:11:11.347031 | 2026-07-24 16:11:11.347224 | PLAY [Base post] 2026-07-24 16:11:11.371334 | 2026-07-24 16:11:11.371584 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-07-24 16:11:12.157739 | debian-bookworm | changed 2026-07-24 16:11:12.171394 | 2026-07-24 16:11:12.171718 | PLAY RECAP 2026-07-24 16:11:12.171895 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-07-24 16:11:12.172112 | 2026-07-24 16:11:12.317091 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-07-24 16:11:12.319339 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-07-24 16:11:13.136100 | 2026-07-24 16:11:13.136299 | PLAY [Base post-logs] 2026-07-24 16:11:13.147834 | 2026-07-24 16:11:13.147966 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-07-24 16:11:13.633240 | localhost | changed 2026-07-24 16:11:13.646919 | 2026-07-24 16:11:13.647122 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-07-24 16:11:13.697009 | localhost | ok 2026-07-24 16:11:13.700963 | 2026-07-24 16:11:13.701101 | TASK [Set zuul-log-path fact] 2026-07-24 16:11:13.729441 | localhost | ok 2026-07-24 16:11:13.741451 | 2026-07-24 16:11:13.741590 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-07-24 16:11:13.780015 | localhost | ok 2026-07-24 16:11:13.787788 | 2026-07-24 16:11:13.787971 | TASK [upload-logs : Create log directories] 2026-07-24 16:11:14.378110 | localhost | changed 2026-07-24 16:11:14.382115 | 2026-07-24 16:11:14.382246 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-07-24 16:11:14.996201 | localhost -> localhost | ok: Runtime: 0:00:00.008553 2026-07-24 16:11:15.005608 | 2026-07-24 16:11:15.005807 | TASK [upload-logs : Upload logs to log server] 2026-07-24 16:11:15.583089 | localhost | Output suppressed because no_log was given 2026-07-24 16:11:15.585389 | 2026-07-24 16:11:15.585502 | LOOP [upload-logs : Compress console log and json output] 2026-07-24 16:11:15.633280 | localhost | skipping: Conditional result was False 2026-07-24 16:11:15.639469 | localhost | skipping: Conditional result was False 2026-07-24 16:11:15.644107 | 2026-07-24 16:11:15.644219 | LOOP [upload-logs : Upload compressed console log and json output] 2026-07-24 16:11:15.692815 | localhost | skipping: Conditional result was False 2026-07-24 16:11:15.693698 | 2026-07-24 16:11:15.697033 | localhost | skipping: Conditional result was False 2026-07-24 16:11:15.705223 | 2026-07-24 16:11:15.705409 | LOOP [upload-logs : Upload console log and json output]