2026-09-13 12:55:03.604288 | Job console starting 2026-09-13 12:55:03.617806 | Updating git repos 2026-09-13 12:55:03.705368 | Cloning repos into workspace 2026-09-13 12:55:03.805258 | Restoring repo states 2026-09-13 12:55:03.874907 | Merging changes 2026-09-13 12:55:04.882483 | Checking out repos 2026-09-13 12:55:05.103220 | Preparing playbooks 2026-09-13 12:55:07.571489 | Running Ansible setup 2026-09-13 12:55:13.406732 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-09-13 12:55:14.802739 | 2026-09-13 12:55:14.802860 | PLAY [Base pre] 2026-09-13 12:55:14.833193 | 2026-09-13 12:55:14.833304 | TASK [Setup log path fact] 2026-09-13 12:55:14.884112 | debian-bookworm | ok 2026-09-13 12:55:14.908484 | 2026-09-13 12:55:14.908594 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-09-13 12:55:14.973665 | debian-bookworm | ok 2026-09-13 12:55:14.981826 | 2026-09-13 12:55:14.981919 | TASK [emit-job-header : Print job information] 2026-09-13 12:55:15.068262 | # Job Information 2026-09-13 12:55:15.068458 | Ansible Version: 2.16.14 2026-09-13 12:55:15.068489 | Job: python-osism-test-setup 2026-09-13 12:55:15.068522 | Pipeline: check 2026-09-13 12:55:15.068545 | Executor: 521e9411259a 2026-09-13 12:55:15.068563 | Triggered by: https://github.com/osism/python-osism/pull/2693 2026-09-13 12:55:15.068581 | Event ID: 5027bc70-af72-11f1-89c0-b7e78b41774a 2026-09-13 12:55:15.072957 | 2026-09-13 12:55:15.073076 | LOOP [emit-job-header : Print node information] 2026-09-13 12:55:15.326841 | debian-bookworm | ok: 2026-09-13 12:55:15.327018 | debian-bookworm | # Node Information 2026-09-13 12:55:15.327050 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-09-13 12:55:15.327083 | debian-bookworm | Hostname: debian 2026-09-13 12:55:15.327101 | debian-bookworm | Username: zuul 2026-09-13 12:55:15.327118 | debian-bookworm | Distro: Debian 12.15 2026-09-13 12:55:15.327138 | debian-bookworm | Provider: regiocloud-a 2026-09-13 12:55:15.327155 | debian-bookworm | Region: 2026-09-13 12:55:15.327173 | debian-bookworm | Label: debian-bookworm 2026-09-13 12:55:15.327189 | debian-bookworm | Product Name: OpenStack Nova 2026-09-13 12:55:15.327205 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe1e:1f40 2026-09-13 12:55:15.344569 | 2026-09-13 12:55:15.344664 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-09-13 12:55:16.144427 | debian-bookworm -> localhost | changed 2026-09-13 12:55:16.150401 | 2026-09-13 12:55:16.150492 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-09-13 12:55:18.004984 | debian-bookworm -> localhost | changed 2026-09-13 12:55:18.013818 | 2026-09-13 12:55:18.013906 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-09-13 12:55:18.661220 | debian-bookworm -> localhost | ok 2026-09-13 12:55:18.666089 | 2026-09-13 12:55:18.666175 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-09-13 12:55:18.714721 | debian-bookworm | ok 2026-09-13 12:55:18.750087 | debian-bookworm | included: /var/lib/zuul/builds/56a5559b8aab4777aa4687411925faca/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-09-13 12:55:18.763266 | 2026-09-13 12:55:18.763362 | TASK [add-build-sshkey : Create Temp SSH key] 2026-09-13 12:55:21.414607 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-09-13 12:55:21.414792 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/56a5559b8aab4777aa4687411925faca/work/56a5559b8aab4777aa4687411925faca_id_rsa 2026-09-13 12:55:21.414825 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/56a5559b8aab4777aa4687411925faca/work/56a5559b8aab4777aa4687411925faca_id_rsa.pub 2026-09-13 12:55:21.414847 | debian-bookworm -> localhost | The key fingerprint is: 2026-09-13 12:55:21.414868 | debian-bookworm -> localhost | SHA256:zqn3RhBOs963ZmALUqTc9yC+L6TYGYN2WojdZ2AnQlc zuul-build-sshkey 2026-09-13 12:55:21.414887 | debian-bookworm -> localhost | The key's randomart image is: 2026-09-13 12:55:21.414915 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-09-13 12:55:21.414934 | debian-bookworm -> localhost | | .E | 2026-09-13 12:55:21.414952 | debian-bookworm -> localhost | | . . = | 2026-09-13 12:55:21.414969 | debian-bookworm -> localhost | | . o * + | 2026-09-13 12:55:21.414986 | debian-bookworm -> localhost | | . * O o | 2026-09-13 12:55:21.415003 | debian-bookworm -> localhost | | o * BS= o | 2026-09-13 12:55:21.415025 | debian-bookworm -> localhost | | . = BoB.= o | 2026-09-13 12:55:21.415042 | debian-bookworm -> localhost | | . * X+= + . | 2026-09-13 12:55:21.415068 | debian-bookworm -> localhost | | o +.+ o + | 2026-09-13 12:55:21.415086 | debian-bookworm -> localhost | | .. =oo | 2026-09-13 12:55:21.415103 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-09-13 12:55:21.415150 | debian-bookworm -> localhost | ok: Runtime: 0:00:01.890464 2026-09-13 12:55:21.420807 | 2026-09-13 12:55:21.420889 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-09-13 12:55:21.458455 | debian-bookworm | ok 2026-09-13 12:55:21.467501 | debian-bookworm | included: /var/lib/zuul/builds/56a5559b8aab4777aa4687411925faca/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-09-13 12:55:21.476654 | 2026-09-13 12:55:21.476742 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-09-13 12:55:21.489907 | debian-bookworm | skipping: Conditional result was False 2026-09-13 12:55:21.494697 | 2026-09-13 12:55:21.494771 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-09-13 12:55:22.127434 | debian-bookworm | changed 2026-09-13 12:55:22.131919 | 2026-09-13 12:55:22.132041 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-09-13 12:55:22.428618 | debian-bookworm | ok 2026-09-13 12:55:22.433081 | 2026-09-13 12:55:22.433162 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-09-13 12:55:23.181330 | debian-bookworm | changed 2026-09-13 12:55:23.204787 | 2026-09-13 12:55:23.204919 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-09-13 12:55:23.980944 | debian-bookworm | changed 2026-09-13 12:55:23.987651 | 2026-09-13 12:55:23.987754 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-09-13 12:55:24.027333 | debian-bookworm | skipping: Conditional result was False 2026-09-13 12:55:24.037386 | 2026-09-13 12:55:24.037527 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-09-13 12:55:24.835154 | debian-bookworm -> localhost | changed 2026-09-13 12:55:24.865708 | 2026-09-13 12:55:24.865829 | TASK [add-build-sshkey : Add back temp key] 2026-09-13 12:55:25.355239 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/56a5559b8aab4777aa4687411925faca/work/56a5559b8aab4777aa4687411925faca_id_rsa (zuul-build-sshkey) 2026-09-13 12:55:25.355420 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.018066 2026-09-13 12:55:25.361490 | 2026-09-13 12:55:25.361579 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-09-13 12:55:25.814105 | debian-bookworm | ok 2026-09-13 12:55:25.819854 | 2026-09-13 12:55:25.819962 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-09-13 12:55:25.863124 | debian-bookworm | skipping: Conditional result was False 2026-09-13 12:55:25.873771 | 2026-09-13 12:55:25.873869 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-09-13 12:55:26.396976 | debian-bookworm | ok 2026-09-13 12:55:26.416565 | 2026-09-13 12:55:26.416664 | TASK [validate-host : Define zuul_info_dir fact] 2026-09-13 12:55:26.448308 | debian-bookworm | ok 2026-09-13 12:55:26.452450 | 2026-09-13 12:55:26.452527 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-09-13 12:55:26.828441 | debian-bookworm -> localhost | ok 2026-09-13 12:55:26.833473 | 2026-09-13 12:55:26.833646 | TASK [validate-host : Collect information about the host] 2026-09-13 12:55:27.963496 | debian-bookworm | ok 2026-09-13 12:55:27.972724 | 2026-09-13 12:55:27.972808 | TASK [validate-host : Sanitize hostname] 2026-09-13 12:55:28.152212 | debian-bookworm | ok 2026-09-13 12:55:28.166739 | 2026-09-13 12:55:28.166838 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-09-13 12:55:29.388428 | debian-bookworm -> localhost | changed 2026-09-13 12:55:29.393132 | 2026-09-13 12:55:29.393219 | TASK [validate-host : Collect information about zuul worker] 2026-09-13 12:55:30.012360 | debian-bookworm | ok 2026-09-13 12:55:30.018492 | 2026-09-13 12:55:30.018599 | TASK [validate-host : Write out all zuul information for each host] 2026-09-13 12:55:30.533434 | debian-bookworm -> localhost | changed 2026-09-13 12:55:30.541040 | 2026-09-13 12:55:30.541130 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-09-13 12:55:30.817923 | debian-bookworm | ok 2026-09-13 12:55:30.824486 | 2026-09-13 12:55:30.824582 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-09-13 12:55:32.806551 | debian-bookworm | changed: 2026-09-13 12:55:32.806723 | debian-bookworm | cd+++++++++ src/ 2026-09-13 12:55:32.806758 | debian-bookworm | cd+++++++++ src/github.com/ 2026-09-13 12:55:32.806782 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-09-13 12:55:32.806803 | debian-bookworm | cd+++++++++ src/github.com/osism/python-osism/ 2026-09-13 12:55:32.806824 | debian-bookworm | Containerfile 2026-09-13 12:55:32.807092 | debian-bookworm | Containerfile 2026-09-13 12:55:32.814631 | debian-bookworm | localhost | ok: "/var/lib/zuul/builds/56a5559b8aab4777aa4687411925faca/work/logs" 2026-09-13 12:58:10.286683 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/56a5559b8aab4777aa4687411925faca/work/artifacts" 2026-09-13 12:58:10.578811 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/56a5559b8aab4777aa4687411925faca/work/docs" 2026-09-13 12:58:10.599187 | 2026-09-13 12:58:10.599348 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-09-13 12:58:11.390100 | debian-bookworm | changed: 2026-09-13 12:58:11.390369 | debian-bookworm | .d..t...... ./ 2026-09-13 12:58:11.390411 | debian-bookworm | >f+++++++++ net-debug.post.txt 2026-09-13 12:58:11.390439 | debian-bookworm | >f+++++++++ net-debug.pre.txt 2026-09-13 12:58:11.390464 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-09-13 12:58:11.390487 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-09-13 12:58:11.390508 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-09-13 12:58:11.390545 | debian-bookworm | changed: All items complete 2026-09-13 12:58:11.390568 | 2026-09-13 12:58:11.974221 | debian-bookworm | changed: .d..t...... ./ 2026-09-13 12:58:12.567459 | debian-bookworm | changed: .d..t...... ./ 2026-09-13 12:58:12.594754 | 2026-09-13 12:58:12.594895 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-09-13 12:58:12.947109 | debian-bookworm -> localhost | ok: Item: artifacts Runtime: 0:00:00.010945 2026-09-13 12:58:13.225122 | debian-bookworm -> localhost | ok: Item: docs Runtime: 0:00:00.010976 2026-09-13 12:58:13.239602 | 2026-09-13 12:58:13.239698 | PLAY RECAP 2026-09-13 12:58:13.239814 | debian-bookworm | ok: 8 changed: 3 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-09-13 12:58:13.239843 | 2026-09-13 12:58:13.365403 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-09-13 12:58:13.366502 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-09-13 12:58:14.108460 | 2026-09-13 12:58:14.108618 | PLAY [Base post] 2026-09-13 12:58:14.122452 | 2026-09-13 12:58:14.122578 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-09-13 12:58:14.960481 | debian-bookworm | changed 2026-09-13 12:58:14.970290 | 2026-09-13 12:58:14.970417 | PLAY RECAP 2026-09-13 12:58:14.970494 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-09-13 12:58:14.970569 | 2026-09-13 12:58:15.100248 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-09-13 12:58:15.101189 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-09-13 12:58:15.941431 | 2026-09-13 12:58:15.941633 | PLAY [Base post-logs] 2026-09-13 12:58:15.951815 | 2026-09-13 12:58:15.951938 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-09-13 12:58:16.409477 | localhost | changed 2026-09-13 12:58:16.422195 | 2026-09-13 12:58:16.422404 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-09-13 12:58:16.458138 | localhost | ok 2026-09-13 12:58:16.461764 | 2026-09-13 12:58:16.461872 | TASK [Set zuul-log-path fact] 2026-09-13 12:58:16.488788 | localhost | ok 2026-09-13 12:58:16.504512 | 2026-09-13 12:58:16.504657 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-09-13 12:58:16.541896 | localhost | ok 2026-09-13 12:58:16.548645 | 2026-09-13 12:58:16.548845 | TASK [upload-logs : Create log directories] 2026-09-13 12:58:17.053204 | localhost | changed 2026-09-13 12:58:17.056163 | 2026-09-13 12:58:17.056272 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-09-13 12:58:17.538207 | localhost -> localhost | ok: Runtime: 0:00:00.007255 2026-09-13 12:58:17.542419 | 2026-09-13 12:58:17.542535 | TASK [upload-logs : Upload logs to log server] 2026-09-13 12:58:18.097455 | localhost | Output suppressed because no_log was given 2026-09-13 12:58:18.100834 | 2026-09-13 12:58:18.101020 | LOOP [upload-logs : Compress console log and json output] 2026-09-13 12:58:18.150355 | localhost | skipping: Conditional result was False 2026-09-13 12:58:18.156204 | localhost | skipping: Conditional result was False 2026-09-13 12:58:18.161932 | 2026-09-13 12:58:18.162158 | LOOP [upload-logs : Upload compressed console log and json output] 2026-09-13 12:58:18.212141 | localhost | skipping: Conditional result was False 2026-09-13 12:58:18.212426 | 2026-09-13 12:58:18.217186 | localhost | skipping: Conditional result was False 2026-09-13 12:58:18.229881 | 2026-09-13 12:58:18.229998 | LOOP [upload-logs : Upload console log and json output]