2025-12-05 08:16:09.348080 | Job console starting 2025-12-05 08:16:09.382023 | Updating git repos 2025-12-05 08:16:09.462176 | Cloning repos into workspace 2025-12-05 08:16:09.605957 | Restoring repo states 2025-12-05 08:16:09.642136 | Merging changes 2025-12-05 08:16:10.285571 | Checking out repos 2025-12-05 08:16:10.386402 | Preparing playbooks 2025-12-05 08:16:12.106181 | Running Ansible setup 2025-12-05 08:16:18.123715 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-12-05 08:16:19.544647 | 2025-12-05 08:16:19.544818 | PLAY [Base pre] 2025-12-05 08:16:19.577698 | 2025-12-05 08:16:19.577860 | TASK [Setup log path fact] 2025-12-05 08:16:19.628305 | debian-bookworm | ok 2025-12-05 08:16:19.659695 | 2025-12-05 08:16:19.659873 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-12-05 08:16:19.709238 | debian-bookworm | ok 2025-12-05 08:16:19.731435 | 2025-12-05 08:16:19.731589 | TASK [emit-job-header : Print job information] 2025-12-05 08:16:19.828120 | # Job Information 2025-12-05 08:16:19.828356 | Ansible Version: 2.16.14 2025-12-05 08:16:19.828395 | Job: python-osism-test-setup 2025-12-05 08:16:19.828428 | Pipeline: check 2025-12-05 08:16:19.828451 | Executor: 521e9411259a 2025-12-05 08:16:19.828472 | Triggered by: https://github.com/osism/python-osism/pull/1898 2025-12-05 08:16:19.828495 | Event ID: a1f0ebb0-d1b2-11f0-9a26-8a72fd1aa6a3 2025-12-05 08:16:19.833881 | 2025-12-05 08:16:19.834028 | LOOP [emit-job-header : Print node information] 2025-12-05 08:16:19.987728 | debian-bookworm | ok: 2025-12-05 08:16:19.987912 | debian-bookworm | # Node Information 2025-12-05 08:16:19.987946 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-12-05 08:16:19.988007 | debian-bookworm | Hostname: debian 2025-12-05 08:16:19.988031 | debian-bookworm | Username: zuul 2025-12-05 08:16:19.988051 | debian-bookworm | Distro: Debian 12.12 2025-12-05 08:16:19.988076 | debian-bookworm | Provider: regiocloud-a 2025-12-05 08:16:19.988097 | debian-bookworm | Region: 2025-12-05 08:16:19.988119 | debian-bookworm | Label: debian-bookworm 2025-12-05 08:16:19.988140 | debian-bookworm | Product Name: OpenStack Nova 2025-12-05 08:16:19.988160 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe36:f0b5 2025-12-05 08:16:20.006787 | 2025-12-05 08:16:20.015224 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-12-05 08:16:20.893820 | debian-bookworm -> localhost | changed 2025-12-05 08:16:20.901519 | 2025-12-05 08:16:20.901661 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-12-05 08:16:22.772793 | debian-bookworm -> localhost | changed 2025-12-05 08:16:22.791898 | 2025-12-05 08:16:22.792086 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-12-05 08:16:23.396485 | debian-bookworm -> localhost | ok 2025-12-05 08:16:23.402935 | 2025-12-05 08:16:23.403093 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-12-05 08:16:23.432002 | debian-bookworm | ok 2025-12-05 08:16:23.465604 | debian-bookworm | included: /var/lib/zuul/builds/d79b01bac26243d08862b134a93f174b/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-12-05 08:16:23.481380 | 2025-12-05 08:16:23.481509 | TASK [add-build-sshkey : Create Temp SSH key] 2025-12-05 08:16:25.419082 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-12-05 08:16:25.419311 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/d79b01bac26243d08862b134a93f174b/work/d79b01bac26243d08862b134a93f174b_id_rsa 2025-12-05 08:16:25.419349 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/d79b01bac26243d08862b134a93f174b/work/d79b01bac26243d08862b134a93f174b_id_rsa.pub 2025-12-05 08:16:25.419375 | debian-bookworm -> localhost | The key fingerprint is: 2025-12-05 08:16:25.419401 | debian-bookworm -> localhost | SHA256:N74fdUlaIH6eh3XQNs21uknD1/jL8nrcfySd3X7/h18 zuul-build-sshkey 2025-12-05 08:16:25.419423 | debian-bookworm -> localhost | The key's randomart image is: 2025-12-05 08:16:25.419457 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-12-05 08:16:25.419480 | debian-bookworm -> localhost | | . ..o+| 2025-12-05 08:16:25.419503 | debian-bookworm -> localhost | | . . .o*| 2025-12-05 08:16:25.419524 | debian-bookworm -> localhost | | . . *o| 2025-12-05 08:16:25.419543 | debian-bookworm -> localhost | | + O.+| 2025-12-05 08:16:25.419564 | debian-bookworm -> localhost | | S o Xo=*| 2025-12-05 08:16:25.419592 | debian-bookworm -> localhost | | o ...B+=| 2025-12-05 08:16:25.419613 | debian-bookworm -> localhost | | . .o.=E| 2025-12-05 08:16:25.419633 | debian-bookworm -> localhost | | . oooO| 2025-12-05 08:16:25.419654 | debian-bookworm -> localhost | | ....==%| 2025-12-05 08:16:25.419674 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-12-05 08:16:25.419728 | debian-bookworm -> localhost | ok: Runtime: 0:00:01.122357 2025-12-05 08:16:25.426820 | 2025-12-05 08:16:25.427081 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-12-05 08:16:25.465812 | debian-bookworm | ok 2025-12-05 08:16:25.477473 | debian-bookworm | included: /var/lib/zuul/builds/d79b01bac26243d08862b134a93f174b/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-12-05 08:16:25.486643 | 2025-12-05 08:16:25.486763 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-12-05 08:16:25.510666 | debian-bookworm | skipping: Conditional result was False 2025-12-05 08:16:25.516629 | 2025-12-05 08:16:25.516744 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-12-05 08:16:26.436379 | debian-bookworm | changed 2025-12-05 08:16:26.445986 | 2025-12-05 08:16:26.446124 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-12-05 08:16:26.711325 | debian-bookworm | ok 2025-12-05 08:16:26.717072 | 2025-12-05 08:16:26.717199 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-12-05 08:16:27.363270 | debian-bookworm | changed 2025-12-05 08:16:27.370614 | 2025-12-05 08:16:27.370752 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-12-05 08:16:28.007483 | debian-bookworm | changed 2025-12-05 08:16:28.018044 | 2025-12-05 08:16:28.018181 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-12-05 08:16:28.068321 | debian-bookworm | skipping: Conditional result was False 2025-12-05 08:16:28.076012 | 2025-12-05 08:16:28.076147 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-12-05 08:16:28.685448 | debian-bookworm -> localhost | changed 2025-12-05 08:16:28.700837 | 2025-12-05 08:16:28.701012 | TASK [add-build-sshkey : Add back temp key] 2025-12-05 08:16:29.316767 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/d79b01bac26243d08862b134a93f174b/work/d79b01bac26243d08862b134a93f174b_id_rsa (zuul-build-sshkey) 2025-12-05 08:16:29.317041 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.036582 2025-12-05 08:16:29.325483 | 2025-12-05 08:16:29.325619 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-12-05 08:16:29.863695 | debian-bookworm | ok 2025-12-05 08:16:29.869678 | 2025-12-05 08:16:29.869801 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-12-05 08:16:29.894325 | debian-bookworm | skipping: Conditional result was False 2025-12-05 08:16:29.905053 | 2025-12-05 08:16:29.905179 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-12-05 08:16:30.572761 | debian-bookworm | ok 2025-12-05 08:16:30.598681 | 2025-12-05 08:16:30.598866 | TASK [validate-host : Define zuul_info_dir fact] 2025-12-05 08:16:30.659572 | debian-bookworm | ok 2025-12-05 08:16:30.672710 | 2025-12-05 08:16:30.672844 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-12-05 08:16:31.140091 | debian-bookworm -> localhost | ok 2025-12-05 08:16:31.149570 | 2025-12-05 08:16:31.149706 | TASK [validate-host : Collect information about the host] 2025-12-05 08:16:32.425580 | debian-bookworm | ok 2025-12-05 08:16:32.436920 | 2025-12-05 08:16:32.437093 | TASK [validate-host : Sanitize hostname] 2025-12-05 08:16:32.559283 | debian-bookworm | ok 2025-12-05 08:16:32.608132 | 2025-12-05 08:16:32.608551 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-12-05 08:16:33.487113 | debian-bookworm -> localhost | changed 2025-12-05 08:16:33.493360 | 2025-12-05 08:16:33.493490 | TASK [validate-host : Collect information about zuul worker] 2025-12-05 08:16:33.979510 | debian-bookworm | ok 2025-12-05 08:16:33.991440 | 2025-12-05 08:16:33.991599 | TASK [validate-host : Write out all zuul information for each host] 2025-12-05 08:16:34.972544 | debian-bookworm -> localhost | changed 2025-12-05 08:16:34.985630 | 2025-12-05 08:16:34.985758 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-12-05 08:16:35.303751 | debian-bookworm | ok 2025-12-05 08:16:35.328338 | 2025-12-05 08:16:35.328487 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-12-05 08:16:36.620309 | debian-bookworm | changed: 2025-12-05 08:16:36.620517 | debian-bookworm | cd+++++++++ src/ 2025-12-05 08:16:36.620552 | debian-bookworm | cd+++++++++ src/github.com/ 2025-12-05 08:16:36.620576 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-12-05 08:16:36.620598 | debian-bookworm | cd+++++++++ src/github.com/osism/python-osism/ 2025-12-05 08:16:36.620618 | debian-bookworm | Containerfile 2025-12-05 08:16:36.620749 | debian-bookworm | Containerfile 2025-12-05 08:16:36.684209 | debian-bookworm | localhost | ok: Runtime: 0:00:00.009057 2025-12-05 08:17:54.321245 | 2025-12-05 08:17:54.321421 | TASK [upload-logs : Upload logs to log server] 2025-12-05 08:17:54.919973 | localhost | Output suppressed because no_log was given 2025-12-05 08:17:54.923173 | 2025-12-05 08:17:54.923348 | LOOP [upload-logs : Compress console log and json output] 2025-12-05 08:17:55.000613 | localhost | skipping: Conditional result was False 2025-12-05 08:17:55.006563 | localhost | skipping: Conditional result was False 2025-12-05 08:17:55.018952 | 2025-12-05 08:17:55.019198 | LOOP [upload-logs : Upload compressed console log and json output] 2025-12-05 08:17:55.079249 | localhost | skipping: Conditional result was False 2025-12-05 08:17:55.079840 | 2025-12-05 08:17:55.083873 | localhost | skipping: Conditional result was False 2025-12-05 08:17:55.092724 | 2025-12-05 08:17:55.092992 | LOOP [upload-logs : Upload console log and json output]