2026-07-22 15:53:39.197252 | Job console starting 2026-07-22 15:53:39.227546 | Updating git repos 2026-07-22 15:53:39.297144 | Cloning repos into workspace 2026-07-22 15:53:39.388349 | Restoring repo states 2026-07-22 15:53:39.428265 | Merging changes 2026-07-22 15:53:40.642881 | Checking out repos 2026-07-22 15:53:40.756171 | Preparing playbooks 2026-07-22 15:53:43.196872 | Running Ansible setup 2026-07-22 15:53:51.455088 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-07-22 15:53:53.352468 | 2026-07-22 15:53:53.352622 | PLAY [Base pre] 2026-07-22 15:53:53.414419 | 2026-07-22 15:53:53.414566 | TASK [Setup log path fact] 2026-07-22 15:53:53.461495 | debian-bookworm | ok 2026-07-22 15:53:53.490220 | 2026-07-22 15:53:53.490371 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-07-22 15:53:53.541527 | debian-bookworm | ok 2026-07-22 15:53:53.566269 | 2026-07-22 15:53:53.566418 | TASK [emit-job-header : Print job information] 2026-07-22 15:53:53.655486 | # Job Information 2026-07-22 15:53:53.655699 | Ansible Version: 2.16.14 2026-07-22 15:53:53.655737 | Job: python-osism-test-setup 2026-07-22 15:53:53.655768 | Pipeline: check 2026-07-22 15:53:53.655790 | Executor: 521e9411259a 2026-07-22 15:53:53.655811 | Triggered by: https://github.com/osism/python-osism/pull/2503 2026-07-22 15:53:53.655832 | Event ID: 7a1e7f20-85e5-11f1-99ac-74c4a8b7bddd 2026-07-22 15:53:53.660758 | 2026-07-22 15:53:53.660864 | LOOP [emit-job-header : Print node information] 2026-07-22 15:53:53.941178 | debian-bookworm | ok: 2026-07-22 15:53:53.941452 | debian-bookworm | # Node Information 2026-07-22 15:53:53.941499 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-07-22 15:53:53.941526 | debian-bookworm | Hostname: debian 2026-07-22 15:53:53.941549 | debian-bookworm | Username: zuul 2026-07-22 15:53:53.941570 | debian-bookworm | Distro: Debian 12.15 2026-07-22 15:53:53.941593 | debian-bookworm | Provider: regiocloud-a 2026-07-22 15:53:53.941614 | debian-bookworm | Region: 2026-07-22 15:53:53.941636 | debian-bookworm | Label: debian-bookworm 2026-07-22 15:53:53.941656 | debian-bookworm | Product Name: OpenStack Nova 2026-07-22 15:53:53.941676 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe36:711f 2026-07-22 15:53:53.972081 | 2026-07-22 15:53:53.972205 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-07-22 15:53:55.117061 | debian-bookworm -> localhost | changed 2026-07-22 15:53:55.125291 | 2026-07-22 15:53:55.125402 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-07-22 15:53:58.149815 | debian-bookworm -> localhost | changed 2026-07-22 15:53:58.165362 | 2026-07-22 15:53:58.165482 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-07-22 15:53:58.842512 | debian-bookworm -> localhost | ok 2026-07-22 15:53:58.847710 | 2026-07-22 15:53:58.847799 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-07-22 15:53:58.885604 | debian-bookworm | ok 2026-07-22 15:53:58.908337 | debian-bookworm | included: /var/lib/zuul/builds/802b551c5bb44a2882b996dfe93e9a81/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-07-22 15:53:58.915800 | 2026-07-22 15:53:58.915886 | TASK [add-build-sshkey : Create Temp SSH key] 2026-07-22 15:54:01.791785 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-07-22 15:54:01.791995 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/802b551c5bb44a2882b996dfe93e9a81/work/802b551c5bb44a2882b996dfe93e9a81_id_rsa 2026-07-22 15:54:01.792032 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/802b551c5bb44a2882b996dfe93e9a81/work/802b551c5bb44a2882b996dfe93e9a81_id_rsa.pub 2026-07-22 15:54:01.792058 | debian-bookworm -> localhost | The key fingerprint is: 2026-07-22 15:54:01.792082 | debian-bookworm -> localhost | SHA256:PwOeEo+PP/W3FvFsWZw+DpkWMzOuGrC4jcAKwFSS7pw zuul-build-sshkey 2026-07-22 15:54:01.792105 | debian-bookworm -> localhost | The key's randomart image is: 2026-07-22 15:54:01.792134 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-07-22 15:54:01.792155 | debian-bookworm -> localhost | | ... | 2026-07-22 15:54:01.792176 | debian-bookworm -> localhost | | .o | 2026-07-22 15:54:01.792196 | debian-bookworm -> localhost | |.. ..| 2026-07-22 15:54:01.792215 | debian-bookworm -> localhost | |o. *..o| 2026-07-22 15:54:01.792235 | debian-bookworm -> localhost | |+.. ..S . X+o| 2026-07-22 15:54:01.792262 | debian-bookworm -> localhost | |.E. .=o+. *.++| 2026-07-22 15:54:01.792283 | debian-bookworm -> localhost | |. o .o.+o+.o oo.| 2026-07-22 15:54:01.792303 | debian-bookworm -> localhost | |. . . ++. .+. o. | 2026-07-22 15:54:01.792339 | debian-bookworm -> localhost | | . o.ooo. o.. | 2026-07-22 15:54:01.792361 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-07-22 15:54:01.792423 | debian-bookworm -> localhost | ok: Runtime: 0:00:01.909438 2026-07-22 15:54:01.801247 | 2026-07-22 15:54:01.801355 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-07-22 15:54:01.841374 | debian-bookworm | ok 2026-07-22 15:54:01.868015 | debian-bookworm | included: /var/lib/zuul/builds/802b551c5bb44a2882b996dfe93e9a81/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-07-22 15:54:01.879182 | 2026-07-22 15:54:01.879301 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-07-22 15:54:01.902703 | debian-bookworm | skipping: Conditional result was False 2026-07-22 15:54:01.907869 | 2026-07-22 15:54:01.907966 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-07-22 15:54:02.709892 | debian-bookworm | changed 2026-07-22 15:54:02.714536 | 2026-07-22 15:54:02.714621 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-07-22 15:54:02.971012 | debian-bookworm | ok 2026-07-22 15:54:02.975634 | 2026-07-22 15:54:02.975721 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-07-22 15:54:03.733525 | debian-bookworm | changed 2026-07-22 15:54:03.741329 | 2026-07-22 15:54:03.741454 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-07-22 15:54:04.553106 | debian-bookworm | changed 2026-07-22 15:54:04.558562 | 2026-07-22 15:54:04.558648 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-07-22 15:54:04.601194 | debian-bookworm | skipping: Conditional result was False 2026-07-22 15:54:04.613274 | 2026-07-22 15:54:04.613395 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-07-22 15:54:05.465772 | debian-bookworm -> localhost | changed 2026-07-22 15:54:05.475292 | 2026-07-22 15:54:05.475386 | TASK [add-build-sshkey : Add back temp key] 2026-07-22 15:54:06.340218 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/802b551c5bb44a2882b996dfe93e9a81/work/802b551c5bb44a2882b996dfe93e9a81_id_rsa (zuul-build-sshkey) 2026-07-22 15:54:06.340435 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.025235 2026-07-22 15:54:06.347194 | 2026-07-22 15:54:06.347291 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-07-22 15:54:06.960019 | debian-bookworm | ok 2026-07-22 15:54:06.964712 | 2026-07-22 15:54:06.964803 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-07-22 15:54:07.020847 | debian-bookworm | skipping: Conditional result was False 2026-07-22 15:54:07.029331 | 2026-07-22 15:54:07.029427 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-07-22 15:54:07.543911 | debian-bookworm | ok 2026-07-22 15:54:07.565100 | 2026-07-22 15:54:07.565217 | TASK [validate-host : Define zuul_info_dir fact] 2026-07-22 15:54:07.605112 | debian-bookworm | ok 2026-07-22 15:54:07.610321 | 2026-07-22 15:54:07.610437 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-07-22 15:54:08.115666 | debian-bookworm -> localhost | ok 2026-07-22 15:54:08.124339 | 2026-07-22 15:54:08.124463 | TASK [validate-host : Collect information about the host] 2026-07-22 15:54:10.582106 | debian-bookworm | ok 2026-07-22 15:54:10.593895 | 2026-07-22 15:54:10.594002 | TASK [validate-host : Sanitize hostname] 2026-07-22 15:54:10.749529 | debian-bookworm | ok 2026-07-22 15:54:10.753688 | 2026-07-22 15:54:10.753778 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-07-22 15:54:11.470286 | debian-bookworm -> localhost | changed 2026-07-22 15:54:11.488547 | 2026-07-22 15:54:11.488665 | TASK [validate-host : Collect information about zuul worker] 2026-07-22 15:54:11.885523 | debian-bookworm | ok 2026-07-22 15:54:11.894751 | 2026-07-22 15:54:11.894966 | TASK [validate-host : Write out all zuul information for each host] 2026-07-22 15:54:12.979091 | debian-bookworm -> localhost | changed 2026-07-22 15:54:12.993125 | 2026-07-22 15:54:12.993335 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-07-22 15:54:13.287929 | debian-bookworm | ok 2026-07-22 15:54:13.296596 | 2026-07-22 15:54:13.296726 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-07-22 15:54:14.270050 | debian-bookworm | changed: 2026-07-22 15:54:14.270231 | debian-bookworm | cd+++++++++ src/ 2026-07-22 15:54:14.270265 | debian-bookworm | cd+++++++++ src/github.com/ 2026-07-22 15:54:14.270289 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-07-22 15:54:14.270311 | debian-bookworm | cd+++++++++ src/github.com/osism/python-osism/ 2026-07-22 15:54:14.270332 | debian-bookworm | Containerfile 2026-07-22 15:54:14.270478 | debian-bookworm | Containerfile 2026-07-22 15:54:14.289388 | debian-bookworm | localhost | ok: "/var/lib/zuul/builds/802b551c5bb44a2882b996dfe93e9a81/work/logs" 2026-07-22 15:56:47.654150 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/802b551c5bb44a2882b996dfe93e9a81/work/artifacts" 2026-07-22 15:56:47.932666 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/802b551c5bb44a2882b996dfe93e9a81/work/docs" 2026-07-22 15:56:47.945523 | 2026-07-22 15:56:47.945677 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-07-22 15:56:48.841669 | debian-bookworm | changed: 2026-07-22 15:56:48.842074 | debian-bookworm | .d..t...... ./ 2026-07-22 15:56:48.842138 | debian-bookworm | >f+++++++++ net-debug.post.txt 2026-07-22 15:56:48.842173 | debian-bookworm | >f+++++++++ net-debug.pre.txt 2026-07-22 15:56:48.842203 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-07-22 15:56:48.842234 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-07-22 15:56:48.842264 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-07-22 15:56:48.842326 | debian-bookworm | changed: All items complete 2026-07-22 15:56:48.842364 | 2026-07-22 15:56:49.420558 | debian-bookworm | changed: .d..t...... ./ 2026-07-22 15:56:50.001365 | debian-bookworm | changed: .d..t...... ./ 2026-07-22 15:56:50.030384 | 2026-07-22 15:56:50.030608 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-07-22 15:56:50.435332 | debian-bookworm -> localhost | ok: Item: artifacts Runtime: 0:00:00.007154 2026-07-22 15:56:50.716984 | debian-bookworm -> localhost | ok: Item: docs Runtime: 0:00:00.008089 2026-07-22 15:56:50.737973 | 2026-07-22 15:56:50.738166 | PLAY RECAP 2026-07-22 15:56:50.738235 | debian-bookworm | ok: 8 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-07-22 15:56:50.738284 | 2026-07-22 15:56:50.897701 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-07-22 15:56:50.898815 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-07-22 15:56:51.693822 | 2026-07-22 15:56:51.694000 | PLAY [Base post] 2026-07-22 15:56:51.708307 | 2026-07-22 15:56:51.708471 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-07-22 15:56:52.618809 | debian-bookworm | changed 2026-07-22 15:56:52.633351 | 2026-07-22 15:56:52.633586 | PLAY RECAP 2026-07-22 15:56:52.633713 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-07-22 15:56:52.633845 | 2026-07-22 15:56:52.777735 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-07-22 15:56:52.779370 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-07-22 15:56:53.648491 | 2026-07-22 15:56:53.648689 | PLAY [Base post-logs] 2026-07-22 15:56:53.659829 | 2026-07-22 15:56:53.659991 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-07-22 15:56:54.142251 | localhost | changed 2026-07-22 15:56:54.153004 | 2026-07-22 15:56:54.153169 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-07-22 15:56:54.205246 | localhost | ok 2026-07-22 15:56:54.216528 | 2026-07-22 15:56:54.216751 | TASK [Set zuul-log-path fact] 2026-07-22 15:56:54.237077 | localhost | ok 2026-07-22 15:56:54.248765 | 2026-07-22 15:56:54.248897 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-07-22 15:56:54.275818 | localhost | ok 2026-07-22 15:56:54.279743 | 2026-07-22 15:56:54.279867 | TASK [upload-logs : Create log directories] 2026-07-22 15:56:54.821863 | localhost | changed 2026-07-22 15:56:54.827777 | 2026-07-22 15:56:54.827953 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-07-22 15:56:55.387329 | localhost -> localhost | ok: Runtime: 0:00:00.007651 2026-07-22 15:56:55.391804 | 2026-07-22 15:56:55.391940 | TASK [upload-logs : Upload logs to log server] 2026-07-22 15:56:56.018934 | localhost | Output suppressed because no_log was given 2026-07-22 15:56:56.021048 | 2026-07-22 15:56:56.021162 | LOOP [upload-logs : Compress console log and json output] 2026-07-22 15:56:56.091001 | localhost | skipping: Conditional result was False 2026-07-22 15:56:56.107727 | localhost | skipping: Conditional result was False 2026-07-22 15:56:56.112897 | 2026-07-22 15:56:56.113069 | LOOP [upload-logs : Upload compressed console log and json output] 2026-07-22 15:56:56.163886 | localhost | skipping: Conditional result was False 2026-07-22 15:56:56.164193 | 2026-07-22 15:56:56.171504 | localhost | skipping: Conditional result was False 2026-07-22 15:56:56.186396 | 2026-07-22 15:56:56.186624 | LOOP [upload-logs : Upload console log and json output]