2025-08-27 12:30:55.171947 | Job console starting 2025-08-27 12:30:55.195032 | Updating git repos 2025-08-27 12:30:55.253303 | Cloning repos into workspace 2025-08-27 12:30:55.331547 | Restoring repo states 2025-08-27 12:30:55.336411 | Merging changes 2025-08-27 12:30:55.892851 | Checking out repos 2025-08-27 12:30:55.971108 | Preparing playbooks 2025-08-27 12:30:56.797635 | Running Ansible setup 2025-08-27 12:32:21.703489 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-08-27 12:32:22.468473 | 2025-08-27 12:32:22.468637 | PLAY [Base pre] 2025-08-27 12:32:22.484605 | 2025-08-27 12:32:22.484741 | TASK [Setup log path fact] 2025-08-27 12:32:22.514285 | debian-bookworm | ok 2025-08-27 12:32:22.529769 | 2025-08-27 12:32:22.529910 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-08-27 12:32:22.579916 | debian-bookworm | ok 2025-08-27 12:32:22.593677 | 2025-08-27 12:32:22.593817 | TASK [emit-job-header : Print job information] 2025-08-27 12:32:22.650329 | # Job Information 2025-08-27 12:32:22.650597 | Ansible Version: 2.16.14 2025-08-27 12:32:22.650658 | Job: openstack-project-manager-tox 2025-08-27 12:32:22.650717 | Pipeline: check 2025-08-27 12:32:22.650758 | Executor: 521e9411259a 2025-08-27 12:32:22.650796 | Triggered by: https://github.com/osism/openstack-project-manager/pull/237 2025-08-27 12:32:22.650862 | Event ID: a83abce0-8341-11f0-921b-319088aa9e91 2025-08-27 12:32:22.658298 | 2025-08-27 12:32:22.658440 | LOOP [emit-job-header : Print node information] 2025-08-27 12:32:22.767287 | debian-bookworm | ok: 2025-08-27 12:32:22.767590 | debian-bookworm | # Node Information 2025-08-27 12:32:22.767657 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-08-27 12:32:22.767709 | debian-bookworm | Hostname: debian 2025-08-27 12:32:22.767755 | debian-bookworm | Username: zuul 2025-08-27 12:32:22.767799 | debian-bookworm | Distro: Debian 12.11 2025-08-27 12:32:22.767849 | debian-bookworm | Provider: regiocloud-a 2025-08-27 12:32:22.767911 | debian-bookworm | Region: 2025-08-27 12:32:22.767955 | debian-bookworm | Label: debian-bookworm 2025-08-27 12:32:22.767997 | debian-bookworm | Product Name: OpenStack Nova 2025-08-27 12:32:22.768039 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe6c:b142 2025-08-27 12:32:22.790759 | 2025-08-27 12:32:22.790943 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-08-27 12:32:23.261479 | debian-bookworm -> localhost | changed 2025-08-27 12:32:23.277334 | 2025-08-27 12:32:23.277510 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-08-27 12:32:24.315386 | debian-bookworm -> localhost | changed 2025-08-27 12:32:24.326365 | 2025-08-27 12:32:24.326479 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-08-27 12:32:24.603618 | debian-bookworm -> localhost | ok 2025-08-27 12:32:24.615171 | 2025-08-27 12:32:24.615347 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-08-27 12:32:24.650148 | debian-bookworm | ok 2025-08-27 12:32:24.676280 | debian-bookworm | included: /var/lib/zuul/builds/21c0e3d3d4b2432baafd65fe5f670de8/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-08-27 12:32:24.682425 | 2025-08-27 12:32:24.682527 | TASK [add-build-sshkey : Create Temp SSH key] 2025-08-27 12:32:26.211273 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-08-27 12:32:26.211495 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/21c0e3d3d4b2432baafd65fe5f670de8/work/21c0e3d3d4b2432baafd65fe5f670de8_id_rsa 2025-08-27 12:32:26.211537 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/21c0e3d3d4b2432baafd65fe5f670de8/work/21c0e3d3d4b2432baafd65fe5f670de8_id_rsa.pub 2025-08-27 12:32:26.211565 | debian-bookworm -> localhost | The key fingerprint is: 2025-08-27 12:32:26.211590 | debian-bookworm -> localhost | SHA256:ohQATi9cz4La64QmETPHwQeacZOGGQpMjevtIIfXUxU zuul-build-sshkey 2025-08-27 12:32:26.211626 | debian-bookworm -> localhost | The key's randomart image is: 2025-08-27 12:32:26.211649 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-08-27 12:32:26.211672 | debian-bookworm -> localhost | |BOO+ E. | 2025-08-27 12:32:26.211694 | debian-bookworm -> localhost | |OOB=+ . | 2025-08-27 12:32:26.211715 | debian-bookworm -> localhost | |B*++.o . | 2025-08-27 12:32:26.211736 | debian-bookworm -> localhost | |.B. ... | 2025-08-27 12:32:26.211755 | debian-bookworm -> localhost | |+.o.... S | 2025-08-27 12:32:26.211781 | debian-bookworm -> localhost | |o=o+o. . | 2025-08-27 12:32:26.211802 | debian-bookworm -> localhost | |++= .. | 2025-08-27 12:32:26.211841 | debian-bookworm -> localhost | |oo . | 2025-08-27 12:32:26.211936 | debian-bookworm -> localhost | | . | 2025-08-27 12:32:26.211969 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-08-27 12:32:26.212032 | debian-bookworm -> localhost | ok: Runtime: 0:00:01.026570 2025-08-27 12:32:26.219764 | 2025-08-27 12:32:26.219881 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-08-27 12:32:26.241004 | debian-bookworm | ok 2025-08-27 12:32:26.252991 | debian-bookworm | included: /var/lib/zuul/builds/21c0e3d3d4b2432baafd65fe5f670de8/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-08-27 12:32:26.264890 | 2025-08-27 12:32:26.264989 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-08-27 12:32:26.289725 | debian-bookworm | skipping: Conditional result was False 2025-08-27 12:32:26.297179 | 2025-08-27 12:32:26.299443 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-08-27 12:32:26.950097 | debian-bookworm | changed 2025-08-27 12:32:26.957383 | 2025-08-27 12:32:26.957500 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-08-27 12:32:27.227328 | debian-bookworm | ok 2025-08-27 12:32:27.236550 | 2025-08-27 12:32:27.236684 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-08-27 12:32:27.925543 | debian-bookworm | changed 2025-08-27 12:32:27.935539 | 2025-08-27 12:32:27.935680 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-08-27 12:32:28.576018 | debian-bookworm | changed 2025-08-27 12:32:28.584744 | 2025-08-27 12:32:28.584881 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-08-27 12:32:28.609360 | debian-bookworm | skipping: Conditional result was False 2025-08-27 12:32:28.623669 | 2025-08-27 12:32:28.623834 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-08-27 12:32:29.064569 | debian-bookworm -> localhost | changed 2025-08-27 12:32:29.087109 | 2025-08-27 12:32:29.087370 | TASK [add-build-sshkey : Add back temp key] 2025-08-27 12:32:29.437201 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/21c0e3d3d4b2432baafd65fe5f670de8/work/21c0e3d3d4b2432baafd65fe5f670de8_id_rsa (zuul-build-sshkey) 2025-08-27 12:32:29.437582 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.019362 2025-08-27 12:32:29.450692 | 2025-08-27 12:32:29.450931 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-08-27 12:32:29.872609 | debian-bookworm | ok 2025-08-27 12:32:29.880901 | 2025-08-27 12:32:29.881036 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-08-27 12:32:29.915451 | debian-bookworm | skipping: Conditional result was False 2025-08-27 12:32:29.940205 | 2025-08-27 12:32:29.940405 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-08-27 12:32:30.373116 | debian-bookworm | ok 2025-08-27 12:32:30.387122 | 2025-08-27 12:32:30.387251 | TASK [validate-host : Define zuul_info_dir fact] 2025-08-27 12:32:30.431283 | debian-bookworm | ok 2025-08-27 12:32:30.439378 | 2025-08-27 12:32:30.439508 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-08-27 12:32:30.738005 | debian-bookworm -> localhost | ok 2025-08-27 12:32:30.750664 | 2025-08-27 12:32:30.750811 | TASK [validate-host : Collect information about the host] 2025-08-27 12:33:11.954209 | debian-bookworm | ok 2025-08-27 12:33:11.968104 | 2025-08-27 12:33:11.968250 | TASK [validate-host : Sanitize hostname] 2025-08-27 12:33:12.070542 | debian-bookworm | ok 2025-08-27 12:33:12.107181 | 2025-08-27 12:33:12.107319 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-08-27 12:33:12.669260 | debian-bookworm -> localhost | changed 2025-08-27 12:33:12.682309 | 2025-08-27 12:33:12.682468 | TASK [validate-host : Collect information about zuul worker] 2025-08-27 12:33:13.124045 | debian-bookworm | ok 2025-08-27 12:33:13.131721 | 2025-08-27 12:33:13.131876 | TASK [validate-host : Write out all zuul information for each host] 2025-08-27 12:33:13.685637 | debian-bookworm -> localhost | changed 2025-08-27 12:33:13.703972 | 2025-08-27 12:33:13.704107 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-08-27 12:33:13.968662 | debian-bookworm | ok 2025-08-27 12:33:13.975213 | 2025-08-27 12:33:13.975332 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-08-27 12:33:14.792107 | debian-bookworm | changed: 2025-08-27 12:33:14.792830 | debian-bookworm | cd+++++++++ src/ 2025-08-27 12:33:14.792903 | debian-bookworm | cd+++++++++ src/github.com/ 2025-08-27 12:33:14.792948 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-08-27 12:33:14.792988 | debian-bookworm | cd+++++++++ src/github.com/osism/openstack-project-manager/ 2025-08-27 12:33:14.793026 | debian-bookworm | localhost | changed 2025-08-27 12:35:33.382528 | 2025-08-27 12:35:33.382694 | TASK [fetch-tox-output : Ensure zuul-output tox dir] 2025-08-27 12:35:33.437894 | debian-bookworm | skipping: Conditional result was False 2025-08-27 12:35:33.450242 | 2025-08-27 12:35:33.450416 | TASK [fetch-tox-output : Set envlist fact] 2025-08-27 12:35:33.487981 | debian-bookworm | ok 2025-08-27 12:35:33.497693 | 2025-08-27 12:35:33.497827 | TASK [fetch-tox-output : Get tox version] 2025-08-27 12:35:34.029863 | debian-bookworm | ERROR 2025-08-27 12:35:34.030284 | debian-bookworm | { 2025-08-27 12:35:34.030346 | debian-bookworm | "msg": "[Errno 2] No such file or directory: b'tox'", 2025-08-27 12:35:34.030387 | debian-bookworm | "rc": 2 2025-08-27 12:35:34.030417 | debian-bookworm | } failure 2025-08-27 12:35:34.032943 | 2025-08-27 12:35:34.033083 | PLAY RECAP 2025-08-27 12:35:34.033171 | debian-bookworm | ok: 3 changed: 1 unreachable: 0 failed: 1 skipped: 2 rescued: 0 ignored: 0 2025-08-27 12:35:34.033211 | 2025-08-27 12:35:34.179790 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2025-08-27 12:35:34.181605 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2025-08-27 12:35:34.898500 | 2025-08-27 12:35:34.898661 | PLAY [all] 2025-08-27 12:35:34.914109 | 2025-08-27 12:35:34.914261 | TASK [fetch-subunit-output : Find stestr or testr executable] 2025-08-27 12:35:35.164075 | debian-bookworm | changed: non-zero return code 2025-08-27 12:35:35.173617 | 2025-08-27 12:35:35.173794 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2025-08-27 12:35:35.198739 | debian-bookworm | skipping: Conditional result was False 2025-08-27 12:35:35.213431 | 2025-08-27 12:35:35.213606 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2025-08-27 12:35:35.248636 | 2025-08-27 12:35:35.248923 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2025-08-27 12:35:35.287114 | 2025-08-27 12:35:35.287381 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2025-08-27 12:35:35.311646 | debian-bookworm | skipping: Conditional result was False 2025-08-27 12:35:35.324515 | 2025-08-27 12:35:35.324707 | LOOP [fetch-subunit-output : Generate subunit file] 2025-08-27 12:35:35.360143 | 2025-08-27 12:35:35.360467 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2025-08-27 12:35:35.384829 | debian-bookworm | skipping: Conditional result was False 2025-08-27 12:35:35.394606 | 2025-08-27 12:35:35.394754 | TASK [fetch-subunit-output : Remove the temporary file] 2025-08-27 12:35:35.419450 | debian-bookworm | skipping: Conditional result was False 2025-08-27 12:35:35.429811 | 2025-08-27 12:35:35.429960 | TASK [fetch-subunit-output : Process and fetch subunit results] 2025-08-27 12:35:35.454317 | debian-bookworm | skipping: Conditional result was False 2025-08-27 12:35:35.467406 | 2025-08-27 12:35:35.467520 | PLAY RECAP 2025-08-27 12:35:35.467602 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2025-08-27 12:35:35.467643 | 2025-08-27 12:35:35.586683 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2025-08-27 12:35:35.589247 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-08-27 12:35:36.289958 | 2025-08-27 12:35:36.290133 | PLAY [Base post] 2025-08-27 12:35:36.303178 | 2025-08-27 12:35:36.303306 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-08-27 12:35:36.887695 | debian-bookworm | changed 2025-08-27 12:35:36.897965 | 2025-08-27 12:35:36.898103 | PLAY RECAP 2025-08-27 12:35:36.898185 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-08-27 12:35:36.898277 | 2025-08-27 12:35:37.017838 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-08-27 12:35:37.021187 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2025-08-27 12:35:37.797432 | 2025-08-27 12:35:37.797601 | PLAY [Base post-logs] 2025-08-27 12:35:37.808426 | 2025-08-27 12:35:37.808563 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-08-27 12:35:38.261045 | localhost | changed 2025-08-27 12:35:38.271042 | 2025-08-27 12:35:38.271199 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-08-27 12:35:38.306606 | localhost | ok 2025-08-27 12:35:38.310238 | 2025-08-27 12:35:38.310353 | TASK [Set zuul-log-path fact] 2025-08-27 12:35:38.325152 | localhost | ok 2025-08-27 12:35:38.333421 | 2025-08-27 12:35:38.333537 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-08-27 12:35:38.358206 | localhost | ok 2025-08-27 12:35:38.362092 | 2025-08-27 12:35:38.362214 | TASK [upload-logs : Create log directories] 2025-08-27 12:35:38.839346 | localhost | changed 2025-08-27 12:35:38.844315 | 2025-08-27 12:35:38.844486 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-08-27 12:35:39.336252 | localhost -> localhost | ok: Runtime: 0:00:00.004064 2025-08-27 12:35:39.340348 | 2025-08-27 12:35:39.340464 | TASK [upload-logs : Upload logs to log server] 2025-08-27 12:35:39.895257 | localhost | Output suppressed because no_log was given 2025-08-27 12:35:39.899467 | 2025-08-27 12:35:39.899680 | LOOP [upload-logs : Compress console log and json output] 2025-08-27 12:35:39.959240 | localhost | skipping: Conditional result was False 2025-08-27 12:35:39.964358 | localhost | skipping: Conditional result was False 2025-08-27 12:35:39.972028 | 2025-08-27 12:35:39.972268 | LOOP [upload-logs : Upload compressed console log and json output] 2025-08-27 12:35:40.018306 | localhost | skipping: Conditional result was False 2025-08-27 12:35:40.018938 | 2025-08-27 12:35:40.022471 | localhost | skipping: Conditional result was False 2025-08-27 12:35:40.036628 | 2025-08-27 12:35:40.036915 | LOOP [upload-logs : Upload console log and json output]