2025-12-04 21:58:50.084621 | Job console starting 2025-12-04 21:58:50.095655 | Updating git repos 2025-12-04 21:58:50.166171 | Cloning repos into workspace 2025-12-04 21:58:50.349313 | Restoring repo states 2025-12-04 21:58:50.361055 | Merging changes 2025-12-04 21:58:51.664020 | Checking out repos 2025-12-04 21:58:51.743740 | Preparing playbooks 2025-12-04 21:58:53.746548 | Running Ansible setup 2025-12-04 21:59:01.944574 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-12-04 21:59:03.480073 | 2025-12-04 21:59:03.480289 | PLAY [Base pre] 2025-12-04 21:59:03.506959 | 2025-12-04 21:59:03.507160 | TASK [Setup log path fact] 2025-12-04 21:59:03.542151 | debian-bookworm | ok 2025-12-04 21:59:03.576375 | 2025-12-04 21:59:03.576552 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-12-04 21:59:03.623547 | debian-bookworm | ok 2025-12-04 21:59:03.664214 | 2025-12-04 21:59:03.664424 | TASK [emit-job-header : Print job information] 2025-12-04 21:59:03.761052 | # Job Information 2025-12-04 21:59:03.761280 | Ansible Version: 2.16.14 2025-12-04 21:59:03.761319 | Job: python-osism-test-setup 2025-12-04 21:59:03.761352 | Pipeline: check 2025-12-04 21:59:03.761375 | Executor: 521e9411259a 2025-12-04 21:59:03.761396 | Triggered by: https://github.com/osism/python-osism/pull/1898 2025-12-04 21:59:03.761419 | Event ID: 37579b10-d15c-11f0-83cc-ab02b982f357 2025-12-04 21:59:03.766644 | 2025-12-04 21:59:03.766776 | LOOP [emit-job-header : Print node information] 2025-12-04 21:59:03.920271 | debian-bookworm | ok: 2025-12-04 21:59:03.920572 | debian-bookworm | # Node Information 2025-12-04 21:59:03.920617 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-12-04 21:59:03.920645 | debian-bookworm | Hostname: debian 2025-12-04 21:59:03.920668 | debian-bookworm | Username: zuul 2025-12-04 21:59:03.920689 | debian-bookworm | Distro: Debian 12.12 2025-12-04 21:59:03.920714 | debian-bookworm | Provider: regiocloud-a 2025-12-04 21:59:03.920735 | debian-bookworm | Region: 2025-12-04 21:59:03.920755 | debian-bookworm | Label: debian-bookworm 2025-12-04 21:59:03.920774 | debian-bookworm | Product Name: OpenStack Nova 2025-12-04 21:59:03.920793 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe05:4146 2025-12-04 21:59:03.940163 | 2025-12-04 21:59:03.940322 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-12-04 21:59:05.223973 | debian-bookworm -> localhost | changed 2025-12-04 21:59:05.234791 | 2025-12-04 21:59:05.234962 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-12-04 21:59:07.493297 | debian-bookworm -> localhost | changed 2025-12-04 21:59:07.513937 | 2025-12-04 21:59:07.514854 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-12-04 21:59:08.243776 | debian-bookworm -> localhost | ok 2025-12-04 21:59:08.260299 | 2025-12-04 21:59:08.260448 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-12-04 21:59:08.304649 | debian-bookworm | ok 2025-12-04 21:59:08.331478 | debian-bookworm | included: /var/lib/zuul/builds/72a073c13bdd45b98903ac00fda57316/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-12-04 21:59:08.340040 | 2025-12-04 21:59:08.340210 | TASK [add-build-sshkey : Create Temp SSH key] 2025-12-04 21:59:10.237779 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-12-04 21:59:10.238020 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/72a073c13bdd45b98903ac00fda57316/work/72a073c13bdd45b98903ac00fda57316_id_rsa 2025-12-04 21:59:10.238059 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/72a073c13bdd45b98903ac00fda57316/work/72a073c13bdd45b98903ac00fda57316_id_rsa.pub 2025-12-04 21:59:10.238086 | debian-bookworm -> localhost | The key fingerprint is: 2025-12-04 21:59:10.238153 | debian-bookworm -> localhost | SHA256:I75Pk5o0f94GxDAQvVR6FhdYgD/rL1DinFMkrHBQPf0 zuul-build-sshkey 2025-12-04 21:59:10.238415 | debian-bookworm -> localhost | The key's randomart image is: 2025-12-04 21:59:10.238466 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-12-04 21:59:10.238493 | debian-bookworm -> localhost | | .o+* ++++. | 2025-12-04 21:59:10.238517 | debian-bookworm -> localhost | | . . @.+o | 2025-12-04 21:59:10.238539 | debian-bookworm -> localhost | | o o.Xo. | 2025-12-04 21:59:10.238559 | debian-bookworm -> localhost | | . ooB E | 2025-12-04 21:59:10.238579 | debian-bookworm -> localhost | | .oS* o | 2025-12-04 21:59:10.238605 | debian-bookworm -> localhost | | . .*oo | 2025-12-04 21:59:10.238625 | debian-bookworm -> localhost | | + ++ . | 2025-12-04 21:59:10.238645 | debian-bookworm -> localhost | | . B .+.. | 2025-12-04 21:59:10.238666 | debian-bookworm -> localhost | | +.oo.+o | 2025-12-04 21:59:10.238687 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-12-04 21:59:10.238752 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.966512 2025-12-04 21:59:10.247015 | 2025-12-04 21:59:10.247317 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-12-04 21:59:10.323766 | debian-bookworm | ok 2025-12-04 21:59:10.351856 | debian-bookworm | included: /var/lib/zuul/builds/72a073c13bdd45b98903ac00fda57316/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-12-04 21:59:10.379479 | 2025-12-04 21:59:10.379626 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-12-04 21:59:10.449187 | debian-bookworm | skipping: Conditional result was False 2025-12-04 21:59:10.457795 | 2025-12-04 21:59:10.457930 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-12-04 21:59:11.643952 | debian-bookworm | changed 2025-12-04 21:59:11.681792 | 2025-12-04 21:59:11.681938 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-12-04 21:59:11.972933 | debian-bookworm | ok 2025-12-04 21:59:11.981228 | 2025-12-04 21:59:11.981353 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-12-04 21:59:12.749026 | debian-bookworm | changed 2025-12-04 21:59:12.776560 | 2025-12-04 21:59:12.778334 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-12-04 21:59:13.609675 | debian-bookworm | changed 2025-12-04 21:59:13.619656 | 2025-12-04 21:59:13.619793 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-12-04 21:59:13.662323 | debian-bookworm | skipping: Conditional result was False 2025-12-04 21:59:13.669141 | 2025-12-04 21:59:13.669276 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-12-04 21:59:14.397790 | debian-bookworm -> localhost | changed 2025-12-04 21:59:14.420316 | 2025-12-04 21:59:14.420495 | TASK [add-build-sshkey : Add back temp key] 2025-12-04 21:59:14.921510 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/72a073c13bdd45b98903ac00fda57316/work/72a073c13bdd45b98903ac00fda57316_id_rsa (zuul-build-sshkey) 2025-12-04 21:59:14.921979 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.015524 2025-12-04 21:59:14.939236 | 2025-12-04 21:59:14.939389 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-12-04 21:59:15.425966 | debian-bookworm | ok 2025-12-04 21:59:15.432636 | 2025-12-04 21:59:15.432777 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-12-04 21:59:15.457534 | debian-bookworm | skipping: Conditional result was False 2025-12-04 21:59:15.469130 | 2025-12-04 21:59:15.469264 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-12-04 21:59:15.930133 | debian-bookworm | ok 2025-12-04 21:59:15.948347 | 2025-12-04 21:59:15.948489 | TASK [validate-host : Define zuul_info_dir fact] 2025-12-04 21:59:15.994107 | debian-bookworm | ok 2025-12-04 21:59:16.002257 | 2025-12-04 21:59:16.002398 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-12-04 21:59:16.387627 | debian-bookworm -> localhost | ok 2025-12-04 21:59:16.394537 | 2025-12-04 21:59:16.394710 | TASK [validate-host : Collect information about the host] 2025-12-04 21:59:17.578631 | debian-bookworm | ok 2025-12-04 21:59:17.593780 | 2025-12-04 21:59:17.593928 | TASK [validate-host : Sanitize hostname] 2025-12-04 21:59:17.715828 | debian-bookworm | ok 2025-12-04 21:59:17.793382 | 2025-12-04 21:59:17.793532 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-12-04 21:59:18.481439 | debian-bookworm -> localhost | changed 2025-12-04 21:59:18.488025 | 2025-12-04 21:59:18.488202 | TASK [validate-host : Collect information about zuul worker] 2025-12-04 21:59:18.971586 | debian-bookworm | ok 2025-12-04 21:59:18.980064 | 2025-12-04 21:59:18.980250 | TASK [validate-host : Write out all zuul information for each host] 2025-12-04 21:59:19.633846 | debian-bookworm -> localhost | changed 2025-12-04 21:59:19.651407 | 2025-12-04 21:59:19.651639 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-12-04 21:59:19.936245 | debian-bookworm | ok 2025-12-04 21:59:19.944114 | 2025-12-04 21:59:19.944243 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-12-04 21:59:21.192629 | debian-bookworm | changed: 2025-12-04 21:59:21.192902 | debian-bookworm | cd+++++++++ src/ 2025-12-04 21:59:21.192955 | debian-bookworm | cd+++++++++ src/github.com/ 2025-12-04 21:59:21.192990 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-12-04 21:59:21.193023 | debian-bookworm | cd+++++++++ src/github.com/osism/python-osism/ 2025-12-04 21:59:21.193052 | debian-bookworm | Containerfile 2025-12-04 21:59:21.193316 | debian-bookworm | Containerfile 2025-12-04 21:59:21.227291 | debian-bookworm | localhost | ok: Runtime: 0:00:00.032132 2025-12-04 22:00:39.541717 | 2025-12-04 22:00:39.541841 | TASK [upload-logs : Upload logs to log server] 2025-12-04 22:00:40.286108 | localhost | Output suppressed because no_log was given 2025-12-04 22:00:40.288585 | 2025-12-04 22:00:40.288708 | LOOP [upload-logs : Compress console log and json output] 2025-12-04 22:00:40.347370 | localhost | skipping: Conditional result was False 2025-12-04 22:00:40.354202 | localhost | skipping: Conditional result was False 2025-12-04 22:00:40.366309 | 2025-12-04 22:00:40.366449 | LOOP [upload-logs : Upload compressed console log and json output] 2025-12-04 22:00:40.419481 | localhost | skipping: Conditional result was False 2025-12-04 22:00:40.419781 | 2025-12-04 22:00:40.425559 | localhost | skipping: Conditional result was False 2025-12-04 22:00:40.434025 | 2025-12-04 22:00:40.434189 | LOOP [upload-logs : Upload console log and json output]