2026-08-06 13:35:50.498026 | Job console starting 2026-08-06 13:35:50.507374 | Updating git repos 2026-08-06 13:35:50.552421 | Cloning repos into workspace 2026-08-06 13:35:50.611297 | Restoring repo states 2026-08-06 13:35:50.632827 | Merging changes 2026-08-06 13:35:51.443326 | Checking out repos 2026-08-06 13:35:51.495888 | Preparing playbooks 2026-08-06 13:35:52.551563 | Running Ansible setup 2026-08-06 13:35:58.695262 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-08-06 13:36:00.130514 | 2026-08-06 13:36:00.130683 | PLAY [Base pre] 2026-08-06 13:36:00.179901 | 2026-08-06 13:36:00.180053 | TASK [Setup log path fact] 2026-08-06 13:36:00.246712 | debian-bookworm | ok 2026-08-06 13:36:00.297031 | 2026-08-06 13:36:00.297241 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-08-06 13:36:00.336559 | debian-bookworm | ok 2026-08-06 13:36:00.356263 | 2026-08-06 13:36:00.356385 | TASK [emit-job-header : Print job information] 2026-08-06 13:36:00.452074 | # Job Information 2026-08-06 13:36:00.452301 | Ansible Version: 2.16.14 2026-08-06 13:36:00.452335 | Job: python-osism-test-setup 2026-08-06 13:36:00.452370 | Pipeline: check 2026-08-06 13:36:00.452394 | Executor: 521e9411259a 2026-08-06 13:36:00.452414 | Triggered by: https://github.com/osism/python-osism/pull/2583 2026-08-06 13:36:00.452437 | Event ID: 9d5ca950-919b-11f1-99e7-bb2cc733ba2a 2026-08-06 13:36:00.462733 | 2026-08-06 13:36:00.462903 | LOOP [emit-job-header : Print node information] 2026-08-06 13:36:00.673435 | debian-bookworm | ok: 2026-08-06 13:36:00.673719 | debian-bookworm | # Node Information 2026-08-06 13:36:00.673760 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-08-06 13:36:00.673817 | debian-bookworm | Hostname: debian 2026-08-06 13:36:00.673842 | debian-bookworm | Username: zuul 2026-08-06 13:36:00.673866 | debian-bookworm | Distro: Debian 12.15 2026-08-06 13:36:00.673898 | debian-bookworm | Provider: regiocloud-a 2026-08-06 13:36:00.673927 | debian-bookworm | Region: 2026-08-06 13:36:00.673949 | debian-bookworm | Label: debian-bookworm 2026-08-06 13:36:00.673969 | debian-bookworm | Product Name: OpenStack Nova 2026-08-06 13:36:00.673989 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:feff:286c 2026-08-06 13:36:00.693437 | 2026-08-06 13:36:00.693562 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-08-06 13:36:01.763805 | debian-bookworm -> localhost | changed 2026-08-06 13:36:01.770987 | 2026-08-06 13:36:01.771092 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-08-06 13:36:04.091972 | debian-bookworm -> localhost | changed 2026-08-06 13:36:04.104578 | 2026-08-06 13:36:04.104700 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-08-06 13:36:04.455487 | debian-bookworm -> localhost | ok 2026-08-06 13:36:04.461833 | 2026-08-06 13:36:04.461950 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-08-06 13:36:04.481964 | debian-bookworm | ok 2026-08-06 13:36:04.498970 | debian-bookworm | included: /var/lib/zuul/builds/281b8de7cff34b9dab41e071d69657ea/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-08-06 13:36:04.505176 | 2026-08-06 13:36:04.505273 | TASK [add-build-sshkey : Create Temp SSH key] 2026-08-06 13:36:06.627894 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-08-06 13:36:06.628102 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/281b8de7cff34b9dab41e071d69657ea/work/281b8de7cff34b9dab41e071d69657ea_id_rsa 2026-08-06 13:36:06.628143 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/281b8de7cff34b9dab41e071d69657ea/work/281b8de7cff34b9dab41e071d69657ea_id_rsa.pub 2026-08-06 13:36:06.628170 | debian-bookworm -> localhost | The key fingerprint is: 2026-08-06 13:36:06.628195 | debian-bookworm -> localhost | SHA256:FS0jIlzI73gInhjO6nY3q3cDkAf5FhBsQy22WZ9sAGU zuul-build-sshkey 2026-08-06 13:36:06.628218 | debian-bookworm -> localhost | The key's randomart image is: 2026-08-06 13:36:06.628248 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-08-06 13:36:06.628270 | debian-bookworm -> localhost | | oBBE. .. | 2026-08-06 13:36:06.628292 | debian-bookworm -> localhost | | O*=. . o.. | 2026-08-06 13:36:06.628312 | debian-bookworm -> localhost | | o Xo=....o | 2026-08-06 13:36:06.628332 | debian-bookworm -> localhost | |. .= +.= . | 2026-08-06 13:36:06.628351 | debian-bookworm -> localhost | |o+ o++. S | 2026-08-06 13:36:06.628373 | debian-bookworm -> localhost | |.oo o.o | 2026-08-06 13:36:06.628394 | debian-bookworm -> localhost | |. .. | 2026-08-06 13:36:06.628414 | debian-bookworm -> localhost | |.. . + o | 2026-08-06 13:36:06.628434 | debian-bookworm -> localhost | |o...+.+ . | 2026-08-06 13:36:06.628454 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-08-06 13:36:06.628509 | debian-bookworm -> localhost | ok: Runtime: 0:00:01.544221 2026-08-06 13:36:06.636207 | 2026-08-06 13:36:06.636318 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-08-06 13:36:06.665095 | debian-bookworm | ok 2026-08-06 13:36:06.676940 | debian-bookworm | included: /var/lib/zuul/builds/281b8de7cff34b9dab41e071d69657ea/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-08-06 13:36:06.689992 | 2026-08-06 13:36:06.690107 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-08-06 13:36:06.713958 | debian-bookworm | skipping: Conditional result was False 2026-08-06 13:36:06.720208 | 2026-08-06 13:36:06.720318 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-08-06 13:36:07.295622 | debian-bookworm | changed 2026-08-06 13:36:07.305606 | 2026-08-06 13:36:07.305734 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-08-06 13:36:07.581585 | debian-bookworm | ok 2026-08-06 13:36:07.587640 | 2026-08-06 13:36:07.587818 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-08-06 13:36:08.186518 | debian-bookworm | changed 2026-08-06 13:36:08.192003 | 2026-08-06 13:36:08.192103 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-08-06 13:36:08.850628 | debian-bookworm | changed 2026-08-06 13:36:08.861613 | 2026-08-06 13:36:08.861738 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-08-06 13:36:08.875551 | debian-bookworm | skipping: Conditional result was False 2026-08-06 13:36:08.883476 | 2026-08-06 13:36:08.883577 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-08-06 13:36:09.235884 | debian-bookworm -> localhost | changed 2026-08-06 13:36:09.245142 | 2026-08-06 13:36:09.245228 | TASK [add-build-sshkey : Add back temp key] 2026-08-06 13:36:09.632868 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/281b8de7cff34b9dab41e071d69657ea/work/281b8de7cff34b9dab41e071d69657ea_id_rsa (zuul-build-sshkey) 2026-08-06 13:36:09.633436 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.012222 2026-08-06 13:36:09.652306 | 2026-08-06 13:36:09.652511 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-08-06 13:36:10.052287 | debian-bookworm | ok 2026-08-06 13:36:10.063957 | 2026-08-06 13:36:10.064073 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-08-06 13:36:10.097726 | debian-bookworm | skipping: Conditional result was False 2026-08-06 13:36:10.108518 | 2026-08-06 13:36:10.108624 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-08-06 13:36:10.540558 | debian-bookworm | ok 2026-08-06 13:36:10.554017 | 2026-08-06 13:36:10.554125 | TASK [validate-host : Define zuul_info_dir fact] 2026-08-06 13:36:10.592970 | debian-bookworm | ok 2026-08-06 13:36:10.603245 | 2026-08-06 13:36:10.603480 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-08-06 13:36:10.993043 | debian-bookworm -> localhost | ok 2026-08-06 13:36:10.999987 | 2026-08-06 13:36:11.000107 | TASK [validate-host : Collect information about the host] 2026-08-06 13:36:12.175217 | debian-bookworm | ok 2026-08-06 13:36:12.203042 | 2026-08-06 13:36:12.203180 | TASK [validate-host : Sanitize hostname] 2026-08-06 13:36:12.468060 | debian-bookworm | ok 2026-08-06 13:36:12.473215 | 2026-08-06 13:36:12.473335 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-08-06 13:36:13.110013 | debian-bookworm -> localhost | changed 2026-08-06 13:36:13.122400 | 2026-08-06 13:36:13.122595 | TASK [validate-host : Collect information about zuul worker] 2026-08-06 13:36:13.574278 | debian-bookworm | ok 2026-08-06 13:36:13.581040 | 2026-08-06 13:36:13.581176 | TASK [validate-host : Write out all zuul information for each host] 2026-08-06 13:36:14.277481 | debian-bookworm -> localhost | changed 2026-08-06 13:36:14.288270 | 2026-08-06 13:36:14.288398 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-08-06 13:36:14.584106 | debian-bookworm | ok 2026-08-06 13:36:14.590552 | 2026-08-06 13:36:14.590700 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-08-06 13:36:15.836798 | debian-bookworm | changed: 2026-08-06 13:36:15.837061 | debian-bookworm | cd+++++++++ src/ 2026-08-06 13:36:15.837178 | debian-bookworm | cd+++++++++ src/github.com/ 2026-08-06 13:36:15.837209 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-08-06 13:36:15.837232 | debian-bookworm | cd+++++++++ src/github.com/osism/python-osism/ 2026-08-06 13:36:15.837254 | debian-bookworm | Containerfile 2026-08-06 13:36:15.837393 | debian-bookworm | Containerfile 2026-08-06 13:36:15.845893 | debian-bookworm | localhost | ok: "/var/lib/zuul/builds/281b8de7cff34b9dab41e071d69657ea/work/logs" 2026-08-06 13:38:50.050917 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/281b8de7cff34b9dab41e071d69657ea/work/artifacts" 2026-08-06 13:38:50.328787 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/281b8de7cff34b9dab41e071d69657ea/work/docs" 2026-08-06 13:38:50.346227 | 2026-08-06 13:38:50.346426 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-08-06 13:38:51.195772 | debian-bookworm | changed: 2026-08-06 13:38:51.196161 | debian-bookworm | .d..t...... ./ 2026-08-06 13:38:51.196221 | debian-bookworm | >f+++++++++ net-debug.post.txt 2026-08-06 13:38:51.196261 | debian-bookworm | >f+++++++++ net-debug.pre.txt 2026-08-06 13:38:51.196294 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-08-06 13:38:51.196327 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-08-06 13:38:51.196358 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-08-06 13:38:51.196419 | debian-bookworm | changed: All items complete 2026-08-06 13:38:51.196453 | 2026-08-06 13:38:51.771927 | debian-bookworm | changed: .d..t...... ./ 2026-08-06 13:38:52.372298 | debian-bookworm | changed: .d..t...... ./ 2026-08-06 13:38:52.398135 | 2026-08-06 13:38:52.398307 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-08-06 13:38:52.765246 | debian-bookworm -> localhost | ok: Item: artifacts Runtime: 0:00:00.010953 2026-08-06 13:38:53.096003 | debian-bookworm -> localhost | ok: Item: docs Runtime: 0:00:00.011563 2026-08-06 13:38:53.111804 | 2026-08-06 13:38:53.111985 | PLAY RECAP 2026-08-06 13:38:53.112050 | debian-bookworm | ok: 8 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-08-06 13:38:53.112076 | 2026-08-06 13:38:53.291514 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-08-06 13:38:53.293629 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-08-06 13:38:54.164003 | 2026-08-06 13:38:54.164212 | PLAY [Base post] 2026-08-06 13:38:54.179228 | 2026-08-06 13:38:54.179413 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-08-06 13:38:55.012765 | debian-bookworm | changed 2026-08-06 13:38:55.023144 | 2026-08-06 13:38:55.023302 | PLAY RECAP 2026-08-06 13:38:55.023383 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-08-06 13:38:55.023463 | 2026-08-06 13:38:55.166626 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-08-06 13:38:55.167612 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-08-06 13:38:56.057241 | 2026-08-06 13:38:56.057448 | PLAY [Base post-logs] 2026-08-06 13:38:56.069314 | 2026-08-06 13:38:56.069484 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-08-06 13:38:56.566929 | localhost | changed 2026-08-06 13:38:56.583275 | 2026-08-06 13:38:56.583519 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-08-06 13:38:56.623580 | localhost | ok 2026-08-06 13:38:56.632330 | 2026-08-06 13:38:56.632550 | TASK [Set zuul-log-path fact] 2026-08-06 13:38:56.662084 | localhost | ok 2026-08-06 13:38:56.678288 | 2026-08-06 13:38:56.678505 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-08-06 13:38:56.719181 | localhost | ok 2026-08-06 13:38:56.726711 | 2026-08-06 13:38:56.727074 | TASK [upload-logs : Create log directories] 2026-08-06 13:38:57.292980 | localhost | changed 2026-08-06 13:38:57.297900 | 2026-08-06 13:38:57.298072 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-08-06 13:38:57.834018 | localhost -> localhost | ok: Runtime: 0:00:00.006169 2026-08-06 13:38:57.840016 | 2026-08-06 13:38:57.840156 | TASK [upload-logs : Upload logs to log server] 2026-08-06 13:38:58.447889 | localhost | Output suppressed because no_log was given 2026-08-06 13:38:58.452290 | 2026-08-06 13:38:58.452501 | LOOP [upload-logs : Compress console log and json output] 2026-08-06 13:38:58.514017 | localhost | skipping: Conditional result was False 2026-08-06 13:38:58.519056 | localhost | skipping: Conditional result was False 2026-08-06 13:38:58.532843 | 2026-08-06 13:38:58.533040 | LOOP [upload-logs : Upload compressed console log and json output] 2026-08-06 13:38:58.585510 | localhost | skipping: Conditional result was False 2026-08-06 13:38:58.586315 | 2026-08-06 13:38:58.589537 | localhost | skipping: Conditional result was False 2026-08-06 13:38:58.598224 | 2026-08-06 13:38:58.598453 | LOOP [upload-logs : Upload console log and json output]