2026-08-05 09:53:22.787523 | Job console starting 2026-08-05 09:53:22.815881 | Updating git repos 2026-08-05 09:53:23.023205 | Cloning repos into workspace 2026-08-05 09:53:23.194112 | Restoring repo states 2026-08-05 09:53:23.241151 | Merging changes 2026-08-05 09:53:24.524793 | Checking out repos 2026-08-05 09:53:24.661185 | Preparing playbooks 2026-08-05 09:53:27.311135 | Running Ansible setup 2026-08-05 09:53:33.672580 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-08-05 09:53:35.586885 | 2026-08-05 09:53:35.587037 | PLAY [Base pre] 2026-08-05 09:53:35.604472 | 2026-08-05 09:53:35.604736 | TASK [Setup log path fact] 2026-08-05 09:53:35.624627 | debian-bookworm | ok 2026-08-05 09:53:35.649122 | 2026-08-05 09:53:35.649263 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-08-05 09:53:35.677321 | debian-bookworm | ok 2026-08-05 09:53:35.687812 | 2026-08-05 09:53:35.687924 | TASK [emit-job-header : Print job information] 2026-08-05 09:53:35.778589 | # Job Information 2026-08-05 09:53:35.778791 | Ansible Version: 2.16.14 2026-08-05 09:53:35.778947 | Job: python-osism-test-setup 2026-08-05 09:53:35.778988 | Pipeline: check 2026-08-05 09:53:35.779012 | Executor: 521e9411259a 2026-08-05 09:53:35.779034 | Triggered by: https://github.com/osism/python-osism/pull/2551 2026-08-05 09:53:35.779056 | Event ID: 772bf030-90b3-11f1-9e68-e3fe7f5192e4 2026-08-05 09:53:35.784156 | 2026-08-05 09:53:35.784253 | LOOP [emit-job-header : Print node information] 2026-08-05 09:53:35.981572 | debian-bookworm | ok: 2026-08-05 09:53:35.984896 | debian-bookworm | # Node Information 2026-08-05 09:53:35.984992 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-08-05 09:53:35.985041 | debian-bookworm | Hostname: debian 2026-08-05 09:53:35.985066 | debian-bookworm | Username: zuul 2026-08-05 09:53:35.985105 | debian-bookworm | Distro: Debian 12.15 2026-08-05 09:53:35.985131 | debian-bookworm | Provider: regiocloud-a 2026-08-05 09:53:35.985167 | debian-bookworm | Region: 2026-08-05 09:53:35.985191 | debian-bookworm | Label: debian-bookworm 2026-08-05 09:53:35.985314 | debian-bookworm | Product Name: OpenStack Nova 2026-08-05 09:53:35.985344 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fefb:81bc 2026-08-05 09:53:35.997951 | 2026-08-05 09:53:35.998142 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-08-05 09:53:37.281406 | debian-bookworm -> localhost | changed 2026-08-05 09:53:37.288659 | 2026-08-05 09:53:37.288792 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-08-05 09:53:39.809981 | debian-bookworm -> localhost | changed 2026-08-05 09:53:39.830451 | 2026-08-05 09:53:39.830596 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-08-05 09:53:40.509481 | debian-bookworm -> localhost | ok 2026-08-05 09:53:40.515626 | 2026-08-05 09:53:40.515757 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-08-05 09:53:40.556285 | debian-bookworm | ok 2026-08-05 09:53:40.588014 | debian-bookworm | included: /var/lib/zuul/builds/542aeefa45a14cbe9dcd236d7e2075fc/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-08-05 09:53:40.594100 | 2026-08-05 09:53:40.594198 | TASK [add-build-sshkey : Create Temp SSH key] 2026-08-05 09:53:42.745693 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-08-05 09:53:42.745991 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/542aeefa45a14cbe9dcd236d7e2075fc/work/542aeefa45a14cbe9dcd236d7e2075fc_id_rsa 2026-08-05 09:53:42.746030 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/542aeefa45a14cbe9dcd236d7e2075fc/work/542aeefa45a14cbe9dcd236d7e2075fc_id_rsa.pub 2026-08-05 09:53:42.746056 | debian-bookworm -> localhost | The key fingerprint is: 2026-08-05 09:53:42.746082 | debian-bookworm -> localhost | SHA256:80hDUcJ6sl3Op7oIoDuwnmYlEKJWOQPP4LMnZgNsl3c zuul-build-sshkey 2026-08-05 09:53:42.746106 | debian-bookworm -> localhost | The key's randomart image is: 2026-08-05 09:53:42.746146 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-08-05 09:53:42.746169 | debian-bookworm -> localhost | | o. . .o.. | 2026-08-05 09:53:42.746190 | debian-bookworm -> localhost | |= +=. .o | 2026-08-05 09:53:42.746210 | debian-bookworm -> localhost | |+*.=o. E. | 2026-08-05 09:53:42.746230 | debian-bookworm -> localhost | |=.+ . +.. . | 2026-08-05 09:53:42.746250 | debian-bookworm -> localhost | |o*.. =S+ | 2026-08-05 09:53:42.746274 | debian-bookworm -> localhost | |+o+o ...=o . | 2026-08-05 09:53:42.746294 | debian-bookworm -> localhost | |o.o . . .o | 2026-08-05 09:53:42.746313 | debian-bookworm -> localhost | |o+. . . . | 2026-08-05 09:53:42.746334 | debian-bookworm -> localhost | |=+ . oo | 2026-08-05 09:53:42.746354 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-08-05 09:53:42.746412 | debian-bookworm -> localhost | ok: Runtime: 0:00:01.155832 2026-08-05 09:53:42.761359 | 2026-08-05 09:53:42.761566 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-08-05 09:53:42.791563 | debian-bookworm | ok 2026-08-05 09:53:42.822588 | debian-bookworm | included: /var/lib/zuul/builds/542aeefa45a14cbe9dcd236d7e2075fc/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-08-05 09:53:42.839431 | 2026-08-05 09:53:42.839730 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-08-05 09:53:42.887141 | debian-bookworm | skipping: Conditional result was False 2026-08-05 09:53:42.893859 | 2026-08-05 09:53:42.893984 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-08-05 09:53:43.692682 | debian-bookworm | changed 2026-08-05 09:53:43.700506 | 2026-08-05 09:53:43.700634 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-08-05 09:53:43.983883 | debian-bookworm | ok 2026-08-05 09:53:43.992070 | 2026-08-05 09:53:43.992188 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-08-05 09:53:44.670378 | debian-bookworm | changed 2026-08-05 09:53:44.676080 | 2026-08-05 09:53:44.676181 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-08-05 09:53:45.358538 | debian-bookworm | changed 2026-08-05 09:53:45.366213 | 2026-08-05 09:53:45.366327 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-08-05 09:53:45.401280 | debian-bookworm | skipping: Conditional result was False 2026-08-05 09:53:45.407512 | 2026-08-05 09:53:45.407616 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-08-05 09:53:46.247305 | debian-bookworm -> localhost | changed 2026-08-05 09:53:46.259564 | 2026-08-05 09:53:46.259667 | TASK [add-build-sshkey : Add back temp key] 2026-08-05 09:53:46.891484 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/542aeefa45a14cbe9dcd236d7e2075fc/work/542aeefa45a14cbe9dcd236d7e2075fc_id_rsa (zuul-build-sshkey) 2026-08-05 09:53:46.891725 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.009955 2026-08-05 09:53:46.899270 | 2026-08-05 09:53:46.899369 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-08-05 09:53:47.543694 | debian-bookworm | ok 2026-08-05 09:53:47.556319 | 2026-08-05 09:53:47.556434 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-08-05 09:53:47.599892 | debian-bookworm | skipping: Conditional result was False 2026-08-05 09:53:47.610230 | 2026-08-05 09:53:47.610349 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-08-05 09:53:48.075545 | debian-bookworm | ok 2026-08-05 09:53:48.096114 | 2026-08-05 09:53:48.096288 | TASK [validate-host : Define zuul_info_dir fact] 2026-08-05 09:53:48.145137 | debian-bookworm | ok 2026-08-05 09:53:48.198982 | 2026-08-05 09:53:48.199149 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-08-05 09:53:48.904505 | debian-bookworm -> localhost | ok 2026-08-05 09:53:48.912409 | 2026-08-05 09:53:48.912621 | TASK [validate-host : Collect information about the host] 2026-08-05 09:53:50.130973 | debian-bookworm | ok 2026-08-05 09:53:50.143558 | 2026-08-05 09:53:50.143657 | TASK [validate-host : Sanitize hostname] 2026-08-05 09:53:50.272827 | debian-bookworm | ok 2026-08-05 09:53:50.278372 | 2026-08-05 09:53:50.278456 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-08-05 09:53:51.022400 | debian-bookworm -> localhost | changed 2026-08-05 09:53:51.027569 | 2026-08-05 09:53:51.027812 | TASK [validate-host : Collect information about zuul worker] 2026-08-05 09:53:51.665526 | debian-bookworm | ok 2026-08-05 09:53:51.669769 | 2026-08-05 09:53:51.669855 | TASK [validate-host : Write out all zuul information for each host] 2026-08-05 09:53:52.427076 | debian-bookworm -> localhost | changed 2026-08-05 09:53:52.435855 | 2026-08-05 09:53:52.435953 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-08-05 09:53:52.722470 | debian-bookworm | ok 2026-08-05 09:53:52.727458 | 2026-08-05 09:53:52.727550 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-08-05 09:53:54.285763 | debian-bookworm | changed: 2026-08-05 09:53:54.285952 | debian-bookworm | cd+++++++++ src/ 2026-08-05 09:53:54.285987 | debian-bookworm | cd+++++++++ src/github.com/ 2026-08-05 09:53:54.286239 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-08-05 09:53:54.286280 | debian-bookworm | cd+++++++++ src/github.com/osism/python-osism/ 2026-08-05 09:53:54.286302 | debian-bookworm | Containerfile 2026-08-05 09:53:54.286433 | debian-bookworm | Containerfile 2026-08-05 09:53:54.311315 | debian-bookworm | localhost | ok: "/var/lib/zuul/builds/542aeefa45a14cbe9dcd236d7e2075fc/work/logs" 2026-08-05 09:56:34.186674 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/542aeefa45a14cbe9dcd236d7e2075fc/work/artifacts" 2026-08-05 09:56:34.477916 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/542aeefa45a14cbe9dcd236d7e2075fc/work/docs" 2026-08-05 09:56:34.497368 | 2026-08-05 09:56:34.497547 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-08-05 09:56:35.356571 | debian-bookworm | changed: 2026-08-05 09:56:35.356880 | debian-bookworm | .d..t...... ./ 2026-08-05 09:56:35.356918 | debian-bookworm | >f+++++++++ net-debug.post.txt 2026-08-05 09:56:35.356942 | debian-bookworm | >f+++++++++ net-debug.pre.txt 2026-08-05 09:56:35.356963 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-08-05 09:56:35.356984 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-08-05 09:56:35.357006 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-08-05 09:56:35.357048 | debian-bookworm | changed: All items complete 2026-08-05 09:56:35.357084 | 2026-08-05 09:56:35.954728 | debian-bookworm | changed: .d..t...... ./ 2026-08-05 09:56:36.568744 | debian-bookworm | changed: .d..t...... ./ 2026-08-05 09:56:36.593047 | 2026-08-05 09:56:36.593231 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-08-05 09:56:36.970175 | debian-bookworm -> localhost | ok: Item: artifacts Runtime: 0:00:00.006819 2026-08-05 09:56:37.263746 | debian-bookworm -> localhost | ok: Item: docs Runtime: 0:00:00.010305 2026-08-05 09:56:37.277446 | 2026-08-05 09:56:37.277543 | PLAY RECAP 2026-08-05 09:56:37.277597 | debian-bookworm | ok: 8 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-08-05 09:56:37.277622 | 2026-08-05 09:56:37.424040 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-08-05 09:56:37.425024 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-08-05 09:56:38.214144 | 2026-08-05 09:56:38.214327 | PLAY [Base post] 2026-08-05 09:56:38.238465 | 2026-08-05 09:56:38.238633 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-08-05 09:56:39.090979 | debian-bookworm | changed 2026-08-05 09:56:39.100882 | 2026-08-05 09:56:39.101020 | PLAY RECAP 2026-08-05 09:56:39.101099 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-08-05 09:56:39.101177 | 2026-08-05 09:56:39.268507 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-08-05 09:56:39.269457 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-08-05 09:56:40.141492 | 2026-08-05 09:56:40.141703 | PLAY [Base post-logs] 2026-08-05 09:56:40.153532 | 2026-08-05 09:56:40.153689 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-08-05 09:56:40.644807 | localhost | changed 2026-08-05 09:56:40.663552 | 2026-08-05 09:56:40.663757 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-08-05 09:56:40.693061 | localhost | ok 2026-08-05 09:56:40.696638 | 2026-08-05 09:56:40.696749 | TASK [Set zuul-log-path fact] 2026-08-05 09:56:40.722486 | localhost | ok 2026-08-05 09:56:40.731963 | 2026-08-05 09:56:40.732094 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-08-05 09:56:40.768170 | localhost | ok 2026-08-05 09:56:40.771517 | 2026-08-05 09:56:40.771926 | TASK [upload-logs : Create log directories] 2026-08-05 09:56:41.306189 | localhost | changed 2026-08-05 09:56:41.309743 | 2026-08-05 09:56:41.309880 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-08-05 09:56:41.854409 | localhost -> localhost | ok: Runtime: 0:00:00.011299 2026-08-05 09:56:41.863381 | 2026-08-05 09:56:41.863594 | TASK [upload-logs : Upload logs to log server] 2026-08-05 09:56:42.514510 | localhost | Output suppressed because no_log was given 2026-08-05 09:56:42.519078 | 2026-08-05 09:56:42.519274 | LOOP [upload-logs : Compress console log and json output] 2026-08-05 09:56:42.580267 | localhost | skipping: Conditional result was False 2026-08-05 09:56:42.585553 | localhost | skipping: Conditional result was False 2026-08-05 09:56:42.593349 | 2026-08-05 09:56:42.593581 | LOOP [upload-logs : Upload compressed console log and json output] 2026-08-05 09:56:42.643243 | localhost | skipping: Conditional result was False 2026-08-05 09:56:42.643898 | 2026-08-05 09:56:42.647521 | localhost | skipping: Conditional result was False 2026-08-05 09:56:42.656174 | 2026-08-05 09:56:42.656396 | LOOP [upload-logs : Upload console log and json output]