2026-09-07 18:53:37.110779 | Job console starting 2026-09-07 18:53:37.145486 | Updating git repos 2026-09-07 18:53:37.199008 | Cloning repos into workspace 2026-09-07 18:53:37.242811 | Restoring repo states 2026-09-07 18:53:37.260725 | Merging changes 2026-09-07 18:53:38.025077 | Checking out repos 2026-09-07 18:53:38.124478 | Preparing playbooks 2026-09-07 18:53:38.864390 | Running Ansible setup 2026-09-07 18:55:03.467660 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-09-07 18:55:04.308877 | 2026-09-07 18:55:04.309068 | PLAY [Base pre] 2026-09-07 18:55:04.326745 | 2026-09-07 18:55:04.326878 | TASK [Setup log path fact] 2026-09-07 18:55:04.355758 | debian-bookworm | ok 2026-09-07 18:55:04.371883 | 2026-09-07 18:55:04.372013 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-09-07 18:55:04.400164 | debian-bookworm | ok 2026-09-07 18:55:04.410594 | 2026-09-07 18:55:04.410698 | TASK [emit-job-header : Print job information] 2026-09-07 18:55:04.453756 | # Job Information 2026-09-07 18:55:04.454057 | Ansible Version: 2.16.14 2026-09-07 18:55:04.454133 | Job: python-osism-test-setup 2026-09-07 18:55:04.454203 | Pipeline: check 2026-09-07 18:55:04.454251 | Executor: 521e9411259a 2026-09-07 18:55:04.454294 | Triggered by: https://github.com/osism/python-osism/pull/2681 2026-09-07 18:55:04.454342 | Event ID: 1b56a3f0-aaed-11f1-97c8-56b4cc67b7a8 2026-09-07 18:55:04.462209 | 2026-09-07 18:55:04.462343 | LOOP [emit-job-header : Print node information] 2026-09-07 18:55:04.571112 | debian-bookworm | ok: 2026-09-07 18:55:04.572333 | debian-bookworm | # Node Information 2026-09-07 18:55:04.572418 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-09-07 18:55:04.572448 | debian-bookworm | Hostname: debian 2026-09-07 18:55:04.572473 | debian-bookworm | Username: zuul 2026-09-07 18:55:04.572494 | debian-bookworm | Distro: Debian 12.15 2026-09-07 18:55:04.572519 | debian-bookworm | Provider: regiocloud-a 2026-09-07 18:55:04.572540 | debian-bookworm | Region: 2026-09-07 18:55:04.572561 | debian-bookworm | Label: debian-bookworm 2026-09-07 18:55:04.572581 | debian-bookworm | Product Name: OpenStack Nova 2026-09-07 18:55:04.572601 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe16:57a4 2026-09-07 18:55:04.592347 | 2026-09-07 18:55:04.592465 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-09-07 18:55:05.095768 | debian-bookworm -> localhost | changed 2026-09-07 18:55:05.103560 | 2026-09-07 18:55:05.103696 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-09-07 18:55:06.158015 | debian-bookworm -> localhost | changed 2026-09-07 18:55:06.179836 | 2026-09-07 18:55:06.179977 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-09-07 18:55:06.572333 | debian-bookworm -> localhost | ok 2026-09-07 18:55:06.580198 | 2026-09-07 18:55:06.580324 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-09-07 18:55:06.618508 | debian-bookworm | ok 2026-09-07 18:55:06.650307 | debian-bookworm | included: /var/lib/zuul/builds/3608177b477549699c898fab4466096e/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-09-07 18:55:06.657108 | 2026-09-07 18:55:06.657227 | TASK [add-build-sshkey : Create Temp SSH key] 2026-09-07 18:55:08.122354 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-09-07 18:55:08.122534 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/3608177b477549699c898fab4466096e/work/3608177b477549699c898fab4466096e_id_rsa 2026-09-07 18:55:08.122571 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/3608177b477549699c898fab4466096e/work/3608177b477549699c898fab4466096e_id_rsa.pub 2026-09-07 18:55:08.122597 | debian-bookworm -> localhost | The key fingerprint is: 2026-09-07 18:55:08.122622 | debian-bookworm -> localhost | SHA256:vtTVTrgdVh6LsNm4/XfSNIv21FOnvwCXha/WsdeQ2pU zuul-build-sshkey 2026-09-07 18:55:08.122643 | debian-bookworm -> localhost | The key's randomart image is: 2026-09-07 18:55:08.122672 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-09-07 18:55:08.122692 | debian-bookworm -> localhost | | | 2026-09-07 18:55:08.122713 | debian-bookworm -> localhost | | . | 2026-09-07 18:55:08.122732 | debian-bookworm -> localhost | | . . o.| 2026-09-07 18:55:08.122751 | debian-bookworm -> localhost | | *o=++| 2026-09-07 18:55:08.122770 | debian-bookworm -> localhost | | S =o=BE=| 2026-09-07 18:55:08.122793 | debian-bookworm -> localhost | | . . .=Oo*O| 2026-09-07 18:55:08.122813 | debian-bookworm -> localhost | | o ..o=*B*| 2026-09-07 18:55:08.122832 | debian-bookworm -> localhost | | . . .o=o*| 2026-09-07 18:55:08.122852 | debian-bookworm -> localhost | | . . .==| 2026-09-07 18:55:08.122872 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-09-07 18:55:08.122917 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.844854 2026-09-07 18:55:08.128935 | 2026-09-07 18:55:08.129020 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-09-07 18:55:08.146806 | debian-bookworm | ok 2026-09-07 18:55:08.168448 | debian-bookworm | included: /var/lib/zuul/builds/3608177b477549699c898fab4466096e/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-09-07 18:55:08.176730 | 2026-09-07 18:55:08.176815 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-09-07 18:55:08.189791 | debian-bookworm | skipping: Conditional result was False 2026-09-07 18:55:08.196534 | 2026-09-07 18:55:08.196636 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-09-07 18:55:08.906784 | debian-bookworm | changed 2026-09-07 18:55:08.911989 | 2026-09-07 18:55:08.912086 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-09-07 18:55:09.181504 | debian-bookworm | ok 2026-09-07 18:55:09.190609 | 2026-09-07 18:55:09.190719 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-09-07 18:55:09.854851 | debian-bookworm | changed 2026-09-07 18:55:09.874058 | 2026-09-07 18:55:09.874169 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-09-07 18:55:10.610820 | debian-bookworm | changed 2026-09-07 18:55:10.622305 | 2026-09-07 18:55:10.622415 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-09-07 18:55:10.635163 | debian-bookworm | skipping: Conditional result was False 2026-09-07 18:55:10.641457 | 2026-09-07 18:55:10.641583 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-09-07 18:55:11.261291 | debian-bookworm -> localhost | changed 2026-09-07 18:55:11.271514 | 2026-09-07 18:55:11.271599 | TASK [add-build-sshkey : Add back temp key] 2026-09-07 18:55:11.644356 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/3608177b477549699c898fab4466096e/work/3608177b477549699c898fab4466096e_id_rsa (zuul-build-sshkey) 2026-09-07 18:55:11.644617 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.008844 2026-09-07 18:55:11.652500 | 2026-09-07 18:55:11.652592 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-09-07 18:55:12.118295 | debian-bookworm | ok 2026-09-07 18:55:12.123858 | 2026-09-07 18:55:12.123948 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-09-07 18:55:12.176921 | debian-bookworm | skipping: Conditional result was False 2026-09-07 18:55:12.186725 | 2026-09-07 18:55:12.186819 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-09-07 18:55:12.848959 | debian-bookworm | ok 2026-09-07 18:55:12.875878 | 2026-09-07 18:55:12.875991 | TASK [validate-host : Define zuul_info_dir fact] 2026-09-07 18:55:12.932586 | debian-bookworm | ok 2026-09-07 18:55:12.938830 | 2026-09-07 18:55:12.938926 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-09-07 18:55:13.317498 | debian-bookworm -> localhost | ok 2026-09-07 18:55:13.323536 | 2026-09-07 18:55:13.323619 | TASK [validate-host : Collect information about the host] 2026-09-07 18:55:54.533192 | debian-bookworm | ok 2026-09-07 18:55:54.560032 | 2026-09-07 18:55:54.560147 | TASK [validate-host : Sanitize hostname] 2026-09-07 18:55:54.721013 | debian-bookworm | ok 2026-09-07 18:55:54.726514 | 2026-09-07 18:55:54.726593 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-09-07 18:55:55.545675 | debian-bookworm -> localhost | changed 2026-09-07 18:55:55.551148 | 2026-09-07 18:55:55.551246 | TASK [validate-host : Collect information about zuul worker] 2026-09-07 18:55:56.087312 | debian-bookworm | ok 2026-09-07 18:55:56.094249 | 2026-09-07 18:55:56.094349 | TASK [validate-host : Write out all zuul information for each host] 2026-09-07 18:55:57.450048 | debian-bookworm -> localhost | changed 2026-09-07 18:55:57.457733 | 2026-09-07 18:55:57.457810 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-09-07 18:55:57.802592 | debian-bookworm | ok 2026-09-07 18:55:57.807401 | 2026-09-07 18:55:57.807479 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-09-07 18:55:59.899949 | debian-bookworm | changed: 2026-09-07 18:55:59.900123 | debian-bookworm | cd+++++++++ src/ 2026-09-07 18:55:59.900152 | debian-bookworm | cd+++++++++ src/github.com/ 2026-09-07 18:55:59.900172 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-09-07 18:55:59.900190 | debian-bookworm | cd+++++++++ src/github.com/osism/python-osism/ 2026-09-07 18:55:59.900207 | debian-bookworm | Containerfile 2026-09-07 18:55:59.900313 | debian-bookworm | Containerfile 2026-09-07 18:55:59.911871 | debian-bookworm | localhost | ok: "/var/lib/zuul/builds/3608177b477549699c898fab4466096e/work/logs" 2026-09-07 19:10:50.127983 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/3608177b477549699c898fab4466096e/work/artifacts" 2026-09-07 19:10:50.387188 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/3608177b477549699c898fab4466096e/work/docs" 2026-09-07 19:10:50.410453 | 2026-09-07 19:10:50.410604 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-09-07 19:10:51.183224 | debian-bookworm | changed: 2026-09-07 19:10:51.183607 | debian-bookworm | .d..t...... ./ 2026-09-07 19:10:51.183682 | debian-bookworm | >f+++++++++ net-debug.post.txt 2026-09-07 19:10:51.183795 | debian-bookworm | >f+++++++++ net-debug.pre.txt 2026-09-07 19:10:51.183846 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-09-07 19:10:51.183890 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-09-07 19:10:51.183931 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-09-07 19:10:51.184003 | debian-bookworm | changed: All items complete 2026-09-07 19:10:51.184053 | 2026-09-07 19:10:51.732638 | debian-bookworm | changed: .d..t...... ./ 2026-09-07 19:10:52.296516 | debian-bookworm | changed: .d..t...... ./ 2026-09-07 19:10:52.320634 | 2026-09-07 19:10:52.320772 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-09-07 19:10:52.658800 | debian-bookworm -> localhost | ok: Item: artifacts Runtime: 0:00:00.009622 2026-09-07 19:10:52.957365 | debian-bookworm -> localhost | ok: Item: docs Runtime: 0:00:00.009956 2026-09-07 19:10:52.971871 | 2026-09-07 19:10:52.972141 | PLAY RECAP 2026-09-07 19:10:52.972203 | debian-bookworm | ok: 8 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-09-07 19:10:52.972230 | 2026-09-07 19:10:53.093231 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-09-07 19:10:53.095206 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-09-07 19:10:53.835882 | 2026-09-07 19:10:53.836029 | PLAY [Base post] 2026-09-07 19:10:53.849388 | 2026-09-07 19:10:53.849513 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-09-07 19:10:54.412927 | debian-bookworm | changed 2026-09-07 19:10:54.422750 | 2026-09-07 19:10:54.422865 | PLAY RECAP 2026-09-07 19:10:54.422941 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-09-07 19:10:54.423014 | 2026-09-07 19:10:54.549926 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-09-07 19:10:54.554615 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-09-07 19:10:55.294703 | 2026-09-07 19:10:55.294879 | PLAY [Base post-logs] 2026-09-07 19:10:55.304933 | 2026-09-07 19:10:55.305060 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-09-07 19:10:55.735788 | localhost | changed 2026-09-07 19:10:55.753919 | 2026-09-07 19:10:55.754111 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-09-07 19:10:55.782101 | localhost | ok 2026-09-07 19:10:55.788027 | 2026-09-07 19:10:55.788179 | TASK [Set zuul-log-path fact] 2026-09-07 19:10:55.815707 | localhost | ok 2026-09-07 19:10:55.829659 | 2026-09-07 19:10:55.829817 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-09-07 19:10:55.867230 | localhost | ok 2026-09-07 19:10:55.873700 | 2026-09-07 19:10:55.873901 | TASK [upload-logs : Create log directories] 2026-09-07 19:10:56.352554 | localhost | changed 2026-09-07 19:10:56.357296 | 2026-09-07 19:10:56.357448 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-09-07 19:10:56.861002 | localhost -> localhost | ok: Runtime: 0:00:00.006787 2026-09-07 19:10:56.870339 | 2026-09-07 19:10:56.870556 | TASK [upload-logs : Upload logs to log server] 2026-09-07 19:10:57.406185 | localhost | Output suppressed because no_log was given 2026-09-07 19:10:57.409409 | 2026-09-07 19:10:57.409588 | LOOP [upload-logs : Compress console log and json output] 2026-09-07 19:10:57.460971 | localhost | skipping: Conditional result was False 2026-09-07 19:10:57.465115 | localhost | skipping: Conditional result was False 2026-09-07 19:10:57.472371 | 2026-09-07 19:10:57.472595 | LOOP [upload-logs : Upload compressed console log and json output] 2026-09-07 19:10:57.517373 | localhost | skipping: Conditional result was False 2026-09-07 19:10:57.517939 | 2026-09-07 19:10:57.521369 | localhost | skipping: Conditional result was False 2026-09-07 19:10:57.534515 | 2026-09-07 19:10:57.534771 | LOOP [upload-logs : Upload console log and json output]