2025-08-10 00:20:23.897546 | Job console starting 2025-08-10 00:20:23.905822 | Updating git repos 2025-08-10 00:20:23.928460 | Cloning repos into workspace 2025-08-10 00:20:23.962748 | Restoring repo states 2025-08-10 00:20:23.971139 | Merging changes 2025-08-10 00:20:24.504909 | Checking out repos 2025-08-10 00:20:24.588999 | Preparing playbooks 2025-08-10 00:20:25.760357 | Running Ansible setup 2025-08-10 00:21:52.239518 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-08-10 00:21:53.568672 | 2025-08-10 00:21:53.568792 | PLAY [Base pre] 2025-08-10 00:21:53.595463 | 2025-08-10 00:21:53.596981 | TASK [Setup log path fact] 2025-08-10 00:21:53.624386 | debian-bookworm | ok 2025-08-10 00:21:53.640359 | 2025-08-10 00:21:53.640454 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-08-10 00:21:53.710226 | debian-bookworm | ok 2025-08-10 00:21:53.728360 | 2025-08-10 00:21:53.728448 | TASK [emit-job-header : Print job information] 2025-08-10 00:21:53.806500 | # Job Information 2025-08-10 00:21:53.806662 | Ansible Version: 2.16.14 2025-08-10 00:21:53.806692 | Job: tox 2025-08-10 00:21:53.806720 | Pipeline: check 2025-08-10 00:21:53.806740 | Executor: 521e9411259a 2025-08-10 00:21:53.806758 | Triggered by: https://github.com/osism/openstack-image-manager/pull/917 2025-08-10 00:21:53.806778 | Event ID: c9a86c80-757f-11f0-851f-c0a646198a7a 2025-08-10 00:21:53.810890 | 2025-08-10 00:21:53.810975 | LOOP [emit-job-header : Print node information] 2025-08-10 00:21:53.952528 | debian-bookworm | ok: 2025-08-10 00:21:53.952723 | debian-bookworm | # Node Information 2025-08-10 00:21:53.952758 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-08-10 00:21:53.952784 | debian-bookworm | Hostname: debian 2025-08-10 00:21:53.952806 | debian-bookworm | Username: zuul 2025-08-10 00:21:53.952827 | debian-bookworm | Distro: Debian 12.11 2025-08-10 00:21:53.952851 | debian-bookworm | Provider: regiocloud-a 2025-08-10 00:21:53.952872 | debian-bookworm | Region: 2025-08-10 00:21:53.952892 | debian-bookworm | Label: debian-bookworm 2025-08-10 00:21:53.952911 | debian-bookworm | Product Name: OpenStack Nova 2025-08-10 00:21:53.952931 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:feac:7d40 2025-08-10 00:21:53.964635 | 2025-08-10 00:21:53.964754 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-08-10 00:21:54.401615 | debian-bookworm -> localhost | changed 2025-08-10 00:21:54.408260 | 2025-08-10 00:21:54.408353 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-08-10 00:21:55.357537 | debian-bookworm -> localhost | changed 2025-08-10 00:21:55.368134 | 2025-08-10 00:21:55.368226 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-08-10 00:21:55.648125 | debian-bookworm -> localhost | ok 2025-08-10 00:21:55.654188 | 2025-08-10 00:21:55.654289 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-08-10 00:21:55.681868 | debian-bookworm | ok 2025-08-10 00:21:55.699620 | debian-bookworm | included: /var/lib/zuul/builds/64dfccccf3ae4642b4340047815815c4/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-08-10 00:21:55.705795 | 2025-08-10 00:21:55.705891 | TASK [add-build-sshkey : Create Temp SSH key] 2025-08-10 00:21:57.455559 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-08-10 00:21:57.455723 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/64dfccccf3ae4642b4340047815815c4/work/64dfccccf3ae4642b4340047815815c4_id_rsa 2025-08-10 00:21:57.455755 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/64dfccccf3ae4642b4340047815815c4/work/64dfccccf3ae4642b4340047815815c4_id_rsa.pub 2025-08-10 00:21:57.455779 | debian-bookworm -> localhost | The key fingerprint is: 2025-08-10 00:21:57.455800 | debian-bookworm -> localhost | SHA256:gWiJJf6j1so6mDAf+nOpj8pz2CvEAqLLoifRhHPzKYs zuul-build-sshkey 2025-08-10 00:21:57.455819 | debian-bookworm -> localhost | The key's randomart image is: 2025-08-10 00:21:57.455850 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-08-10 00:21:57.455870 | debian-bookworm -> localhost | | . . | 2025-08-10 00:21:57.455888 | debian-bookworm -> localhost | | . + o . | 2025-08-10 00:21:57.455904 | debian-bookworm -> localhost | | .o + . . | 2025-08-10 00:21:57.455921 | debian-bookworm -> localhost | |= +o . | 2025-08-10 00:21:57.455938 | debian-bookworm -> localhost | |== oo. S | 2025-08-10 00:21:57.455962 | debian-bookworm -> localhost | |*o+oo. | 2025-08-10 00:21:57.455980 | debian-bookworm -> localhost | |*O=+.. | 2025-08-10 00:21:57.455997 | debian-bookworm -> localhost | |EB==o | 2025-08-10 00:21:57.456014 | debian-bookworm -> localhost | |*OX*o | 2025-08-10 00:21:57.456031 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-08-10 00:21:57.456074 | debian-bookworm -> localhost | ok: Runtime: 0:00:01.362384 2025-08-10 00:21:57.461142 | 2025-08-10 00:21:57.461221 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-08-10 00:21:57.477710 | debian-bookworm | ok 2025-08-10 00:21:57.485040 | debian-bookworm | included: /var/lib/zuul/builds/64dfccccf3ae4642b4340047815815c4/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-08-10 00:21:57.492073 | 2025-08-10 00:21:57.492156 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-08-10 00:21:57.505418 | debian-bookworm | skipping: Conditional result was False 2025-08-10 00:21:57.510180 | 2025-08-10 00:21:57.510259 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-08-10 00:21:58.178037 | debian-bookworm | changed 2025-08-10 00:21:58.183399 | 2025-08-10 00:21:58.183474 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-08-10 00:21:58.455169 | debian-bookworm | ok 2025-08-10 00:21:58.465855 | 2025-08-10 00:21:58.466374 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-08-10 00:21:59.227109 | debian-bookworm | changed 2025-08-10 00:21:59.233594 | 2025-08-10 00:21:59.233689 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-08-10 00:21:59.912003 | debian-bookworm | changed 2025-08-10 00:21:59.930204 | 2025-08-10 00:21:59.930347 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-08-10 00:21:59.957199 | debian-bookworm | skipping: Conditional result was False 2025-08-10 00:21:59.967295 | 2025-08-10 00:21:59.967625 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-08-10 00:22:00.521321 | debian-bookworm -> localhost | changed 2025-08-10 00:22:00.532962 | 2025-08-10 00:22:00.533058 | TASK [add-build-sshkey : Add back temp key] 2025-08-10 00:22:00.849072 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/64dfccccf3ae4642b4340047815815c4/work/64dfccccf3ae4642b4340047815815c4_id_rsa (zuul-build-sshkey) 2025-08-10 00:22:00.849438 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.009523 2025-08-10 00:22:00.860758 | 2025-08-10 00:22:00.860875 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-08-10 00:22:01.257478 | debian-bookworm | ok 2025-08-10 00:22:01.264122 | 2025-08-10 00:22:01.264215 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-08-10 00:22:01.289047 | debian-bookworm | skipping: Conditional result was False 2025-08-10 00:22:01.300021 | 2025-08-10 00:22:01.300125 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-08-10 00:22:01.746495 | debian-bookworm | ok 2025-08-10 00:22:01.762408 | 2025-08-10 00:22:01.762510 | TASK [validate-host : Define zuul_info_dir fact] 2025-08-10 00:22:01.801495 | debian-bookworm | ok 2025-08-10 00:22:01.807295 | 2025-08-10 00:22:01.807394 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-08-10 00:22:02.034782 | debian-bookworm -> localhost | ok 2025-08-10 00:22:02.040802 | 2025-08-10 00:22:02.040886 | TASK [validate-host : Collect information about the host] 2025-08-10 00:22:43.143991 | debian-bookworm | ok 2025-08-10 00:22:43.154316 | 2025-08-10 00:22:43.154404 | TASK [validate-host : Sanitize hostname] 2025-08-10 00:22:43.263849 | debian-bookworm | ok 2025-08-10 00:22:43.292226 | 2025-08-10 00:22:43.292326 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-08-10 00:22:43.839976 | debian-bookworm -> localhost | changed 2025-08-10 00:22:43.845916 | 2025-08-10 00:22:43.846015 | TASK [validate-host : Collect information about zuul worker] 2025-08-10 00:22:44.230398 | debian-bookworm | ok 2025-08-10 00:22:44.237367 | 2025-08-10 00:22:44.237492 | TASK [validate-host : Write out all zuul information for each host] 2025-08-10 00:22:44.985643 | debian-bookworm -> localhost | changed 2025-08-10 00:22:44.995728 | 2025-08-10 00:22:44.995847 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-08-10 00:22:45.268731 | debian-bookworm | ok 2025-08-10 00:22:45.274534 | 2025-08-10 00:22:45.274639 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-08-10 00:22:46.310772 | debian-bookworm | changed: 2025-08-10 00:22:46.310989 | debian-bookworm | cd+++++++++ src/ 2025-08-10 00:22:46.311024 | debian-bookworm | cd+++++++++ src/github.com/ 2025-08-10 00:22:46.311049 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-08-10 00:22:46.311109 | debian-bookworm | cd+++++++++ src/github.com/osism/openstack-image-manager/ 2025-08-10 00:22:46.311131 | debian-bookworm | localhost | changed 2025-08-10 00:25:05.512445 | 2025-08-10 00:25:05.512575 | TASK [fetch-tox-output : Ensure zuul-output tox dir] 2025-08-10 00:25:05.556631 | debian-bookworm | skipping: Conditional result was False 2025-08-10 00:25:05.568760 | 2025-08-10 00:25:05.568965 | TASK [fetch-tox-output : Set envlist fact] 2025-08-10 00:25:05.625606 | debian-bookworm | ok 2025-08-10 00:25:05.634666 | 2025-08-10 00:25:05.634796 | TASK [fetch-tox-output : Get tox version] 2025-08-10 00:25:06.176300 | debian-bookworm | ERROR 2025-08-10 00:25:06.176935 | debian-bookworm | { 2025-08-10 00:25:06.177070 | debian-bookworm | "msg": "[Errno 2] No such file or directory: b'tox'", 2025-08-10 00:25:06.177161 | debian-bookworm | "rc": 2 2025-08-10 00:25:06.177225 | debian-bookworm | } failure 2025-08-10 00:25:06.180510 | 2025-08-10 00:25:06.180667 | PLAY RECAP 2025-08-10 00:25:06.180792 | debian-bookworm | ok: 3 changed: 1 unreachable: 0 failed: 1 skipped: 2 rescued: 0 ignored: 0 2025-08-10 00:25:06.180853 | 2025-08-10 00:25:06.306365 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2025-08-10 00:25:06.309717 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2025-08-10 00:25:07.043060 | 2025-08-10 00:25:07.043241 | PLAY [all] 2025-08-10 00:25:07.059314 | 2025-08-10 00:25:07.059467 | TASK [fetch-subunit-output : Find stestr or testr executable] 2025-08-10 00:25:07.286257 | debian-bookworm | changed: non-zero return code 2025-08-10 00:25:07.293492 | 2025-08-10 00:25:07.293638 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2025-08-10 00:25:07.318274 | debian-bookworm | skipping: Conditional result was False 2025-08-10 00:25:07.328383 | 2025-08-10 00:25:07.328532 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2025-08-10 00:25:07.368150 | 2025-08-10 00:25:07.368426 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2025-08-10 00:25:07.404351 | 2025-08-10 00:25:07.404587 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2025-08-10 00:25:07.428873 | debian-bookworm | skipping: Conditional result was False 2025-08-10 00:25:07.442687 | 2025-08-10 00:25:07.442909 | LOOP [fetch-subunit-output : Generate subunit file] 2025-08-10 00:25:07.478250 | 2025-08-10 00:25:07.478640 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2025-08-10 00:25:07.503308 | debian-bookworm | skipping: Conditional result was False 2025-08-10 00:25:07.514068 | 2025-08-10 00:25:07.514236 | TASK [fetch-subunit-output : Remove the temporary file] 2025-08-10 00:25:07.538497 | debian-bookworm | skipping: Conditional result was False 2025-08-10 00:25:07.553511 | 2025-08-10 00:25:07.553725 | TASK [fetch-subunit-output : Process and fetch subunit results] 2025-08-10 00:25:07.578550 | debian-bookworm | skipping: Conditional result was False 2025-08-10 00:25:07.591350 | 2025-08-10 00:25:07.591460 | PLAY RECAP 2025-08-10 00:25:07.591540 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2025-08-10 00:25:07.591582 | 2025-08-10 00:25:07.712658 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2025-08-10 00:25:07.715422 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-08-10 00:25:08.438032 | 2025-08-10 00:25:08.438192 | PLAY [Base post] 2025-08-10 00:25:08.451659 | 2025-08-10 00:25:08.451790 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-08-10 00:25:09.013542 | debian-bookworm | changed 2025-08-10 00:25:09.021812 | 2025-08-10 00:25:09.021939 | PLAY RECAP 2025-08-10 00:25:09.022032 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-08-10 00:25:09.022108 | 2025-08-10 00:25:09.144566 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-08-10 00:25:09.149172 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2025-08-10 00:25:09.920562 | 2025-08-10 00:25:09.920728 | PLAY [Base post-logs] 2025-08-10 00:25:09.931124 | 2025-08-10 00:25:09.931260 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-08-10 00:25:10.427320 | localhost | changed 2025-08-10 00:25:10.445460 | 2025-08-10 00:25:10.445642 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-08-10 00:25:10.484224 | localhost | ok 2025-08-10 00:25:10.489819 | 2025-08-10 00:25:10.489970 | TASK [Set zuul-log-path fact] 2025-08-10 00:25:10.508761 | localhost | ok 2025-08-10 00:25:10.523711 | 2025-08-10 00:25:10.523851 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-08-10 00:25:10.561597 | localhost | ok 2025-08-10 00:25:10.567502 | 2025-08-10 00:25:10.567653 | TASK [upload-logs : Create log directories] 2025-08-10 00:25:11.078290 | localhost | changed 2025-08-10 00:25:11.082656 | 2025-08-10 00:25:11.082797 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-08-10 00:25:11.584792 | localhost -> localhost | ok: Runtime: 0:00:00.007711 2025-08-10 00:25:11.595387 | 2025-08-10 00:25:11.595596 | TASK [upload-logs : Upload logs to log server] 2025-08-10 00:25:12.155018 | localhost | Output suppressed because no_log was given 2025-08-10 00:25:12.158825 | 2025-08-10 00:25:12.159189 | LOOP [upload-logs : Compress console log and json output] 2025-08-10 00:25:12.209120 | localhost | skipping: Conditional result was False 2025-08-10 00:25:12.214184 | localhost | skipping: Conditional result was False 2025-08-10 00:25:12.222239 | 2025-08-10 00:25:12.222490 | LOOP [upload-logs : Upload compressed console log and json output] 2025-08-10 00:25:12.267683 | localhost | skipping: Conditional result was False 2025-08-10 00:25:12.268268 | 2025-08-10 00:25:12.271828 | localhost | skipping: Conditional result was False 2025-08-10 00:25:12.285511 | 2025-08-10 00:25:12.285737 | LOOP [upload-logs : Upload console log and json output]