2026-09-27 16:59:10.481543 | Job console starting 2026-09-27 16:59:10.520389 | Updating git repos 2026-09-27 16:59:10.605463 | Cloning repos into workspace 2026-09-27 16:59:10.738246 | Restoring repo states 2026-09-27 16:59:10.792109 | Merging changes 2026-09-27 16:59:11.664881 | Checking out repos 2026-09-27 16:59:11.776070 | Preparing playbooks 2026-09-27 16:59:13.786752 | Running Ansible setup 2026-09-27 16:59:18.935437 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-09-27 16:59:20.314174 | 2026-09-27 16:59:20.314291 | PLAY [Base pre] 2026-09-27 16:59:20.339234 | 2026-09-27 16:59:20.339340 | TASK [Setup log path fact] 2026-09-27 16:59:20.367019 | debian-bookworm | ok 2026-09-27 16:59:20.385852 | 2026-09-27 16:59:20.391402 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-09-27 16:59:20.428160 | debian-bookworm | ok 2026-09-27 16:59:20.437121 | 2026-09-27 16:59:20.437205 | TASK [emit-job-header : Print job information] 2026-09-27 16:59:20.512284 | # Job Information 2026-09-27 16:59:20.512536 | Ansible Version: 2.16.14 2026-09-27 16:59:20.512572 | Job: python-osism-test-setup 2026-09-27 16:59:20.512606 | Pipeline: check 2026-09-27 16:59:20.512630 | Executor: 521e9411259a 2026-09-27 16:59:20.512647 | Triggered by: https://github.com/osism/python-osism/pull/2729 2026-09-27 16:59:20.512665 | Event ID: bb367330-ba94-11f1-9b2b-bd97ba8fef6b 2026-09-27 16:59:20.516811 | 2026-09-27 16:59:20.516915 | LOOP [emit-job-header : Print node information] 2026-09-27 16:59:20.709447 | debian-bookworm | ok: 2026-09-27 16:59:20.709638 | debian-bookworm | # Node Information 2026-09-27 16:59:20.709670 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-09-27 16:59:20.709692 | debian-bookworm | Hostname: debian 2026-09-27 16:59:20.709710 | debian-bookworm | Username: zuul 2026-09-27 16:59:20.709728 | debian-bookworm | Distro: Debian 12.15 2026-09-27 16:59:20.709747 | debian-bookworm | Provider: regiocloud-a 2026-09-27 16:59:20.709764 | debian-bookworm | Region: 2026-09-27 16:59:20.709781 | debian-bookworm | Label: debian-bookworm 2026-09-27 16:59:20.709798 | debian-bookworm | Product Name: OpenStack Nova 2026-09-27 16:59:20.709814 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe6e:cb6a 2026-09-27 16:59:20.721375 | 2026-09-27 16:59:20.721465 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-09-27 16:59:21.686231 | debian-bookworm -> localhost | changed 2026-09-27 16:59:21.692222 | 2026-09-27 16:59:21.692326 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-09-27 16:59:23.577628 | debian-bookworm -> localhost | changed 2026-09-27 16:59:23.588301 | 2026-09-27 16:59:23.588391 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-09-27 16:59:24.126383 | debian-bookworm -> localhost | ok 2026-09-27 16:59:24.131222 | 2026-09-27 16:59:24.131309 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-09-27 16:59:24.158727 | debian-bookworm | ok 2026-09-27 16:59:24.186555 | debian-bookworm | included: /var/lib/zuul/builds/35d020999a53405bb1b2f6b9c9f7896d/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-09-27 16:59:24.207702 | 2026-09-27 16:59:24.207791 | TASK [add-build-sshkey : Create Temp SSH key] 2026-09-27 16:59:25.599105 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-09-27 16:59:25.599262 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/35d020999a53405bb1b2f6b9c9f7896d/work/35d020999a53405bb1b2f6b9c9f7896d_id_rsa 2026-09-27 16:59:25.599293 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/35d020999a53405bb1b2f6b9c9f7896d/work/35d020999a53405bb1b2f6b9c9f7896d_id_rsa.pub 2026-09-27 16:59:25.599315 | debian-bookworm -> localhost | The key fingerprint is: 2026-09-27 16:59:25.599336 | debian-bookworm -> localhost | SHA256:0eiq4/249ZrgrhYTEW7QuXn3Pdkh639foXEpOXqJQXs zuul-build-sshkey 2026-09-27 16:59:25.599355 | debian-bookworm -> localhost | The key's randomart image is: 2026-09-27 16:59:25.599381 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-09-27 16:59:25.599399 | debian-bookworm -> localhost | | ...o | 2026-09-27 16:59:25.599417 | debian-bookworm -> localhost | | o+ o | 2026-09-27 16:59:25.599433 | debian-bookworm -> localhost | | o+ o .. | 2026-09-27 16:59:25.599449 | debian-bookworm -> localhost | | .+ ..... o o .| 2026-09-27 16:59:25.599465 | debian-bookworm -> localhost | | o .S. + E = | 2026-09-27 16:59:25.599487 | debian-bookworm -> localhost | | o . . X O .| 2026-09-27 16:59:25.599503 | debian-bookworm -> localhost | | oo . + = .| 2026-09-27 16:59:25.599519 | debian-bookworm -> localhost | | o+ + o o o| 2026-09-27 16:59:25.599536 | debian-bookworm -> localhost | | o++*o+.. ...o| 2026-09-27 16:59:25.599553 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-09-27 16:59:25.599591 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.517012 2026-09-27 16:59:25.604717 | 2026-09-27 16:59:25.604797 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-09-27 16:59:25.652748 | debian-bookworm | ok 2026-09-27 16:59:25.660818 | debian-bookworm | included: /var/lib/zuul/builds/35d020999a53405bb1b2f6b9c9f7896d/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-09-27 16:59:25.675892 | 2026-09-27 16:59:25.675986 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-09-27 16:59:25.705275 | debian-bookworm | skipping: Conditional result was False 2026-09-27 16:59:25.711025 | 2026-09-27 16:59:25.711119 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-09-27 16:59:26.356788 | debian-bookworm | changed 2026-09-27 16:59:26.361676 | 2026-09-27 16:59:26.361753 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-09-27 16:59:26.690475 | debian-bookworm | ok 2026-09-27 16:59:26.695866 | 2026-09-27 16:59:26.695943 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-09-27 16:59:27.444998 | debian-bookworm | changed 2026-09-27 16:59:27.449173 | 2026-09-27 16:59:27.449248 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-09-27 16:59:28.069814 | debian-bookworm | changed 2026-09-27 16:59:28.074204 | 2026-09-27 16:59:28.074276 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-09-27 16:59:28.086819 | debian-bookworm | skipping: Conditional result was False 2026-09-27 16:59:28.091910 | 2026-09-27 16:59:28.092010 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-09-27 16:59:28.635839 | debian-bookworm -> localhost | changed 2026-09-27 16:59:28.645222 | 2026-09-27 16:59:28.645325 | TASK [add-build-sshkey : Add back temp key] 2026-09-27 16:59:29.144266 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/35d020999a53405bb1b2f6b9c9f7896d/work/35d020999a53405bb1b2f6b9c9f7896d_id_rsa (zuul-build-sshkey) 2026-09-27 16:59:29.144441 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.011473 2026-09-27 16:59:29.150554 | 2026-09-27 16:59:29.150631 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-09-27 16:59:29.538977 | debian-bookworm | ok 2026-09-27 16:59:29.543063 | 2026-09-27 16:59:29.543144 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-09-27 16:59:29.586102 | debian-bookworm | skipping: Conditional result was False 2026-09-27 16:59:29.594257 | 2026-09-27 16:59:29.594352 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-09-27 16:59:30.062201 | debian-bookworm | ok 2026-09-27 16:59:30.071337 | 2026-09-27 16:59:30.071419 | TASK [validate-host : Define zuul_info_dir fact] 2026-09-27 16:59:30.097894 | debian-bookworm | ok 2026-09-27 16:59:30.102072 | 2026-09-27 16:59:30.102144 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-09-27 16:59:30.531656 | debian-bookworm -> localhost | ok 2026-09-27 16:59:30.536719 | 2026-09-27 16:59:30.536794 | TASK [validate-host : Collect information about the host] 2026-09-27 16:59:31.701796 | debian-bookworm | ok 2026-09-27 16:59:31.732897 | 2026-09-27 16:59:31.733014 | TASK [validate-host : Sanitize hostname] 2026-09-27 16:59:31.946487 | debian-bookworm | ok 2026-09-27 16:59:31.950164 | 2026-09-27 16:59:31.950241 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-09-27 16:59:32.622412 | debian-bookworm -> localhost | changed 2026-09-27 16:59:32.627105 | 2026-09-27 16:59:32.627183 | TASK [validate-host : Collect information about zuul worker] 2026-09-27 16:59:33.021494 | debian-bookworm | ok 2026-09-27 16:59:33.025301 | 2026-09-27 16:59:33.025378 | TASK [validate-host : Write out all zuul information for each host] 2026-09-27 16:59:33.754274 | debian-bookworm -> localhost | changed 2026-09-27 16:59:33.761747 | 2026-09-27 16:59:33.761827 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-09-27 16:59:34.031089 | debian-bookworm | ok 2026-09-27 16:59:34.035473 | 2026-09-27 16:59:34.035550 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-09-27 16:59:36.425538 | debian-bookworm | changed: 2026-09-27 16:59:36.425870 | debian-bookworm | cd+++++++++ src/ 2026-09-27 16:59:36.425908 | debian-bookworm | cd+++++++++ src/github.com/ 2026-09-27 16:59:36.425934 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-09-27 16:59:36.425986 | debian-bookworm | cd+++++++++ src/github.com/osism/python-osism/ 2026-09-27 16:59:36.426011 | debian-bookworm | Containerfile 2026-09-27 16:59:36.426148 | debian-bookworm | Containerfile 2026-09-27 16:59:36.434809 | debian-bookworm | localhost | ok: "/var/lib/zuul/builds/35d020999a53405bb1b2f6b9c9f7896d/work/logs" 2026-09-27 17:02:18.033216 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/35d020999a53405bb1b2f6b9c9f7896d/work/artifacts" 2026-09-27 17:02:18.311121 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/35d020999a53405bb1b2f6b9c9f7896d/work/docs" 2026-09-27 17:02:18.326242 | 2026-09-27 17:02:18.326362 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-09-27 17:02:19.069901 | debian-bookworm | changed: 2026-09-27 17:02:19.070162 | debian-bookworm | .d..t...... ./ 2026-09-27 17:02:19.070199 | debian-bookworm | >f+++++++++ net-debug.post.txt 2026-09-27 17:02:19.070223 | debian-bookworm | >f+++++++++ net-debug.pre.txt 2026-09-27 17:02:19.070245 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-09-27 17:02:19.070276 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-09-27 17:02:19.070297 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-09-27 17:02:19.070334 | debian-bookworm | changed: All items complete 2026-09-27 17:02:19.070355 | 2026-09-27 17:02:19.656818 | debian-bookworm | changed: .d..t...... ./ 2026-09-27 17:02:20.233157 | debian-bookworm | changed: .d..t...... ./ 2026-09-27 17:02:20.286573 | 2026-09-27 17:02:20.286704 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-09-27 17:02:20.617452 | debian-bookworm -> localhost | ok: Item: artifacts Runtime: 0:00:00.009723 2026-09-27 17:02:20.883354 | debian-bookworm -> localhost | ok: Item: docs Runtime: 0:00:00.009966 2026-09-27 17:02:20.906628 | 2026-09-27 17:02:20.906737 | PLAY RECAP 2026-09-27 17:02:20.906854 | debian-bookworm | ok: 8 changed: 3 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-09-27 17:02:20.906879 | 2026-09-27 17:02:21.032749 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-09-27 17:02:21.033671 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-09-27 17:02:21.830831 | 2026-09-27 17:02:21.831000 | PLAY [Base post] 2026-09-27 17:02:21.845038 | 2026-09-27 17:02:21.845204 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-09-27 17:02:22.641412 | debian-bookworm | changed 2026-09-27 17:02:22.654176 | 2026-09-27 17:02:22.654398 | PLAY RECAP 2026-09-27 17:02:22.654550 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-09-27 17:02:22.654711 | 2026-09-27 17:02:22.776335 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-09-27 17:02:22.778563 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-09-27 17:02:23.581877 | 2026-09-27 17:02:23.582037 | PLAY [Base post-logs] 2026-09-27 17:02:23.592303 | 2026-09-27 17:02:23.592429 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-09-27 17:02:24.049150 | localhost | changed 2026-09-27 17:02:24.067631 | 2026-09-27 17:02:24.067810 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-09-27 17:02:24.107833 | localhost | ok 2026-09-27 17:02:24.117814 | 2026-09-27 17:02:24.117996 | TASK [Set zuul-log-path fact] 2026-09-27 17:02:24.134828 | localhost | ok 2026-09-27 17:02:24.146217 | 2026-09-27 17:02:24.146350 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-09-27 17:02:24.174427 | localhost | ok 2026-09-27 17:02:24.179308 | 2026-09-27 17:02:24.179414 | TASK [upload-logs : Create log directories] 2026-09-27 17:02:24.671240 | localhost | changed 2026-09-27 17:02:24.679333 | 2026-09-27 17:02:24.679449 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-09-27 17:02:25.139291 | localhost -> localhost | ok: Runtime: 0:00:00.007553 2026-09-27 17:02:25.148435 | 2026-09-27 17:02:25.148655 | TASK [upload-logs : Upload logs to log server] 2026-09-27 17:02:25.688270 | localhost | Output suppressed because no_log was given 2026-09-27 17:02:25.691454 | 2026-09-27 17:02:25.691589 | LOOP [upload-logs : Compress console log and json output] 2026-09-27 17:02:25.744693 | localhost | skipping: Conditional result was False 2026-09-27 17:02:25.749807 | localhost | skipping: Conditional result was False 2026-09-27 17:02:25.764943 | 2026-09-27 17:02:25.765213 | LOOP [upload-logs : Upload compressed console log and json output] 2026-09-27 17:02:25.819379 | localhost | skipping: Conditional result was False 2026-09-27 17:02:25.819647 | 2026-09-27 17:02:25.826262 | localhost | skipping: Conditional result was False 2026-09-27 17:02:25.834572 | 2026-09-27 17:02:25.834676 | LOOP [upload-logs : Upload console log and json output]