2026-10-05 14:06:32.025481 | Job console starting 2026-10-05 14:06:32.040421 | Updating git repos 2026-10-05 14:06:32.097926 | Cloning repos into workspace 2026-10-05 14:06:32.198461 | Restoring repo states 2026-10-05 14:06:32.229182 | Merging changes 2026-10-05 14:06:33.846676 | Checking out repos 2026-10-05 14:06:33.932256 | Preparing playbooks 2026-10-05 14:06:35.049362 | Running Ansible setup 2026-10-05 14:06:40.768595 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-10-05 14:06:41.923757 | 2026-10-05 14:06:41.923899 | PLAY [Base pre] 2026-10-05 14:06:41.952191 | 2026-10-05 14:06:41.952318 | TASK [Setup log path fact] 2026-10-05 14:06:42.021065 | debian-bookworm | ok 2026-10-05 14:06:42.062627 | 2026-10-05 14:06:42.062764 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-10-05 14:06:42.111459 | debian-bookworm | ok 2026-10-05 14:06:42.124674 | 2026-10-05 14:06:42.124779 | TASK [emit-job-header : Print job information] 2026-10-05 14:06:42.195380 | # Job Information 2026-10-05 14:06:42.195528 | Ansible Version: 2.16.14 2026-10-05 14:06:42.195563 | Job: python-osism-test-setup 2026-10-05 14:06:42.195594 | Pipeline: check 2026-10-05 14:06:42.195617 | Executor: 521e9411259a 2026-10-05 14:06:42.195639 | Triggered by: https://github.com/osism/python-osism/pull/2756 2026-10-05 14:06:42.195661 | Event ID: d3b2d980-c0c5-11f1-8652-d02c784363ed 2026-10-05 14:06:42.200394 | 2026-10-05 14:06:42.200486 | LOOP [emit-job-header : Print node information] 2026-10-05 14:06:42.402166 | debian-bookworm | ok: 2026-10-05 14:06:42.402326 | debian-bookworm | # Node Information 2026-10-05 14:06:42.402412 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-10-05 14:06:42.402441 | debian-bookworm | Hostname: debian 2026-10-05 14:06:42.402464 | debian-bookworm | Username: zuul 2026-10-05 14:06:42.402486 | debian-bookworm | Distro: Debian 12.15 2026-10-05 14:06:42.402511 | debian-bookworm | Provider: regiocloud-a 2026-10-05 14:06:42.402532 | debian-bookworm | Region: 2026-10-05 14:06:42.402554 | debian-bookworm | Label: debian-bookworm 2026-10-05 14:06:42.402573 | debian-bookworm | Product Name: OpenStack Nova 2026-10-05 14:06:42.402593 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe95:4d9a 2026-10-05 14:06:42.412625 | 2026-10-05 14:06:42.412763 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-10-05 14:06:43.061634 | debian-bookworm -> localhost | changed 2026-10-05 14:06:43.067210 | 2026-10-05 14:06:43.067287 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-10-05 14:06:44.280816 | debian-bookworm -> localhost | changed 2026-10-05 14:06:44.312587 | 2026-10-05 14:06:44.312701 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-10-05 14:06:44.542629 | debian-bookworm -> localhost | ok 2026-10-05 14:06:44.547887 | 2026-10-05 14:06:44.547970 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-10-05 14:06:44.569074 | debian-bookworm | ok 2026-10-05 14:06:44.593121 | debian-bookworm | included: /var/lib/zuul/builds/e9b4222cb4d5428d818e0aff05d3f07f/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-10-05 14:06:44.599273 | 2026-10-05 14:06:44.599361 | TASK [add-build-sshkey : Create Temp SSH key] 2026-10-05 14:06:46.194211 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-10-05 14:06:46.194446 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/e9b4222cb4d5428d818e0aff05d3f07f/work/e9b4222cb4d5428d818e0aff05d3f07f_id_rsa 2026-10-05 14:06:46.194501 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/e9b4222cb4d5428d818e0aff05d3f07f/work/e9b4222cb4d5428d818e0aff05d3f07f_id_rsa.pub 2026-10-05 14:06:46.194538 | debian-bookworm -> localhost | The key fingerprint is: 2026-10-05 14:06:46.194571 | debian-bookworm -> localhost | SHA256:4qOWq2o0Y1KVUnRM/GFsXDJKmzMKr3iD2WgSanXGkeU zuul-build-sshkey 2026-10-05 14:06:46.194601 | debian-bookworm -> localhost | The key's randomart image is: 2026-10-05 14:06:46.194644 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-10-05 14:06:46.194675 | debian-bookworm -> localhost | | oo=++o.. | 2026-10-05 14:06:46.194706 | debian-bookworm -> localhost | | . oo*+*o | 2026-10-05 14:06:46.194733 | debian-bookworm -> localhost | | .o o*E . | 2026-10-05 14:06:46.194760 | debian-bookworm -> localhost | | .o...o. | 2026-10-05 14:06:46.194786 | debian-bookworm -> localhost | |.. .o+. S | 2026-10-05 14:06:46.194828 | debian-bookworm -> localhost | |o%..o. . | 2026-10-05 14:06:46.194855 | debian-bookworm -> localhost | |@oB .o | 2026-10-05 14:06:46.194881 | debian-bookworm -> localhost | |+o .o. . | 2026-10-05 14:06:46.194909 | debian-bookworm -> localhost | |o..oo. | 2026-10-05 14:06:46.194936 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-10-05 14:06:46.195004 | debian-bookworm -> localhost | ok: Runtime: 0:00:01.167209 2026-10-05 14:06:46.205201 | 2026-10-05 14:06:46.205424 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-10-05 14:06:46.225666 | debian-bookworm | ok 2026-10-05 14:06:46.235712 | debian-bookworm | included: /var/lib/zuul/builds/e9b4222cb4d5428d818e0aff05d3f07f/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-10-05 14:06:46.242413 | 2026-10-05 14:06:46.242482 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-10-05 14:06:46.255396 | debian-bookworm | skipping: Conditional result was False 2026-10-05 14:06:46.272111 | 2026-10-05 14:06:46.272258 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-10-05 14:06:46.945245 | debian-bookworm | changed 2026-10-05 14:06:46.949608 | 2026-10-05 14:06:46.949687 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-10-05 14:06:47.202355 | debian-bookworm | ok 2026-10-05 14:06:47.207344 | 2026-10-05 14:06:47.207427 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-10-05 14:06:47.987907 | debian-bookworm | changed 2026-10-05 14:06:47.998761 | 2026-10-05 14:06:47.998870 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-10-05 14:06:48.696986 | debian-bookworm | changed 2026-10-05 14:06:48.712032 | 2026-10-05 14:06:48.712140 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-10-05 14:06:48.742757 | debian-bookworm | skipping: Conditional result was False 2026-10-05 14:06:48.779551 | 2026-10-05 14:06:48.779662 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-10-05 14:06:49.424423 | debian-bookworm -> localhost | changed 2026-10-05 14:06:49.436641 | 2026-10-05 14:06:49.436761 | TASK [add-build-sshkey : Add back temp key] 2026-10-05 14:06:50.364179 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/e9b4222cb4d5428d818e0aff05d3f07f/work/e9b4222cb4d5428d818e0aff05d3f07f_id_rsa (zuul-build-sshkey) 2026-10-05 14:06:50.365905 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.043046 2026-10-05 14:06:50.380118 | 2026-10-05 14:06:50.380212 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-10-05 14:06:50.969688 | debian-bookworm | ok 2026-10-05 14:06:50.974255 | 2026-10-05 14:06:50.974338 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-10-05 14:06:51.009770 | debian-bookworm | skipping: Conditional result was False 2026-10-05 14:06:51.019412 | 2026-10-05 14:06:51.019859 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-10-05 14:06:51.561007 | debian-bookworm | ok 2026-10-05 14:06:51.579113 | 2026-10-05 14:06:51.579227 | TASK [validate-host : Define zuul_info_dir fact] 2026-10-05 14:06:51.631091 | debian-bookworm | ok 2026-10-05 14:06:51.636412 | 2026-10-05 14:06:51.636497 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-10-05 14:06:52.161484 | debian-bookworm -> localhost | ok 2026-10-05 14:06:52.175651 | 2026-10-05 14:06:52.175766 | TASK [validate-host : Collect information about the host] 2026-10-05 14:06:53.812580 | debian-bookworm | ok 2026-10-05 14:06:53.847211 | 2026-10-05 14:06:53.847322 | TASK [validate-host : Sanitize hostname] 2026-10-05 14:06:54.173330 | debian-bookworm | ok 2026-10-05 14:06:54.206541 | 2026-10-05 14:06:54.206639 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-10-05 14:06:55.305984 | debian-bookworm -> localhost | changed 2026-10-05 14:06:55.311016 | 2026-10-05 14:06:55.311097 | TASK [validate-host : Collect information about zuul worker] 2026-10-05 14:06:55.755105 | debian-bookworm | ok 2026-10-05 14:06:55.759742 | 2026-10-05 14:06:55.759839 | TASK [validate-host : Write out all zuul information for each host] 2026-10-05 14:06:57.668620 | debian-bookworm -> localhost | changed 2026-10-05 14:06:57.676781 | 2026-10-05 14:06:57.676870 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-10-05 14:06:58.040224 | debian-bookworm | ok 2026-10-05 14:06:58.044752 | 2026-10-05 14:06:58.044862 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-10-05 14:07:00.542642 | debian-bookworm | changed: 2026-10-05 14:07:00.542788 | debian-bookworm | cd+++++++++ src/ 2026-10-05 14:07:00.542828 | debian-bookworm | cd+++++++++ src/github.com/ 2026-10-05 14:07:00.542847 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-10-05 14:07:00.542864 | debian-bookworm | cd+++++++++ src/github.com/osism/python-osism/ 2026-10-05 14:07:00.542880 | debian-bookworm | Containerfile 2026-10-05 14:07:00.542986 | debian-bookworm | Containerfile 2026-10-05 14:07:00.584706 | debian-bookworm | localhost | ok: "/var/lib/zuul/builds/e9b4222cb4d5428d818e0aff05d3f07f/work/logs" 2026-10-05 14:09:37.630654 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/e9b4222cb4d5428d818e0aff05d3f07f/work/artifacts" 2026-10-05 14:09:37.883202 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/e9b4222cb4d5428d818e0aff05d3f07f/work/docs" 2026-10-05 14:09:37.900537 | 2026-10-05 14:09:37.900663 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-10-05 14:09:38.672480 | debian-bookworm | changed: 2026-10-05 14:09:38.672760 | debian-bookworm | .d..t...... ./ 2026-10-05 14:09:38.672802 | debian-bookworm | >f+++++++++ net-debug.post.txt 2026-10-05 14:09:38.672828 | debian-bookworm | >f+++++++++ net-debug.pre.txt 2026-10-05 14:09:38.672865 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-10-05 14:09:38.672886 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-10-05 14:09:38.672906 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-10-05 14:09:38.672947 | debian-bookworm | changed: All items complete 2026-10-05 14:09:38.672969 | 2026-10-05 14:09:39.268457 | debian-bookworm | changed: .d..t...... ./ 2026-10-05 14:09:39.884876 | debian-bookworm | changed: .d..t...... ./ 2026-10-05 14:09:39.916899 | 2026-10-05 14:09:39.917073 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-10-05 14:09:40.269116 | debian-bookworm -> localhost | ok: Item: artifacts Runtime: 0:00:00.006368 2026-10-05 14:09:40.535837 | debian-bookworm -> localhost | ok: Item: docs Runtime: 0:00:00.005374 2026-10-05 14:09:40.556002 | 2026-10-05 14:09:40.556247 | PLAY RECAP 2026-10-05 14:09:40.556325 | debian-bookworm | ok: 8 changed: 3 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-10-05 14:09:40.556356 | 2026-10-05 14:09:40.691170 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-10-05 14:09:40.693151 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-10-05 14:09:41.432518 | 2026-10-05 14:09:41.432679 | PLAY [Base post] 2026-10-05 14:09:41.446470 | 2026-10-05 14:09:41.446605 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-10-05 14:09:42.268063 | debian-bookworm | changed 2026-10-05 14:09:42.274404 | 2026-10-05 14:09:42.274509 | PLAY RECAP 2026-10-05 14:09:42.274585 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-10-05 14:09:42.274649 | 2026-10-05 14:09:42.393510 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-10-05 14:09:42.394577 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-10-05 14:09:43.178176 | 2026-10-05 14:09:43.178343 | PLAY [Base post-logs] 2026-10-05 14:09:43.188825 | 2026-10-05 14:09:43.188977 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-10-05 14:09:43.645935 | localhost | changed 2026-10-05 14:09:43.658429 | 2026-10-05 14:09:43.658599 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-10-05 14:09:43.688857 | localhost | ok 2026-10-05 14:09:43.694248 | 2026-10-05 14:09:43.694357 | TASK [Set zuul-log-path fact] 2026-10-05 14:09:43.720414 | localhost | ok 2026-10-05 14:09:43.729267 | 2026-10-05 14:09:43.729379 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-10-05 14:09:43.755812 | localhost | ok 2026-10-05 14:09:43.762254 | 2026-10-05 14:09:43.762410 | TASK [upload-logs : Create log directories] 2026-10-05 14:09:44.275514 | localhost | changed 2026-10-05 14:09:44.281165 | 2026-10-05 14:09:44.281322 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-10-05 14:09:44.783115 | localhost -> localhost | ok: Runtime: 0:00:00.007295 2026-10-05 14:09:44.791944 | 2026-10-05 14:09:44.792386 | TASK [upload-logs : Upload logs to log server] 2026-10-05 14:09:45.321027 | localhost | Output suppressed because no_log was given 2026-10-05 14:09:45.324663 | 2026-10-05 14:09:45.324896 | LOOP [upload-logs : Compress console log and json output] 2026-10-05 14:09:45.371966 | localhost | skipping: Conditional result was False 2026-10-05 14:09:45.377021 | localhost | skipping: Conditional result was False 2026-10-05 14:09:45.389462 | 2026-10-05 14:09:45.389741 | LOOP [upload-logs : Upload compressed console log and json output] 2026-10-05 14:09:45.435978 | localhost | skipping: Conditional result was False 2026-10-05 14:09:45.436565 | 2026-10-05 14:09:45.440019 | localhost | skipping: Conditional result was False 2026-10-05 14:09:45.453513 | 2026-10-05 14:09:45.453762 | LOOP [upload-logs : Upload console log and json output]