2025-10-30 03:15:29.963448 | Job console starting 2025-10-30 03:15:29.970385 | Updating git repos 2025-10-30 03:15:29.986816 | Cloning repos into workspace 2025-10-30 03:15:30.031413 | Restoring repo states 2025-10-30 03:15:30.034945 | Merging changes 2025-10-30 03:15:30.034958 | Checking out repos 2025-10-30 03:15:30.090817 | Preparing playbooks 2025-10-30 03:15:30.829294 | Running Ansible setup 2025-10-30 03:15:36.331325 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-10-30 03:15:37.586345 | 2025-10-30 03:15:37.586524 | PLAY [Base pre] 2025-10-30 03:15:37.633026 | 2025-10-30 03:15:37.633218 | TASK [Setup log path fact] 2025-10-30 03:15:37.677226 | debian-bookworm | ok 2025-10-30 03:15:37.705072 | 2025-10-30 03:15:37.705249 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-30 03:15:37.773114 | debian-bookworm | ok 2025-10-30 03:15:37.792390 | 2025-10-30 03:15:37.797265 | TASK [emit-job-header : Print job information] 2025-10-30 03:15:37.881332 | # Job Information 2025-10-30 03:15:37.881519 | Ansible Version: 2.16.14 2025-10-30 03:15:37.881554 | Job: python-black 2025-10-30 03:15:37.881587 | Pipeline: periodic-daily 2025-10-30 03:15:37.881610 | Executor: 521e9411259a 2025-10-30 03:15:37.881629 | Triggered by: https://github.com/osism/testbed 2025-10-30 03:15:37.881650 | Event ID: d720bee3e6b94d4aa483f497c920f604 2025-10-30 03:15:37.886956 | 2025-10-30 03:15:37.887077 | LOOP [emit-job-header : Print node information] 2025-10-30 03:15:38.101439 | debian-bookworm | ok: 2025-10-30 03:15:38.101607 | debian-bookworm | # Node Information 2025-10-30 03:15:38.101640 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-10-30 03:15:38.101665 | debian-bookworm | Hostname: debian 2025-10-30 03:15:38.101687 | debian-bookworm | Username: zuul 2025-10-30 03:15:38.101709 | debian-bookworm | Distro: Debian 12.12 2025-10-30 03:15:38.101733 | debian-bookworm | Provider: regiocloud-a 2025-10-30 03:15:38.101754 | debian-bookworm | Region: 2025-10-30 03:15:38.101776 | debian-bookworm | Label: debian-bookworm 2025-10-30 03:15:38.101796 | debian-bookworm | Product Name: OpenStack Nova 2025-10-30 03:15:38.101815 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe4f:735 2025-10-30 03:15:38.125880 | 2025-10-30 03:15:38.126012 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-10-30 03:15:39.042347 | debian-bookworm -> localhost | changed 2025-10-30 03:15:39.053336 | 2025-10-30 03:15:39.053464 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-10-30 03:15:40.961337 | debian-bookworm -> localhost | changed 2025-10-30 03:15:40.971946 | 2025-10-30 03:15:40.972033 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-10-30 03:15:41.343856 | debian-bookworm -> localhost | ok 2025-10-30 03:15:41.349736 | 2025-10-30 03:15:41.349831 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-10-30 03:15:41.378445 | debian-bookworm | ok 2025-10-30 03:15:41.395195 | debian-bookworm | included: /var/lib/zuul/builds/0e85fdff82f04964a8af1d3d59ca6e0e/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-10-30 03:15:41.401670 | 2025-10-30 03:15:41.401756 | TASK [add-build-sshkey : Create Temp SSH key] 2025-10-30 03:15:43.559621 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-10-30 03:15:43.559794 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/0e85fdff82f04964a8af1d3d59ca6e0e/work/0e85fdff82f04964a8af1d3d59ca6e0e_id_rsa 2025-10-30 03:15:43.559827 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/0e85fdff82f04964a8af1d3d59ca6e0e/work/0e85fdff82f04964a8af1d3d59ca6e0e_id_rsa.pub 2025-10-30 03:15:43.559849 | debian-bookworm -> localhost | The key fingerprint is: 2025-10-30 03:15:43.559870 | debian-bookworm -> localhost | SHA256:BplZTmHw4yDA62mwnkPEbnjsmA4tMkvv2N7valI3VSg zuul-build-sshkey 2025-10-30 03:15:43.559887 | debian-bookworm -> localhost | The key's randomart image is: 2025-10-30 03:15:43.560333 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-10-30 03:15:43.560374 | debian-bookworm -> localhost | | .. ..=.. | 2025-10-30 03:15:43.560396 | debian-bookworm -> localhost | | .. E . . | 2025-10-30 03:15:43.560415 | debian-bookworm -> localhost | |. .. * = . | 2025-10-30 03:15:43.560440 | debian-bookworm -> localhost | |.o. . + o | 2025-10-30 03:15:43.560457 | debian-bookworm -> localhost | |+= . S | 2025-10-30 03:15:43.560482 | debian-bookworm -> localhost | |o=* . + | 2025-10-30 03:15:43.560500 | debian-bookworm -> localhost | |OBo . . . | 2025-10-30 03:15:43.560516 | debian-bookworm -> localhost | |*B=... | 2025-10-30 03:15:43.560534 | debian-bookworm -> localhost | |oo+=oo+o | 2025-10-30 03:15:43.560551 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-10-30 03:15:43.560592 | debian-bookworm -> localhost | ok: Runtime: 0:00:01.566395 2025-10-30 03:15:43.565758 | 2025-10-30 03:15:43.565831 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-10-30 03:15:43.591057 | debian-bookworm | ok 2025-10-30 03:15:43.600936 | debian-bookworm | included: /var/lib/zuul/builds/0e85fdff82f04964a8af1d3d59ca6e0e/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-10-30 03:15:43.608236 | 2025-10-30 03:15:43.608317 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-10-30 03:15:43.621302 | debian-bookworm | skipping: Conditional result was False 2025-10-30 03:15:43.625879 | 2025-10-30 03:15:43.625955 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-10-30 03:15:44.280377 | debian-bookworm | changed 2025-10-30 03:15:44.284624 | 2025-10-30 03:15:44.284693 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-10-30 03:15:44.538081 | debian-bookworm | ok 2025-10-30 03:15:44.545757 | 2025-10-30 03:15:44.545872 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-10-30 03:15:45.194742 | debian-bookworm | changed 2025-10-30 03:15:45.201263 | 2025-10-30 03:15:45.201354 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-10-30 03:15:45.871705 | debian-bookworm | changed 2025-10-30 03:15:45.878647 | 2025-10-30 03:15:45.878747 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-10-30 03:15:45.902581 | debian-bookworm | skipping: Conditional result was False 2025-10-30 03:15:45.910749 | 2025-10-30 03:15:45.910879 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-10-30 03:15:46.310479 | debian-bookworm -> localhost | changed 2025-10-30 03:15:46.322346 | 2025-10-30 03:15:46.322460 | TASK [add-build-sshkey : Add back temp key] 2025-10-30 03:15:46.714495 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/0e85fdff82f04964a8af1d3d59ca6e0e/work/0e85fdff82f04964a8af1d3d59ca6e0e_id_rsa (zuul-build-sshkey) 2025-10-30 03:15:46.714700 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.013271 2025-10-30 03:15:46.722287 | 2025-10-30 03:15:46.722380 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-10-30 03:15:47.135218 | debian-bookworm | ok 2025-10-30 03:15:47.139576 | 2025-10-30 03:15:47.139651 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-10-30 03:15:47.178597 | debian-bookworm | skipping: Conditional result was False 2025-10-30 03:15:47.191643 | 2025-10-30 03:15:47.191736 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-10-30 03:15:47.579940 | debian-bookworm | ok 2025-10-30 03:15:47.593328 | 2025-10-30 03:15:47.593423 | TASK [validate-host : Define zuul_info_dir fact] 2025-10-30 03:15:47.622203 | debian-bookworm | ok 2025-10-30 03:15:47.626822 | 2025-10-30 03:15:47.626934 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-10-30 03:15:47.882638 | debian-bookworm -> localhost | ok 2025-10-30 03:15:47.898005 | 2025-10-30 03:15:47.898114 | TASK [validate-host : Collect information about the host] 2025-10-30 03:15:49.005141 | debian-bookworm | ok 2025-10-30 03:15:49.018411 | 2025-10-30 03:15:49.018540 | TASK [validate-host : Sanitize hostname] 2025-10-30 03:15:49.121926 | debian-bookworm | ok 2025-10-30 03:15:49.153099 | 2025-10-30 03:15:49.153219 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-10-30 03:15:49.652108 | debian-bookworm -> localhost | changed 2025-10-30 03:15:49.661079 | 2025-10-30 03:15:49.661217 | TASK [validate-host : Collect information about zuul worker] 2025-10-30 03:15:50.082383 | debian-bookworm | ok 2025-10-30 03:15:50.087962 | 2025-10-30 03:15:50.088090 | TASK [validate-host : Write out all zuul information for each host] 2025-10-30 03:15:50.558816 | debian-bookworm -> localhost | changed 2025-10-30 03:15:50.568422 | 2025-10-30 03:15:50.568512 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-10-30 03:15:50.846104 | debian-bookworm | ok 2025-10-30 03:15:50.855319 | 2025-10-30 03:15:50.855453 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-10-30 03:15:51.980546 | debian-bookworm | changed: 2025-10-30 03:15:51.980765 | debian-bookworm | cd+++++++++ src/ 2025-10-30 03:15:51.980800 | debian-bookworm | cd+++++++++ src/github.com/ 2025-10-30 03:15:51.980824 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-10-30 03:15:51.980846 | debian-bookworm | cd+++++++++ src/github.com/osism/testbed/ 2025-10-30 03:15:51.980867 | debian-bookworm | localhost | ok: Runtime: 0:00:00.005394 2025-10-30 03:16:17.327217 | 2025-10-30 03:16:17.327356 | TASK [upload-logs : Upload logs to log server] 2025-10-30 03:16:17.811388 | localhost | Output suppressed because no_log was given 2025-10-30 03:16:17.812968 | 2025-10-30 03:16:17.813050 | LOOP [upload-logs : Compress console log and json output] 2025-10-30 03:16:17.854922 | localhost | skipping: Conditional result was False 2025-10-30 03:16:17.860395 | localhost | skipping: Conditional result was False 2025-10-30 03:16:17.868395 | 2025-10-30 03:16:17.868502 | LOOP [upload-logs : Upload compressed console log and json output] 2025-10-30 03:16:17.916550 | localhost | skipping: Conditional result was False 2025-10-30 03:16:17.916798 | 2025-10-30 03:16:17.926097 | localhost | skipping: Conditional result was False 2025-10-30 03:16:17.937075 | 2025-10-30 03:16:17.937195 | LOOP [upload-logs : Upload console log and json output]