2026-09-10 05:11:33.583640 | Job console starting 2026-09-10 05:11:33.597756 | Updating git repos 2026-09-10 05:11:33.653514 | Cloning repos into workspace 2026-09-10 05:11:33.764591 | Restoring repo states 2026-09-10 05:11:33.782286 | Merging changes 2026-09-10 05:11:34.635166 | Checking out repos 2026-09-10 05:11:34.783445 | Preparing playbooks 2026-09-10 05:11:36.766172 | Running Ansible setup 2026-09-10 05:11:42.422389 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-09-10 05:11:43.527869 | 2026-09-10 05:11:43.527993 | PLAY [Base pre] 2026-09-10 05:11:43.572971 | 2026-09-10 05:11:43.573083 | TASK [Setup log path fact] 2026-09-10 05:11:43.610027 | debian-bookworm | ok 2026-09-10 05:11:43.638558 | 2026-09-10 05:11:43.638683 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-09-10 05:11:43.685648 | debian-bookworm | ok 2026-09-10 05:11:43.697661 | 2026-09-10 05:11:43.697774 | TASK [emit-job-header : Print job information] 2026-09-10 05:11:43.776175 | # Job Information 2026-09-10 05:11:43.776331 | Ansible Version: 2.16.14 2026-09-10 05:11:43.776366 | Job: python-osism-test-setup 2026-09-10 05:11:43.776398 | Pipeline: check 2026-09-10 05:11:43.776431 | Executor: 521e9411259a 2026-09-10 05:11:43.776452 | Triggered by: https://github.com/osism/python-osism/pull/2686 2026-09-10 05:11:43.776474 | Event ID: 100dba70-acd6-11f1-97ed-c3761c2954e8 2026-09-10 05:11:43.790259 | 2026-09-10 05:11:43.790369 | LOOP [emit-job-header : Print node information] 2026-09-10 05:11:44.009477 | debian-bookworm | ok: 2026-09-10 05:11:44.017533 | debian-bookworm | # Node Information 2026-09-10 05:11:44.017607 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-09-10 05:11:44.017631 | debian-bookworm | Hostname: debian 2026-09-10 05:11:44.017651 | debian-bookworm | Username: zuul 2026-09-10 05:11:44.017670 | debian-bookworm | Distro: Debian 12.15 2026-09-10 05:11:44.017689 | debian-bookworm | Provider: regiocloud-a 2026-09-10 05:11:44.017706 | debian-bookworm | Region: 2026-09-10 05:11:44.017724 | debian-bookworm | Label: debian-bookworm 2026-09-10 05:11:44.017740 | debian-bookworm | Product Name: OpenStack Nova 2026-09-10 05:11:44.017755 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe22:b60 2026-09-10 05:11:44.036743 | 2026-09-10 05:11:44.036850 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-09-10 05:11:44.855632 | debian-bookworm -> localhost | changed 2026-09-10 05:11:44.861344 | 2026-09-10 05:11:44.861460 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-09-10 05:11:46.794181 | debian-bookworm -> localhost | changed 2026-09-10 05:11:46.802804 | 2026-09-10 05:11:46.802887 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-09-10 05:11:47.413455 | debian-bookworm -> localhost | ok 2026-09-10 05:11:47.418450 | 2026-09-10 05:11:47.418536 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-09-10 05:11:47.447045 | debian-bookworm | ok 2026-09-10 05:11:47.473177 | debian-bookworm | included: /var/lib/zuul/builds/52d8b161c0754fac916ff1d386b21ac6/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-09-10 05:11:47.481910 | 2026-09-10 05:11:47.481990 | TASK [add-build-sshkey : Create Temp SSH key] 2026-09-10 05:11:49.416621 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-09-10 05:11:49.416794 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/52d8b161c0754fac916ff1d386b21ac6/work/52d8b161c0754fac916ff1d386b21ac6_id_rsa 2026-09-10 05:11:49.416825 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/52d8b161c0754fac916ff1d386b21ac6/work/52d8b161c0754fac916ff1d386b21ac6_id_rsa.pub 2026-09-10 05:11:49.416847 | debian-bookworm -> localhost | The key fingerprint is: 2026-09-10 05:11:49.416867 | debian-bookworm -> localhost | SHA256:OPFqIIFxfWVVT/nEbg6B07B4c8uh1RtcqudsXOD8V3Q zuul-build-sshkey 2026-09-10 05:11:49.416884 | debian-bookworm -> localhost | The key's randomart image is: 2026-09-10 05:11:49.416913 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-09-10 05:11:49.416931 | debian-bookworm -> localhost | |. .. .o...o+.o.| 2026-09-10 05:11:49.416947 | debian-bookworm -> localhost | | + . .. .o+=o+| 2026-09-10 05:11:49.416964 | debian-bookworm -> localhost | |. . .. . +.BBE| 2026-09-10 05:11:49.416979 | debian-bookworm -> localhost | | . + . X.=B| 2026-09-10 05:11:49.416995 | debian-bookworm -> localhost | | . . o S o *=o| 2026-09-10 05:11:49.417016 | debian-bookworm -> localhost | | . . o = oo| 2026-09-10 05:11:49.417032 | debian-bookworm -> localhost | | o = o| 2026-09-10 05:11:49.417047 | debian-bookworm -> localhost | | . . .| 2026-09-10 05:11:49.417063 | debian-bookworm -> localhost | | | 2026-09-10 05:11:49.417079 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-09-10 05:11:49.417116 | debian-bookworm -> localhost | ok: Runtime: 0:00:01.095279 2026-09-10 05:11:49.422217 | 2026-09-10 05:11:49.422302 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-09-10 05:11:49.449271 | debian-bookworm | ok 2026-09-10 05:11:49.461323 | debian-bookworm | included: /var/lib/zuul/builds/52d8b161c0754fac916ff1d386b21ac6/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-09-10 05:11:49.478474 | 2026-09-10 05:11:49.478563 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-09-10 05:11:49.510733 | debian-bookworm | skipping: Conditional result was False 2026-09-10 05:11:49.517493 | 2026-09-10 05:11:49.517597 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-09-10 05:11:50.284536 | debian-bookworm | changed 2026-09-10 05:11:50.290750 | 2026-09-10 05:11:50.290844 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-09-10 05:11:50.591616 | debian-bookworm | ok 2026-09-10 05:11:50.596914 | 2026-09-10 05:11:50.597004 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-09-10 05:11:51.377792 | debian-bookworm | changed 2026-09-10 05:11:51.382198 | 2026-09-10 05:11:51.382281 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-09-10 05:11:52.110053 | debian-bookworm | changed 2026-09-10 05:11:52.117869 | 2026-09-10 05:11:52.117961 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-09-10 05:11:52.150878 | debian-bookworm | skipping: Conditional result was False 2026-09-10 05:11:52.156755 | 2026-09-10 05:11:52.156843 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-09-10 05:11:52.611568 | debian-bookworm -> localhost | changed 2026-09-10 05:11:52.625271 | 2026-09-10 05:11:52.625374 | TASK [add-build-sshkey : Add back temp key] 2026-09-10 05:11:53.216437 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/52d8b161c0754fac916ff1d386b21ac6/work/52d8b161c0754fac916ff1d386b21ac6_id_rsa (zuul-build-sshkey) 2026-09-10 05:11:53.216645 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.020704 2026-09-10 05:11:53.223816 | 2026-09-10 05:11:53.223907 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-09-10 05:11:53.884547 | debian-bookworm | ok 2026-09-10 05:11:53.891804 | 2026-09-10 05:11:53.891899 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-09-10 05:11:53.930893 | debian-bookworm | skipping: Conditional result was False 2026-09-10 05:11:53.950144 | 2026-09-10 05:11:53.950254 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-09-10 05:11:54.479197 | debian-bookworm | ok 2026-09-10 05:11:54.493613 | 2026-09-10 05:11:54.493704 | TASK [validate-host : Define zuul_info_dir fact] 2026-09-10 05:11:54.526916 | debian-bookworm | ok 2026-09-10 05:11:54.531048 | 2026-09-10 05:11:54.531122 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-09-10 05:11:54.920400 | debian-bookworm -> localhost | ok 2026-09-10 05:11:54.925531 | 2026-09-10 05:11:54.925824 | TASK [validate-host : Collect information about the host] 2026-09-10 05:11:56.356898 | debian-bookworm | ok 2026-09-10 05:11:56.371589 | 2026-09-10 05:11:56.371672 | TASK [validate-host : Sanitize hostname] 2026-09-10 05:11:56.493640 | debian-bookworm | ok 2026-09-10 05:11:56.521802 | 2026-09-10 05:11:56.521909 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-09-10 05:11:57.369460 | debian-bookworm -> localhost | changed 2026-09-10 05:11:57.375083 | 2026-09-10 05:11:57.375173 | TASK [validate-host : Collect information about zuul worker] 2026-09-10 05:11:57.768801 | debian-bookworm | ok 2026-09-10 05:11:57.779399 | 2026-09-10 05:11:57.779546 | TASK [validate-host : Write out all zuul information for each host] 2026-09-10 05:11:58.448468 | debian-bookworm -> localhost | changed 2026-09-10 05:11:58.456643 | 2026-09-10 05:11:58.456718 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-09-10 05:11:58.778710 | debian-bookworm | ok 2026-09-10 05:11:58.798636 | 2026-09-10 05:11:58.798743 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-09-10 05:12:01.452203 | debian-bookworm | changed: 2026-09-10 05:12:01.452379 | debian-bookworm | cd+++++++++ src/ 2026-09-10 05:12:01.452427 | debian-bookworm | cd+++++++++ src/github.com/ 2026-09-10 05:12:01.452453 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-09-10 05:12:01.452475 | debian-bookworm | cd+++++++++ src/github.com/osism/python-osism/ 2026-09-10 05:12:01.452506 | debian-bookworm | Containerfile 2026-09-10 05:12:01.452646 | debian-bookworm | Containerfile 2026-09-10 05:12:01.466252 | debian-bookworm | localhost | ok: "/var/lib/zuul/builds/52d8b161c0754fac916ff1d386b21ac6/work/logs" 2026-09-10 05:14:46.898016 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/52d8b161c0754fac916ff1d386b21ac6/work/artifacts" 2026-09-10 05:14:47.164614 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/52d8b161c0754fac916ff1d386b21ac6/work/docs" 2026-09-10 05:14:47.183239 | 2026-09-10 05:14:47.183435 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-09-10 05:14:47.958178 | debian-bookworm | changed: 2026-09-10 05:14:47.958490 | debian-bookworm | .d..t...... ./ 2026-09-10 05:14:47.958536 | debian-bookworm | >f+++++++++ net-debug.post.txt 2026-09-10 05:14:47.958566 | debian-bookworm | >f+++++++++ net-debug.pre.txt 2026-09-10 05:14:47.958594 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-09-10 05:14:47.958619 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-09-10 05:14:47.958644 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-09-10 05:14:47.958693 | debian-bookworm | changed: All items complete 2026-09-10 05:14:47.958727 | 2026-09-10 05:14:48.484872 | debian-bookworm | changed: .d..t...... ./ 2026-09-10 05:14:49.029575 | debian-bookworm | changed: .d..t...... ./ 2026-09-10 05:14:49.060834 | 2026-09-10 05:14:49.060983 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-09-10 05:14:49.391855 | debian-bookworm -> localhost | ok: Item: artifacts Runtime: 0:00:00.010322 2026-09-10 05:14:49.659788 | debian-bookworm -> localhost | ok: Item: docs Runtime: 0:00:00.010477 2026-09-10 05:14:49.675190 | 2026-09-10 05:14:49.675364 | PLAY RECAP 2026-09-10 05:14:49.675440 | debian-bookworm | ok: 8 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-09-10 05:14:49.675469 | 2026-09-10 05:14:49.802485 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-09-10 05:14:49.803304 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-09-10 05:14:50.552377 | 2026-09-10 05:14:50.552562 | PLAY [Base post] 2026-09-10 05:14:50.566660 | 2026-09-10 05:14:50.566793 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-09-10 05:14:51.400953 | debian-bookworm | changed 2026-09-10 05:14:51.410312 | 2026-09-10 05:14:51.410465 | PLAY RECAP 2026-09-10 05:14:51.410636 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-09-10 05:14:51.410704 | 2026-09-10 05:14:51.583193 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-09-10 05:14:51.584087 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-09-10 05:14:52.364516 | 2026-09-10 05:14:52.364677 | PLAY [Base post-logs] 2026-09-10 05:14:52.374805 | 2026-09-10 05:14:52.374935 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-09-10 05:14:52.847438 | localhost | changed 2026-09-10 05:14:52.860187 | 2026-09-10 05:14:52.860349 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-09-10 05:14:52.898316 | localhost | ok 2026-09-10 05:14:52.905980 | 2026-09-10 05:14:52.906149 | TASK [Set zuul-log-path fact] 2026-09-10 05:14:52.934852 | localhost | ok 2026-09-10 05:14:52.949863 | 2026-09-10 05:14:52.950018 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-09-10 05:14:52.987663 | localhost | ok 2026-09-10 05:14:52.994025 | 2026-09-10 05:14:52.994193 | TASK [upload-logs : Create log directories] 2026-09-10 05:14:53.477830 | localhost | changed 2026-09-10 05:14:53.480621 | 2026-09-10 05:14:53.480727 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-09-10 05:14:53.980413 | localhost -> localhost | ok: Runtime: 0:00:00.006280 2026-09-10 05:14:53.984518 | 2026-09-10 05:14:53.984636 | TASK [upload-logs : Upload logs to log server] 2026-09-10 05:14:54.547161 | localhost | Output suppressed because no_log was given 2026-09-10 05:14:54.549863 | 2026-09-10 05:14:54.549994 | LOOP [upload-logs : Compress console log and json output] 2026-09-10 05:14:54.607266 | localhost | skipping: Conditional result was False 2026-09-10 05:14:54.613115 | localhost | skipping: Conditional result was False 2026-09-10 05:14:54.620617 | 2026-09-10 05:14:54.620862 | LOOP [upload-logs : Upload compressed console log and json output] 2026-09-10 05:14:54.666641 | localhost | skipping: Conditional result was False 2026-09-10 05:14:54.666903 | 2026-09-10 05:14:54.671676 | localhost | skipping: Conditional result was False 2026-09-10 05:14:54.683376 | 2026-09-10 05:14:54.683611 | LOOP [upload-logs : Upload console log and json output]