2026-04-20 03:01:48.943758 | Job console starting 2026-04-20 03:01:48.958040 | Updating git repos 2026-04-20 03:01:49.029900 | Cloning repos into workspace 2026-04-20 03:01:49.091121 | Restoring repo states 2026-04-20 03:01:49.138270 | Merging changes 2026-04-20 03:01:49.138284 | Checking out repos 2026-04-20 03:01:49.230620 | Preparing playbooks 2026-04-20 03:01:50.260046 | Running Ansible setup 2026-04-20 03:03:15.960523 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-04-20 03:03:16.910431 | 2026-04-20 03:03:16.910612 | PLAY [Base pre] 2026-04-20 03:03:16.929496 | 2026-04-20 03:03:16.929657 | TASK [Setup log path fact] 2026-04-20 03:03:16.971228 | debian-bookworm | ok 2026-04-20 03:03:16.989788 | 2026-04-20 03:03:16.989960 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-20 03:03:17.034226 | debian-bookworm | ok 2026-04-20 03:03:17.049526 | 2026-04-20 03:03:17.049673 | TASK [emit-job-header : Print job information] 2026-04-20 03:03:17.124517 | # Job Information 2026-04-20 03:03:17.124781 | Ansible Version: 2.16.14 2026-04-20 03:03:17.124819 | Job: cfg-generics-tox-check 2026-04-20 03:03:17.124857 | Pipeline: periodic-daily 2026-04-20 03:03:17.124881 | Executor: 521e9411259a 2026-04-20 03:03:17.124901 | Triggered by: https://github.com/osism/generics 2026-04-20 03:03:17.124923 | Event ID: 45101f5656d64c05abde1273a024273b 2026-04-20 03:03:17.134313 | 2026-04-20 03:03:17.134463 | LOOP [emit-job-header : Print node information] 2026-04-20 03:03:17.282749 | debian-bookworm | ok: 2026-04-20 03:03:17.283040 | debian-bookworm | # Node Information 2026-04-20 03:03:17.283090 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-04-20 03:03:17.283124 | debian-bookworm | Hostname: debian 2026-04-20 03:03:17.283325 | debian-bookworm | Username: zuul 2026-04-20 03:03:17.283368 | debian-bookworm | Distro: Debian 12.13 2026-04-20 03:03:17.283396 | debian-bookworm | Provider: regiocloud-a 2026-04-20 03:03:17.283419 | debian-bookworm | Region: 2026-04-20 03:03:17.283439 | debian-bookworm | Label: debian-bookworm 2026-04-20 03:03:17.283460 | debian-bookworm | Product Name: OpenStack Nova 2026-04-20 03:03:17.283480 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe2f:e8b7 2026-04-20 03:03:17.317621 | 2026-04-20 03:03:17.317768 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-04-20 03:03:18.624755 | debian-bookworm -> localhost | changed 2026-04-20 03:03:18.651946 | 2026-04-20 03:03:18.652104 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-04-20 03:03:21.044650 | debian-bookworm -> localhost | changed 2026-04-20 03:03:21.068075 | 2026-04-20 03:03:21.068211 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-04-20 03:03:21.368068 | debian-bookworm -> localhost | ok 2026-04-20 03:03:21.374423 | 2026-04-20 03:03:21.374565 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-04-20 03:03:21.396551 | debian-bookworm | ok 2026-04-20 03:03:21.413538 | debian-bookworm | included: /var/lib/zuul/builds/27105a8e9cab400a9a5d86c43cb04cc1/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-04-20 03:03:21.419393 | 2026-04-20 03:03:21.419471 | TASK [add-build-sshkey : Create Temp SSH key] 2026-04-20 03:03:23.305748 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-04-20 03:03:23.306005 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/27105a8e9cab400a9a5d86c43cb04cc1/work/27105a8e9cab400a9a5d86c43cb04cc1_id_rsa 2026-04-20 03:03:23.306046 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/27105a8e9cab400a9a5d86c43cb04cc1/work/27105a8e9cab400a9a5d86c43cb04cc1_id_rsa.pub 2026-04-20 03:03:23.306074 | debian-bookworm -> localhost | The key fingerprint is: 2026-04-20 03:03:23.306101 | debian-bookworm -> localhost | SHA256:FgyJNfvrwJz7JvV0TqLoOyN5DuTvExeIGjUxA9aUQrM zuul-build-sshkey 2026-04-20 03:03:23.306125 | debian-bookworm -> localhost | The key's randomart image is: 2026-04-20 03:03:23.306160 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-04-20 03:03:23.306182 | debian-bookworm -> localhost | | .=+==+. | 2026-04-20 03:03:23.306204 | debian-bookworm -> localhost | | ..o*o.= | 2026-04-20 03:03:23.306224 | debian-bookworm -> localhost | | Eo o..o | 2026-04-20 03:03:23.306244 | debian-bookworm -> localhost | | . . .... | 2026-04-20 03:03:23.306298 | debian-bookworm -> localhost | | o. S. | 2026-04-20 03:03:23.306326 | debian-bookworm -> localhost | | .o o.oo.o o | 2026-04-20 03:03:23.306347 | debian-bookworm -> localhost | | o.==.+ = | 2026-04-20 03:03:23.306367 | debian-bookworm -> localhost | | ooB+o . . | 2026-04-20 03:03:23.306389 | debian-bookworm -> localhost | | *BOo | 2026-04-20 03:03:23.306409 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-04-20 03:03:23.306464 | debian-bookworm -> localhost | ok: Runtime: 0:00:01.344470 2026-04-20 03:03:23.313665 | 2026-04-20 03:03:23.313791 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-04-20 03:03:23.343196 | debian-bookworm | ok 2026-04-20 03:03:23.353493 | debian-bookworm | included: /var/lib/zuul/builds/27105a8e9cab400a9a5d86c43cb04cc1/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-04-20 03:03:23.363247 | 2026-04-20 03:03:23.363382 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-04-20 03:03:23.387537 | debian-bookworm | skipping: Conditional result was False 2026-04-20 03:03:23.394477 | 2026-04-20 03:03:23.394615 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-04-20 03:03:24.018039 | debian-bookworm | changed 2026-04-20 03:03:24.024571 | 2026-04-20 03:03:24.024695 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-04-20 03:03:24.259072 | debian-bookworm | ok 2026-04-20 03:03:24.266054 | 2026-04-20 03:03:24.266186 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-04-20 03:03:25.029246 | debian-bookworm | changed 2026-04-20 03:03:25.045013 | 2026-04-20 03:03:25.045158 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-04-20 03:03:25.768170 | debian-bookworm | changed 2026-04-20 03:03:25.774185 | 2026-04-20 03:03:25.774319 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-04-20 03:03:25.804207 | debian-bookworm | skipping: Conditional result was False 2026-04-20 03:03:25.810659 | 2026-04-20 03:03:25.810782 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-04-20 03:03:26.971247 | debian-bookworm -> localhost | changed 2026-04-20 03:03:26.984522 | 2026-04-20 03:03:26.984671 | TASK [add-build-sshkey : Add back temp key] 2026-04-20 03:03:27.562408 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/27105a8e9cab400a9a5d86c43cb04cc1/work/27105a8e9cab400a9a5d86c43cb04cc1_id_rsa (zuul-build-sshkey) 2026-04-20 03:03:27.562724 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.045090 2026-04-20 03:03:27.571866 | 2026-04-20 03:03:27.571994 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-04-20 03:03:28.119870 | debian-bookworm | ok 2026-04-20 03:03:28.158401 | 2026-04-20 03:03:28.158570 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-04-20 03:03:28.234097 | debian-bookworm | skipping: Conditional result was False 2026-04-20 03:03:28.257559 | 2026-04-20 03:03:28.258088 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-04-20 03:03:28.751647 | debian-bookworm | ok 2026-04-20 03:03:28.761411 | 2026-04-20 03:03:28.761508 | TASK [validate-host : Define zuul_info_dir fact] 2026-04-20 03:03:28.793963 | debian-bookworm | ok 2026-04-20 03:03:28.798318 | 2026-04-20 03:03:28.798401 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-04-20 03:03:29.570873 | debian-bookworm -> localhost | ok 2026-04-20 03:03:29.577556 | 2026-04-20 03:03:29.577665 | TASK [validate-host : Collect information about the host] 2026-04-20 03:04:11.237489 | debian-bookworm | ok 2026-04-20 03:04:11.251461 | 2026-04-20 03:04:11.251618 | TASK [validate-host : Sanitize hostname] 2026-04-20 03:04:11.380084 | debian-bookworm | ok 2026-04-20 03:04:11.475066 | 2026-04-20 03:04:11.475166 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-04-20 03:04:12.821713 | debian-bookworm -> localhost | changed 2026-04-20 03:04:12.828697 | 2026-04-20 03:04:12.828789 | TASK [validate-host : Collect information about zuul worker] 2026-04-20 03:04:13.491162 | debian-bookworm | ok 2026-04-20 03:04:13.497026 | 2026-04-20 03:04:13.497119 | TASK [validate-host : Write out all zuul information for each host] 2026-04-20 03:04:14.980862 | debian-bookworm -> localhost | changed 2026-04-20 03:04:14.988450 | 2026-04-20 03:04:14.988529 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-04-20 03:04:15.282096 | debian-bookworm | ok 2026-04-20 03:04:15.289743 | 2026-04-20 03:04:15.290080 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-04-20 03:04:16.618007 | debian-bookworm | changed: 2026-04-20 03:04:16.618156 | debian-bookworm | cd+++++++++ src/ 2026-04-20 03:04:16.618185 | debian-bookworm | cd+++++++++ src/github.com/ 2026-04-20 03:04:16.618205 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-04-20 03:04:16.618224 | debian-bookworm | cd+++++++++ src/github.com/osism/generics/ 2026-04-20 03:04:16.618242 | debian-bookworm | localhost | changed 2026-04-20 03:06:38.411644 | 2026-04-20 03:06:38.411736 | TASK [fetch-tox-output : Ensure zuul-output tox dir] 2026-04-20 03:06:38.444824 | debian-bookworm | skipping: Conditional result was False 2026-04-20 03:06:38.449190 | 2026-04-20 03:06:38.449276 | TASK [fetch-tox-output : Set envlist fact] 2026-04-20 03:06:38.476983 | debian-bookworm | ok 2026-04-20 03:06:38.481556 | 2026-04-20 03:06:38.481638 | TASK [fetch-tox-output : Get tox version] 2026-04-20 03:06:39.008055 | debian-bookworm | ERROR 2026-04-20 03:06:39.008260 | debian-bookworm | { 2026-04-20 03:06:39.008289 | debian-bookworm | "msg": "[Errno 2] No such file or directory: b'tox'", 2026-04-20 03:06:39.008313 | debian-bookworm | "rc": 2 2026-04-20 03:06:39.008343 | debian-bookworm | } failure 2026-04-20 03:06:39.009577 | 2026-04-20 03:06:39.009631 | PLAY RECAP 2026-04-20 03:06:39.009671 | debian-bookworm | ok: 3 changed: 1 unreachable: 0 failed: 1 skipped: 2 rescued: 0 ignored: 0 2026-04-20 03:06:39.009690 | 2026-04-20 03:06:39.175689 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2026-04-20 03:06:39.176970 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2026-04-20 03:06:40.213026 | 2026-04-20 03:06:40.213154 | PLAY [all] 2026-04-20 03:06:40.225961 | 2026-04-20 03:06:40.226062 | TASK [fetch-subunit-output : Find stestr or testr executable] 2026-04-20 03:06:40.489121 | debian-bookworm | changed: non-zero return code 2026-04-20 03:06:40.493530 | 2026-04-20 03:06:40.493623 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2026-04-20 03:06:40.528016 | debian-bookworm | skipping: Conditional result was False 2026-04-20 03:06:40.532689 | 2026-04-20 03:06:40.532779 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2026-04-20 03:06:40.570313 | 2026-04-20 03:06:40.570488 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2026-04-20 03:06:40.598471 | 2026-04-20 03:06:40.598613 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2026-04-20 03:06:40.631238 | debian-bookworm | skipping: Conditional result was False 2026-04-20 03:06:40.636092 | 2026-04-20 03:06:40.636183 | LOOP [fetch-subunit-output : Generate subunit file] 2026-04-20 03:06:40.684398 | 2026-04-20 03:06:40.684555 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2026-04-20 03:06:40.727276 | debian-bookworm | skipping: Conditional result was False 2026-04-20 03:06:40.731870 | 2026-04-20 03:06:40.731963 | TASK [fetch-subunit-output : Remove the temporary file] 2026-04-20 03:06:40.754551 | debian-bookworm | skipping: Conditional result was False 2026-04-20 03:06:40.761047 | 2026-04-20 03:06:40.761153 | TASK [fetch-subunit-output : Process and fetch subunit results] 2026-04-20 03:06:40.796235 | debian-bookworm | skipping: Conditional result was False 2026-04-20 03:06:40.801306 | 2026-04-20 03:06:40.801390 | PLAY RECAP 2026-04-20 03:06:40.801431 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2026-04-20 03:06:40.801452 | 2026-04-20 03:06:41.015364 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2026-04-20 03:06:41.016297 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-04-20 03:06:42.182202 | 2026-04-20 03:06:42.182765 | PLAY [Base post] 2026-04-20 03:06:42.217212 | 2026-04-20 03:06:42.217364 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-04-20 03:06:42.824634 | debian-bookworm | changed 2026-04-20 03:06:42.834425 | 2026-04-20 03:06:42.834519 | PLAY RECAP 2026-04-20 03:06:42.834636 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-20 03:06:42.834748 | 2026-04-20 03:06:42.982690 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-04-20 03:06:42.983496 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-04-20 03:06:44.089104 | 2026-04-20 03:06:44.089226 | PLAY [Base post-logs] 2026-04-20 03:06:44.114541 | 2026-04-20 03:06:44.114656 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-04-20 03:06:44.694444 | localhost | changed 2026-04-20 03:06:44.702607 | 2026-04-20 03:06:44.702728 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-04-20 03:06:44.743478 | localhost | ok 2026-04-20 03:06:44.746101 | 2026-04-20 03:06:44.746173 | TASK [Set zuul-log-path fact] 2026-04-20 03:06:44.761235 | localhost | ok 2026-04-20 03:06:44.767483 | 2026-04-20 03:06:44.767564 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-20 03:06:44.791620 | localhost | ok 2026-04-20 03:06:44.793897 | 2026-04-20 03:06:44.793969 | TASK [upload-logs : Create log directories] 2026-04-20 03:06:45.214544 | localhost | changed 2026-04-20 03:06:45.216982 | 2026-04-20 03:06:45.217064 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-04-20 03:06:45.655503 | localhost -> localhost | ok: Runtime: 0:00:00.005460 2026-04-20 03:06:45.658466 | 2026-04-20 03:06:45.658546 | TASK [upload-logs : Upload logs to log server] 2026-04-20 03:06:46.199279 | localhost | Output suppressed because no_log was given 2026-04-20 03:06:46.200853 | 2026-04-20 03:06:46.200934 | LOOP [upload-logs : Compress console log and json output] 2026-04-20 03:06:46.242181 | localhost | skipping: Conditional result was False 2026-04-20 03:06:46.248420 | localhost | skipping: Conditional result was False 2026-04-20 03:06:46.256082 | 2026-04-20 03:06:46.256178 | LOOP [upload-logs : Upload compressed console log and json output] 2026-04-20 03:06:46.297603 | localhost | skipping: Conditional result was False 2026-04-20 03:06:46.297912 | 2026-04-20 03:06:46.302433 | localhost | skipping: Conditional result was False 2026-04-20 03:06:46.311135 | 2026-04-20 03:06:46.311227 | LOOP [upload-logs : Upload console log and json output]