2026-09-01 02:05:38.595188 | Job console starting 2026-09-01 02:05:38.619183 | Updating git repos 2026-09-01 02:05:38.674114 | Cloning repos into workspace 2026-09-01 02:05:38.757949 | Restoring repo states 2026-09-01 02:05:38.774304 | Merging changes 2026-09-01 02:05:39.439685 | Checking out repos 2026-09-01 02:05:39.471170 | Preparing playbooks 2026-09-01 02:05:40.096966 | Running Ansible setup 2026-09-01 02:05:44.589600 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-09-01 02:05:45.364909 | 2026-09-01 02:05:45.365078 | PLAY [Base pre] 2026-09-01 02:05:45.382304 | 2026-09-01 02:05:45.382418 | TASK [Setup log path fact] 2026-09-01 02:05:45.411529 | debian-bookworm | ok 2026-09-01 02:05:45.427338 | 2026-09-01 02:05:45.427458 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-09-01 02:05:45.467162 | debian-bookworm | ok 2026-09-01 02:05:45.477569 | 2026-09-01 02:05:45.477669 | TASK [emit-job-header : Print job information] 2026-09-01 02:05:45.532496 | # Job Information 2026-09-01 02:05:45.532736 | Ansible Version: 2.16.14 2026-09-01 02:05:45.532794 | Job: python-black 2026-09-01 02:05:45.532850 | Pipeline: check 2026-09-01 02:05:45.532890 | Executor: 521e9411259a 2026-09-01 02:05:45.532927 | Triggered by: https://github.com/osism/ansible-collection-services/pull/2163 2026-09-01 02:05:45.532964 | Event ID: 4a4332d0-a5a9-11f1-92da-0172e5499677 2026-09-01 02:05:45.540466 | 2026-09-01 02:05:45.540589 | LOOP [emit-job-header : Print node information] 2026-09-01 02:05:45.669487 | debian-bookworm | ok: 2026-09-01 02:05:45.669748 | debian-bookworm | # Node Information 2026-09-01 02:05:45.669807 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-09-01 02:05:45.669850 | debian-bookworm | Hostname: debian 2026-09-01 02:05:45.669888 | debian-bookworm | Username: zuul 2026-09-01 02:05:45.669923 | debian-bookworm | Distro: Debian 12.15 2026-09-01 02:05:45.669964 | debian-bookworm | Provider: regiocloud-a 2026-09-01 02:05:45.670001 | debian-bookworm | Region: 2026-09-01 02:05:45.670092 | debian-bookworm | Label: debian-bookworm 2026-09-01 02:05:45.670133 | debian-bookworm | Product Name: OpenStack Nova 2026-09-01 02:05:45.670168 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe21:b413 2026-09-01 02:05:45.692198 | 2026-09-01 02:05:45.692343 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-09-01 02:05:46.192666 | debian-bookworm -> localhost | changed 2026-09-01 02:05:46.200525 | 2026-09-01 02:05:46.200647 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-09-01 02:05:47.289833 | debian-bookworm -> localhost | changed 2026-09-01 02:05:47.306341 | 2026-09-01 02:05:47.306470 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-09-01 02:05:47.640266 | debian-bookworm -> localhost | ok 2026-09-01 02:05:47.647215 | 2026-09-01 02:05:47.647344 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-09-01 02:05:47.681001 | debian-bookworm | ok 2026-09-01 02:05:47.699305 | debian-bookworm | included: /var/lib/zuul/builds/be1efb3542ca43908d2e6b822bca498c/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-09-01 02:05:47.707128 | 2026-09-01 02:05:47.707230 | TASK [add-build-sshkey : Create Temp SSH key] 2026-09-01 02:05:49.439516 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-09-01 02:05:49.439749 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/be1efb3542ca43908d2e6b822bca498c/work/be1efb3542ca43908d2e6b822bca498c_id_rsa 2026-09-01 02:05:49.439789 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/be1efb3542ca43908d2e6b822bca498c/work/be1efb3542ca43908d2e6b822bca498c_id_rsa.pub 2026-09-01 02:05:49.439816 | debian-bookworm -> localhost | The key fingerprint is: 2026-09-01 02:05:49.439842 | debian-bookworm -> localhost | SHA256:vdteXSySUG2+ZH9sWR98HJo1ddd2R1OD4sW3+5X6paM zuul-build-sshkey 2026-09-01 02:05:49.439865 | debian-bookworm -> localhost | The key's randomart image is: 2026-09-01 02:05:49.439896 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-09-01 02:05:49.439919 | debian-bookworm -> localhost | | o..+X| 2026-09-01 02:05:49.439942 | debian-bookworm -> localhost | | o +o=X| 2026-09-01 02:05:49.439962 | debian-bookworm -> localhost | | o oo*o*| 2026-09-01 02:05:49.439982 | debian-bookworm -> localhost | | . o ++=+| 2026-09-01 02:05:49.440002 | debian-bookworm -> localhost | | S . oo.+@| 2026-09-01 02:05:49.440025 | debian-bookworm -> localhost | | . ..*O| 2026-09-01 02:05:49.440065 | debian-bookworm -> localhost | | . o.*| 2026-09-01 02:05:49.440086 | debian-bookworm -> localhost | | o o..o| 2026-09-01 02:05:49.440107 | debian-bookworm -> localhost | | ..E.oo | 2026-09-01 02:05:49.440127 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-09-01 02:05:49.440177 | debian-bookworm -> localhost | ok: Runtime: 0:00:01.181989 2026-09-01 02:05:49.447646 | 2026-09-01 02:05:49.447777 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-09-01 02:05:49.476131 | debian-bookworm | ok 2026-09-01 02:05:49.486598 | debian-bookworm | included: /var/lib/zuul/builds/be1efb3542ca43908d2e6b822bca498c/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-09-01 02:05:49.495259 | 2026-09-01 02:05:49.495356 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-09-01 02:05:49.518772 | debian-bookworm | skipping: Conditional result was False 2026-09-01 02:05:49.525114 | 2026-09-01 02:05:49.525215 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-09-01 02:05:50.237482 | debian-bookworm | changed 2026-09-01 02:05:50.254304 | 2026-09-01 02:05:50.254473 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-09-01 02:05:50.529139 | debian-bookworm | ok 2026-09-01 02:05:50.536647 | 2026-09-01 02:05:50.536775 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-09-01 02:05:51.199167 | debian-bookworm | changed 2026-09-01 02:05:51.207508 | 2026-09-01 02:05:51.207643 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-09-01 02:05:51.897842 | debian-bookworm | changed 2026-09-01 02:05:51.905922 | 2026-09-01 02:05:51.906085 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-09-01 02:05:51.930666 | debian-bookworm | skipping: Conditional result was False 2026-09-01 02:05:51.939349 | 2026-09-01 02:05:51.939480 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-09-01 02:05:52.369852 | debian-bookworm -> localhost | changed 2026-09-01 02:05:52.389754 | 2026-09-01 02:05:52.389890 | TASK [add-build-sshkey : Add back temp key] 2026-09-01 02:05:52.770534 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/be1efb3542ca43908d2e6b822bca498c/work/be1efb3542ca43908d2e6b822bca498c_id_rsa (zuul-build-sshkey) 2026-09-01 02:05:52.771190 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.018134 2026-09-01 02:05:52.788516 | 2026-09-01 02:05:52.788667 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-09-01 02:05:53.221072 | debian-bookworm | ok 2026-09-01 02:05:53.227296 | 2026-09-01 02:05:53.227415 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-09-01 02:05:53.251835 | debian-bookworm | skipping: Conditional result was False 2026-09-01 02:05:53.265165 | 2026-09-01 02:05:53.265287 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-09-01 02:05:53.710346 | debian-bookworm | ok 2026-09-01 02:05:53.724669 | 2026-09-01 02:05:53.724781 | TASK [validate-host : Define zuul_info_dir fact] 2026-09-01 02:05:53.770073 | debian-bookworm | ok 2026-09-01 02:05:53.784395 | 2026-09-01 02:05:53.784575 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-09-01 02:05:54.113916 | debian-bookworm -> localhost | ok 2026-09-01 02:05:54.127815 | 2026-09-01 02:05:54.127964 | TASK [validate-host : Collect information about the host] 2026-09-01 02:05:55.288498 | debian-bookworm | ok 2026-09-01 02:05:55.301047 | 2026-09-01 02:05:55.301161 | TASK [validate-host : Sanitize hostname] 2026-09-01 02:05:55.467851 | debian-bookworm | ok 2026-09-01 02:05:55.475674 | 2026-09-01 02:05:55.475808 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-09-01 02:05:56.048834 | debian-bookworm -> localhost | changed 2026-09-01 02:05:56.055101 | 2026-09-01 02:05:56.055213 | TASK [validate-host : Collect information about zuul worker] 2026-09-01 02:05:56.490617 | debian-bookworm | ok 2026-09-01 02:05:56.496599 | 2026-09-01 02:05:56.496719 | TASK [validate-host : Write out all zuul information for each host] 2026-09-01 02:05:57.103115 | debian-bookworm -> localhost | changed 2026-09-01 02:05:57.114071 | 2026-09-01 02:05:57.114186 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-09-01 02:05:57.386667 | debian-bookworm | ok 2026-09-01 02:05:57.399123 | 2026-09-01 02:05:57.399280 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-09-01 02:05:58.639719 | debian-bookworm | changed: 2026-09-01 02:05:58.639995 | debian-bookworm | cd+++++++++ src/ 2026-09-01 02:05:58.640074 | debian-bookworm | cd+++++++++ src/github.com/ 2026-09-01 02:05:58.640114 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-09-01 02:05:58.640148 | debian-bookworm | cd+++++++++ src/github.com/osism/ansible-collection-services/ 2026-09-01 02:05:58.640183 | debian-bookworm | localhost | ok: "/var/lib/zuul/builds/be1efb3542ca43908d2e6b822bca498c/work/logs" 2026-09-01 02:07:00.667721 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/be1efb3542ca43908d2e6b822bca498c/work/artifacts" 2026-09-01 02:07:00.967825 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/be1efb3542ca43908d2e6b822bca498c/work/docs" 2026-09-01 02:07:00.982851 | 2026-09-01 02:07:00.982981 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-09-01 02:07:01.870256 | debian-bookworm | changed: 2026-09-01 02:07:01.870589 | debian-bookworm | .d..t...... ./ 2026-09-01 02:07:01.870649 | debian-bookworm | >f+++++++++ net-debug.post.txt 2026-09-01 02:07:01.871240 | debian-bookworm | >f+++++++++ net-debug.pre.txt 2026-09-01 02:07:01.871404 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-09-01 02:07:01.871479 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-09-01 02:07:01.871582 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-09-01 02:07:01.871645 | debian-bookworm | changed: All items complete 2026-09-01 02:07:01.871675 | 2026-09-01 02:07:02.439744 | debian-bookworm | changed: .d..t...... ./ 2026-09-01 02:07:03.048679 | debian-bookworm | changed: .d..t...... ./ 2026-09-01 02:07:03.070826 | 2026-09-01 02:07:03.071056 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-09-01 02:07:03.443621 | debian-bookworm -> localhost | ok: Item: artifacts Runtime: 0:00:00.010105 2026-09-01 02:07:03.714686 | debian-bookworm -> localhost | ok: Item: docs Runtime: 0:00:00.010104 2026-09-01 02:07:03.734201 | 2026-09-01 02:07:03.734327 | PLAY RECAP 2026-09-01 02:07:03.734413 | debian-bookworm | ok: 8 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-09-01 02:07:03.734453 | 2026-09-01 02:07:03.878463 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-09-01 02:07:03.880268 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-09-01 02:07:04.665520 | 2026-09-01 02:07:04.665674 | PLAY [Base post] 2026-09-01 02:07:04.679269 | 2026-09-01 02:07:04.679395 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-09-01 02:07:05.258178 | debian-bookworm | changed 2026-09-01 02:07:05.267401 | 2026-09-01 02:07:05.267517 | PLAY RECAP 2026-09-01 02:07:05.267592 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-09-01 02:07:05.268121 | 2026-09-01 02:07:05.447204 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-09-01 02:07:05.448633 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-09-01 02:07:06.255913 | 2026-09-01 02:07:06.256116 | PLAY [Base post-logs] 2026-09-01 02:07:06.266678 | 2026-09-01 02:07:06.266809 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-09-01 02:07:06.763684 | localhost | changed 2026-09-01 02:07:06.780299 | 2026-09-01 02:07:06.780471 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-09-01 02:07:06.806768 | localhost | ok 2026-09-01 02:07:06.811412 | 2026-09-01 02:07:06.811527 | TASK [Set zuul-log-path fact] 2026-09-01 02:07:06.826920 | localhost | ok 2026-09-01 02:07:06.836757 | 2026-09-01 02:07:06.836873 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-09-01 02:07:06.862236 | localhost | ok 2026-09-01 02:07:06.866559 | 2026-09-01 02:07:06.866687 | TASK [upload-logs : Create log directories] 2026-09-01 02:07:07.359898 | localhost | changed 2026-09-01 02:07:07.365347 | 2026-09-01 02:07:07.365532 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-09-01 02:07:07.883410 | localhost -> localhost | ok: Runtime: 0:00:00.008465 2026-09-01 02:07:07.893313 | 2026-09-01 02:07:07.893501 | TASK [upload-logs : Upload logs to log server] 2026-09-01 02:07:08.489927 | localhost | Output suppressed because no_log was given 2026-09-01 02:07:08.495422 | 2026-09-01 02:07:08.495572 | LOOP [upload-logs : Compress console log and json output] 2026-09-01 02:07:08.546966 | localhost | skipping: Conditional result was False 2026-09-01 02:07:08.554699 | localhost | skipping: Conditional result was False 2026-09-01 02:07:08.566895 | 2026-09-01 02:07:08.570274 | LOOP [upload-logs : Upload compressed console log and json output] 2026-09-01 02:07:08.623464 | localhost | skipping: Conditional result was False 2026-09-01 02:07:08.623727 | 2026-09-01 02:07:08.630395 | localhost | skipping: Conditional result was False 2026-09-01 02:07:08.636540 | 2026-09-01 02:07:08.636705 | LOOP [upload-logs : Upload console log and json output]