2025-10-28 14:03:36.722221 | Job console starting 2025-10-28 14:03:36.765956 | Updating git repos 2025-10-28 14:03:36.835788 | Cloning repos into workspace 2025-10-28 14:03:36.903282 | Restoring repo states 2025-10-28 14:03:36.931921 | Merging changes 2025-10-28 14:03:37.699177 | Checking out repos 2025-10-28 14:03:37.762946 | Preparing playbooks 2025-10-28 14:03:38.861164 | Running Ansible setup 2025-10-28 14:03:44.276008 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-10-28 14:03:45.518076 | 2025-10-28 14:03:45.518191 | PLAY [Base pre] 2025-10-28 14:03:45.532113 | 2025-10-28 14:03:45.532209 | TASK [Setup log path fact] 2025-10-28 14:03:45.559282 | debian-bookworm | ok 2025-10-28 14:03:45.571251 | 2025-10-28 14:03:45.571353 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-28 14:03:45.629225 | debian-bookworm | ok 2025-10-28 14:03:45.648900 | 2025-10-28 14:03:45.648990 | TASK [emit-job-header : Print job information] 2025-10-28 14:03:45.708703 | # Job Information 2025-10-28 14:03:45.708853 | Ansible Version: 2.16.14 2025-10-28 14:03:45.708882 | Job: python-black 2025-10-28 14:03:45.708909 | Pipeline: check 2025-10-28 14:03:45.708928 | Executor: 521e9411259a 2025-10-28 14:03:45.708945 | Triggered by: https://github.com/osism/python-osism/pull/1777 2025-10-28 14:03:45.708963 | Event ID: e0df4f20-b406-11f0-9ff5-ed55cd6501a4 2025-10-28 14:03:45.712804 | 2025-10-28 14:03:45.712900 | LOOP [emit-job-header : Print node information] 2025-10-28 14:03:45.857537 | debian-bookworm | ok: 2025-10-28 14:03:45.857673 | debian-bookworm | # Node Information 2025-10-28 14:03:45.857700 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-10-28 14:03:45.857720 | debian-bookworm | Hostname: debian 2025-10-28 14:03:45.857738 | debian-bookworm | Username: zuul 2025-10-28 14:03:45.857755 | debian-bookworm | Distro: Debian 12.12 2025-10-28 14:03:45.857775 | debian-bookworm | Provider: regiocloud-a 2025-10-28 14:03:45.857792 | debian-bookworm | Region: 2025-10-28 14:03:45.857810 | debian-bookworm | Label: debian-bookworm 2025-10-28 14:03:45.857848 | debian-bookworm | Product Name: OpenStack Nova 2025-10-28 14:03:45.857867 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fef2:f467 2025-10-28 14:03:45.870258 | 2025-10-28 14:03:45.870346 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-10-28 14:03:46.607067 | debian-bookworm -> localhost | changed 2025-10-28 14:03:46.613061 | 2025-10-28 14:03:46.613149 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-10-28 14:03:47.932752 | debian-bookworm -> localhost | changed 2025-10-28 14:03:47.941138 | 2025-10-28 14:03:47.941225 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-10-28 14:03:48.273000 | debian-bookworm -> localhost | ok 2025-10-28 14:03:48.277812 | 2025-10-28 14:03:48.277907 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-10-28 14:03:48.294786 | debian-bookworm | ok 2025-10-28 14:03:48.333516 | debian-bookworm | included: /var/lib/zuul/builds/2e379026c1fa4f338133125de3681a33/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-10-28 14:03:48.339609 | 2025-10-28 14:03:48.339688 | TASK [add-build-sshkey : Create Temp SSH key] 2025-10-28 14:03:50.080880 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-10-28 14:03:50.081045 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/2e379026c1fa4f338133125de3681a33/work/2e379026c1fa4f338133125de3681a33_id_rsa 2025-10-28 14:03:50.081075 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/2e379026c1fa4f338133125de3681a33/work/2e379026c1fa4f338133125de3681a33_id_rsa.pub 2025-10-28 14:03:50.081096 | debian-bookworm -> localhost | The key fingerprint is: 2025-10-28 14:03:50.081115 | debian-bookworm -> localhost | SHA256:Do1q3JrYp5lEMhIdDCuFNxfKDUVmY0YzzmfjayLiMww zuul-build-sshkey 2025-10-28 14:03:50.081133 | debian-bookworm -> localhost | The key's randomart image is: 2025-10-28 14:03:50.081157 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-10-28 14:03:50.081174 | debian-bookworm -> localhost | |.+++&. | 2025-10-28 14:03:50.081191 | debian-bookworm -> localhost | |.=+%.+ | 2025-10-28 14:03:50.081207 | debian-bookworm -> localhost | |+.+o+ + | 2025-10-28 14:03:50.081223 | debian-bookworm -> localhost | |.. + + | 2025-10-28 14:03:50.081239 | debian-bookworm -> localhost | |. o . + S | 2025-10-28 14:03:50.081256 | debian-bookworm -> localhost | |E. = o + | 2025-10-28 14:03:50.081272 | debian-bookworm -> localhost | |+ . * + . | 2025-10-28 14:03:50.081287 | debian-bookworm -> localhost | |.= * B. | 2025-10-28 14:03:50.081304 | debian-bookworm -> localhost | | .+ Bo | 2025-10-28 14:03:50.081320 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-10-28 14:03:50.081362 | debian-bookworm -> localhost | ok: Runtime: 0:00:01.162247 2025-10-28 14:03:50.086291 | 2025-10-28 14:03:50.086364 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-10-28 14:03:50.112318 | debian-bookworm | ok 2025-10-28 14:03:50.121146 | debian-bookworm | included: /var/lib/zuul/builds/2e379026c1fa4f338133125de3681a33/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-10-28 14:03:50.137441 | 2025-10-28 14:03:50.137514 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-10-28 14:03:50.169954 | debian-bookworm | skipping: Conditional result was False 2025-10-28 14:03:50.174437 | 2025-10-28 14:03:50.174507 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-10-28 14:03:50.808835 | debian-bookworm | changed 2025-10-28 14:03:50.815334 | 2025-10-28 14:03:50.815428 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-10-28 14:03:51.070215 | debian-bookworm | ok 2025-10-28 14:03:51.076902 | 2025-10-28 14:03:51.076997 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-10-28 14:03:51.744492 | debian-bookworm | changed 2025-10-28 14:03:51.754005 | 2025-10-28 14:03:51.754106 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-10-28 14:03:52.363557 | debian-bookworm | changed 2025-10-28 14:03:52.376767 | 2025-10-28 14:03:52.376891 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-10-28 14:03:52.402215 | debian-bookworm | skipping: Conditional result was False 2025-10-28 14:03:52.418663 | 2025-10-28 14:03:52.418799 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-10-28 14:03:52.810981 | debian-bookworm -> localhost | changed 2025-10-28 14:03:52.821965 | 2025-10-28 14:03:52.822055 | TASK [add-build-sshkey : Add back temp key] 2025-10-28 14:03:53.201444 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/2e379026c1fa4f338133125de3681a33/work/2e379026c1fa4f338133125de3681a33_id_rsa (zuul-build-sshkey) 2025-10-28 14:03:53.201646 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.025345 2025-10-28 14:03:53.209306 | 2025-10-28 14:03:53.209400 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-10-28 14:03:53.671925 | debian-bookworm | ok 2025-10-28 14:03:53.676679 | 2025-10-28 14:03:53.676764 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-10-28 14:03:53.691020 | debian-bookworm | skipping: Conditional result was False 2025-10-28 14:03:53.707364 | 2025-10-28 14:03:53.707465 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-10-28 14:03:54.098357 | debian-bookworm | ok 2025-10-28 14:03:54.113702 | 2025-10-28 14:03:54.113856 | TASK [validate-host : Define zuul_info_dir fact] 2025-10-28 14:03:54.156782 | debian-bookworm | ok 2025-10-28 14:03:54.161777 | 2025-10-28 14:03:54.161877 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-10-28 14:03:54.490253 | debian-bookworm -> localhost | ok 2025-10-28 14:03:54.496360 | 2025-10-28 14:03:54.496448 | TASK [validate-host : Collect information about the host] 2025-10-28 14:03:55.666542 | debian-bookworm | ok 2025-10-28 14:03:55.678474 | 2025-10-28 14:03:55.678566 | TASK [validate-host : Sanitize hostname] 2025-10-28 14:03:55.757088 | debian-bookworm | ok 2025-10-28 14:03:55.785360 | 2025-10-28 14:03:55.785465 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-10-28 14:03:56.484916 | debian-bookworm -> localhost | changed 2025-10-28 14:03:56.490223 | 2025-10-28 14:03:56.490318 | TASK [validate-host : Collect information about zuul worker] 2025-10-28 14:03:56.931086 | debian-bookworm | ok 2025-10-28 14:03:56.935453 | 2025-10-28 14:03:56.935540 | TASK [validate-host : Write out all zuul information for each host] 2025-10-28 14:03:57.610802 | debian-bookworm -> localhost | changed 2025-10-28 14:03:57.618451 | 2025-10-28 14:03:57.618530 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-10-28 14:03:57.906904 | debian-bookworm | ok 2025-10-28 14:03:57.911452 | 2025-10-28 14:03:57.911530 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-10-28 14:03:58.838043 | debian-bookworm | changed: 2025-10-28 14:03:58.838204 | debian-bookworm | cd+++++++++ src/ 2025-10-28 14:03:58.838238 | debian-bookworm | cd+++++++++ src/github.com/ 2025-10-28 14:03:58.838262 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-10-28 14:03:58.838284 | debian-bookworm | cd+++++++++ src/github.com/osism/python-osism/ 2025-10-28 14:03:58.838305 | debian-bookworm | Containerfile 2025-10-28 14:03:58.838418 | debian-bookworm | Containerfile 2025-10-28 14:03:58.844525 | debian-bookworm | localhost | ok: Runtime: 0:00:00.004318 2025-10-28 14:04:25.921394 | 2025-10-28 14:04:25.921544 | TASK [upload-logs : Upload logs to log server] 2025-10-28 14:04:26.478032 | localhost | Output suppressed because no_log was given 2025-10-28 14:04:26.482567 | 2025-10-28 14:04:26.482779 | LOOP [upload-logs : Compress console log and json output] 2025-10-28 14:04:26.540204 | localhost | skipping: Conditional result was False 2025-10-28 14:04:26.545383 | localhost | skipping: Conditional result was False 2025-10-28 14:04:26.557394 | 2025-10-28 14:04:26.557616 | LOOP [upload-logs : Upload compressed console log and json output] 2025-10-28 14:04:26.612917 | localhost | skipping: Conditional result was False 2025-10-28 14:04:26.613864 | 2025-10-28 14:04:26.617224 | localhost | skipping: Conditional result was False 2025-10-28 14:04:26.629729 | 2025-10-28 14:04:26.630044 | LOOP [upload-logs : Upload console log and json output]