2026-07-13 06:42:44.828046 | Job console starting 2026-07-13 06:42:44.837749 | Updating git repos 2026-07-13 06:42:44.903158 | Cloning repos into workspace 2026-07-13 06:42:44.943260 | Restoring repo states 2026-07-13 06:42:44.973149 | Merging changes 2026-07-13 06:42:45.711344 | Checking out repos 2026-07-13 06:42:45.832081 | Preparing playbooks 2026-07-13 06:42:46.794660 | Running Ansible setup 2026-07-13 06:42:53.225602 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-07-13 06:42:54.299984 | 2026-07-13 06:42:54.300277 | PLAY [Base pre] 2026-07-13 06:42:54.329158 | 2026-07-13 06:42:54.329294 | TASK [Setup log path fact] 2026-07-13 06:42:54.359331 | debian-bookworm | ok 2026-07-13 06:42:54.375848 | 2026-07-13 06:42:54.375988 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-07-13 06:42:54.414223 | debian-bookworm | ok 2026-07-13 06:42:54.424874 | 2026-07-13 06:42:54.424986 | TASK [emit-job-header : Print job information] 2026-07-13 06:42:54.463318 | # Job Information 2026-07-13 06:42:54.463487 | Ansible Version: 2.16.14 2026-07-13 06:42:54.463523 | Job: python-osism-test-setup 2026-07-13 06:42:54.463557 | Pipeline: check 2026-07-13 06:42:54.463580 | Executor: 521e9411259a 2026-07-13 06:42:54.463602 | Triggered by: https://github.com/osism/python-osism/pull/2455 2026-07-13 06:42:54.463624 | Event ID: 9fd7b4a0-7e85-11f1-8ff0-68a5334243d7 2026-07-13 06:42:54.468437 | 2026-07-13 06:42:54.468539 | LOOP [emit-job-header : Print node information] 2026-07-13 06:42:54.572831 | debian-bookworm | ok: 2026-07-13 06:42:54.573017 | debian-bookworm | # Node Information 2026-07-13 06:42:54.573185 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-07-13 06:42:54.573224 | debian-bookworm | Hostname: debian 2026-07-13 06:42:54.573249 | debian-bookworm | Username: zuul 2026-07-13 06:42:54.573270 | debian-bookworm | Distro: Debian 12.15 2026-07-13 06:42:54.573294 | debian-bookworm | Provider: regiocloud-a 2026-07-13 06:42:54.573316 | debian-bookworm | Region: 2026-07-13 06:42:54.573339 | debian-bookworm | Label: debian-bookworm 2026-07-13 06:42:54.573360 | debian-bookworm | Product Name: OpenStack Nova 2026-07-13 06:42:54.573381 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:feee:771f 2026-07-13 06:42:54.592202 | 2026-07-13 06:42:54.592326 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-07-13 06:42:55.328929 | debian-bookworm -> localhost | changed 2026-07-13 06:42:55.335553 | 2026-07-13 06:42:55.335659 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-07-13 06:42:56.477241 | debian-bookworm -> localhost | changed 2026-07-13 06:42:56.490962 | 2026-07-13 06:42:56.491067 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-07-13 06:42:56.848030 | debian-bookworm -> localhost | ok 2026-07-13 06:42:56.855474 | 2026-07-13 06:42:56.855595 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-07-13 06:42:56.897477 | debian-bookworm | ok 2026-07-13 06:42:56.941879 | debian-bookworm | included: /var/lib/zuul/builds/f6964560204a45f59dba87df83dd2823/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-07-13 06:42:56.951339 | 2026-07-13 06:42:56.951440 | TASK [add-build-sshkey : Create Temp SSH key] 2026-07-13 06:42:58.564452 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-07-13 06:42:58.564674 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/f6964560204a45f59dba87df83dd2823/work/f6964560204a45f59dba87df83dd2823_id_rsa 2026-07-13 06:42:58.564714 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/f6964560204a45f59dba87df83dd2823/work/f6964560204a45f59dba87df83dd2823_id_rsa.pub 2026-07-13 06:42:58.564741 | debian-bookworm -> localhost | The key fingerprint is: 2026-07-13 06:42:58.564767 | debian-bookworm -> localhost | SHA256:2xRjdwuSf1FhtAPbI5z4RQ7Mr/oqSllaVIwjkkijtR8 zuul-build-sshkey 2026-07-13 06:42:58.564790 | debian-bookworm -> localhost | The key's randomart image is: 2026-07-13 06:42:58.564820 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-07-13 06:42:58.564842 | debian-bookworm -> localhost | | .+. . o.oo.=o| 2026-07-13 06:42:58.564865 | debian-bookworm -> localhost | | o.oo . o.oooX..| 2026-07-13 06:42:58.564885 | debian-bookworm -> localhost | | . . E. ..B.o=+B | 2026-07-13 06:42:58.564906 | debian-bookworm -> localhost | | . . .. *.oo+o| 2026-07-13 06:42:58.564926 | debian-bookworm -> localhost | | . So. ..+ | 2026-07-13 06:42:58.564948 | debian-bookworm -> localhost | | =+ o | 2026-07-13 06:42:58.564968 | debian-bookworm -> localhost | | +. . . | 2026-07-13 06:42:58.564988 | debian-bookworm -> localhost | | . . . | 2026-07-13 06:42:58.565009 | debian-bookworm -> localhost | | .. ..o. | 2026-07-13 06:42:58.565029 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-07-13 06:42:58.565084 | debian-bookworm -> localhost | ok: Runtime: 0:00:01.142648 2026-07-13 06:42:58.572417 | 2026-07-13 06:42:58.572520 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-07-13 06:42:58.591775 | debian-bookworm | ok 2026-07-13 06:42:58.601799 | debian-bookworm | included: /var/lib/zuul/builds/f6964560204a45f59dba87df83dd2823/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-07-13 06:42:58.610314 | 2026-07-13 06:42:58.610402 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-07-13 06:42:58.643792 | debian-bookworm | skipping: Conditional result was False 2026-07-13 06:42:58.650317 | 2026-07-13 06:42:58.650419 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-07-13 06:42:59.337665 | debian-bookworm | changed 2026-07-13 06:42:59.346339 | 2026-07-13 06:42:59.346476 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-07-13 06:42:59.606529 | debian-bookworm | ok 2026-07-13 06:42:59.610984 | 2026-07-13 06:42:59.611075 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-07-13 06:43:00.269578 | debian-bookworm | changed 2026-07-13 06:43:00.274027 | 2026-07-13 06:43:00.274116 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-07-13 06:43:00.938292 | debian-bookworm | changed 2026-07-13 06:43:00.943001 | 2026-07-13 06:43:00.943122 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-07-13 06:43:00.966625 | debian-bookworm | skipping: Conditional result was False 2026-07-13 06:43:00.981926 | 2026-07-13 06:43:00.982036 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-07-13 06:43:01.473454 | debian-bookworm -> localhost | changed 2026-07-13 06:43:01.483802 | 2026-07-13 06:43:01.483907 | TASK [add-build-sshkey : Add back temp key] 2026-07-13 06:43:01.779787 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/f6964560204a45f59dba87df83dd2823/work/f6964560204a45f59dba87df83dd2823_id_rsa (zuul-build-sshkey) 2026-07-13 06:43:01.779978 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.011262 2026-07-13 06:43:01.786165 | 2026-07-13 06:43:01.786258 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-07-13 06:43:02.251899 | debian-bookworm | ok 2026-07-13 06:43:02.258786 | 2026-07-13 06:43:02.258905 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-07-13 06:43:02.284577 | debian-bookworm | skipping: Conditional result was False 2026-07-13 06:43:02.307391 | 2026-07-13 06:43:02.307578 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-07-13 06:43:02.808228 | debian-bookworm | ok 2026-07-13 06:43:02.830294 | 2026-07-13 06:43:02.830472 | TASK [validate-host : Define zuul_info_dir fact] 2026-07-13 06:43:02.873935 | debian-bookworm | ok 2026-07-13 06:43:02.881583 | 2026-07-13 06:43:02.881946 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-07-13 06:43:03.150793 | debian-bookworm -> localhost | ok 2026-07-13 06:43:03.163994 | 2026-07-13 06:43:03.164166 | TASK [validate-host : Collect information about the host] 2026-07-13 06:43:04.265676 | debian-bookworm | ok 2026-07-13 06:43:04.277510 | 2026-07-13 06:43:04.277615 | TASK [validate-host : Sanitize hostname] 2026-07-13 06:43:04.420584 | debian-bookworm | ok 2026-07-13 06:43:04.424998 | 2026-07-13 06:43:04.425101 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-07-13 06:43:04.984427 | debian-bookworm -> localhost | changed 2026-07-13 06:43:04.990430 | 2026-07-13 06:43:04.990535 | TASK [validate-host : Collect information about zuul worker] 2026-07-13 06:43:05.438790 | debian-bookworm | ok 2026-07-13 06:43:05.450198 | 2026-07-13 06:43:05.450348 | TASK [validate-host : Write out all zuul information for each host] 2026-07-13 06:43:06.483883 | debian-bookworm -> localhost | changed 2026-07-13 06:43:06.497589 | 2026-07-13 06:43:06.497732 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-07-13 06:43:06.780994 | debian-bookworm | ok 2026-07-13 06:43:06.791012 | 2026-07-13 06:43:06.791190 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-07-13 06:43:07.931467 | debian-bookworm | changed: 2026-07-13 06:43:07.931722 | debian-bookworm | cd+++++++++ src/ 2026-07-13 06:43:07.931769 | debian-bookworm | cd+++++++++ src/github.com/ 2026-07-13 06:43:07.931794 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-07-13 06:43:07.931816 | debian-bookworm | cd+++++++++ src/github.com/osism/python-osism/ 2026-07-13 06:43:07.931837 | debian-bookworm | Containerfile 2026-07-13 06:43:07.931968 | debian-bookworm | Containerfile 2026-07-13 06:43:07.948496 | debian-bookworm | localhost | ok: "/var/lib/zuul/builds/f6964560204a45f59dba87df83dd2823/work/logs" 2026-07-13 06:45:41.306255 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/f6964560204a45f59dba87df83dd2823/work/artifacts" 2026-07-13 06:45:41.598156 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/f6964560204a45f59dba87df83dd2823/work/docs" 2026-07-13 06:45:41.618430 | 2026-07-13 06:45:41.618758 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-07-13 06:45:42.510298 | debian-bookworm | changed: 2026-07-13 06:45:42.510698 | debian-bookworm | .d..t...... ./ 2026-07-13 06:45:42.510765 | debian-bookworm | >f+++++++++ net-debug.post.txt 2026-07-13 06:45:42.510855 | debian-bookworm | >f+++++++++ net-debug.pre.txt 2026-07-13 06:45:42.510899 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-07-13 06:45:42.511012 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-07-13 06:45:42.511058 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-07-13 06:45:42.511137 | debian-bookworm | changed: All items complete 2026-07-13 06:45:42.511187 | 2026-07-13 06:45:43.113245 | debian-bookworm | changed: .d..t...... ./ 2026-07-13 06:45:43.738140 | debian-bookworm | changed: .d..t...... ./ 2026-07-13 06:45:43.760226 | 2026-07-13 06:45:43.760372 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-07-13 06:45:44.134100 | debian-bookworm -> localhost | ok: Item: artifacts Runtime: 0:00:00.012300 2026-07-13 06:45:44.432032 | debian-bookworm -> localhost | ok: Item: docs Runtime: 0:00:00.006904 2026-07-13 06:45:44.443567 | 2026-07-13 06:45:44.443703 | PLAY RECAP 2026-07-13 06:45:44.443844 | debian-bookworm | ok: 8 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-07-13 06:45:44.443877 | 2026-07-13 06:45:44.605853 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-07-13 06:45:44.606927 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-07-13 06:45:45.424487 | 2026-07-13 06:45:45.424703 | PLAY [Base post] 2026-07-13 06:45:45.440546 | 2026-07-13 06:45:45.440735 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-07-13 06:45:46.291472 | debian-bookworm | changed 2026-07-13 06:45:46.302081 | 2026-07-13 06:45:46.302280 | PLAY RECAP 2026-07-13 06:45:46.302401 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-07-13 06:45:46.302512 | 2026-07-13 06:45:46.449309 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-07-13 06:45:46.450389 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-07-13 06:45:47.315836 | 2026-07-13 06:45:47.316053 | PLAY [Base post-logs] 2026-07-13 06:45:47.328610 | 2026-07-13 06:45:47.328811 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-07-13 06:45:47.850425 | localhost | changed 2026-07-13 06:45:47.874972 | 2026-07-13 06:45:47.875370 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-07-13 06:45:47.915641 | localhost | ok 2026-07-13 06:45:47.919584 | 2026-07-13 06:45:47.919708 | TASK [Set zuul-log-path fact] 2026-07-13 06:45:47.951492 | localhost | ok 2026-07-13 06:45:47.972008 | 2026-07-13 06:45:47.972195 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-07-13 06:45:48.001536 | localhost | ok 2026-07-13 06:45:48.007109 | 2026-07-13 06:45:48.007279 | TASK [upload-logs : Create log directories] 2026-07-13 06:45:48.576429 | localhost | changed 2026-07-13 06:45:48.580148 | 2026-07-13 06:45:48.580268 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-07-13 06:45:49.143626 | localhost -> localhost | ok: Runtime: 0:00:00.007433 2026-07-13 06:45:49.148774 | 2026-07-13 06:45:49.148898 | TASK [upload-logs : Upload logs to log server] 2026-07-13 06:45:49.775545 | localhost | Output suppressed because no_log was given 2026-07-13 06:45:49.778405 | 2026-07-13 06:45:49.778566 | LOOP [upload-logs : Compress console log and json output] 2026-07-13 06:45:49.844599 | localhost | skipping: Conditional result was False 2026-07-13 06:45:49.850219 | localhost | skipping: Conditional result was False 2026-07-13 06:45:49.866206 | 2026-07-13 06:45:49.866450 | LOOP [upload-logs : Upload compressed console log and json output] 2026-07-13 06:45:49.916531 | localhost | skipping: Conditional result was False 2026-07-13 06:45:49.916972 | 2026-07-13 06:45:49.921145 | localhost | skipping: Conditional result was False 2026-07-13 06:45:49.929538 | 2026-07-13 06:45:49.929840 | LOOP [upload-logs : Upload console log and json output]