2026-07-15 20:12:28.320394 | Job console starting 2026-07-15 20:12:28.334606 | Updating git repos 2026-07-15 20:12:28.388159 | Cloning repos into workspace 2026-07-15 20:12:28.568297 | Restoring repo states 2026-07-15 20:12:28.589365 | Merging changes 2026-07-15 20:12:29.495935 | Checking out repos 2026-07-15 20:12:29.554611 | Preparing playbooks 2026-07-15 20:12:32.004988 | Running Ansible setup 2026-07-15 20:12:38.697086 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-07-15 20:12:40.024434 | 2026-07-15 20:12:40.024576 | PLAY [Base pre] 2026-07-15 20:12:40.063432 | 2026-07-15 20:12:40.063584 | TASK [Setup log path fact] 2026-07-15 20:12:40.084061 | debian-bookworm | ok 2026-07-15 20:12:40.104351 | 2026-07-15 20:12:40.104529 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-07-15 20:12:40.145314 | debian-bookworm | ok 2026-07-15 20:12:40.159213 | 2026-07-15 20:12:40.159347 | TASK [emit-job-header : Print job information] 2026-07-15 20:12:40.199103 | # Job Information 2026-07-15 20:12:40.199283 | Ansible Version: 2.16.14 2026-07-15 20:12:40.199321 | Job: python-osism-test-setup 2026-07-15 20:12:40.199353 | Pipeline: check 2026-07-15 20:12:40.199377 | Executor: 521e9411259a 2026-07-15 20:12:40.199398 | Triggered by: https://github.com/osism/python-osism/pull/2292 2026-07-15 20:12:40.199420 | Event ID: 604f7150-8089-11f1-9f54-e041c4def435 2026-07-15 20:12:40.221714 | 2026-07-15 20:12:40.221856 | LOOP [emit-job-header : Print node information] 2026-07-15 20:12:40.439782 | debian-bookworm | ok: 2026-07-15 20:12:40.440022 | debian-bookworm | # Node Information 2026-07-15 20:12:40.440060 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-07-15 20:12:40.440087 | debian-bookworm | Hostname: debian 2026-07-15 20:12:40.440111 | debian-bookworm | Username: zuul 2026-07-15 20:12:40.440133 | debian-bookworm | Distro: Debian 12.15 2026-07-15 20:12:40.440156 | debian-bookworm | Provider: regiocloud-a 2026-07-15 20:12:40.440178 | debian-bookworm | Region: 2026-07-15 20:12:40.440199 | debian-bookworm | Label: debian-bookworm 2026-07-15 20:12:40.440234 | debian-bookworm | Product Name: OpenStack Nova 2026-07-15 20:12:40.440256 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe72:2b26 2026-07-15 20:12:40.468177 | 2026-07-15 20:12:40.468338 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-07-15 20:12:41.225773 | debian-bookworm -> localhost | changed 2026-07-15 20:12:41.232848 | 2026-07-15 20:12:41.233027 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-07-15 20:12:42.281170 | debian-bookworm -> localhost | changed 2026-07-15 20:12:42.304749 | 2026-07-15 20:12:42.304880 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-07-15 20:12:42.690583 | debian-bookworm -> localhost | ok 2026-07-15 20:12:42.697499 | 2026-07-15 20:12:42.697620 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-07-15 20:12:42.731728 | debian-bookworm | ok 2026-07-15 20:12:42.759800 | debian-bookworm | included: /var/lib/zuul/builds/ad7ccc5f612a4df1ac19b75cc57328b6/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-07-15 20:12:42.776429 | 2026-07-15 20:12:42.776542 | TASK [add-build-sshkey : Create Temp SSH key] 2026-07-15 20:12:44.070382 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-07-15 20:12:44.070599 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/ad7ccc5f612a4df1ac19b75cc57328b6/work/ad7ccc5f612a4df1ac19b75cc57328b6_id_rsa 2026-07-15 20:12:44.070638 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/ad7ccc5f612a4df1ac19b75cc57328b6/work/ad7ccc5f612a4df1ac19b75cc57328b6_id_rsa.pub 2026-07-15 20:12:44.070664 | debian-bookworm -> localhost | The key fingerprint is: 2026-07-15 20:12:44.070689 | debian-bookworm -> localhost | SHA256:TzJQLcjoEkft+eNz2P2gAsJonc0TTIFubTbBW/lwVaU zuul-build-sshkey 2026-07-15 20:12:44.070712 | debian-bookworm -> localhost | The key's randomart image is: 2026-07-15 20:12:44.070741 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-07-15 20:12:44.070762 | debian-bookworm -> localhost | | ..*.o.o ......| 2026-07-15 20:12:44.070783 | debian-bookworm -> localhost | | . + B.= o . | 2026-07-15 20:12:44.070803 | debian-bookworm -> localhost | | = =.= = E | 2026-07-15 20:12:44.070823 | debian-bookworm -> localhost | | . + @. . | 2026-07-15 20:12:44.070912 | debian-bookworm -> localhost | | * * +S . | 2026-07-15 20:12:44.070941 | debian-bookworm -> localhost | | o = = o= | 2026-07-15 20:12:44.070962 | debian-bookworm -> localhost | | . . + +... | 2026-07-15 20:12:44.070982 | debian-bookworm -> localhost | | = o... | 2026-07-15 20:12:44.071003 | debian-bookworm -> localhost | | +. .. | 2026-07-15 20:12:44.071023 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-07-15 20:12:44.071077 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.706578 2026-07-15 20:12:44.079391 | 2026-07-15 20:12:44.079500 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-07-15 20:12:44.107656 | debian-bookworm | ok 2026-07-15 20:12:44.119030 | debian-bookworm | included: /var/lib/zuul/builds/ad7ccc5f612a4df1ac19b75cc57328b6/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-07-15 20:12:44.128188 | 2026-07-15 20:12:44.128318 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-07-15 20:12:44.172430 | debian-bookworm | skipping: Conditional result was False 2026-07-15 20:12:44.179553 | 2026-07-15 20:12:44.179659 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-07-15 20:12:45.013667 | debian-bookworm | changed 2026-07-15 20:12:45.024626 | 2026-07-15 20:12:45.025101 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-07-15 20:12:45.336003 | debian-bookworm | ok 2026-07-15 20:12:45.340956 | 2026-07-15 20:12:45.341053 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-07-15 20:12:46.147868 | debian-bookworm | changed 2026-07-15 20:12:46.173800 | 2026-07-15 20:12:46.173922 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-07-15 20:12:46.907286 | debian-bookworm | changed 2026-07-15 20:12:46.913735 | 2026-07-15 20:12:46.913844 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-07-15 20:12:46.928600 | debian-bookworm | skipping: Conditional result was False 2026-07-15 20:12:46.934508 | 2026-07-15 20:12:46.934600 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-07-15 20:12:47.705747 | debian-bookworm -> localhost | changed 2026-07-15 20:12:47.716167 | 2026-07-15 20:12:47.716275 | TASK [add-build-sshkey : Add back temp key] 2026-07-15 20:12:48.270151 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/ad7ccc5f612a4df1ac19b75cc57328b6/work/ad7ccc5f612a4df1ac19b75cc57328b6_id_rsa (zuul-build-sshkey) 2026-07-15 20:12:48.270407 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.030792 2026-07-15 20:12:48.277824 | 2026-07-15 20:12:48.277921 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-07-15 20:12:48.743733 | debian-bookworm | ok 2026-07-15 20:12:48.753707 | 2026-07-15 20:12:48.753861 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-07-15 20:12:48.779126 | debian-bookworm | skipping: Conditional result was False 2026-07-15 20:12:48.792529 | 2026-07-15 20:12:48.792647 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-07-15 20:12:49.278771 | debian-bookworm | ok 2026-07-15 20:12:49.289840 | 2026-07-15 20:12:49.290025 | TASK [validate-host : Define zuul_info_dir fact] 2026-07-15 20:12:49.317891 | debian-bookworm | ok 2026-07-15 20:12:49.324063 | 2026-07-15 20:12:49.324171 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-07-15 20:12:49.641717 | debian-bookworm -> localhost | ok 2026-07-15 20:12:49.654824 | 2026-07-15 20:12:49.654967 | TASK [validate-host : Collect information about the host] 2026-07-15 20:12:50.738311 | debian-bookworm | ok 2026-07-15 20:12:50.758435 | 2026-07-15 20:12:50.758608 | TASK [validate-host : Sanitize hostname] 2026-07-15 20:12:50.944938 | debian-bookworm | ok 2026-07-15 20:12:50.949479 | 2026-07-15 20:12:50.949571 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-07-15 20:12:51.650750 | debian-bookworm -> localhost | changed 2026-07-15 20:12:51.656611 | 2026-07-15 20:12:51.656710 | TASK [validate-host : Collect information about zuul worker] 2026-07-15 20:12:52.089326 | debian-bookworm | ok 2026-07-15 20:12:52.094677 | 2026-07-15 20:12:52.094797 | TASK [validate-host : Write out all zuul information for each host] 2026-07-15 20:12:52.820324 | debian-bookworm -> localhost | changed 2026-07-15 20:12:52.831283 | 2026-07-15 20:12:52.831460 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-07-15 20:12:53.089566 | debian-bookworm | ok 2026-07-15 20:12:53.107036 | 2026-07-15 20:12:53.107211 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-07-15 20:12:54.637818 | debian-bookworm | changed: 2026-07-15 20:12:54.638124 | debian-bookworm | cd+++++++++ src/ 2026-07-15 20:12:54.638162 | debian-bookworm | cd+++++++++ src/github.com/ 2026-07-15 20:12:54.638188 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-07-15 20:12:54.638210 | debian-bookworm | cd+++++++++ src/github.com/osism/python-osism/ 2026-07-15 20:12:54.638231 | debian-bookworm | Containerfile 2026-07-15 20:12:54.638364 | debian-bookworm | Containerfile 2026-07-15 20:12:54.678187 | debian-bookworm | localhost | ok: "/var/lib/zuul/builds/ad7ccc5f612a4df1ac19b75cc57328b6/work/logs" 2026-07-15 20:15:24.669510 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/ad7ccc5f612a4df1ac19b75cc57328b6/work/artifacts" 2026-07-15 20:15:24.933622 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/ad7ccc5f612a4df1ac19b75cc57328b6/work/docs" 2026-07-15 20:15:24.943907 | 2026-07-15 20:15:24.944093 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-07-15 20:15:25.963886 | debian-bookworm | changed: 2026-07-15 20:15:25.964309 | debian-bookworm | .d..t...... ./ 2026-07-15 20:15:25.964348 | debian-bookworm | >f+++++++++ net-debug.post.txt 2026-07-15 20:15:25.964373 | debian-bookworm | >f+++++++++ net-debug.pre.txt 2026-07-15 20:15:25.964395 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-07-15 20:15:25.964418 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-07-15 20:15:25.964439 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-07-15 20:15:25.964484 | debian-bookworm | changed: All items complete 2026-07-15 20:15:25.964511 | 2026-07-15 20:15:26.620603 | debian-bookworm | changed: .d..t...... ./ 2026-07-15 20:15:27.313159 | debian-bookworm | changed: .d..t...... ./ 2026-07-15 20:15:27.343024 | 2026-07-15 20:15:27.343211 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-07-15 20:15:27.948742 | debian-bookworm -> localhost | ok: Item: artifacts Runtime: 0:00:00.014716 2026-07-15 20:15:28.467828 | debian-bookworm -> localhost | ok: Item: docs Runtime: 0:00:00.015501 2026-07-15 20:15:28.482917 | 2026-07-15 20:15:28.483076 | PLAY RECAP 2026-07-15 20:15:28.483139 | debian-bookworm | ok: 8 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-07-15 20:15:28.483166 | 2026-07-15 20:15:28.679315 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-07-15 20:15:28.680298 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-07-15 20:15:29.619329 | 2026-07-15 20:15:29.619547 | PLAY [Base post] 2026-07-15 20:15:29.637199 | 2026-07-15 20:15:29.637389 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-07-15 20:15:30.575470 | debian-bookworm | changed 2026-07-15 20:15:30.582376 | 2026-07-15 20:15:30.582490 | PLAY RECAP 2026-07-15 20:15:30.582555 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-07-15 20:15:30.582618 | 2026-07-15 20:15:30.731355 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-07-15 20:15:30.732442 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-07-15 20:15:31.686812 | 2026-07-15 20:15:31.687097 | PLAY [Base post-logs] 2026-07-15 20:15:31.700424 | 2026-07-15 20:15:31.700619 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-07-15 20:15:32.232757 | localhost | changed 2026-07-15 20:15:32.249116 | 2026-07-15 20:15:32.249375 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-07-15 20:15:32.287427 | localhost | ok 2026-07-15 20:15:32.292279 | 2026-07-15 20:15:32.292409 | TASK [Set zuul-log-path fact] 2026-07-15 20:15:32.311041 | localhost | ok 2026-07-15 20:15:32.328009 | 2026-07-15 20:15:32.328229 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-07-15 20:15:32.368819 | localhost | ok 2026-07-15 20:15:32.379377 | 2026-07-15 20:15:32.379523 | TASK [upload-logs : Create log directories] 2026-07-15 20:15:33.204296 | localhost | changed 2026-07-15 20:15:33.210925 | 2026-07-15 20:15:33.211080 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-07-15 20:15:33.921597 | localhost -> localhost | ok: Runtime: 0:00:00.005026 2026-07-15 20:15:33.929689 | 2026-07-15 20:15:33.929894 | TASK [upload-logs : Upload logs to log server] 2026-07-15 20:15:34.539120 | localhost | Output suppressed because no_log was given 2026-07-15 20:15:34.541253 | 2026-07-15 20:15:34.541367 | LOOP [upload-logs : Compress console log and json output] 2026-07-15 20:15:34.592928 | localhost | skipping: Conditional result was False 2026-07-15 20:15:34.599566 | localhost | skipping: Conditional result was False 2026-07-15 20:15:34.609833 | 2026-07-15 20:15:34.609951 | LOOP [upload-logs : Upload compressed console log and json output] 2026-07-15 20:15:34.664095 | localhost | skipping: Conditional result was False 2026-07-15 20:15:34.664436 | 2026-07-15 20:15:34.671594 | localhost | skipping: Conditional result was False 2026-07-15 20:15:34.677987 | 2026-07-15 20:15:34.678125 | LOOP [upload-logs : Upload console log and json output]