2026-07-20 08:30:34.895745 | Job console starting 2026-07-20 08:30:34.933908 | Updating git repos 2026-07-20 08:30:35.003013 | Cloning repos into workspace 2026-07-20 08:30:35.206318 | Restoring repo states 2026-07-20 08:30:35.260273 | Merging changes 2026-07-20 08:30:36.239479 | Checking out repos 2026-07-20 08:30:36.381708 | Preparing playbooks 2026-07-20 08:30:37.939502 | Running Ansible setup 2026-07-20 08:30:44.446970 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-07-20 08:30:45.621094 | 2026-07-20 08:30:45.621247 | PLAY [Base pre] 2026-07-20 08:30:45.665552 | 2026-07-20 08:30:45.665697 | TASK [Setup log path fact] 2026-07-20 08:30:45.698330 | debian-bookworm | ok 2026-07-20 08:30:45.722549 | 2026-07-20 08:30:45.722699 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-07-20 08:30:45.782189 | debian-bookworm | ok 2026-07-20 08:30:45.804613 | 2026-07-20 08:30:45.804750 | TASK [emit-job-header : Print job information] 2026-07-20 08:30:45.884235 | # Job Information 2026-07-20 08:30:45.884421 | Ansible Version: 2.16.14 2026-07-20 08:30:45.884534 | Job: python-black 2026-07-20 08:30:45.884572 | Pipeline: check 2026-07-20 08:30:45.884598 | Executor: 521e9411259a 2026-07-20 08:30:45.884620 | Triggered by: https://github.com/osism/ansible-collection-services/pull/2144 2026-07-20 08:30:45.884641 | Event ID: 140afc80-8415-11f1-9405-b88dfa1c996d 2026-07-20 08:30:45.889875 | 2026-07-20 08:30:45.889989 | LOOP [emit-job-header : Print node information] 2026-07-20 08:30:46.041206 | debian-bookworm | ok: 2026-07-20 08:30:46.041383 | debian-bookworm | # Node Information 2026-07-20 08:30:46.041416 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-07-20 08:30:46.041472 | debian-bookworm | Hostname: debian 2026-07-20 08:30:46.041494 | debian-bookworm | Username: zuul 2026-07-20 08:30:46.041516 | debian-bookworm | Distro: Debian 12.15 2026-07-20 08:30:46.041541 | debian-bookworm | Provider: regiocloud-a 2026-07-20 08:30:46.041562 | debian-bookworm | Region: 2026-07-20 08:30:46.041584 | debian-bookworm | Label: debian-bookworm 2026-07-20 08:30:46.041604 | debian-bookworm | Product Name: OpenStack Nova 2026-07-20 08:30:46.041623 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe80:c09 2026-07-20 08:30:46.052369 | 2026-07-20 08:30:46.052509 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-07-20 08:30:47.050066 | debian-bookworm -> localhost | changed 2026-07-20 08:30:47.057227 | 2026-07-20 08:30:47.057347 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-07-20 08:30:48.817989 | debian-bookworm -> localhost | changed 2026-07-20 08:30:48.836478 | 2026-07-20 08:30:48.836609 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-07-20 08:30:49.562596 | debian-bookworm -> localhost | ok 2026-07-20 08:30:49.569085 | 2026-07-20 08:30:49.569204 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-07-20 08:30:49.629170 | debian-bookworm | ok 2026-07-20 08:30:49.666578 | debian-bookworm | included: /var/lib/zuul/builds/3bf76d9fcfb54d92b3f2f44d67f337db/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-07-20 08:30:49.679041 | 2026-07-20 08:30:49.679144 | TASK [add-build-sshkey : Create Temp SSH key] 2026-07-20 08:30:51.879732 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-07-20 08:30:51.879963 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/3bf76d9fcfb54d92b3f2f44d67f337db/work/3bf76d9fcfb54d92b3f2f44d67f337db_id_rsa 2026-07-20 08:30:51.879996 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/3bf76d9fcfb54d92b3f2f44d67f337db/work/3bf76d9fcfb54d92b3f2f44d67f337db_id_rsa.pub 2026-07-20 08:30:51.880019 | debian-bookworm -> localhost | The key fingerprint is: 2026-07-20 08:30:51.880040 | debian-bookworm -> localhost | SHA256:VJ31p1xGT5I/mToA4tdOJN08aK3aKVPZig0JORk3VUg zuul-build-sshkey 2026-07-20 08:30:51.880058 | debian-bookworm -> localhost | The key's randomart image is: 2026-07-20 08:30:51.880082 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-07-20 08:30:51.880101 | debian-bookworm -> localhost | | .+o=EB+o.o| 2026-07-20 08:30:51.880118 | debian-bookworm -> localhost | | *.+.*o=.=.| 2026-07-20 08:30:51.880134 | debian-bookworm -> localhost | | . = B + ..O| 2026-07-20 08:30:51.880150 | debian-bookworm -> localhost | | o + B o Bo| 2026-07-20 08:30:51.880166 | debian-bookworm -> localhost | | S X + + .| 2026-07-20 08:30:51.880188 | debian-bookworm -> localhost | | = * o | 2026-07-20 08:30:51.880205 | debian-bookworm -> localhost | | o . | 2026-07-20 08:30:51.880221 | debian-bookworm -> localhost | | | 2026-07-20 08:30:51.880237 | debian-bookworm -> localhost | | | 2026-07-20 08:30:51.880254 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-07-20 08:30:51.880300 | debian-bookworm -> localhost | ok: Runtime: 0:00:01.461196 2026-07-20 08:30:51.885907 | 2026-07-20 08:30:51.885991 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-07-20 08:30:51.954094 | debian-bookworm | ok 2026-07-20 08:30:51.967468 | debian-bookworm | included: /var/lib/zuul/builds/3bf76d9fcfb54d92b3f2f44d67f337db/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-07-20 08:30:51.979185 | 2026-07-20 08:30:51.979277 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-07-20 08:30:52.023739 | debian-bookworm | skipping: Conditional result was False 2026-07-20 08:30:52.028920 | 2026-07-20 08:30:52.029006 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-07-20 08:30:52.927495 | debian-bookworm | changed 2026-07-20 08:30:52.939207 | 2026-07-20 08:30:52.939308 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-07-20 08:30:53.202301 | debian-bookworm | ok 2026-07-20 08:30:53.207004 | 2026-07-20 08:30:53.207083 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-07-20 08:30:54.059695 | debian-bookworm | changed 2026-07-20 08:30:54.077186 | 2026-07-20 08:30:54.077289 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-07-20 08:30:54.797295 | debian-bookworm | changed 2026-07-20 08:30:54.804889 | 2026-07-20 08:30:54.805065 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-07-20 08:30:54.838972 | debian-bookworm | skipping: Conditional result was False 2026-07-20 08:30:54.845822 | 2026-07-20 08:30:54.845917 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-07-20 08:30:55.641904 | debian-bookworm -> localhost | changed 2026-07-20 08:30:55.654130 | 2026-07-20 08:30:55.654237 | TASK [add-build-sshkey : Add back temp key] 2026-07-20 08:30:56.039461 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/3bf76d9fcfb54d92b3f2f44d67f337db/work/3bf76d9fcfb54d92b3f2f44d67f337db_id_rsa (zuul-build-sshkey) 2026-07-20 08:30:56.039654 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.031878 2026-07-20 08:30:56.045980 | 2026-07-20 08:30:56.046055 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-07-20 08:30:56.448821 | debian-bookworm | ok 2026-07-20 08:30:56.473462 | 2026-07-20 08:30:56.473668 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-07-20 08:30:56.509100 | debian-bookworm | skipping: Conditional result was False 2026-07-20 08:30:56.527959 | 2026-07-20 08:30:56.528067 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-07-20 08:30:56.965709 | debian-bookworm | ok 2026-07-20 08:30:56.980073 | 2026-07-20 08:30:56.980174 | TASK [validate-host : Define zuul_info_dir fact] 2026-07-20 08:30:57.012114 | debian-bookworm | ok 2026-07-20 08:30:57.024509 | 2026-07-20 08:30:57.024682 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-07-20 08:30:57.316394 | debian-bookworm -> localhost | ok 2026-07-20 08:30:57.331726 | 2026-07-20 08:30:57.331887 | TASK [validate-host : Collect information about the host] 2026-07-20 08:30:58.659380 | debian-bookworm | ok 2026-07-20 08:30:58.673204 | 2026-07-20 08:30:58.673295 | TASK [validate-host : Sanitize hostname] 2026-07-20 08:30:58.805337 | debian-bookworm | ok 2026-07-20 08:30:58.809649 | 2026-07-20 08:30:58.809736 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-07-20 08:30:59.397578 | debian-bookworm -> localhost | changed 2026-07-20 08:30:59.403549 | 2026-07-20 08:30:59.403649 | TASK [validate-host : Collect information about zuul worker] 2026-07-20 08:30:59.818810 | debian-bookworm | ok 2026-07-20 08:30:59.824786 | 2026-07-20 08:30:59.824933 | TASK [validate-host : Write out all zuul information for each host] 2026-07-20 08:31:00.392656 | debian-bookworm -> localhost | changed 2026-07-20 08:31:00.405488 | 2026-07-20 08:31:00.405640 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-07-20 08:31:00.702038 | debian-bookworm | ok 2026-07-20 08:31:00.713031 | 2026-07-20 08:31:00.713190 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-07-20 08:31:01.866046 | debian-bookworm | changed: 2026-07-20 08:31:01.866320 | debian-bookworm | cd+++++++++ src/ 2026-07-20 08:31:01.866356 | debian-bookworm | cd+++++++++ src/github.com/ 2026-07-20 08:31:01.866381 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-07-20 08:31:01.866404 | debian-bookworm | cd+++++++++ src/github.com/osism/ansible-collection-services/ 2026-07-20 08:31:01.866424 | debian-bookworm | localhost | ok: "/var/lib/zuul/builds/3bf76d9fcfb54d92b3f2f44d67f337db/work/logs" 2026-07-20 08:32:04.503740 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/3bf76d9fcfb54d92b3f2f44d67f337db/work/artifacts" 2026-07-20 08:32:04.883791 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/3bf76d9fcfb54d92b3f2f44d67f337db/work/docs" 2026-07-20 08:32:04.898099 | 2026-07-20 08:32:04.898244 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-07-20 08:32:05.898716 | debian-bookworm | changed: 2026-07-20 08:32:05.899024 | debian-bookworm | .d..t...... ./ 2026-07-20 08:32:05.899065 | debian-bookworm | >f+++++++++ net-debug.post.txt 2026-07-20 08:32:05.899090 | debian-bookworm | >f+++++++++ net-debug.pre.txt 2026-07-20 08:32:05.899112 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-07-20 08:32:05.899133 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-07-20 08:32:05.899153 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-07-20 08:32:05.899203 | debian-bookworm | changed: All items complete 2026-07-20 08:32:05.899229 | 2026-07-20 08:32:06.846778 | debian-bookworm | changed: .d..t...... ./ 2026-07-20 08:32:07.619330 | debian-bookworm | changed: .d..t...... ./ 2026-07-20 08:32:07.638680 | 2026-07-20 08:32:07.638814 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-07-20 08:32:08.072591 | debian-bookworm -> localhost | ok: Item: artifacts Runtime: 0:00:00.016512 2026-07-20 08:32:08.370418 | debian-bookworm -> localhost | ok: Item: docs Runtime: 0:00:00.005142 2026-07-20 08:32:08.387136 | 2026-07-20 08:32:08.387270 | PLAY RECAP 2026-07-20 08:32:08.387324 | debian-bookworm | ok: 8 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-07-20 08:32:08.387350 | 2026-07-20 08:32:08.500487 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-07-20 08:32:08.501472 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-07-20 08:32:09.203874 | 2026-07-20 08:32:09.204019 | PLAY [Base post] 2026-07-20 08:32:09.216310 | 2026-07-20 08:32:09.216439 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-07-20 08:32:09.773862 | debian-bookworm | changed 2026-07-20 08:32:09.788726 | 2026-07-20 08:32:09.788847 | PLAY RECAP 2026-07-20 08:32:09.788909 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-07-20 08:32:09.789462 | 2026-07-20 08:32:09.960866 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-07-20 08:32:09.962514 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-07-20 08:32:10.711828 | 2026-07-20 08:32:10.711971 | PLAY [Base post-logs] 2026-07-20 08:32:10.721691 | 2026-07-20 08:32:10.721808 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-07-20 08:32:11.196746 | localhost | changed 2026-07-20 08:32:11.206558 | 2026-07-20 08:32:11.206726 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-07-20 08:32:11.231757 | localhost | ok 2026-07-20 08:32:11.234845 | 2026-07-20 08:32:11.234938 | TASK [Set zuul-log-path fact] 2026-07-20 08:32:11.259749 | localhost | ok 2026-07-20 08:32:11.267237 | 2026-07-20 08:32:11.267340 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-07-20 08:32:11.291609 | localhost | ok 2026-07-20 08:32:11.294359 | 2026-07-20 08:32:11.294445 | TASK [upload-logs : Create log directories] 2026-07-20 08:32:11.892202 | localhost | changed 2026-07-20 08:32:11.895460 | 2026-07-20 08:32:11.895595 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-07-20 08:32:12.526473 | localhost -> localhost | ok: Runtime: 0:00:00.008865 2026-07-20 08:32:12.531094 | 2026-07-20 08:32:12.531216 | TASK [upload-logs : Upload logs to log server] 2026-07-20 08:32:13.113762 | localhost | Output suppressed because no_log was given 2026-07-20 08:32:13.115811 | 2026-07-20 08:32:13.115920 | LOOP [upload-logs : Compress console log and json output] 2026-07-20 08:32:13.166096 | localhost | skipping: Conditional result was False 2026-07-20 08:32:13.171681 | localhost | skipping: Conditional result was False 2026-07-20 08:32:13.185162 | 2026-07-20 08:32:13.185284 | LOOP [upload-logs : Upload compressed console log and json output] 2026-07-20 08:32:13.243268 | localhost | skipping: Conditional result was False 2026-07-20 08:32:13.243626 | 2026-07-20 08:32:13.258058 | localhost | skipping: Conditional result was False 2026-07-20 08:32:13.264103 | 2026-07-20 08:32:13.264285 | LOOP [upload-logs : Upload console log and json output]