2025-08-22 03:13:10.247902 | Job console starting 2025-08-22 03:13:10.259248 | Updating git repos 2025-08-22 03:13:10.276151 | Cloning repos into workspace 2025-08-22 03:13:10.325417 | Restoring repo states 2025-08-22 03:13:10.335260 | Merging changes 2025-08-22 03:13:10.335303 | Checking out repos 2025-08-22 03:13:10.383934 | Preparing playbooks 2025-08-22 03:13:10.986309 | Running Ansible setup 2025-08-22 03:14:35.137943 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-08-22 03:14:35.962966 | 2025-08-22 03:14:35.963087 | PLAY [Base pre] 2025-08-22 03:14:35.985878 | 2025-08-22 03:14:35.986017 | TASK [Setup log path fact] 2025-08-22 03:14:36.018805 | debian-bookworm | ok 2025-08-22 03:14:36.033786 | 2025-08-22 03:14:36.033905 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-08-22 03:14:36.062612 | debian-bookworm | ok 2025-08-22 03:14:36.071818 | 2025-08-22 03:14:36.071909 | TASK [emit-job-header : Print job information] 2025-08-22 03:14:36.111718 | # Job Information 2025-08-22 03:14:36.112829 | Ansible Version: 2.16.14 2025-08-22 03:14:36.112873 | Job: cfg-cookiecutter-tox-2023.2 2025-08-22 03:14:36.112904 | Pipeline: periodic-daily 2025-08-22 03:14:36.112942 | Executor: 521e9411259a 2025-08-22 03:14:36.112964 | Triggered by: https://github.com/osism/cfg-cookiecutter 2025-08-22 03:14:36.112987 | Event ID: a9dd7d8563ee474ca12d8bc977ac8ce4 2025-08-22 03:14:36.128114 | 2025-08-22 03:14:36.128221 | LOOP [emit-job-header : Print node information] 2025-08-22 03:14:36.277446 | debian-bookworm | ok: 2025-08-22 03:14:36.277608 | debian-bookworm | # Node Information 2025-08-22 03:14:36.277641 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-08-22 03:14:36.277666 | debian-bookworm | Hostname: debian 2025-08-22 03:14:36.277687 | debian-bookworm | Username: zuul 2025-08-22 03:14:36.277707 | debian-bookworm | Distro: Debian 12.11 2025-08-22 03:14:36.277730 | debian-bookworm | Provider: regiocloud-a 2025-08-22 03:14:36.277751 | debian-bookworm | Region: 2025-08-22 03:14:36.277771 | debian-bookworm | Label: debian-bookworm 2025-08-22 03:14:36.277791 | debian-bookworm | Product Name: OpenStack Nova 2025-08-22 03:14:36.277810 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe87:4a42 2025-08-22 03:14:36.296857 | 2025-08-22 03:14:36.296976 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-08-22 03:14:36.898492 | debian-bookworm -> localhost | changed 2025-08-22 03:14:36.904303 | 2025-08-22 03:14:36.904395 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-08-22 03:14:37.916068 | debian-bookworm -> localhost | changed 2025-08-22 03:14:37.926413 | 2025-08-22 03:14:37.926509 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-08-22 03:14:38.275857 | debian-bookworm -> localhost | ok 2025-08-22 03:14:38.282456 | 2025-08-22 03:14:38.282568 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-08-22 03:14:38.327381 | debian-bookworm | ok 2025-08-22 03:14:38.358573 | debian-bookworm | included: /var/lib/zuul/builds/75450dd9243a4130b60f924e6fc4dc68/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-08-22 03:14:38.373190 | 2025-08-22 03:14:38.373295 | TASK [add-build-sshkey : Create Temp SSH key] 2025-08-22 03:14:41.994903 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-08-22 03:14:41.995146 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/75450dd9243a4130b60f924e6fc4dc68/work/75450dd9243a4130b60f924e6fc4dc68_id_rsa 2025-08-22 03:14:41.995186 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/75450dd9243a4130b60f924e6fc4dc68/work/75450dd9243a4130b60f924e6fc4dc68_id_rsa.pub 2025-08-22 03:14:41.995213 | debian-bookworm -> localhost | The key fingerprint is: 2025-08-22 03:14:41.995239 | debian-bookworm -> localhost | SHA256:XAUEcwnsJ1QjRe9TBudBe1VD6TY4ZCwowE65zhum08k zuul-build-sshkey 2025-08-22 03:14:41.995261 | debian-bookworm -> localhost | The key's randomart image is: 2025-08-22 03:14:41.995289 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-08-22 03:14:41.995311 | debian-bookworm -> localhost | | ..o.=BXo+.=o=| 2025-08-22 03:14:41.995332 | debian-bookworm -> localhost | | + .o=.= B +o| 2025-08-22 03:14:41.995352 | debian-bookworm -> localhost | | o .o. . = B .| 2025-08-22 03:14:41.995372 | debian-bookworm -> localhost | | o .o... = = | 2025-08-22 03:14:41.995392 | debian-bookworm -> localhost | | o So o o .| 2025-08-22 03:14:41.995419 | debian-bookworm -> localhost | | = . | 2025-08-22 03:14:41.995440 | debian-bookworm -> localhost | | = + | 2025-08-22 03:14:41.995459 | debian-bookworm -> localhost | | o E | 2025-08-22 03:14:41.995479 | debian-bookworm -> localhost | | . | 2025-08-22 03:14:41.995499 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-08-22 03:14:41.995547 | debian-bookworm -> localhost | ok: Runtime: 0:00:02.528011 2025-08-22 03:14:42.002154 | 2025-08-22 03:14:42.002246 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-08-22 03:14:42.040795 | debian-bookworm | ok 2025-08-22 03:14:42.061116 | debian-bookworm | included: /var/lib/zuul/builds/75450dd9243a4130b60f924e6fc4dc68/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-08-22 03:14:42.080609 | 2025-08-22 03:14:42.080712 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-08-22 03:14:42.119296 | debian-bookworm | skipping: Conditional result was False 2025-08-22 03:14:42.125386 | 2025-08-22 03:14:42.125483 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-08-22 03:14:42.953415 | debian-bookworm | changed 2025-08-22 03:14:42.960381 | 2025-08-22 03:14:42.960483 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-08-22 03:14:43.230346 | debian-bookworm | ok 2025-08-22 03:14:43.251838 | 2025-08-22 03:14:43.251976 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-08-22 03:14:43.951737 | debian-bookworm | changed 2025-08-22 03:14:43.962036 | 2025-08-22 03:14:43.962142 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-08-22 03:14:44.683522 | debian-bookworm | changed 2025-08-22 03:14:44.689410 | 2025-08-22 03:14:44.689501 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-08-22 03:14:44.722949 | debian-bookworm | skipping: Conditional result was False 2025-08-22 03:14:44.728957 | 2025-08-22 03:14:44.729056 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-08-22 03:14:45.293300 | debian-bookworm -> localhost | changed 2025-08-22 03:14:45.303798 | 2025-08-22 03:14:45.303888 | TASK [add-build-sshkey : Add back temp key] 2025-08-22 03:14:45.617308 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/75450dd9243a4130b60f924e6fc4dc68/work/75450dd9243a4130b60f924e6fc4dc68_id_rsa (zuul-build-sshkey) 2025-08-22 03:14:45.617853 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.011350 2025-08-22 03:14:45.640390 | 2025-08-22 03:14:45.640683 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-08-22 03:14:46.012075 | debian-bookworm | ok 2025-08-22 03:14:46.016217 | 2025-08-22 03:14:46.016293 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-08-22 03:14:46.028756 | debian-bookworm | skipping: Conditional result was False 2025-08-22 03:14:46.037583 | 2025-08-22 03:14:46.037664 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-08-22 03:14:46.408612 | debian-bookworm | ok 2025-08-22 03:14:46.422954 | 2025-08-22 03:14:46.423049 | TASK [validate-host : Define zuul_info_dir fact] 2025-08-22 03:14:46.472951 | debian-bookworm | ok 2025-08-22 03:14:46.477032 | 2025-08-22 03:14:46.477110 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-08-22 03:14:46.868006 | debian-bookworm -> localhost | ok 2025-08-22 03:14:46.875315 | 2025-08-22 03:14:46.875411 | TASK [validate-host : Collect information about the host] 2025-08-22 03:15:28.023681 | debian-bookworm | ok 2025-08-22 03:15:28.035536 | 2025-08-22 03:15:28.035637 | TASK [validate-host : Sanitize hostname] 2025-08-22 03:15:28.125234 | debian-bookworm | ok 2025-08-22 03:15:28.164634 | 2025-08-22 03:15:28.164771 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-08-22 03:15:28.970753 | debian-bookworm -> localhost | changed 2025-08-22 03:15:28.979848 | 2025-08-22 03:15:28.979986 | TASK [validate-host : Collect information about zuul worker] 2025-08-22 03:15:29.376658 | debian-bookworm | ok 2025-08-22 03:15:29.381345 | 2025-08-22 03:15:29.381439 | TASK [validate-host : Write out all zuul information for each host] 2025-08-22 03:15:29.901038 | debian-bookworm -> localhost | changed 2025-08-22 03:15:29.910015 | 2025-08-22 03:15:29.910106 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-08-22 03:15:30.136711 | debian-bookworm | ok 2025-08-22 03:15:30.141845 | 2025-08-22 03:15:30.141943 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-08-22 03:15:31.168287 | debian-bookworm | changed: 2025-08-22 03:15:31.168474 | debian-bookworm | cd+++++++++ src/ 2025-08-22 03:15:31.168663 | debian-bookworm | cd+++++++++ src/github.com/ 2025-08-22 03:15:31.168703 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-08-22 03:15:31.168727 | debian-bookworm | cd+++++++++ src/github.com/osism/cfg-cookiecutter/ 2025-08-22 03:15:31.168750 | debian-bookworm | Containerfile 2025-08-22 03:15:31.168931 | debian-bookworm | localhost | changed 2025-08-22 03:17:49.687350 | 2025-08-22 03:17:49.687434 | TASK [fetch-tox-output : Ensure zuul-output tox dir] 2025-08-22 03:17:49.750643 | debian-bookworm | skipping: Conditional result was False 2025-08-22 03:17:49.759366 | 2025-08-22 03:17:49.759465 | TASK [fetch-tox-output : Set envlist fact] 2025-08-22 03:17:49.806141 | debian-bookworm | ok 2025-08-22 03:17:49.810623 | 2025-08-22 03:17:49.810698 | TASK [fetch-tox-output : Get tox version] 2025-08-22 03:17:50.365654 | debian-bookworm | ERROR 2025-08-22 03:17:50.366168 | debian-bookworm | { 2025-08-22 03:17:50.366226 | debian-bookworm | "msg": "[Errno 2] No such file or directory: b'tox'", 2025-08-22 03:17:50.366261 | debian-bookworm | "rc": 2 2025-08-22 03:17:50.366285 | debian-bookworm | } failure 2025-08-22 03:17:50.368194 | 2025-08-22 03:17:50.368258 | PLAY RECAP 2025-08-22 03:17:50.368307 | debian-bookworm | ok: 3 changed: 1 unreachable: 0 failed: 1 skipped: 2 rescued: 0 ignored: 0 2025-08-22 03:17:50.368331 | 2025-08-22 03:17:50.516356 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2025-08-22 03:17:50.517234 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2025-08-22 03:17:51.322697 | 2025-08-22 03:17:51.322819 | PLAY [all] 2025-08-22 03:17:51.337483 | 2025-08-22 03:17:51.337582 | TASK [fetch-subunit-output : Find stestr or testr executable] 2025-08-22 03:17:51.587442 | debian-bookworm | changed: non-zero return code 2025-08-22 03:17:51.591761 | 2025-08-22 03:17:51.591874 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2025-08-22 03:17:51.604512 | debian-bookworm | skipping: Conditional result was False 2025-08-22 03:17:51.609009 | 2025-08-22 03:17:51.609094 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2025-08-22 03:17:51.626417 | 2025-08-22 03:17:51.626558 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2025-08-22 03:17:51.644309 | 2025-08-22 03:17:51.644447 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2025-08-22 03:17:51.656837 | debian-bookworm | skipping: Conditional result was False 2025-08-22 03:17:51.661426 | 2025-08-22 03:17:51.661514 | LOOP [fetch-subunit-output : Generate subunit file] 2025-08-22 03:17:51.678179 | 2025-08-22 03:17:51.678357 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2025-08-22 03:17:51.690718 | debian-bookworm | skipping: Conditional result was False 2025-08-22 03:17:51.695238 | 2025-08-22 03:17:51.695323 | TASK [fetch-subunit-output : Remove the temporary file] 2025-08-22 03:17:51.707628 | debian-bookworm | skipping: Conditional result was False 2025-08-22 03:17:51.712057 | 2025-08-22 03:17:51.712130 | TASK [fetch-subunit-output : Process and fetch subunit results] 2025-08-22 03:17:51.724215 | debian-bookworm | skipping: Conditional result was False 2025-08-22 03:17:51.729078 | 2025-08-22 03:17:51.729131 | PLAY RECAP 2025-08-22 03:17:51.729169 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2025-08-22 03:17:51.729190 | 2025-08-22 03:17:51.802894 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2025-08-22 03:17:51.804135 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-08-22 03:17:52.501411 | 2025-08-22 03:17:52.501549 | PLAY [Base post] 2025-08-22 03:17:52.524232 | 2025-08-22 03:17:52.524335 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-08-22 03:17:52.979794 | debian-bookworm | changed 2025-08-22 03:17:52.986982 | 2025-08-22 03:17:52.987080 | PLAY RECAP 2025-08-22 03:17:52.987139 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-08-22 03:17:52.987200 | 2025-08-22 03:17:53.097461 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-08-22 03:17:53.098420 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2025-08-22 03:17:53.694749 | 2025-08-22 03:17:53.694911 | PLAY [Base post-logs] 2025-08-22 03:17:53.702985 | 2025-08-22 03:17:53.703086 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-08-22 03:17:54.253408 | localhost | changed 2025-08-22 03:17:54.261038 | 2025-08-22 03:17:54.261150 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-08-22 03:17:54.287695 | localhost | ok 2025-08-22 03:17:54.290108 | 2025-08-22 03:17:54.290177 | TASK [Set zuul-log-path fact] 2025-08-22 03:17:54.303890 | localhost | ok 2025-08-22 03:17:54.310261 | 2025-08-22 03:17:54.310349 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-08-22 03:17:54.344743 | localhost | ok 2025-08-22 03:17:54.351118 | 2025-08-22 03:17:54.351206 | TASK [upload-logs : Create log directories] 2025-08-22 03:17:54.838681 | localhost | changed 2025-08-22 03:17:54.841279 | 2025-08-22 03:17:54.841363 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-08-22 03:17:55.334890 | localhost -> localhost | ok: Runtime: 0:00:00.010112 2025-08-22 03:17:55.340708 | 2025-08-22 03:17:55.340811 | TASK [upload-logs : Upload logs to log server] 2025-08-22 03:17:55.860907 | localhost | Output suppressed because no_log was given 2025-08-22 03:17:55.862785 | 2025-08-22 03:17:55.863307 | LOOP [upload-logs : Compress console log and json output] 2025-08-22 03:17:55.915172 | localhost | skipping: Conditional result was False 2025-08-22 03:17:55.919376 | localhost | skipping: Conditional result was False 2025-08-22 03:17:55.932838 | 2025-08-22 03:17:55.933137 | LOOP [upload-logs : Upload compressed console log and json output] 2025-08-22 03:17:55.969432 | localhost | skipping: Conditional result was False 2025-08-22 03:17:55.969996 | 2025-08-22 03:17:55.972318 | localhost | skipping: Conditional result was False 2025-08-22 03:17:55.987045 | 2025-08-22 03:17:55.987158 | LOOP [upload-logs : Upload console log and json output]