2026-09-27 16:53:14.014184 | Job console starting 2026-09-27 16:53:14.020136 | Updating git repos 2026-09-27 16:53:14.065668 | Cloning repos into workspace 2026-09-27 16:53:14.132194 | Restoring repo states 2026-09-27 16:53:14.172433 | Merging changes 2026-09-27 16:53:15.372480 | Checking out repos 2026-09-27 16:53:15.437702 | Preparing playbooks 2026-09-27 16:53:17.051969 | Running Ansible setup 2026-09-27 16:53:22.459609 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-09-27 16:53:23.437597 | 2026-09-27 16:53:23.437708 | PLAY [Base pre] 2026-09-27 16:53:23.458254 | 2026-09-27 16:53:23.458367 | TASK [Setup log path fact] 2026-09-27 16:53:23.496267 | debian-bookworm | ok 2026-09-27 16:53:23.518691 | 2026-09-27 16:53:23.518808 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-09-27 16:53:23.583884 | debian-bookworm | ok 2026-09-27 16:53:23.602215 | 2026-09-27 16:53:23.602314 | TASK [emit-job-header : Print job information] 2026-09-27 16:53:23.656451 | # Job Information 2026-09-27 16:53:23.656592 | Ansible Version: 2.16.14 2026-09-27 16:53:23.656620 | Job: python-osism-test-setup 2026-09-27 16:53:23.656647 | Pipeline: check 2026-09-27 16:53:23.656666 | Executor: 521e9411259a 2026-09-27 16:53:23.656684 | Triggered by: https://github.com/osism/python-osism/pull/2729 2026-09-27 16:53:23.656702 | Event ID: de9101c0-ba93-11f1-8499-8ba4f9fbc0c7 2026-09-27 16:53:23.680933 | 2026-09-27 16:53:23.681031 | LOOP [emit-job-header : Print node information] 2026-09-27 16:53:23.849230 | debian-bookworm | ok: 2026-09-27 16:53:23.849372 | debian-bookworm | # Node Information 2026-09-27 16:53:23.849399 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-09-27 16:53:23.849420 | debian-bookworm | Hostname: debian 2026-09-27 16:53:23.849437 | debian-bookworm | Username: zuul 2026-09-27 16:53:23.849454 | debian-bookworm | Distro: Debian 12.15 2026-09-27 16:53:23.849473 | debian-bookworm | Provider: regiocloud-a 2026-09-27 16:53:23.849490 | debian-bookworm | Region: 2026-09-27 16:53:23.849508 | debian-bookworm | Label: debian-bookworm 2026-09-27 16:53:23.849523 | debian-bookworm | Product Name: OpenStack Nova 2026-09-27 16:53:23.849539 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe77:e7f9 2026-09-27 16:53:23.864333 | 2026-09-27 16:53:23.864433 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-09-27 16:53:24.672143 | debian-bookworm -> localhost | changed 2026-09-27 16:53:24.677893 | 2026-09-27 16:53:24.678083 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-09-27 16:53:26.520591 | debian-bookworm -> localhost | changed 2026-09-27 16:53:26.529359 | 2026-09-27 16:53:26.529447 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-09-27 16:53:27.115702 | debian-bookworm -> localhost | ok 2026-09-27 16:53:27.121014 | 2026-09-27 16:53:27.121106 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-09-27 16:53:27.159329 | debian-bookworm | ok 2026-09-27 16:53:27.187037 | debian-bookworm | included: /var/lib/zuul/builds/236fbd86544344f8a3258a7be778fea2/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-09-27 16:53:27.205304 | 2026-09-27 16:53:27.205391 | TASK [add-build-sshkey : Create Temp SSH key] 2026-09-27 16:53:29.844241 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-09-27 16:53:29.844410 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/236fbd86544344f8a3258a7be778fea2/work/236fbd86544344f8a3258a7be778fea2_id_rsa 2026-09-27 16:53:29.844442 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/236fbd86544344f8a3258a7be778fea2/work/236fbd86544344f8a3258a7be778fea2_id_rsa.pub 2026-09-27 16:53:29.844465 | debian-bookworm -> localhost | The key fingerprint is: 2026-09-27 16:53:29.844486 | debian-bookworm -> localhost | SHA256:9eycS9sjqX0kkULHVZX/E1hs71wjGQfiObDP7mJ3HME zuul-build-sshkey 2026-09-27 16:53:29.844505 | debian-bookworm -> localhost | The key's randomart image is: 2026-09-27 16:53:29.844532 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-09-27 16:53:29.844551 | debian-bookworm -> localhost | | . o o+.=| 2026-09-27 16:53:29.844568 | debian-bookworm -> localhost | | = =. * | 2026-09-27 16:53:29.844596 | debian-bookworm -> localhost | | o.=..B o| 2026-09-27 16:53:29.844613 | debian-bookworm -> localhost | | .+o+E o+| 2026-09-27 16:53:29.844629 | debian-bookworm -> localhost | | S +o.oo=| 2026-09-27 16:53:29.844648 | debian-bookworm -> localhost | | .o.o..+| 2026-09-27 16:53:29.844664 | debian-bookworm -> localhost | | .*+. .| 2026-09-27 16:53:29.844680 | debian-bookworm -> localhost | | o.+o*o | 2026-09-27 16:53:29.844697 | debian-bookworm -> localhost | | . +o=o.. | 2026-09-27 16:53:29.844715 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-09-27 16:53:29.844758 | debian-bookworm -> localhost | ok: Runtime: 0:00:01.912028 2026-09-27 16:53:29.850637 | 2026-09-27 16:53:29.850721 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-09-27 16:53:29.897127 | debian-bookworm | ok 2026-09-27 16:53:29.912597 | debian-bookworm | included: /var/lib/zuul/builds/236fbd86544344f8a3258a7be778fea2/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-09-27 16:53:29.919732 | 2026-09-27 16:53:29.919810 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-09-27 16:53:29.942429 | debian-bookworm | skipping: Conditional result was False 2026-09-27 16:53:29.947056 | 2026-09-27 16:53:29.947136 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-09-27 16:53:30.625800 | debian-bookworm | changed 2026-09-27 16:53:30.630258 | 2026-09-27 16:53:30.630338 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-09-27 16:53:30.890129 | debian-bookworm | ok 2026-09-27 16:53:30.894537 | 2026-09-27 16:53:30.894632 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-09-27 16:53:31.563328 | debian-bookworm | changed 2026-09-27 16:53:31.568866 | 2026-09-27 16:53:31.568955 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-09-27 16:53:32.226782 | debian-bookworm | changed 2026-09-27 16:53:32.239846 | 2026-09-27 16:53:32.239930 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-09-27 16:53:32.262804 | debian-bookworm | skipping: Conditional result was False 2026-09-27 16:53:32.267611 | 2026-09-27 16:53:32.267688 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-09-27 16:53:32.677123 | debian-bookworm -> localhost | changed 2026-09-27 16:53:32.686556 | 2026-09-27 16:53:32.686647 | TASK [add-build-sshkey : Add back temp key] 2026-09-27 16:53:33.005272 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/236fbd86544344f8a3258a7be778fea2/work/236fbd86544344f8a3258a7be778fea2_id_rsa (zuul-build-sshkey) 2026-09-27 16:53:33.005771 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.016518 2026-09-27 16:53:33.017899 | 2026-09-27 16:53:33.018018 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-09-27 16:53:33.385263 | debian-bookworm | ok 2026-09-27 16:53:33.390350 | 2026-09-27 16:53:33.390443 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-09-27 16:53:33.413346 | debian-bookworm | skipping: Conditional result was False 2026-09-27 16:53:33.423189 | 2026-09-27 16:53:33.423273 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-09-27 16:53:33.848370 | debian-bookworm | ok 2026-09-27 16:53:33.864415 | 2026-09-27 16:53:33.864519 | TASK [validate-host : Define zuul_info_dir fact] 2026-09-27 16:53:33.907128 | debian-bookworm | ok 2026-09-27 16:53:33.914442 | 2026-09-27 16:53:33.914544 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-09-27 16:53:34.140232 | debian-bookworm -> localhost | ok 2026-09-27 16:53:34.146408 | 2026-09-27 16:53:34.146501 | TASK [validate-host : Collect information about the host] 2026-09-27 16:53:35.232292 | debian-bookworm | ok 2026-09-27 16:53:35.248297 | 2026-09-27 16:53:35.248400 | TASK [validate-host : Sanitize hostname] 2026-09-27 16:53:35.367919 | debian-bookworm | ok 2026-09-27 16:53:35.371569 | 2026-09-27 16:53:35.371658 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-09-27 16:53:35.849948 | debian-bookworm -> localhost | changed 2026-09-27 16:53:35.862035 | 2026-09-27 16:53:35.862175 | TASK [validate-host : Collect information about zuul worker] 2026-09-27 16:53:36.284675 | debian-bookworm | ok 2026-09-27 16:53:36.291480 | 2026-09-27 16:53:36.291623 | TASK [validate-host : Write out all zuul information for each host] 2026-09-27 16:53:36.809982 | debian-bookworm -> localhost | changed 2026-09-27 16:53:36.818999 | 2026-09-27 16:53:36.819084 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-09-27 16:53:37.111476 | debian-bookworm | ok 2026-09-27 16:53:37.120225 | 2026-09-27 16:53:37.120332 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-09-27 16:53:38.836732 | debian-bookworm | changed: 2026-09-27 16:53:38.836906 | debian-bookworm | cd+++++++++ src/ 2026-09-27 16:53:38.836940 | debian-bookworm | cd+++++++++ src/github.com/ 2026-09-27 16:53:38.836964 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-09-27 16:53:38.836985 | debian-bookworm | cd+++++++++ src/github.com/osism/python-osism/ 2026-09-27 16:53:38.837004 | debian-bookworm | Containerfile 2026-09-27 16:53:38.837132 | debian-bookworm | Containerfile 2026-09-27 16:53:38.844866 | debian-bookworm | localhost | ok: "/var/lib/zuul/builds/236fbd86544344f8a3258a7be778fea2/work/logs" 2026-09-27 16:56:22.984128 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/236fbd86544344f8a3258a7be778fea2/work/artifacts" 2026-09-27 16:56:23.258052 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/236fbd86544344f8a3258a7be778fea2/work/docs" 2026-09-27 16:56:23.281419 | 2026-09-27 16:56:23.281693 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-09-27 16:56:24.067382 | debian-bookworm | changed: 2026-09-27 16:56:24.067612 | debian-bookworm | .d..t...... ./ 2026-09-27 16:56:24.067647 | debian-bookworm | >f+++++++++ net-debug.post.txt 2026-09-27 16:56:24.067671 | debian-bookworm | >f+++++++++ net-debug.pre.txt 2026-09-27 16:56:24.067693 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-09-27 16:56:24.067714 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-09-27 16:56:24.067734 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-09-27 16:56:24.067767 | debian-bookworm | changed: All items complete 2026-09-27 16:56:24.067825 | 2026-09-27 16:56:24.626708 | debian-bookworm | changed: .d..t...... ./ 2026-09-27 16:56:25.204351 | debian-bookworm | changed: .d..t...... ./ 2026-09-27 16:56:25.222998 | 2026-09-27 16:56:25.223122 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-09-27 16:56:25.585311 | debian-bookworm -> localhost | ok: Item: artifacts Runtime: 0:00:00.007762 2026-09-27 16:56:25.891620 | debian-bookworm -> localhost | ok: Item: docs Runtime: 0:00:00.008321 2026-09-27 16:56:25.909270 | 2026-09-27 16:56:25.909371 | PLAY RECAP 2026-09-27 16:56:25.909423 | debian-bookworm | ok: 8 changed: 3 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-09-27 16:56:25.909448 | 2026-09-27 16:56:26.051117 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-09-27 16:56:26.052013 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-09-27 16:56:26.819208 | 2026-09-27 16:56:26.819357 | PLAY [Base post] 2026-09-27 16:56:26.832953 | 2026-09-27 16:56:26.833083 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-09-27 16:56:27.596331 | debian-bookworm | changed 2026-09-27 16:56:27.604532 | 2026-09-27 16:56:27.604647 | PLAY RECAP 2026-09-27 16:56:27.604872 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-09-27 16:56:27.604965 | 2026-09-27 16:56:27.747383 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-09-27 16:56:27.748454 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-09-27 16:56:28.527903 | 2026-09-27 16:56:28.528066 | PLAY [Base post-logs] 2026-09-27 16:56:28.538739 | 2026-09-27 16:56:28.538921 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-09-27 16:56:29.013680 | localhost | changed 2026-09-27 16:56:29.030659 | 2026-09-27 16:56:29.030849 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-09-27 16:56:29.069091 | localhost | ok 2026-09-27 16:56:29.075212 | 2026-09-27 16:56:29.075365 | TASK [Set zuul-log-path fact] 2026-09-27 16:56:29.103521 | localhost | ok 2026-09-27 16:56:29.118780 | 2026-09-27 16:56:29.118999 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-09-27 16:56:29.156301 | localhost | ok 2026-09-27 16:56:29.163667 | 2026-09-27 16:56:29.163940 | TASK [upload-logs : Create log directories] 2026-09-27 16:56:29.657741 | localhost | changed 2026-09-27 16:56:29.660574 | 2026-09-27 16:56:29.660681 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-09-27 16:56:30.160335 | localhost -> localhost | ok: Runtime: 0:00:00.007360 2026-09-27 16:56:30.164459 | 2026-09-27 16:56:30.164573 | TASK [upload-logs : Upload logs to log server] 2026-09-27 16:56:30.728073 | localhost | Output suppressed because no_log was given 2026-09-27 16:56:30.731025 | 2026-09-27 16:56:30.731176 | LOOP [upload-logs : Compress console log and json output] 2026-09-27 16:56:30.792364 | localhost | skipping: Conditional result was False 2026-09-27 16:56:30.797182 | localhost | skipping: Conditional result was False 2026-09-27 16:56:30.811990 | 2026-09-27 16:56:30.812208 | LOOP [upload-logs : Upload compressed console log and json output] 2026-09-27 16:56:30.859964 | localhost | skipping: Conditional result was False 2026-09-27 16:56:30.860533 | 2026-09-27 16:56:30.864962 | localhost | skipping: Conditional result was False 2026-09-27 16:56:30.877159 | 2026-09-27 16:56:30.877381 | LOOP [upload-logs : Upload console log and json output]