2025-01-08 11:36:37.007863 | Job console starting... 2025-01-08 11:36:37.026316 | Updating repositories 2025-01-08 11:36:37.062485 | Preparing job workspace 2025-01-08 11:36:39.277252 | Running Ansible setup... 2025-01-08 11:36:46.622494 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-01-08 11:36:47.514627 | 2025-01-08 11:36:47.514743 | PLAY [Base pre] 2025-01-08 11:36:47.537506 | 2025-01-08 11:36:47.537611 | TASK [Setup log path fact] 2025-01-08 11:36:47.585363 | ubuntu-noble | ok 2025-01-08 11:36:47.604600 | 2025-01-08 11:36:47.604718 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-01-08 11:36:47.647601 | ubuntu-noble | ok 2025-01-08 11:36:47.654614 | 2025-01-08 11:36:47.654701 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-01-08 11:36:47.688755 | ubuntu-noble | skipping: Conditional result was False 2025-01-08 11:36:47.694562 | 2025-01-08 11:36:47.694649 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-01-08 11:36:47.748464 | ubuntu-noble | skipping: Conditional result was False 2025-01-08 11:36:47.762262 | 2025-01-08 11:36:47.762524 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-01-08 11:36:47.797036 | ubuntu-noble | skipping: Conditional result was False 2025-01-08 11:36:47.813151 | 2025-01-08 11:36:47.813674 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-01-08 11:36:47.839536 | ubuntu-noble | skipping: Conditional result was False 2025-01-08 11:36:47.849697 | 2025-01-08 11:36:47.849784 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-01-08 11:36:47.872856 | ubuntu-noble | skipping: Conditional result was False 2025-01-08 11:36:47.884048 | 2025-01-08 11:36:47.884125 | TASK [emit-job-header : Print job information] 2025-01-08 11:36:47.932516 | # Job Information 2025-01-08 11:36:47.932641 | Ansible Version: 2.15.3 2025-01-08 11:36:47.932667 | Job: tox 2025-01-08 11:36:47.932691 | Pipeline: check 2025-01-08 11:36:47.932709 | Executor: 7d211f194f6a 2025-01-08 11:36:47.932725 | Triggered by: https://github.com/osism/openstack-image-manager/pull/867 2025-01-08 11:36:47.932740 | Event ID: af324790-cdb4-11ef-808c-57368555ff30 2025-01-08 11:36:47.937893 | 2025-01-08 11:36:47.938015 | LOOP [emit-job-header : Print node information] 2025-01-08 11:36:48.071524 | ubuntu-noble | ok: 2025-01-08 11:36:48.074581 | ubuntu-noble | # Node Information 2025-01-08 11:36:48.074654 | ubuntu-noble | Inventory Hostname: ubuntu-noble 2025-01-08 11:36:48.074685 | ubuntu-noble | Hostname: ubuntu 2025-01-08 11:36:48.074719 | ubuntu-noble | Username: zuul 2025-01-08 11:36:48.074749 | ubuntu-noble | Distro: Ubuntu 24.04 2025-01-08 11:36:48.074780 | ubuntu-noble | Provider: regiocloud-a 2025-01-08 11:36:48.075017 | ubuntu-noble | Label: ubuntu-noble 2025-01-08 11:36:48.075086 | ubuntu-noble | Product Name: OpenStack Nova 2025-01-08 11:36:48.075118 | ubuntu-noble | Interface IP: 81.163.193.18 2025-01-08 11:36:48.095729 | 2025-01-08 11:36:48.095825 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-01-08 11:36:48.761493 | ubuntu-noble -> localhost | changed 2025-01-08 11:36:48.785524 | 2025-01-08 11:36:48.785644 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-01-08 11:36:49.925080 | ubuntu-noble -> localhost | changed 2025-01-08 11:36:49.946579 | 2025-01-08 11:36:49.946674 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-01-08 11:36:50.447863 | ubuntu-noble -> localhost | ok 2025-01-08 11:36:50.454115 | 2025-01-08 11:36:50.454190 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-01-08 11:36:50.516000 | ubuntu-noble | ok 2025-01-08 11:36:50.541317 | ubuntu-noble | included: /var/lib/zuul/builds/71d9c06aafaa48f6a221650f8ca0dd74/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-01-08 11:36:50.563614 | 2025-01-08 11:36:50.563707 | TASK [add-build-sshkey : Create Temp SSH key] 2025-01-08 11:36:51.990436 | ubuntu-noble -> localhost | Generating public/private rsa key pair. 2025-01-08 11:36:51.990633 | ubuntu-noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/71d9c06aafaa48f6a221650f8ca0dd74/work/71d9c06aafaa48f6a221650f8ca0dd74_id_rsa 2025-01-08 11:36:51.990665 | ubuntu-noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/71d9c06aafaa48f6a221650f8ca0dd74/work/71d9c06aafaa48f6a221650f8ca0dd74_id_rsa.pub 2025-01-08 11:36:51.990685 | ubuntu-noble -> localhost | The key fingerprint is: 2025-01-08 11:36:51.990703 | ubuntu-noble -> localhost | SHA256:j5GhwacK6SDO1N8cvHN5lUsBUq/M+QATw+61krUAnDM zuul-build-sshkey 2025-01-08 11:36:51.990721 | ubuntu-noble -> localhost | The key's randomart image is: 2025-01-08 11:36:51.990740 | ubuntu-noble -> localhost | +---[RSA 3072]----+ 2025-01-08 11:36:51.990757 | ubuntu-noble -> localhost | | . o+.o | 2025-01-08 11:36:51.990773 | ubuntu-noble -> localhost | | . E .+ o | 2025-01-08 11:36:51.990789 | ubuntu-noble -> localhost | | o Bo o | 2025-01-08 11:36:51.990804 | ubuntu-noble -> localhost | | .. .= ==oo o | 2025-01-08 11:36:51.990819 | ubuntu-noble -> localhost | |o.o. ooS =*o+ | 2025-01-08 11:36:51.990835 | ubuntu-noble -> localhost | |=o ...o oB.o= . | 2025-01-08 11:36:51.990850 | ubuntu-noble -> localhost | | o. .. =.oo. o | 2025-01-08 11:36:51.990866 | ubuntu-noble -> localhost | | o . | 2025-01-08 11:36:51.990882 | ubuntu-noble -> localhost | | | 2025-01-08 11:36:51.990897 | ubuntu-noble -> localhost | +----[SHA256]-----+ 2025-01-08 11:36:51.990935 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.521465 2025-01-08 11:36:51.997733 | 2025-01-08 11:36:51.997822 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-01-08 11:36:52.089394 | ubuntu-noble | ok 2025-01-08 11:36:52.102501 | ubuntu-noble | included: /var/lib/zuul/builds/71d9c06aafaa48f6a221650f8ca0dd74/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-01-08 11:36:52.124792 | 2025-01-08 11:36:52.124888 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-01-08 11:36:52.138433 | ubuntu-noble | skipping: Conditional result was False 2025-01-08 11:36:52.144395 | 2025-01-08 11:36:52.144474 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-01-08 11:36:52.977193 | ubuntu-noble | changed 2025-01-08 11:36:53.021605 | 2025-01-08 11:36:53.021722 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-01-08 11:36:53.350892 | ubuntu-noble | ok 2025-01-08 11:36:53.356582 | 2025-01-08 11:36:53.356675 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-01-08 11:36:54.267970 | ubuntu-noble | changed 2025-01-08 11:36:54.273725 | 2025-01-08 11:36:54.273798 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-01-08 11:36:55.120276 | ubuntu-noble | changed 2025-01-08 11:36:55.137772 | 2025-01-08 11:36:55.137861 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-01-08 11:36:55.230151 | ubuntu-noble | skipping: Conditional result was False 2025-01-08 11:36:55.241330 | 2025-01-08 11:36:55.241420 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-01-08 11:36:55.852459 | ubuntu-noble -> localhost | changed 2025-01-08 11:36:55.947619 | 2025-01-08 11:36:55.947709 | TASK [add-build-sshkey : Add back temp key] 2025-01-08 11:36:56.752496 | ubuntu-noble -> localhost | Identity added: /var/lib/zuul/builds/71d9c06aafaa48f6a221650f8ca0dd74/work/71d9c06aafaa48f6a221650f8ca0dd74_id_rsa (zuul-build-sshkey) 2025-01-08 11:36:56.752670 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.065173 2025-01-08 11:36:56.758723 | 2025-01-08 11:36:56.758807 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-01-08 11:36:57.314241 | ubuntu-noble | ok 2025-01-08 11:36:57.332747 | 2025-01-08 11:36:57.332937 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-01-08 11:36:57.386121 | ubuntu-noble | skipping: Conditional result was False 2025-01-08 11:36:57.404889 | 2025-01-08 11:36:57.404981 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-01-08 11:36:58.056612 | ubuntu-noble | ok 2025-01-08 11:36:58.071129 | 2025-01-08 11:36:58.071252 | TASK [validate-host : Define zuul_info_dir fact] 2025-01-08 11:36:58.115594 | ubuntu-noble | ok 2025-01-08 11:36:58.123058 | 2025-01-08 11:36:58.123141 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-01-08 11:36:58.438655 | ubuntu-noble -> localhost | ok 2025-01-08 11:36:58.447392 | 2025-01-08 11:36:58.447611 | TASK [validate-host : Collect information about the host] 2025-01-08 11:36:59.724034 | ubuntu-noble | ok 2025-01-08 11:36:59.756486 | 2025-01-08 11:36:59.757288 | TASK [validate-host : Sanitize hostname] 2025-01-08 11:36:59.855019 | ubuntu-noble | ok 2025-01-08 11:36:59.860314 | 2025-01-08 11:36:59.860392 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-01-08 11:37:00.446996 | ubuntu-noble -> localhost | changed 2025-01-08 11:37:00.452781 | 2025-01-08 11:37:00.452856 | TASK [validate-host : Collect information about zuul worker] 2025-01-08 11:37:01.093996 | ubuntu-noble | ok 2025-01-08 11:37:01.098904 | 2025-01-08 11:37:01.098988 | TASK [validate-host : Write out all zuul information for each host] 2025-01-08 11:37:01.623628 | ubuntu-noble -> localhost | changed 2025-01-08 11:37:01.636424 | 2025-01-08 11:37:01.636523 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-01-08 11:37:01.941089 | ubuntu-noble | ok 2025-01-08 11:37:01.949594 | 2025-01-08 11:37:01.949687 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-01-08 11:37:02.947105 | ubuntu-noble | changed: 2025-01-08 11:37:02.947406 | ubuntu-noble | cd+++++++++ src/ 2025-01-08 11:37:02.947436 | ubuntu-noble | cd+++++++++ src/github.com/ 2025-01-08 11:37:02.947455 | ubuntu-noble | cd+++++++++ src/github.com/osism/ 2025-01-08 11:37:02.947473 | ubuntu-noble | cd+++++++++ src/github.com/osism/openstack-image-manager/ 2025-01-08 11:37:02.947489 | ubuntu-noble | =5.5 (from tox) 2025-01-08 11:37:33.711315 | ubuntu-noble | Downloading cachetools-5.5.0-py3-none-any.whl.metadata (5.3 kB) 2025-01-08 11:37:33.772038 | ubuntu-noble | Collecting chardet>=5.2 (from tox) 2025-01-08 11:37:33.785472 | ubuntu-noble | Downloading chardet-5.2.0-py3-none-any.whl.metadata (3.4 kB) 2025-01-08 11:37:33.853190 | ubuntu-noble | Collecting colorama>=0.4.6 (from tox) 2025-01-08 11:37:33.865959 | ubuntu-noble | Downloading colorama-0.4.6-py2.py3-none-any.whl.metadata (17 kB) 2025-01-08 11:37:33.942888 | ubuntu-noble | Collecting filelock>=3.16.1 (from tox) 2025-01-08 11:37:33.954923 | ubuntu-noble | Downloading filelock-3.16.1-py3-none-any.whl.metadata (2.9 kB) 2025-01-08 11:37:34.056078 | ubuntu-noble | Collecting packaging>=24.1 (from tox) 2025-01-08 11:37:34.072589 | ubuntu-noble | Downloading packaging-24.2-py3-none-any.whl.metadata (3.2 kB) 2025-01-08 11:37:34.157888 | ubuntu-noble | Collecting platformdirs>=4.3.6 (from tox) 2025-01-08 11:37:34.170655 | ubuntu-noble | Downloading platformdirs-4.3.6-py3-none-any.whl.metadata (11 kB) 2025-01-08 11:37:34.251216 | ubuntu-noble | Collecting pluggy>=1.5 (from tox) 2025-01-08 11:37:34.266679 | ubuntu-noble | Downloading pluggy-1.5.0-py3-none-any.whl.metadata (4.8 kB) 2025-01-08 11:37:34.334785 | ubuntu-noble | Collecting pyproject-api>=1.8 (from tox) 2025-01-08 11:37:34.347758 | ubuntu-noble | Downloading pyproject_api-1.8.0-py3-none-any.whl.metadata (2.7 kB) 2025-01-08 11:37:34.496519 | ubuntu-noble | Collecting virtualenv>=20.26.6 (from tox) 2025-01-08 11:37:34.508142 | ubuntu-noble | Downloading virtualenv-20.28.1-py3-none-any.whl.metadata (4.5 kB) 2025-01-08 11:37:34.639745 | ubuntu-noble | Collecting distlib<1,>=0.3.7 (from virtualenv>=20.26.6->tox) 2025-01-08 11:37:34.653976 | ubuntu-noble | Downloading distlib-0.3.9-py2.py3-none-any.whl.metadata (5.2 kB) 2025-01-08 11:37:34.707096 | ubuntu-noble | Downloading tox-4.23.2-py3-none-any.whl (166 kB) 2025-01-08 11:37:34.744389 | ubuntu-noble | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 166.8/166.8 kB 5.3 MB/s eta 0:00:00 2025-01-08 11:37:34.759634 | ubuntu-noble | Downloading cachetools-5.5.0-py3-none-any.whl (9.5 kB) 2025-01-08 11:37:34.807680 | ubuntu-noble | Downloading chardet-5.2.0-py3-none-any.whl (199 kB) 2025-01-08 11:37:34.828525 | ubuntu-noble | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 199.4/199.4 kB 14.4 MB/s eta 0:00:00 2025-01-08 11:37:34.842430 | ubuntu-noble | Downloading colorama-0.4.6-py2.py3-none-any.whl (25 kB) 2025-01-08 11:37:34.866283 | ubuntu-noble | Downloading filelock-3.16.1-py3-none-any.whl (16 kB) 2025-01-08 11:37:34.888657 | ubuntu-noble | Downloading packaging-24.2-py3-none-any.whl (65 kB) 2025-01-08 11:37:34.900511 | ubuntu-noble | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 65.5/65.5 kB 7.2 MB/s eta 0:00:00 2025-01-08 11:37:34.914220 | ubuntu-noble | Downloading platformdirs-4.3.6-py3-none-any.whl (18 kB) 2025-01-08 11:37:34.993887 | ubuntu-noble | Downloading pluggy-1.5.0-py3-none-any.whl (20 kB) 2025-01-08 11:37:35.021879 | ubuntu-noble | Downloading pyproject_api-1.8.0-py3-none-any.whl (13 kB) 2025-01-08 11:37:35.056621 | ubuntu-noble | Downloading virtualenv-20.28.1-py3-none-any.whl (4.3 MB) 2025-01-08 11:37:35.290668 | ubuntu-noble | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 4.3/4.3 MB 19.5 MB/s eta 0:00:00 2025-01-08 11:37:35.308565 | ubuntu-noble | Downloading distlib-0.3.9-py2.py3-none-any.whl (468 kB) 2025-01-08 11:37:35.341528 | ubuntu-noble | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 469.0/469.0 kB 17.1 MB/s eta 0:00:00 2025-01-08 11:37:35.452510 | ubuntu-noble | Installing collected packages: distlib, pluggy, platformdirs, packaging, filelock, colorama, chardet, cachetools, virtualenv, pyproject-api, tox 2025-01-08 11:37:36.442563 | ubuntu-noble | Successfully installed cachetools-5.5.0 chardet-5.2.0 colorama-0.4.6 distlib-0.3.9 filelock-3.16.1 packaging-24.2 platformdirs-4.3.6 pluggy-1.5.0 pyproject-api-1.8.0 tox-4.23.2 virtualenv-20.28.1 2025-01-08 11:37:36.995511 | ubuntu-noble | ok: Runtime: 0:00:03.664996 2025-01-08 11:37:37.011803 | 2025-01-08 11:37:37.012075 | TASK [ensure-tox : Export installed tox_executable path] 2025-01-08 11:37:37.068174 | ubuntu-noble | ok 2025-01-08 11:37:37.077841 | 2025-01-08 11:37:37.077970 | TASK [ensure-tox : Output tox version] 2025-01-08 11:37:37.785954 | ubuntu-noble | ROOT: No tox.ini or setup.cfg or pyproject.toml or tox.toml found, assuming empty tox.ini at /home/zuul 2025-01-08 11:37:37.808224 | ubuntu-noble | 4.23.2 from /home/zuul/.local/tox/lib/python3.12/site-packages/tox/__init__.py 2025-01-08 11:37:38.116423 | ubuntu-noble | ok: Runtime: 0:00:00.495734 2025-01-08 11:37:38.189005 | 2025-01-08 11:37:38.189130 | TASK [ensure-tox : Make global symlink] 2025-01-08 11:37:38.215305 | ubuntu-noble | skipping: Conditional result was False 2025-01-08 11:37:38.256619 | 2025-01-08 11:37:38.256730 | PLAY RECAP 2025-01-08 11:37:38.256793 | ubuntu-noble | ok: 13 changed: 9 unreachable: 0 failed: 0 skipped: 14 rescued: 0 ignored: 0 2025-01-08 11:37:38.256909 | 2025-01-08 11:37:38.414685 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/tox/pre.yaml@master] 2025-01-08 11:37:38.418654 | RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/tox/run.yaml@master] 2025-01-08 11:37:39.135330 | 2025-01-08 11:37:39.135506 | PLAY [all] 2025-01-08 11:37:39.164370 | 2025-01-08 11:37:39.164530 | TASK [revoke-sudo : Check if zuul is sudoer] 2025-01-08 11:37:40.206957 | ubuntu-noble | ok: Runtime: 0:00:00.033323 2025-01-08 11:37:40.214869 | 2025-01-08 11:37:40.215026 | TASK [revoke-sudo : Remove sudo access for zuul user.] 2025-01-08 11:37:40.758387 | ubuntu-noble | ok: Runtime: 0:00:00.008746 2025-01-08 11:37:40.768119 | 2025-01-08 11:37:40.768384 | TASK [revoke-sudo : Prove that general sudo access is actually revoked.] 2025-01-08 11:37:41.195297 | ubuntu-noble | sudo: a password is required 2025-01-08 11:37:41.314041 | ubuntu-noble | ok: Runtime: 0:00:00.021714 2025-01-08 11:37:41.330812 | 2025-01-08 11:37:41.330924 | TASK [tox : Check to see if the constraints file exists] 2025-01-08 11:37:41.380340 | ubuntu-noble | skipping: Conditional result was False 2025-01-08 11:37:41.388136 | 2025-01-08 11:37:41.388274 | TASK [tox : Fail if constraints file is missing] 2025-01-08 11:37:41.433062 | ubuntu-noble | skipping: Conditional result was False 2025-01-08 11:37:41.443544 | 2025-01-08 11:37:41.443685 | TASK [tox : Record file location] 2025-01-08 11:37:41.479217 | ubuntu-noble | skipping: Conditional result was False 2025-01-08 11:37:41.495112 | 2025-01-08 11:37:41.495326 | TASK [tox : Install tox siblings] 2025-01-08 11:37:41.530684 | ubuntu-noble | ok 2025-01-08 11:37:41.540442 | ubuntu-noble | included: /var/lib/zuul/builds/71d9c06aafaa48f6a221650f8ca0dd74/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/tox/tasks/siblings.yaml 2025-01-08 11:37:41.547661 | 2025-01-08 11:37:41.547771 | TASK [tox : Run tox without tests] 2025-01-08 11:37:42.811932 | ubuntu-noble | test: install_deps> python -I -m pip install -r requirements.txt 2025-01-08 11:38:02.425625 | ubuntu-noble | test: OK (19.98 seconds) 2025-01-08 11:38:02.491579 | ubuntu-noble | congratulations :) (20.11 seconds) 2025-01-08 11:38:02.610708 | ubuntu-noble | ok: Runtime: 0:00:20.501937 2025-01-08 11:38:02.639883 | 2025-01-08 11:38:02.640048 | TASK [tox : Create a tempfile to save tox showconfig] 2025-01-08 11:38:03.186046 | ubuntu-noble | changed 2025-01-08 11:38:03.199991 | 2025-01-08 11:38:03.200152 | TASK [tox : Get tox envlist config] 2025-01-08 11:38:04.266630 | ubuntu-noble | ok: Runtime: 0:00:00.386613 2025-01-08 11:38:04.291478 | 2025-01-08 11:38:04.291780 | TASK [tox : Install any sibling python packages] 2025-01-08 11:38:04.852404 | ubuntu-noble | ok: No setup.cfg, no action needed 2025-01-08 11:38:04.859273 | 2025-01-08 11:38:04.859437 | TASK [tox : Remove tempfile] 2025-01-08 11:38:05.503921 | ubuntu-noble | changed 2025-01-08 11:38:05.516488 | 2025-01-08 11:38:05.516664 | TASK [tox : Emit tox command] 2025-01-08 11:38:05.580939 | /home/zuul/.local/tox/bin/tox -etest -- test/unit 2025-01-08 11:38:05.587732 | 2025-01-08 11:38:05.587856 | TASK [tox : Run tox] 2025-01-08 11:38:06.710557 | ubuntu-noble | test: commands[0]> python -m unittest discover test/unit 2025-01-08 11:38:08.417696 | ubuntu-noble | ............... 2025-01-08 11:38:08.418013 | ubuntu-noble | ---------------------------------------------------------------------- 2025-01-08 11:38:08.419833 | ubuntu-noble | Ran 15 tests in 0.234s 2025-01-08 11:38:08.636670 | ubuntu-noble | 2025-01-08 11:38:08.636721 | ubuntu-noble | OK 2025-01-08 11:38:08.636735 | ubuntu-noble | test: OK (2.02=setup[0.10]+cmd[1.92] seconds) 2025-01-08 11:38:08.785349 | ubuntu-noble | congratulations :) (2.14 seconds) 2025-01-08 11:38:09.126387 | ubuntu-noble | ok: Runtime: 0:00:02.621591 2025-01-08 11:38:09.136563 | 2025-01-08 11:38:09.136677 | TASK [tox : Look for output] 2025-01-08 11:38:09.885782 | ubuntu-noble | ok 2025-01-08 11:38:09.902707 | 2025-01-08 11:38:09.903340 | TASK [tox : Return file comments to Zuul] 2025-01-08 11:38:09.974317 | ubuntu-noble | skipping: Conditional result was False 2025-01-08 11:38:10.030271 | 2025-01-08 11:38:10.030394 | PLAY RECAP 2025-01-08 11:38:10.030457 | ubuntu-noble | ok: 12 changed: 8 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 0 2025-01-08 11:38:10.030485 | 2025-01-08 11:38:10.168809 | RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/tox/run.yaml@master] 2025-01-08 11:38:10.182527 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/tox/post.yaml@master] 2025-01-08 11:38:10.887811 | 2025-01-08 11:38:10.887971 | PLAY [all] 2025-01-08 11:38:10.916382 | 2025-01-08 11:38:10.916532 | TASK [fetch-tox-output : Set tox log path for multiple nodes] 2025-01-08 11:38:10.983328 | ubuntu-noble | skipping: Conditional result was False 2025-01-08 11:38:10.990509 | 2025-01-08 11:38:10.990656 | TASK [fetch-tox-output : Set tox log path for single node] 2025-01-08 11:38:11.050790 | ubuntu-noble | ok 2025-01-08 11:38:11.061405 | 2025-01-08 11:38:11.061558 | TASK [fetch-tox-output : Ensure local tox dir] 2025-01-08 11:38:11.565640 | ubuntu-noble -> localhost | changed 2025-01-08 11:38:11.574599 | 2025-01-08 11:38:11.574719 | TASK [fetch-tox-output : Ensure zuul-output tox dir] 2025-01-08 11:38:11.641234 | ubuntu-noble | skipping: Conditional result was False 2025-01-08 11:38:11.655898 | 2025-01-08 11:38:11.656099 | TASK [fetch-tox-output : Set envlist fact] 2025-01-08 11:38:11.712293 | ubuntu-noble | ok 2025-01-08 11:38:11.720199 | 2025-01-08 11:38:11.720342 | TASK [fetch-tox-output : Get tox version] 2025-01-08 11:38:12.974559 | ubuntu-noble | ROOT: No tox.ini or setup.cfg or pyproject.toml or tox.toml found, assuming empty tox.ini at /home/zuul 2025-01-08 11:38:12.988496 | ubuntu-noble | 4.23.2 from /home/zuul/.local/tox/lib/python3.12/site-packages/tox/__init__.py 2025-01-08 11:38:13.263427 | ubuntu-noble | ok: Runtime: 0:00:00.541285 2025-01-08 11:38:13.274623 | 2025-01-08 11:38:13.274778 | TASK [fetch-tox-output : Find all default environments] 2025-01-08 11:38:13.355975 | ubuntu-noble | skipping: Conditional result was False 2025-01-08 11:38:13.372763 | 2025-01-08 11:38:13.372904 | TASK [fetch-tox-output : Set envlist fact] 2025-01-08 11:38:13.418676 | ubuntu-noble | skipping: Conditional result was False 2025-01-08 11:38:13.426681 | 2025-01-08 11:38:13.426804 | TASK [fetch-tox-output : Find all default environments] 2025-01-08 11:38:13.504858 | ubuntu-noble | skipping: Conditional result was False 2025-01-08 11:38:13.515812 | 2025-01-08 11:38:13.515970 | TASK [fetch-tox-output : Set envlist fact] 2025-01-08 11:38:13.561592 | ubuntu-noble | skipping: Conditional result was False 2025-01-08 11:38:13.578679 | 2025-01-08 11:38:13.578856 | LOOP [fetch-tox-output : Copy tox logs] 2025-01-08 11:38:13.633807 | ubuntu-noble | skipping: Conditional result was False 2025-01-08 11:38:13.644140 | 2025-01-08 11:38:13.644307 | LOOP [fetch-tox-output : Collect tox logs] 2025-01-08 11:38:14.718623 | ubuntu-noble | changed: 2025-01-08 11:38:14.718906 | ubuntu-noble | cd+++++++++ ./ 2025-01-08 11:38:14.718949 | ubuntu-noble | >f+++++++++ 1-commands[0].log 2025-01-08 11:38:14.718984 | 2025-01-08 11:38:14.761745 | 2025-01-08 11:38:14.761867 | PLAY RECAP 2025-01-08 11:38:14.761965 | ubuntu-noble | ok: 5 changed: 3 unreachable: 0 failed: 0 skipped: 7 rescued: 0 ignored: 0 2025-01-08 11:38:14.761995 | 2025-01-08 11:38:14.904814 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/tox/post.yaml@master] 2025-01-08 11:38:14.914432 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2025-01-08 11:38:15.626595 | 2025-01-08 11:38:15.626799 | PLAY [all] 2025-01-08 11:38:15.652367 | 2025-01-08 11:38:15.652477 | TASK [fetch-subunit-output : Find stestr or testr executable] 2025-01-08 11:38:15.869669 | ubuntu-noble | changed: non-zero return code 2025-01-08 11:38:15.876664 | 2025-01-08 11:38:15.876774 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2025-01-08 11:38:15.924895 | ubuntu-noble | skipping: Conditional result was False 2025-01-08 11:38:15.933067 | 2025-01-08 11:38:15.933177 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2025-01-08 11:38:15.972943 | 2025-01-08 11:38:15.973316 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2025-01-08 11:38:16.006571 | 2025-01-08 11:38:16.006747 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2025-01-08 11:38:16.030746 | ubuntu-noble | skipping: Conditional result was False 2025-01-08 11:38:16.037926 | 2025-01-08 11:38:16.038017 | LOOP [fetch-subunit-output : Generate subunit file] 2025-01-08 11:38:16.062095 | 2025-01-08 11:38:16.062281 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2025-01-08 11:38:16.076906 | ubuntu-noble | skipping: Conditional result was False 2025-01-08 11:38:16.084406 | 2025-01-08 11:38:16.084515 | TASK [fetch-subunit-output : Remove the temporary file] 2025-01-08 11:38:16.098339 | ubuntu-noble | skipping: Conditional result was False 2025-01-08 11:38:16.105278 | 2025-01-08 11:38:16.105363 | TASK [fetch-subunit-output : Process and fetch subunit results] 2025-01-08 11:38:16.119692 | ubuntu-noble | skipping: Conditional result was False 2025-01-08 11:38:16.158260 | 2025-01-08 11:38:16.158330 | PLAY RECAP 2025-01-08 11:38:16.158384 | ubuntu-noble | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2025-01-08 11:38:16.158409 | 2025-01-08 11:38:16.263064 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2025-01-08 11:38:16.266524 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-01-08 11:38:16.779858 | 2025-01-08 11:38:16.779962 | PLAY [Base post] 2025-01-08 11:38:16.801444 | 2025-01-08 11:38:16.801534 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-01-08 11:38:17.860631 | ubuntu-noble | changed 2025-01-08 11:38:17.889285 | 2025-01-08 11:38:17.889379 | PLAY RECAP 2025-01-08 11:38:17.889431 | ubuntu-noble | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-01-08 11:38:17.889481 | 2025-01-08 11:38:17.981048 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-01-08 11:38:17.984160 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2025-01-08 11:38:18.580044 | 2025-01-08 11:38:18.580154 | PLAY [Base post-logs] 2025-01-08 11:38:18.603235 | 2025-01-08 11:38:18.603411 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-01-08 11:38:18.988041 | localhost | changed 2025-01-08 11:38:18.991666 | 2025-01-08 11:38:18.991772 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-01-08 11:38:19.018512 | localhost | ok 2025-01-08 11:38:19.023893 | 2025-01-08 11:38:19.023972 | TASK [Set zuul-log-path fact] 2025-01-08 11:38:19.039985 | localhost | ok 2025-01-08 11:38:19.053157 | 2025-01-08 11:38:19.053351 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-01-08 11:38:19.100833 | localhost | ok 2025-01-08 11:38:19.105404 | 2025-01-08 11:38:19.105486 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-01-08 11:38:19.139587 | localhost | skipping: Conditional result was False 2025-01-08 11:38:19.143038 | 2025-01-08 11:38:19.143132 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-01-08 11:38:19.197553 | localhost | skipping: Conditional result was False 2025-01-08 11:38:19.200971 | 2025-01-08 11:38:19.201063 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-01-08 11:38:19.225286 | localhost | skipping: Conditional result was False 2025-01-08 11:38:19.228485 | 2025-01-08 11:38:19.228573 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-01-08 11:38:19.254138 | localhost | skipping: Conditional result was False 2025-01-08 11:38:19.257663 | 2025-01-08 11:38:19.257752 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-01-08 11:38:19.285493 | localhost | skipping: Conditional result was False 2025-01-08 11:38:19.291509 | 2025-01-08 11:38:19.291604 | TASK [upload-logs : Create log directories] 2025-01-08 11:38:19.832460 | localhost | changed 2025-01-08 11:38:19.837300 | 2025-01-08 11:38:19.837635 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-01-08 11:38:20.295091 | localhost -> localhost | ok: Runtime: 0:00:00.003892 2025-01-08 11:38:20.308945 | 2025-01-08 11:38:20.309089 | TASK [upload-logs : Upload logs to log server] 2025-01-08 11:38:20.871210 | localhost | Output suppressed because no_log was given 2025-01-08 11:38:20.875564 | 2025-01-08 11:38:20.875660 | LOOP [upload-logs : Compress console log and json output] 2025-01-08 11:38:20.929206 | localhost | skipping: Conditional result was False 2025-01-08 11:38:20.954776 | localhost | skipping: Conditional result was False 2025-01-08 11:38:20.963980 | 2025-01-08 11:38:20.964073 | LOOP [upload-logs : Upload compressed console log and json output] 2025-01-08 11:38:21.037168 | localhost | skipping: Conditional result was False 2025-01-08 11:38:21.037433 | 2025-01-08 11:38:21.046999 | localhost | skipping: Conditional result was False 2025-01-08 11:38:21.052161 | 2025-01-08 11:38:21.052266 | LOOP [upload-logs : Upload console log and json output]