2025-09-25 16:41:24.283298 | Job console starting 2025-09-25 16:41:24.293123 | Updating git repos 2025-09-25 16:41:24.339173 | Cloning repos into workspace 2025-09-25 16:41:24.439713 | Restoring repo states 2025-09-25 16:41:24.477302 | Merging changes 2025-09-25 16:41:25.187929 | Checking out repos 2025-09-25 16:41:25.296201 | Preparing playbooks 2025-09-25 16:41:26.373265 | Running Ansible setup 2025-09-25 16:41:31.642739 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-09-25 16:41:32.693455 | 2025-09-25 16:41:32.693579 | PLAY [Base pre] 2025-09-25 16:41:32.745544 | 2025-09-25 16:41:32.745664 | TASK [Setup log path fact] 2025-09-25 16:41:32.783016 | debian-bookworm | ok 2025-09-25 16:41:32.808767 | 2025-09-25 16:41:32.808899 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-09-25 16:41:32.884672 | debian-bookworm | ok 2025-09-25 16:41:32.909845 | 2025-09-25 16:41:32.910391 | TASK [emit-job-header : Print job information] 2025-09-25 16:41:32.952515 | # Job Information 2025-09-25 16:41:32.952674 | Ansible Version: 2.16.14 2025-09-25 16:41:32.952705 | Job: python-black 2025-09-25 16:41:32.952732 | Pipeline: check 2025-09-25 16:41:32.952751 | Executor: 521e9411259a 2025-09-25 16:41:32.952767 | Triggered by: https://github.com/osism/python-osism/pull/1711 2025-09-25 16:41:32.952785 | Event ID: 52f42330-9a2e-11f0-8675-8db23757cef5 2025-09-25 16:41:32.956923 | 2025-09-25 16:41:32.957028 | LOOP [emit-job-header : Print node information] 2025-09-25 16:41:33.051243 | debian-bookworm | ok: 2025-09-25 16:41:33.051404 | debian-bookworm | # Node Information 2025-09-25 16:41:33.051440 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-09-25 16:41:33.051466 | debian-bookworm | Hostname: debian 2025-09-25 16:41:33.051489 | debian-bookworm | Username: zuul 2025-09-25 16:41:33.051510 | debian-bookworm | Distro: Debian 12.12 2025-09-25 16:41:33.051534 | debian-bookworm | Provider: regiocloud-a 2025-09-25 16:41:33.051556 | debian-bookworm | Region: 2025-09-25 16:41:33.051577 | debian-bookworm | Label: debian-bookworm 2025-09-25 16:41:33.051597 | debian-bookworm | Product Name: OpenStack Nova 2025-09-25 16:41:33.051616 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe16:aa12 2025-09-25 16:41:33.062677 | 2025-09-25 16:41:33.062763 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-09-25 16:41:33.867612 | debian-bookworm -> localhost | changed 2025-09-25 16:41:33.877557 | 2025-09-25 16:41:33.877803 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-09-25 16:41:35.398518 | debian-bookworm -> localhost | changed 2025-09-25 16:41:35.415013 | 2025-09-25 16:41:35.415157 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-09-25 16:41:35.998339 | debian-bookworm -> localhost | ok 2025-09-25 16:41:36.004293 | 2025-09-25 16:41:36.004388 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-09-25 16:41:36.032818 | debian-bookworm | ok 2025-09-25 16:41:36.066655 | debian-bookworm | included: /var/lib/zuul/builds/b6dd027ea2d0452dbfddb51ff8b251c1/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-09-25 16:41:36.080678 | 2025-09-25 16:41:36.080777 | TASK [add-build-sshkey : Create Temp SSH key] 2025-09-25 16:41:38.222976 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-09-25 16:41:38.223176 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/b6dd027ea2d0452dbfddb51ff8b251c1/work/b6dd027ea2d0452dbfddb51ff8b251c1_id_rsa 2025-09-25 16:41:38.223215 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/b6dd027ea2d0452dbfddb51ff8b251c1/work/b6dd027ea2d0452dbfddb51ff8b251c1_id_rsa.pub 2025-09-25 16:41:38.223242 | debian-bookworm -> localhost | The key fingerprint is: 2025-09-25 16:41:38.223267 | debian-bookworm -> localhost | SHA256:SZB7TvYdmzDmY0JXjG6v5Fa2mu0j0dVbaMb8oL7flxM zuul-build-sshkey 2025-09-25 16:41:38.223300 | debian-bookworm -> localhost | The key's randomart image is: 2025-09-25 16:41:38.223341 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-09-25 16:41:38.223364 | debian-bookworm -> localhost | | .. o | 2025-09-25 16:41:38.223386 | debian-bookworm -> localhost | | .. . o | 2025-09-25 16:41:38.223406 | debian-bookworm -> localhost | | ... . o o | 2025-09-25 16:41:38.223426 | debian-bookworm -> localhost | | ..=.B . O o| 2025-09-25 16:41:38.223446 | debian-bookworm -> localhost | | *S* * O oo| 2025-09-25 16:41:38.223469 | debian-bookworm -> localhost | | o B X E.| 2025-09-25 16:41:38.223489 | debian-bookworm -> localhost | | = B . o| 2025-09-25 16:41:38.223509 | debian-bookworm -> localhost | | =o+ +.| 2025-09-25 16:41:38.223529 | debian-bookworm -> localhost | | .o+++. +| 2025-09-25 16:41:38.223550 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-09-25 16:41:38.223597 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.930172 2025-09-25 16:41:38.229735 | 2025-09-25 16:41:38.229829 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-09-25 16:41:38.267574 | debian-bookworm | ok 2025-09-25 16:41:38.279801 | debian-bookworm | included: /var/lib/zuul/builds/b6dd027ea2d0452dbfddb51ff8b251c1/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-09-25 16:41:38.293601 | 2025-09-25 16:41:38.293706 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-09-25 16:41:38.306883 | debian-bookworm | skipping: Conditional result was False 2025-09-25 16:41:38.313340 | 2025-09-25 16:41:38.313439 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-09-25 16:41:38.994103 | debian-bookworm | changed 2025-09-25 16:41:38.999693 | 2025-09-25 16:41:38.999798 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-09-25 16:41:39.250001 | debian-bookworm | ok 2025-09-25 16:41:39.255513 | 2025-09-25 16:41:39.255600 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-09-25 16:41:39.922670 | debian-bookworm | changed 2025-09-25 16:41:39.932218 | 2025-09-25 16:41:39.932316 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-09-25 16:41:40.627154 | debian-bookworm | changed 2025-09-25 16:41:40.635981 | 2025-09-25 16:41:40.636103 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-09-25 16:41:40.659042 | debian-bookworm | skipping: Conditional result was False 2025-09-25 16:41:40.664939 | 2025-09-25 16:41:40.665034 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-09-25 16:41:41.113198 | debian-bookworm -> localhost | changed 2025-09-25 16:41:41.134281 | 2025-09-25 16:41:41.134372 | TASK [add-build-sshkey : Add back temp key] 2025-09-25 16:41:41.569255 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/b6dd027ea2d0452dbfddb51ff8b251c1/work/b6dd027ea2d0452dbfddb51ff8b251c1_id_rsa (zuul-build-sshkey) 2025-09-25 16:41:41.569427 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.009478 2025-09-25 16:41:41.575826 | 2025-09-25 16:41:41.575900 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-09-25 16:41:42.056386 | debian-bookworm | ok 2025-09-25 16:41:42.065563 | 2025-09-25 16:41:42.065645 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-09-25 16:41:42.101934 | debian-bookworm | skipping: Conditional result was False 2025-09-25 16:41:42.110295 | 2025-09-25 16:41:42.110380 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-09-25 16:41:42.629030 | debian-bookworm | ok 2025-09-25 16:41:42.639047 | 2025-09-25 16:41:42.639141 | TASK [validate-host : Define zuul_info_dir fact] 2025-09-25 16:41:42.669431 | debian-bookworm | ok 2025-09-25 16:41:42.677839 | 2025-09-25 16:41:42.677917 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-09-25 16:41:43.048912 | debian-bookworm -> localhost | ok 2025-09-25 16:41:43.053945 | 2025-09-25 16:41:43.054016 | TASK [validate-host : Collect information about the host] 2025-09-25 16:41:44.165646 | debian-bookworm | ok 2025-09-25 16:41:44.188761 | 2025-09-25 16:41:44.188869 | TASK [validate-host : Sanitize hostname] 2025-09-25 16:41:44.329761 | debian-bookworm | ok 2025-09-25 16:41:44.398676 | 2025-09-25 16:41:44.398767 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-09-25 16:41:45.531873 | debian-bookworm -> localhost | changed 2025-09-25 16:41:45.537038 | 2025-09-25 16:41:45.537130 | TASK [validate-host : Collect information about zuul worker] 2025-09-25 16:41:46.221749 | debian-bookworm | ok 2025-09-25 16:41:46.226012 | 2025-09-25 16:41:46.226122 | TASK [validate-host : Write out all zuul information for each host] 2025-09-25 16:41:47.318405 | debian-bookworm -> localhost | changed 2025-09-25 16:41:47.326321 | 2025-09-25 16:41:47.326403 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-09-25 16:41:47.666752 | debian-bookworm | ok 2025-09-25 16:41:47.675161 | 2025-09-25 16:41:47.675286 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-09-25 16:41:48.983100 | debian-bookworm | changed: 2025-09-25 16:41:48.983250 | debian-bookworm | cd+++++++++ src/ 2025-09-25 16:41:48.983279 | debian-bookworm | cd+++++++++ src/github.com/ 2025-09-25 16:41:48.983299 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-09-25 16:41:48.983316 | debian-bookworm | cd+++++++++ src/github.com/osism/python-osism/ 2025-09-25 16:41:48.983332 | debian-bookworm | Containerfile 2025-09-25 16:41:48.983426 | debian-bookworm | Containerfile 2025-09-25 16:41:49.023763 | debian-bookworm | localhost | ok: Runtime: 0:00:00.003663 2025-09-25 16:42:16.609672 | 2025-09-25 16:42:16.609766 | TASK [upload-logs : Upload logs to log server] 2025-09-25 16:42:17.150988 | localhost | Output suppressed because no_log was given 2025-09-25 16:42:17.152645 | 2025-09-25 16:42:17.152729 | LOOP [upload-logs : Compress console log and json output] 2025-09-25 16:42:17.187457 | localhost | skipping: Conditional result was False 2025-09-25 16:42:17.191987 | localhost | skipping: Conditional result was False 2025-09-25 16:42:17.197725 | 2025-09-25 16:42:17.197811 | LOOP [upload-logs : Upload compressed console log and json output] 2025-09-25 16:42:17.229741 | localhost | skipping: Conditional result was False 2025-09-25 16:42:17.229957 | 2025-09-25 16:42:17.233542 | localhost | skipping: Conditional result was False 2025-09-25 16:42:17.242857 | 2025-09-25 16:42:17.242943 | LOOP [upload-logs : Upload console log and json output]