2026-03-22 00:25:51.289002 | Job console starting 2026-03-22 00:25:51.308319 | Updating git repos 2026-03-22 00:25:51.354114 | Cloning repos into workspace 2026-03-22 00:25:51.441155 | Restoring repo states 2026-03-22 00:25:51.461614 | Merging changes 2026-03-22 00:25:52.205186 | Checking out repos 2026-03-22 00:25:52.238684 | Preparing playbooks 2026-03-22 00:25:52.968104 | Running Ansible setup 2026-03-22 00:27:18.268602 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-03-22 00:27:19.110560 | 2026-03-22 00:27:19.110770 | PLAY [Base pre] 2026-03-22 00:27:19.128985 | 2026-03-22 00:27:19.129158 | TASK [Setup log path fact] 2026-03-22 00:27:19.153276 | debian-bookworm | ok 2026-03-22 00:27:19.175640 | 2026-03-22 00:27:19.175839 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-03-22 00:27:19.214361 | debian-bookworm | ok 2026-03-22 00:27:19.228802 | 2026-03-22 00:27:19.228989 | TASK [emit-job-header : Print job information] 2026-03-22 00:27:19.271631 | # Job Information 2026-03-22 00:27:19.271871 | Ansible Version: 2.16.14 2026-03-22 00:27:19.271906 | Job: tox 2026-03-22 00:27:19.271939 | Pipeline: check 2026-03-22 00:27:19.271962 | Executor: 521e9411259a 2026-03-22 00:27:19.271982 | Triggered by: https://github.com/osism/openstack-image-manager/pull/1189 2026-03-22 00:27:19.272004 | Event ID: 89155230-2585-11f1-8074-058e86125574 2026-03-22 00:27:19.278344 | 2026-03-22 00:27:19.278502 | LOOP [emit-job-header : Print node information] 2026-03-22 00:27:19.424035 | debian-bookworm | ok: 2026-03-22 00:27:19.424278 | debian-bookworm | # Node Information 2026-03-22 00:27:19.424317 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-03-22 00:27:19.424344 | debian-bookworm | Hostname: debian 2026-03-22 00:27:19.424395 | debian-bookworm | Username: zuul 2026-03-22 00:27:19.424421 | debian-bookworm | Distro: Debian 12.13 2026-03-22 00:27:19.424445 | debian-bookworm | Provider: regiocloud-a 2026-03-22 00:27:19.424467 | debian-bookworm | Region: 2026-03-22 00:27:19.424488 | debian-bookworm | Label: debian-bookworm 2026-03-22 00:27:19.424509 | debian-bookworm | Product Name: OpenStack Nova 2026-03-22 00:27:19.424528 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:feb5:8667 2026-03-22 00:27:19.456315 | 2026-03-22 00:27:19.456526 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-03-22 00:27:19.955957 | debian-bookworm -> localhost | changed 2026-03-22 00:27:19.973601 | 2026-03-22 00:27:19.973815 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-03-22 00:27:21.130796 | debian-bookworm -> localhost | changed 2026-03-22 00:27:21.146589 | 2026-03-22 00:27:21.146745 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-03-22 00:27:21.465853 | debian-bookworm -> localhost | ok 2026-03-22 00:27:21.481434 | 2026-03-22 00:27:21.481610 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-03-22 00:27:21.518450 | debian-bookworm | ok 2026-03-22 00:27:21.539436 | debian-bookworm | included: /var/lib/zuul/builds/6947a371eb934f5db861df345a9e83a3/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-03-22 00:27:21.545654 | 2026-03-22 00:27:21.545758 | TASK [add-build-sshkey : Create Temp SSH key] 2026-03-22 00:27:22.390518 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-03-22 00:27:22.391106 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/6947a371eb934f5db861df345a9e83a3/work/6947a371eb934f5db861df345a9e83a3_id_rsa 2026-03-22 00:27:22.391227 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/6947a371eb934f5db861df345a9e83a3/work/6947a371eb934f5db861df345a9e83a3_id_rsa.pub 2026-03-22 00:27:22.391309 | debian-bookworm -> localhost | The key fingerprint is: 2026-03-22 00:27:22.391503 | debian-bookworm -> localhost | SHA256:HIJkWutHBPtrPdNMpmrLoXZq2IHU64tVypbSKIfYenI zuul-build-sshkey 2026-03-22 00:27:22.391578 | debian-bookworm -> localhost | The key's randomart image is: 2026-03-22 00:27:22.391668 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-03-22 00:27:22.391734 | debian-bookworm -> localhost | | =.. | 2026-03-22 00:27:22.391798 | debian-bookworm -> localhost | | = = | 2026-03-22 00:27:22.391856 | debian-bookworm -> localhost | | ..+ o . | 2026-03-22 00:27:22.391914 | debian-bookworm -> localhost | | ...o o . | 2026-03-22 00:27:22.391971 | debian-bookworm -> localhost | | . ...+ S o | 2026-03-22 00:27:22.392049 | debian-bookworm -> localhost | |.o.+o= o * | 2026-03-22 00:27:22.392110 | debian-bookworm -> localhost | |+ =+B.+ = o | 2026-03-22 00:27:22.392168 | debian-bookworm -> localhost | |.+E==+oo o | 2026-03-22 00:27:22.392228 | debian-bookworm -> localhost | |.+.o+=+. | 2026-03-22 00:27:22.392288 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-03-22 00:27:22.392451 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.281093 2026-03-22 00:27:22.406519 | 2026-03-22 00:27:22.406716 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-03-22 00:27:22.453778 | debian-bookworm | ok 2026-03-22 00:27:22.468148 | debian-bookworm | included: /var/lib/zuul/builds/6947a371eb934f5db861df345a9e83a3/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-03-22 00:27:22.477962 | 2026-03-22 00:27:22.478067 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-03-22 00:27:22.502207 | debian-bookworm | skipping: Conditional result was False 2026-03-22 00:27:22.508489 | 2026-03-22 00:27:22.508600 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-03-22 00:27:23.189228 | debian-bookworm | changed 2026-03-22 00:27:23.197227 | 2026-03-22 00:27:23.197367 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-03-22 00:27:23.478590 | debian-bookworm | ok 2026-03-22 00:27:23.487098 | 2026-03-22 00:27:23.487241 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-03-22 00:27:24.239252 | debian-bookworm | changed 2026-03-22 00:27:24.250032 | 2026-03-22 00:27:24.250180 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-03-22 00:27:24.965191 | debian-bookworm | changed 2026-03-22 00:27:24.972917 | 2026-03-22 00:27:24.973045 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-03-22 00:27:24.997073 | debian-bookworm | skipping: Conditional result was False 2026-03-22 00:27:25.006104 | 2026-03-22 00:27:25.006235 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-03-22 00:27:25.492649 | debian-bookworm -> localhost | changed 2026-03-22 00:27:25.518172 | 2026-03-22 00:27:25.518355 | TASK [add-build-sshkey : Add back temp key] 2026-03-22 00:27:25.887647 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/6947a371eb934f5db861df345a9e83a3/work/6947a371eb934f5db861df345a9e83a3_id_rsa (zuul-build-sshkey) 2026-03-22 00:27:25.888123 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.017521 2026-03-22 00:27:25.902959 | 2026-03-22 00:27:25.903098 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-03-22 00:27:26.315197 | debian-bookworm | ok 2026-03-22 00:27:26.323152 | 2026-03-22 00:27:26.323299 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-03-22 00:27:26.358411 | debian-bookworm | skipping: Conditional result was False 2026-03-22 00:27:26.375995 | 2026-03-22 00:27:26.376149 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-03-22 00:27:26.853185 | debian-bookworm | ok 2026-03-22 00:27:26.869705 | 2026-03-22 00:27:26.869836 | TASK [validate-host : Define zuul_info_dir fact] 2026-03-22 00:27:26.917302 | debian-bookworm | ok 2026-03-22 00:27:26.925761 | 2026-03-22 00:27:26.925899 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-03-22 00:27:27.235804 | debian-bookworm -> localhost | ok 2026-03-22 00:27:27.242793 | 2026-03-22 00:27:27.242923 | TASK [validate-host : Collect information about the host] 2026-03-22 00:28:08.431139 | debian-bookworm | ok 2026-03-22 00:28:08.441861 | 2026-03-22 00:28:08.441973 | TASK [validate-host : Sanitize hostname] 2026-03-22 00:28:08.563538 | debian-bookworm | ok 2026-03-22 00:28:08.603174 | 2026-03-22 00:28:08.603310 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-03-22 00:28:09.220430 | debian-bookworm -> localhost | changed 2026-03-22 00:28:09.227378 | 2026-03-22 00:28:09.227523 | TASK [validate-host : Collect information about zuul worker] 2026-03-22 00:28:09.658121 | debian-bookworm | ok 2026-03-22 00:28:09.665020 | 2026-03-22 00:28:09.665153 | TASK [validate-host : Write out all zuul information for each host] 2026-03-22 00:28:10.207487 | debian-bookworm -> localhost | changed 2026-03-22 00:28:10.223721 | 2026-03-22 00:28:10.223891 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-03-22 00:28:10.534414 | debian-bookworm | ok 2026-03-22 00:28:10.543528 | 2026-03-22 00:28:10.543657 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-03-22 00:28:11.435376 | debian-bookworm | changed: 2026-03-22 00:28:11.435656 | debian-bookworm | cd+++++++++ src/ 2026-03-22 00:28:11.435710 | debian-bookworm | cd+++++++++ src/github.com/ 2026-03-22 00:28:11.435748 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-03-22 00:28:11.435783 | debian-bookworm | cd+++++++++ src/github.com/osism/openstack-image-manager/ 2026-03-22 00:28:11.435816 | debian-bookworm | localhost | changed 2026-03-22 00:30:30.423863 | 2026-03-22 00:30:30.424073 | TASK [fetch-tox-output : Ensure zuul-output tox dir] 2026-03-22 00:30:30.471648 | debian-bookworm | skipping: Conditional result was False 2026-03-22 00:30:30.480403 | 2026-03-22 00:30:30.480607 | TASK [fetch-tox-output : Set envlist fact] 2026-03-22 00:30:30.527479 | debian-bookworm | ok 2026-03-22 00:30:30.536591 | 2026-03-22 00:30:30.536733 | TASK [fetch-tox-output : Get tox version] 2026-03-22 00:30:31.067557 | debian-bookworm | ERROR 2026-03-22 00:30:31.068179 | debian-bookworm | { 2026-03-22 00:30:31.068325 | debian-bookworm | "msg": "[Errno 2] No such file or directory: b'tox'", 2026-03-22 00:30:31.068414 | debian-bookworm | "rc": 2 2026-03-22 00:30:31.068475 | debian-bookworm | } failure 2026-03-22 00:30:31.072409 | 2026-03-22 00:30:31.072672 | PLAY RECAP 2026-03-22 00:30:31.072809 | debian-bookworm | ok: 3 changed: 1 unreachable: 0 failed: 1 skipped: 2 rescued: 0 ignored: 0 2026-03-22 00:30:31.072883 | 2026-03-22 00:30:31.195366 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2026-03-22 00:30:31.196451 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2026-03-22 00:30:31.930815 | 2026-03-22 00:30:31.931010 | PLAY [all] 2026-03-22 00:30:31.946825 | 2026-03-22 00:30:31.946994 | TASK [fetch-subunit-output : Find stestr or testr executable] 2026-03-22 00:30:32.180959 | debian-bookworm | changed: non-zero return code 2026-03-22 00:30:32.190900 | 2026-03-22 00:30:32.191068 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2026-03-22 00:30:32.226680 | debian-bookworm | skipping: Conditional result was False 2026-03-22 00:30:32.241168 | 2026-03-22 00:30:32.241417 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2026-03-22 00:30:32.294400 | 2026-03-22 00:30:32.294803 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2026-03-22 00:30:32.337944 | 2026-03-22 00:30:32.338232 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2026-03-22 00:30:32.363323 | debian-bookworm | skipping: Conditional result was False 2026-03-22 00:30:32.373059 | 2026-03-22 00:30:32.373193 | LOOP [fetch-subunit-output : Generate subunit file] 2026-03-22 00:30:32.406937 | 2026-03-22 00:30:32.407188 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2026-03-22 00:30:32.432348 | debian-bookworm | skipping: Conditional result was False 2026-03-22 00:30:32.446582 | 2026-03-22 00:30:32.446866 | TASK [fetch-subunit-output : Remove the temporary file] 2026-03-22 00:30:32.472712 | debian-bookworm | skipping: Conditional result was False 2026-03-22 00:30:32.486261 | 2026-03-22 00:30:32.486452 | TASK [fetch-subunit-output : Process and fetch subunit results] 2026-03-22 00:30:32.512282 | debian-bookworm | skipping: Conditional result was False 2026-03-22 00:30:32.523659 | 2026-03-22 00:30:32.523777 | PLAY RECAP 2026-03-22 00:30:32.523848 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2026-03-22 00:30:32.523885 | 2026-03-22 00:30:32.651390 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2026-03-22 00:30:32.654081 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-03-22 00:30:33.492912 | 2026-03-22 00:30:33.493095 | PLAY [Base post] 2026-03-22 00:30:33.508190 | 2026-03-22 00:30:33.508358 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-03-22 00:30:34.056122 | debian-bookworm | changed 2026-03-22 00:30:34.071261 | 2026-03-22 00:30:34.071521 | PLAY RECAP 2026-03-22 00:30:34.071599 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-03-22 00:30:34.071667 | 2026-03-22 00:30:34.196574 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-03-22 00:30:34.199412 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-03-22 00:30:34.992147 | 2026-03-22 00:30:34.992337 | PLAY [Base post-logs] 2026-03-22 00:30:35.003745 | 2026-03-22 00:30:35.003913 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-03-22 00:30:35.526554 | localhost | changed 2026-03-22 00:30:35.537539 | 2026-03-22 00:30:35.537716 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-03-22 00:30:35.574180 | localhost | ok 2026-03-22 00:30:35.577743 | 2026-03-22 00:30:35.577847 | TASK [Set zuul-log-path fact] 2026-03-22 00:30:35.593873 | localhost | ok 2026-03-22 00:30:35.602302 | 2026-03-22 00:30:35.602415 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-03-22 00:30:35.639191 | localhost | ok 2026-03-22 00:30:35.644077 | 2026-03-22 00:30:35.644216 | TASK [upload-logs : Create log directories] 2026-03-22 00:30:36.196174 | localhost | changed 2026-03-22 00:30:36.200480 | 2026-03-22 00:30:36.200628 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-03-22 00:30:36.747948 | localhost -> localhost | ok: Runtime: 0:00:00.019064 2026-03-22 00:30:36.754713 | 2026-03-22 00:30:36.754930 | TASK [upload-logs : Upload logs to log server] 2026-03-22 00:30:37.394141 | localhost | Output suppressed because no_log was given 2026-03-22 00:30:37.396831 | 2026-03-22 00:30:37.396966 | LOOP [upload-logs : Compress console log and json output] 2026-03-22 00:30:37.465828 | localhost | skipping: Conditional result was False 2026-03-22 00:30:37.474136 | localhost | skipping: Conditional result was False 2026-03-22 00:30:37.488744 | 2026-03-22 00:30:37.489375 | LOOP [upload-logs : Upload compressed console log and json output] 2026-03-22 00:30:37.569088 | localhost | skipping: Conditional result was False 2026-03-22 00:30:37.569542 | 2026-03-22 00:30:37.576559 | localhost | skipping: Conditional result was False 2026-03-22 00:30:37.581669 | 2026-03-22 00:30:37.581885 | LOOP [upload-logs : Upload console log and json output]