2026-09-30 14:17:05.408361 | Job console starting 2026-09-30 14:17:05.428888 | Updating git repos 2026-09-30 14:17:05.508970 | Cloning repos into workspace 2026-09-30 14:17:05.613001 | Restoring repo states 2026-09-30 14:17:05.639914 | Merging changes 2026-09-30 14:17:06.618910 | Checking out repos 2026-09-30 14:17:06.721191 | Preparing playbooks 2026-09-30 14:17:09.428143 | Running Ansible setup 2026-09-30 14:17:15.404107 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-09-30 14:17:16.692279 | 2026-09-30 14:17:16.692399 | PLAY [Base pre] 2026-09-30 14:17:16.726823 | 2026-09-30 14:17:16.726925 | TASK [Setup log path fact] 2026-09-30 14:17:16.744384 | debian-bookworm | ok 2026-09-30 14:17:16.773119 | 2026-09-30 14:17:16.773238 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-09-30 14:17:16.810643 | debian-bookworm | ok 2026-09-30 14:17:16.820731 | 2026-09-30 14:17:16.820811 | TASK [emit-job-header : Print job information] 2026-09-30 14:17:16.858719 | # Job Information 2026-09-30 14:17:16.858852 | Ansible Version: 2.16.14 2026-09-30 14:17:16.858881 | Job: python-osism-test-setup 2026-09-30 14:17:16.858909 | Pipeline: check 2026-09-30 14:17:16.858928 | Executor: 521e9411259a 2026-09-30 14:17:16.858946 | Triggered by: https://github.com/osism/python-osism/pull/2737 2026-09-30 14:17:16.858964 | Event ID: 95076150-bcd9-11f1-8378-9fd13ba4090e 2026-09-30 14:17:16.863106 | 2026-09-30 14:17:16.863189 | LOOP [emit-job-header : Print node information] 2026-09-30 14:17:16.963539 | debian-bookworm | ok: 2026-09-30 14:17:16.963741 | debian-bookworm | # Node Information 2026-09-30 14:17:16.963772 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-09-30 14:17:16.963793 | debian-bookworm | Hostname: debian 2026-09-30 14:17:16.963811 | debian-bookworm | Username: zuul 2026-09-30 14:17:16.963828 | debian-bookworm | Distro: Debian 12.15 2026-09-30 14:17:16.963849 | debian-bookworm | Provider: regiocloud-a 2026-09-30 14:17:16.963867 | debian-bookworm | Region: 2026-09-30 14:17:16.963885 | debian-bookworm | Label: debian-bookworm 2026-09-30 14:17:16.963901 | debian-bookworm | Product Name: OpenStack Nova 2026-09-30 14:17:16.963918 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe93:e729 2026-09-30 14:17:16.974938 | 2026-09-30 14:17:16.975072 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-09-30 14:17:17.554458 | debian-bookworm -> localhost | changed 2026-09-30 14:17:17.560148 | 2026-09-30 14:17:17.560232 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-09-30 14:17:19.357955 | debian-bookworm -> localhost | changed 2026-09-30 14:17:19.366504 | 2026-09-30 14:17:19.366616 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-09-30 14:17:19.827761 | debian-bookworm -> localhost | ok 2026-09-30 14:17:19.832723 | 2026-09-30 14:17:19.832816 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-09-30 14:17:19.860856 | debian-bookworm | ok 2026-09-30 14:17:19.883474 | debian-bookworm | included: /var/lib/zuul/builds/b0fbe8f7886b4c1c9ef41d700ffa8a16/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-09-30 14:17:19.888301 | 2026-09-30 14:17:19.888376 | TASK [add-build-sshkey : Create Temp SSH key] 2026-09-30 14:17:22.623591 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-09-30 14:17:22.624020 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/b0fbe8f7886b4c1c9ef41d700ffa8a16/work/b0fbe8f7886b4c1c9ef41d700ffa8a16_id_rsa 2026-09-30 14:17:22.624119 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/b0fbe8f7886b4c1c9ef41d700ffa8a16/work/b0fbe8f7886b4c1c9ef41d700ffa8a16_id_rsa.pub 2026-09-30 14:17:22.624189 | debian-bookworm -> localhost | The key fingerprint is: 2026-09-30 14:17:22.624253 | debian-bookworm -> localhost | SHA256:G2XZHCbquMfhhLWh10xwv8ULqMIr18h2p8DiQZqBLHw zuul-build-sshkey 2026-09-30 14:17:22.624479 | debian-bookworm -> localhost | The key's randomart image is: 2026-09-30 14:17:22.624583 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-09-30 14:17:22.624644 | debian-bookworm -> localhost | | . o o | 2026-09-30 14:17:22.624699 | debian-bookworm -> localhost | | + O o | 2026-09-30 14:17:22.624753 | debian-bookworm -> localhost | | + * = o | 2026-09-30 14:17:22.624803 | debian-bookworm -> localhost | |o. . * X + . | 2026-09-30 14:17:22.624854 | debian-bookworm -> localhost | |oo.E. * S o . . | 2026-09-30 14:17:22.624918 | debian-bookworm -> localhost | |. .= o @ + | 2026-09-30 14:17:22.624971 | debian-bookworm -> localhost | | o + X B . | 2026-09-30 14:17:22.625021 | debian-bookworm -> localhost | | . * + o | 2026-09-30 14:17:22.625075 | debian-bookworm -> localhost | | . . | 2026-09-30 14:17:22.625127 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-09-30 14:17:22.625252 | debian-bookworm -> localhost | ok: Runtime: 0:00:01.932672 2026-09-30 14:17:22.643457 | 2026-09-30 14:17:22.643590 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-09-30 14:17:22.662631 | debian-bookworm | ok 2026-09-30 14:17:22.675263 | debian-bookworm | included: /var/lib/zuul/builds/b0fbe8f7886b4c1c9ef41d700ffa8a16/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-09-30 14:17:22.683095 | 2026-09-30 14:17:22.683182 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-09-30 14:17:22.718809 | debian-bookworm | skipping: Conditional result was False 2026-09-30 14:17:22.724571 | 2026-09-30 14:17:22.724663 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-09-30 14:17:23.484060 | debian-bookworm | changed 2026-09-30 14:17:23.488464 | 2026-09-30 14:17:23.488538 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-09-30 14:17:23.751987 | debian-bookworm | ok 2026-09-30 14:17:23.758481 | 2026-09-30 14:17:23.758583 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-09-30 14:17:24.418609 | debian-bookworm | changed 2026-09-30 14:17:24.423684 | 2026-09-30 14:17:24.423769 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-09-30 14:17:25.175475 | debian-bookworm | changed 2026-09-30 14:17:25.180787 | 2026-09-30 14:17:25.180872 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-09-30 14:17:25.215015 | debian-bookworm | skipping: Conditional result was False 2026-09-30 14:17:25.220628 | 2026-09-30 14:17:25.220772 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-09-30 14:17:25.891679 | debian-bookworm -> localhost | changed 2026-09-30 14:17:25.901857 | 2026-09-30 14:17:25.901959 | TASK [add-build-sshkey : Add back temp key] 2026-09-30 14:17:26.269218 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/b0fbe8f7886b4c1c9ef41d700ffa8a16/work/b0fbe8f7886b4c1c9ef41d700ffa8a16_id_rsa (zuul-build-sshkey) 2026-09-30 14:17:26.269414 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.010052 2026-09-30 14:17:26.276599 | 2026-09-30 14:17:26.276694 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-09-30 14:17:26.828943 | debian-bookworm | ok 2026-09-30 14:17:26.841825 | 2026-09-30 14:17:26.841932 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-09-30 14:17:26.865022 | debian-bookworm | skipping: Conditional result was False 2026-09-30 14:17:26.874789 | 2026-09-30 14:17:26.874891 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-09-30 14:17:27.371404 | debian-bookworm | ok 2026-09-30 14:17:27.391834 | 2026-09-30 14:17:27.391940 | TASK [validate-host : Define zuul_info_dir fact] 2026-09-30 14:17:27.419626 | debian-bookworm | ok 2026-09-30 14:17:27.424448 | 2026-09-30 14:17:27.424533 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-09-30 14:17:27.671873 | debian-bookworm -> localhost | ok 2026-09-30 14:17:27.678059 | 2026-09-30 14:17:27.678151 | TASK [validate-host : Collect information about the host] 2026-09-30 14:17:28.800519 | debian-bookworm | ok 2026-09-30 14:17:28.832778 | 2026-09-30 14:17:28.832870 | TASK [validate-host : Sanitize hostname] 2026-09-30 14:17:28.947936 | debian-bookworm | ok 2026-09-30 14:17:28.951410 | 2026-09-30 14:17:28.951481 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-09-30 14:17:29.481398 | debian-bookworm -> localhost | changed 2026-09-30 14:17:29.489035 | 2026-09-30 14:17:29.489124 | TASK [validate-host : Collect information about zuul worker] 2026-09-30 14:17:29.900700 | debian-bookworm | ok 2026-09-30 14:17:29.904445 | 2026-09-30 14:17:29.904520 | TASK [validate-host : Write out all zuul information for each host] 2026-09-30 14:17:30.859228 | debian-bookworm -> localhost | changed 2026-09-30 14:17:30.870388 | 2026-09-30 14:17:30.870478 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-09-30 14:17:31.160612 | debian-bookworm | ok 2026-09-30 14:17:31.176404 | 2026-09-30 14:17:31.176566 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-09-30 14:17:33.481896 | debian-bookworm | changed: 2026-09-30 14:17:33.482107 | debian-bookworm | cd+++++++++ src/ 2026-09-30 14:17:33.482176 | debian-bookworm | cd+++++++++ src/github.com/ 2026-09-30 14:17:33.482210 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-09-30 14:17:33.482237 | debian-bookworm | cd+++++++++ src/github.com/osism/python-osism/ 2026-09-30 14:17:33.482263 | debian-bookworm | Containerfile 2026-09-30 14:17:33.482451 | debian-bookworm | Containerfile 2026-09-30 14:17:33.507894 | debian-bookworm | localhost | ok: "/var/lib/zuul/builds/b0fbe8f7886b4c1c9ef41d700ffa8a16/work/logs" 2026-09-30 14:20:22.247062 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/b0fbe8f7886b4c1c9ef41d700ffa8a16/work/artifacts" 2026-09-30 14:20:22.500458 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/b0fbe8f7886b4c1c9ef41d700ffa8a16/work/docs" 2026-09-30 14:20:22.521460 | 2026-09-30 14:20:22.521663 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-09-30 14:20:23.291410 | debian-bookworm | changed: 2026-09-30 14:20:23.291655 | debian-bookworm | .d..t...... ./ 2026-09-30 14:20:23.291692 | debian-bookworm | >f+++++++++ net-debug.post.txt 2026-09-30 14:20:23.291716 | debian-bookworm | >f+++++++++ net-debug.pre.txt 2026-09-30 14:20:23.291737 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-09-30 14:20:23.291758 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-09-30 14:20:23.291778 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-09-30 14:20:23.291816 | debian-bookworm | changed: All items complete 2026-09-30 14:20:23.291842 | 2026-09-30 14:20:23.851573 | debian-bookworm | changed: .d..t...... ./ 2026-09-30 14:20:24.412193 | debian-bookworm | changed: .d..t...... ./ 2026-09-30 14:20:24.434588 | 2026-09-30 14:20:24.434720 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-09-30 14:20:24.788946 | debian-bookworm -> localhost | ok: Item: artifacts Runtime: 0:00:00.008159 2026-09-30 14:20:25.052567 | debian-bookworm -> localhost | ok: Item: docs Runtime: 0:00:00.005343 2026-09-30 14:20:25.064938 | 2026-09-30 14:20:25.065022 | PLAY RECAP 2026-09-30 14:20:25.065074 | debian-bookworm | ok: 8 changed: 3 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-09-30 14:20:25.065099 | 2026-09-30 14:20:25.214460 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-09-30 14:20:25.215370 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-09-30 14:20:26.050457 | 2026-09-30 14:20:26.050693 | PLAY [Base post] 2026-09-30 14:20:26.070921 | 2026-09-30 14:20:26.071065 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-09-30 14:20:26.883226 | debian-bookworm | changed 2026-09-30 14:20:26.893080 | 2026-09-30 14:20:26.893211 | PLAY RECAP 2026-09-30 14:20:26.893320 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-09-30 14:20:26.893403 | 2026-09-30 14:20:27.007779 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-09-30 14:20:27.009841 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-09-30 14:20:27.778188 | 2026-09-30 14:20:27.778364 | PLAY [Base post-logs] 2026-09-30 14:20:27.788837 | 2026-09-30 14:20:27.788975 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-09-30 14:20:28.270630 | localhost | changed 2026-09-30 14:20:28.288444 | 2026-09-30 14:20:28.288645 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-09-30 14:20:28.327281 | localhost | ok 2026-09-30 14:20:28.333941 | 2026-09-30 14:20:28.334106 | TASK [Set zuul-log-path fact] 2026-09-30 14:20:28.351656 | localhost | ok 2026-09-30 14:20:28.363706 | 2026-09-30 14:20:28.363844 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-09-30 14:20:28.391615 | localhost | ok 2026-09-30 14:20:28.399069 | 2026-09-30 14:20:28.399242 | TASK [upload-logs : Create log directories] 2026-09-30 14:20:28.904194 | localhost | changed 2026-09-30 14:20:28.907173 | 2026-09-30 14:20:28.907308 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-09-30 14:20:29.398191 | localhost -> localhost | ok: Runtime: 0:00:00.007322 2026-09-30 14:20:29.406931 | 2026-09-30 14:20:29.407098 | TASK [upload-logs : Upload logs to log server] 2026-09-30 14:20:29.967590 | localhost | Output suppressed because no_log was given 2026-09-30 14:20:29.972186 | 2026-09-30 14:20:29.972446 | LOOP [upload-logs : Compress console log and json output] 2026-09-30 14:20:30.030762 | localhost | skipping: Conditional result was False 2026-09-30 14:20:30.035681 | localhost | skipping: Conditional result was False 2026-09-30 14:20:30.046949 | 2026-09-30 14:20:30.047141 | LOOP [upload-logs : Upload compressed console log and json output] 2026-09-30 14:20:30.091653 | localhost | skipping: Conditional result was False 2026-09-30 14:20:30.092145 | 2026-09-30 14:20:30.096119 | localhost | skipping: Conditional result was False 2026-09-30 14:20:30.108249 | 2026-09-30 14:20:30.108522 | LOOP [upload-logs : Upload console log and json output]