2025-01-07 16:48:47.671656 | Job console starting... 2025-01-07 16:48:47.712280 | Updating repositories 2025-01-07 16:48:47.772247 | Preparing job workspace 2025-01-07 16:48:49.974086 | Running Ansible setup... 2025-01-07 16:48:57.129804 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-01-07 16:48:58.267757 | 2025-01-07 16:48:58.267880 | PLAY [Base pre] 2025-01-07 16:48:58.333993 | 2025-01-07 16:48:58.334145 | TASK [Setup log path fact] 2025-01-07 16:48:58.381076 | ubuntu-noble | ok 2025-01-07 16:48:58.424737 | 2025-01-07 16:48:58.424870 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-01-07 16:48:58.494939 | ubuntu-noble | ok 2025-01-07 16:48:58.508556 | 2025-01-07 16:48:58.508658 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-01-07 16:48:58.572609 | ubuntu-noble | skipping: Conditional result was False 2025-01-07 16:48:58.578574 | 2025-01-07 16:48:58.578669 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-01-07 16:48:58.629707 | ubuntu-noble | skipping: Conditional result was False 2025-01-07 16:48:58.635843 | 2025-01-07 16:48:58.635928 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-01-07 16:48:58.679266 | ubuntu-noble | skipping: Conditional result was False 2025-01-07 16:48:58.685494 | 2025-01-07 16:48:58.685583 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-01-07 16:48:58.738959 | ubuntu-noble | skipping: Conditional result was False 2025-01-07 16:48:58.747557 | 2025-01-07 16:48:58.747647 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-01-07 16:48:58.763800 | ubuntu-noble | skipping: Conditional result was False 2025-01-07 16:48:58.778835 | 2025-01-07 16:48:58.778927 | TASK [emit-job-header : Print job information] 2025-01-07 16:48:58.949367 | # Job Information 2025-01-07 16:48:58.949508 | Ansible Version: 2.15.3 2025-01-07 16:48:58.949534 | Job: tox 2025-01-07 16:48:58.949559 | Pipeline: check 2025-01-07 16:48:58.949576 | Executor: 7d211f194f6a 2025-01-07 16:48:58.949591 | Triggered by: https://github.com/osism/openstack-image-manager/pull/868 2025-01-07 16:48:58.949606 | Event ID: 3e292340-cd17-11ef-8023-3d5f0147d25d 2025-01-07 16:48:58.960245 | 2025-01-07 16:48:58.960348 | LOOP [emit-job-header : Print node information] 2025-01-07 16:48:59.143004 | ubuntu-noble | ok: 2025-01-07 16:48:59.143150 | ubuntu-noble | # Node Information 2025-01-07 16:48:59.143180 | ubuntu-noble | Inventory Hostname: ubuntu-noble 2025-01-07 16:48:59.143201 | ubuntu-noble | Hostname: ubuntu 2025-01-07 16:48:59.143219 | ubuntu-noble | Username: zuul 2025-01-07 16:48:59.143235 | ubuntu-noble | Distro: Ubuntu 24.04 2025-01-07 16:48:59.143251 | ubuntu-noble | Provider: regiocloud-a 2025-01-07 16:48:59.143267 | ubuntu-noble | Label: ubuntu-noble 2025-01-07 16:48:59.143283 | ubuntu-noble | Product Name: OpenStack Nova 2025-01-07 16:48:59.143301 | ubuntu-noble | Interface IP: 81.163.193.201 2025-01-07 16:48:59.165304 | 2025-01-07 16:48:59.165399 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-01-07 16:49:00.071110 | ubuntu-noble -> localhost | changed 2025-01-07 16:49:00.079265 | 2025-01-07 16:49:00.079357 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-01-07 16:49:01.609619 | ubuntu-noble -> localhost | changed 2025-01-07 16:49:01.627853 | 2025-01-07 16:49:01.627946 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-01-07 16:49:02.092345 | ubuntu-noble -> localhost | ok 2025-01-07 16:49:02.099073 | 2025-01-07 16:49:02.099155 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-01-07 16:49:02.129833 | ubuntu-noble | ok 2025-01-07 16:49:02.177906 | ubuntu-noble | included: /var/lib/zuul/builds/43d202af5656434483873d3a2650db7f/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-01-07 16:49:02.210575 | 2025-01-07 16:49:02.210696 | TASK [add-build-sshkey : Create Temp SSH key] 2025-01-07 16:49:03.503497 | ubuntu-noble -> localhost | Generating public/private rsa key pair. 2025-01-07 16:49:03.503696 | ubuntu-noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/43d202af5656434483873d3a2650db7f/work/43d202af5656434483873d3a2650db7f_id_rsa 2025-01-07 16:49:03.503729 | ubuntu-noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/43d202af5656434483873d3a2650db7f/work/43d202af5656434483873d3a2650db7f_id_rsa.pub 2025-01-07 16:49:03.503752 | ubuntu-noble -> localhost | The key fingerprint is: 2025-01-07 16:49:03.503771 | ubuntu-noble -> localhost | SHA256:CtVjXDKzjejnKW4mJFB9peQhB4LERU3Xdf69NkkTtFw zuul-build-sshkey 2025-01-07 16:49:03.503789 | ubuntu-noble -> localhost | The key's randomart image is: 2025-01-07 16:49:03.503822 | ubuntu-noble -> localhost | +---[RSA 3072]----+ 2025-01-07 16:49:03.503842 | ubuntu-noble -> localhost | |oo++=o+o* o. . .E| 2025-01-07 16:49:03.503859 | ubuntu-noble -> localhost | |.....*o* X o o o| 2025-01-07 16:49:03.503876 | ubuntu-noble -> localhost | | . .= B . . + | 2025-01-07 16:49:03.503892 | ubuntu-noble -> localhost | |. o . . . o| 2025-01-07 16:49:03.503907 | ubuntu-noble -> localhost | | . . . S +.| 2025-01-07 16:49:03.503922 | ubuntu-noble -> localhost | | . .. + . . +| 2025-01-07 16:49:03.503939 | ubuntu-noble -> localhost | | o o o = | 2025-01-07 16:49:03.503955 | ubuntu-noble -> localhost | | ..o. . .| 2025-01-07 16:49:03.503971 | ubuntu-noble -> localhost | | +. | 2025-01-07 16:49:03.503987 | ubuntu-noble -> localhost | +----[SHA256]-----+ 2025-01-07 16:49:03.504025 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.221029 2025-01-07 16:49:03.510466 | 2025-01-07 16:49:03.510552 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-01-07 16:49:03.553510 | ubuntu-noble | ok 2025-01-07 16:49:03.580247 | ubuntu-noble | included: /var/lib/zuul/builds/43d202af5656434483873d3a2650db7f/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-01-07 16:49:03.595829 | 2025-01-07 16:49:03.595930 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-01-07 16:49:03.642570 | ubuntu-noble | skipping: Conditional result was False 2025-01-07 16:49:03.653821 | 2025-01-07 16:49:03.654151 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-01-07 16:49:04.535043 | ubuntu-noble | changed 2025-01-07 16:49:04.545689 | 2025-01-07 16:49:04.545793 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-01-07 16:49:04.839968 | ubuntu-noble | ok 2025-01-07 16:49:04.861846 | 2025-01-07 16:49:04.862104 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-01-07 16:49:05.639468 | ubuntu-noble | changed 2025-01-07 16:49:05.651568 | 2025-01-07 16:49:05.651800 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-01-07 16:49:06.422128 | ubuntu-noble | changed 2025-01-07 16:49:06.434335 | 2025-01-07 16:49:06.434437 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-01-07 16:49:06.468396 | ubuntu-noble | skipping: Conditional result was False 2025-01-07 16:49:06.476691 | 2025-01-07 16:49:06.476787 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-01-07 16:49:07.060051 | ubuntu-noble -> localhost | changed 2025-01-07 16:49:07.073213 | 2025-01-07 16:49:07.073309 | TASK [add-build-sshkey : Add back temp key] 2025-01-07 16:49:07.887055 | ubuntu-noble -> localhost | Identity added: /var/lib/zuul/builds/43d202af5656434483873d3a2650db7f/work/43d202af5656434483873d3a2650db7f_id_rsa (zuul-build-sshkey) 2025-01-07 16:49:07.887262 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.028430 2025-01-07 16:49:07.894729 | 2025-01-07 16:49:07.894825 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-01-07 16:49:08.467898 | ubuntu-noble | ok 2025-01-07 16:49:08.475726 | 2025-01-07 16:49:08.475819 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-01-07 16:49:08.541261 | ubuntu-noble | skipping: Conditional result was False 2025-01-07 16:49:08.554435 | 2025-01-07 16:49:08.554537 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-01-07 16:49:09.135547 | ubuntu-noble | ok 2025-01-07 16:49:09.161609 | 2025-01-07 16:49:09.161721 | TASK [validate-host : Define zuul_info_dir fact] 2025-01-07 16:49:09.196586 | ubuntu-noble | ok 2025-01-07 16:49:09.203463 | 2025-01-07 16:49:09.203557 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-01-07 16:49:09.891523 | ubuntu-noble -> localhost | ok 2025-01-07 16:49:09.910480 | 2025-01-07 16:49:09.910575 | TASK [validate-host : Collect information about the host] 2025-01-07 16:49:11.595828 | ubuntu-noble | ok 2025-01-07 16:49:11.612692 | 2025-01-07 16:49:11.612782 | TASK [validate-host : Sanitize hostname] 2025-01-07 16:49:11.777873 | ubuntu-noble | ok 2025-01-07 16:49:11.782996 | 2025-01-07 16:49:11.783106 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-01-07 16:49:12.656328 | ubuntu-noble -> localhost | changed 2025-01-07 16:49:12.665124 | 2025-01-07 16:49:12.665223 | TASK [validate-host : Collect information about zuul worker] 2025-01-07 16:49:13.299999 | ubuntu-noble | ok 2025-01-07 16:49:13.304765 | 2025-01-07 16:49:13.304843 | TASK [validate-host : Write out all zuul information for each host] 2025-01-07 16:49:14.641381 | ubuntu-noble -> localhost | changed 2025-01-07 16:49:14.659682 | 2025-01-07 16:49:14.659774 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-01-07 16:49:15.042016 | ubuntu-noble | ok 2025-01-07 16:49:15.047638 | 2025-01-07 16:49:15.047721 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-01-07 16:49:16.044356 | ubuntu-noble | changed: 2025-01-07 16:49:16.044497 | ubuntu-noble | cd+++++++++ src/ 2025-01-07 16:49:16.044523 | ubuntu-noble | cd+++++++++ src/github.com/ 2025-01-07 16:49:16.044542 | ubuntu-noble | cd+++++++++ src/github.com/osism/ 2025-01-07 16:49:16.044559 | ubuntu-noble | cd+++++++++ src/github.com/osism/openstack-image-manager/ 2025-01-07 16:49:16.044575 | ubuntu-noble | =5.5 (from tox) 2025-01-07 16:49:48.024251 | ubuntu-noble | Downloading cachetools-5.5.0-py3-none-any.whl.metadata (5.3 kB) 2025-01-07 16:49:48.085147 | ubuntu-noble | Collecting chardet>=5.2 (from tox) 2025-01-07 16:49:48.099590 | ubuntu-noble | Downloading chardet-5.2.0-py3-none-any.whl.metadata (3.4 kB) 2025-01-07 16:49:48.172601 | ubuntu-noble | Collecting colorama>=0.4.6 (from tox) 2025-01-07 16:49:48.185373 | ubuntu-noble | Downloading colorama-0.4.6-py2.py3-none-any.whl.metadata (17 kB) 2025-01-07 16:49:48.258917 | ubuntu-noble | Collecting filelock>=3.16.1 (from tox) 2025-01-07 16:49:48.275046 | ubuntu-noble | Downloading filelock-3.16.1-py3-none-any.whl.metadata (2.9 kB) 2025-01-07 16:49:48.355846 | ubuntu-noble | Collecting packaging>=24.1 (from tox) 2025-01-07 16:49:48.367924 | ubuntu-noble | Downloading packaging-24.2-py3-none-any.whl.metadata (3.2 kB) 2025-01-07 16:49:48.446878 | ubuntu-noble | Collecting platformdirs>=4.3.6 (from tox) 2025-01-07 16:49:48.460625 | ubuntu-noble | Downloading platformdirs-4.3.6-py3-none-any.whl.metadata (11 kB) 2025-01-07 16:49:48.605980 | ubuntu-noble | Collecting pluggy>=1.5 (from tox) 2025-01-07 16:49:48.622517 | ubuntu-noble | Downloading pluggy-1.5.0-py3-none-any.whl.metadata (4.8 kB) 2025-01-07 16:49:48.692646 | ubuntu-noble | Collecting pyproject-api>=1.8 (from tox) 2025-01-07 16:49:48.710453 | ubuntu-noble | Downloading pyproject_api-1.8.0-py3-none-any.whl.metadata (2.7 kB) 2025-01-07 16:49:48.879393 | ubuntu-noble | Collecting virtualenv>=20.26.6 (from tox) 2025-01-07 16:49:48.895858 | ubuntu-noble | Downloading virtualenv-20.28.1-py3-none-any.whl.metadata (4.5 kB) 2025-01-07 16:49:49.024697 | ubuntu-noble | Collecting distlib<1,>=0.3.7 (from virtualenv>=20.26.6->tox) 2025-01-07 16:49:49.039412 | ubuntu-noble | Downloading distlib-0.3.9-py2.py3-none-any.whl.metadata (5.2 kB) 2025-01-07 16:49:49.092492 | ubuntu-noble | Downloading tox-4.23.2-py3-none-any.whl (166 kB) 2025-01-07 16:49:49.137890 | ubuntu-noble | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 166.8/166.8 kB 4.3 MB/s eta 0:00:00 2025-01-07 16:49:49.153482 | ubuntu-noble | Downloading cachetools-5.5.0-py3-none-any.whl (9.5 kB) 2025-01-07 16:49:49.180943 | ubuntu-noble | Downloading chardet-5.2.0-py3-none-any.whl (199 kB) 2025-01-07 16:49:49.200925 | ubuntu-noble | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 199.4/199.4 kB 14.7 MB/s eta 0:00:00 2025-01-07 16:49:49.215900 | ubuntu-noble | Downloading colorama-0.4.6-py2.py3-none-any.whl (25 kB) 2025-01-07 16:49:49.242894 | ubuntu-noble | Downloading filelock-3.16.1-py3-none-any.whl (16 kB) 2025-01-07 16:49:49.266179 | ubuntu-noble | Downloading packaging-24.2-py3-none-any.whl (65 kB) 2025-01-07 16:49:49.280460 | ubuntu-noble | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 65.5/65.5 kB 6.0 MB/s eta 0:00:00 2025-01-07 16:49:49.294100 | ubuntu-noble | Downloading platformdirs-4.3.6-py3-none-any.whl (18 kB) 2025-01-07 16:49:49.318348 | ubuntu-noble | Downloading pluggy-1.5.0-py3-none-any.whl (20 kB) 2025-01-07 16:49:49.346154 | ubuntu-noble | Downloading pyproject_api-1.8.0-py3-none-any.whl (13 kB) 2025-01-07 16:49:49.374908 | ubuntu-noble | Downloading virtualenv-20.28.1-py3-none-any.whl (4.3 MB) 2025-01-07 16:49:49.530177 | ubuntu-noble | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 4.3/4.3 MB 28.8 MB/s eta 0:00:00 2025-01-07 16:49:49.554651 | ubuntu-noble | Downloading distlib-0.3.9-py2.py3-none-any.whl (468 kB) 2025-01-07 16:49:49.588629 | ubuntu-noble | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 469.0/469.0 kB 17.5 MB/s eta 0:00:00 2025-01-07 16:49:49.701842 | ubuntu-noble | Installing collected packages: distlib, pluggy, platformdirs, packaging, filelock, colorama, chardet, cachetools, virtualenv, pyproject-api, tox 2025-01-07 16:49:50.856131 | 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-07 16:49:51.387264 | ubuntu-noble | ok: Runtime: 0:00:04.018097 2025-01-07 16:49:51.399579 | 2025-01-07 16:49:51.399674 | TASK [ensure-tox : Export installed tox_executable path] 2025-01-07 16:49:51.481283 | ubuntu-noble | ok 2025-01-07 16:49:51.489615 | 2025-01-07 16:49:51.489719 | TASK [ensure-tox : Output tox version] 2025-01-07 16:49:52.515865 | 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-07 16:49:52.535128 | ubuntu-noble | 4.23.2 from /home/zuul/.local/tox/lib/python3.12/site-packages/tox/__init__.py 2025-01-07 16:49:53.058255 | ubuntu-noble | ok: Runtime: 0:00:00.605850 2025-01-07 16:49:53.072038 | 2025-01-07 16:49:53.072156 | TASK [ensure-tox : Make global symlink] 2025-01-07 16:49:53.124477 | ubuntu-noble | skipping: Conditional result was False 2025-01-07 16:49:53.184745 | 2025-01-07 16:49:53.184830 | PLAY RECAP 2025-01-07 16:49:53.185055 | ubuntu-noble | ok: 13 changed: 9 unreachable: 0 failed: 0 skipped: 14 rescued: 0 ignored: 0 2025-01-07 16:49:53.185114 | 2025-01-07 16:49:53.323152 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/tox/pre.yaml@master] 2025-01-07 16:49:53.328339 | RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/tox/run.yaml@master] 2025-01-07 16:49:54.064652 | 2025-01-07 16:49:54.064908 | PLAY [all] 2025-01-07 16:49:54.097905 | 2025-01-07 16:49:54.098134 | TASK [revoke-sudo : Check if zuul is sudoer] 2025-01-07 16:49:55.171090 | ubuntu-noble | ok: Runtime: 0:00:00.020383 2025-01-07 16:49:55.181216 | 2025-01-07 16:49:55.181692 | TASK [revoke-sudo : Remove sudo access for zuul user.] 2025-01-07 16:49:56.234018 | ubuntu-noble | ok: Runtime: 0:00:00.011208 2025-01-07 16:49:56.241222 | 2025-01-07 16:49:56.241317 | TASK [revoke-sudo : Prove that general sudo access is actually revoked.] 2025-01-07 16:49:56.622931 | ubuntu-noble | sudo: a password is required 2025-01-07 16:49:56.776740 | ubuntu-noble | ok: Runtime: 0:00:00.017681 2025-01-07 16:49:56.803645 | 2025-01-07 16:49:56.803774 | TASK [tox : Check to see if the constraints file exists] 2025-01-07 16:49:56.860081 | ubuntu-noble | skipping: Conditional result was False 2025-01-07 16:49:56.872966 | 2025-01-07 16:49:56.873124 | TASK [tox : Fail if constraints file is missing] 2025-01-07 16:49:56.909656 | ubuntu-noble | skipping: Conditional result was False 2025-01-07 16:49:56.917632 | 2025-01-07 16:49:56.917748 | TASK [tox : Record file location] 2025-01-07 16:49:56.962257 | ubuntu-noble | skipping: Conditional result was False 2025-01-07 16:49:56.969632 | 2025-01-07 16:49:56.970049 | TASK [tox : Install tox siblings] 2025-01-07 16:49:56.989196 | ubuntu-noble | ok 2025-01-07 16:49:56.997843 | ubuntu-noble | included: /var/lib/zuul/builds/43d202af5656434483873d3a2650db7f/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/tox/tasks/siblings.yaml 2025-01-07 16:49:57.005964 | 2025-01-07 16:49:57.006070 | TASK [tox : Run tox without tests] 2025-01-07 16:49:58.167886 | ubuntu-noble | test: install_deps> python -I -m pip install -r requirements.txt 2025-01-07 16:50:18.481975 | ubuntu-noble | test: OK (20.67 seconds) 2025-01-07 16:50:18.543359 | ubuntu-noble | congratulations :) (20.77 seconds) 2025-01-07 16:50:18.588625 | ubuntu-noble | ok: Runtime: 0:00:21.121141 2025-01-07 16:50:18.596979 | 2025-01-07 16:50:18.597099 | TASK [tox : Create a tempfile to save tox showconfig] 2025-01-07 16:50:19.026934 | ubuntu-noble | changed 2025-01-07 16:50:19.042630 | 2025-01-07 16:50:19.042758 | TASK [tox : Get tox envlist config] 2025-01-07 16:50:20.083828 | ubuntu-noble | ok: Runtime: 0:00:00.388665 2025-01-07 16:50:20.103756 | 2025-01-07 16:50:20.103949 | TASK [tox : Install any sibling python packages] 2025-01-07 16:50:20.934445 | ubuntu-noble | ok: No setup.cfg, no action needed 2025-01-07 16:50:20.944318 | 2025-01-07 16:50:20.944441 | TASK [tox : Remove tempfile] 2025-01-07 16:50:21.638854 | ubuntu-noble | changed 2025-01-07 16:50:21.647617 | 2025-01-07 16:50:21.647727 | TASK [tox : Emit tox command] 2025-01-07 16:50:21.715461 | /home/zuul/.local/tox/bin/tox -etest -- test/unit 2025-01-07 16:50:21.722160 | 2025-01-07 16:50:21.722276 | TASK [tox : Run tox] 2025-01-07 16:50:23.058189 | ubuntu-noble | test: commands[0]> python -m unittest discover test/unit 2025-01-07 16:50:24.462685 | ubuntu-noble | ............... 2025-01-07 16:50:24.463015 | ubuntu-noble | ---------------------------------------------------------------------- 2025-01-07 16:50:24.464033 | ubuntu-noble | Ran 15 tests in 0.326s 2025-01-07 16:50:24.605829 | ubuntu-noble | 2025-01-07 16:50:24.605874 | ubuntu-noble | OK 2025-01-07 16:50:24.605889 | ubuntu-noble | test: OK (1.61=setup[0.07]+cmd[1.54] seconds) 2025-01-07 16:50:24.736736 | ubuntu-noble | congratulations :) (1.81 seconds) 2025-01-07 16:50:24.812448 | ubuntu-noble | ok: Runtime: 0:00:02.356466 2025-01-07 16:50:24.823036 | 2025-01-07 16:50:24.823194 | TASK [tox : Look for output] 2025-01-07 16:50:25.400467 | ubuntu-noble | ok 2025-01-07 16:50:25.410685 | 2025-01-07 16:50:25.410807 | TASK [tox : Return file comments to Zuul] 2025-01-07 16:50:25.456895 | ubuntu-noble | skipping: Conditional result was False 2025-01-07 16:50:25.502469 | 2025-01-07 16:50:25.502581 | PLAY RECAP 2025-01-07 16:50:25.502635 | ubuntu-noble | ok: 12 changed: 8 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 0 2025-01-07 16:50:25.502661 | 2025-01-07 16:50:25.636425 | RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/tox/run.yaml@master] 2025-01-07 16:50:25.640118 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/tox/post.yaml@master] 2025-01-07 16:50:26.343979 | 2025-01-07 16:50:26.344157 | PLAY [all] 2025-01-07 16:50:26.372241 | 2025-01-07 16:50:26.372400 | TASK [fetch-tox-output : Set tox log path for multiple nodes] 2025-01-07 16:50:26.449971 | ubuntu-noble | skipping: Conditional result was False 2025-01-07 16:50:26.457193 | 2025-01-07 16:50:26.457353 | TASK [fetch-tox-output : Set tox log path for single node] 2025-01-07 16:50:26.509742 | ubuntu-noble | ok 2025-01-07 16:50:26.517696 | 2025-01-07 16:50:26.517822 | TASK [fetch-tox-output : Ensure local tox dir] 2025-01-07 16:50:27.002291 | ubuntu-noble -> localhost | changed 2025-01-07 16:50:27.010746 | 2025-01-07 16:50:27.010884 | TASK [fetch-tox-output : Ensure zuul-output tox dir] 2025-01-07 16:50:27.067311 | ubuntu-noble | skipping: Conditional result was False 2025-01-07 16:50:27.075492 | 2025-01-07 16:50:27.075633 | TASK [fetch-tox-output : Set envlist fact] 2025-01-07 16:50:27.186243 | ubuntu-noble | ok 2025-01-07 16:50:27.200700 | 2025-01-07 16:50:27.200819 | TASK [fetch-tox-output : Get tox version] 2025-01-07 16:50:28.570880 | 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-07 16:50:28.595044 | ubuntu-noble | 4.23.2 from /home/zuul/.local/tox/lib/python3.12/site-packages/tox/__init__.py 2025-01-07 16:50:28.752693 | ubuntu-noble | ok: Runtime: 0:00:00.658957 2025-01-07 16:50:28.761999 | 2025-01-07 16:50:28.762144 | TASK [fetch-tox-output : Find all default environments] 2025-01-07 16:50:28.925018 | ubuntu-noble | skipping: Conditional result was False 2025-01-07 16:50:28.948036 | 2025-01-07 16:50:28.948466 | TASK [fetch-tox-output : Set envlist fact] 2025-01-07 16:50:29.004777 | ubuntu-noble | skipping: Conditional result was False 2025-01-07 16:50:29.022252 | 2025-01-07 16:50:29.022402 | TASK [fetch-tox-output : Find all default environments] 2025-01-07 16:50:29.150339 | ubuntu-noble | skipping: Conditional result was False 2025-01-07 16:50:29.171550 | 2025-01-07 16:50:29.171726 | TASK [fetch-tox-output : Set envlist fact] 2025-01-07 16:50:29.240759 | ubuntu-noble | skipping: Conditional result was False 2025-01-07 16:50:29.258047 | 2025-01-07 16:50:29.258217 | LOOP [fetch-tox-output : Copy tox logs] 2025-01-07 16:50:29.349162 | ubuntu-noble | skipping: Conditional result was False 2025-01-07 16:50:29.358934 | 2025-01-07 16:50:29.359116 | LOOP [fetch-tox-output : Collect tox logs] 2025-01-07 16:50:30.386691 | ubuntu-noble | changed: 2025-01-07 16:50:30.386900 | ubuntu-noble | cd+++++++++ ./ 2025-01-07 16:50:30.386936 | ubuntu-noble | >f+++++++++ 1-commands[0].log 2025-01-07 16:50:30.386980 | 2025-01-07 16:50:30.425266 | 2025-01-07 16:50:30.425408 | PLAY RECAP 2025-01-07 16:50:30.425476 | ubuntu-noble | ok: 5 changed: 3 unreachable: 0 failed: 0 skipped: 7 rescued: 0 ignored: 0 2025-01-07 16:50:30.425504 | 2025-01-07 16:50:30.573405 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/tox/post.yaml@master] 2025-01-07 16:50:30.577012 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2025-01-07 16:50:31.421703 | 2025-01-07 16:50:31.421996 | PLAY [all] 2025-01-07 16:50:31.449819 | 2025-01-07 16:50:31.449992 | TASK [fetch-subunit-output : Find stestr or testr executable] 2025-01-07 16:50:31.636330 | ubuntu-noble | changed: non-zero return code 2025-01-07 16:50:31.649587 | 2025-01-07 16:50:31.649747 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2025-01-07 16:50:31.675990 | ubuntu-noble | skipping: Conditional result was False 2025-01-07 16:50:31.691890 | 2025-01-07 16:50:31.692038 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2025-01-07 16:50:31.742965 | 2025-01-07 16:50:31.743204 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2025-01-07 16:50:31.776736 | 2025-01-07 16:50:31.776952 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2025-01-07 16:50:31.836850 | ubuntu-noble | skipping: Conditional result was False 2025-01-07 16:50:31.845284 | 2025-01-07 16:50:31.845437 | LOOP [fetch-subunit-output : Generate subunit file] 2025-01-07 16:50:31.882470 | 2025-01-07 16:50:31.882703 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2025-01-07 16:50:31.901415 | ubuntu-noble | skipping: Conditional result was False 2025-01-07 16:50:31.909322 | 2025-01-07 16:50:31.909452 | TASK [fetch-subunit-output : Remove the temporary file] 2025-01-07 16:50:31.944225 | ubuntu-noble | skipping: Conditional result was False 2025-01-07 16:50:31.951793 | 2025-01-07 16:50:31.951924 | TASK [fetch-subunit-output : Process and fetch subunit results] 2025-01-07 16:50:32.001353 | ubuntu-noble | skipping: Conditional result was False 2025-01-07 16:50:32.090353 | 2025-01-07 16:50:32.090582 | PLAY RECAP 2025-01-07 16:50:32.090643 | ubuntu-noble | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2025-01-07 16:50:32.090670 | 2025-01-07 16:50:32.260374 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2025-01-07 16:50:32.266918 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-01-07 16:50:33.124674 | 2025-01-07 16:50:33.124796 | PLAY [Base post] 2025-01-07 16:50:33.148092 | 2025-01-07 16:50:33.148202 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-01-07 16:50:34.137580 | ubuntu-noble | changed 2025-01-07 16:50:34.169720 | 2025-01-07 16:50:34.169826 | PLAY RECAP 2025-01-07 16:50:34.169892 | ubuntu-noble | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-01-07 16:50:34.169997 | 2025-01-07 16:50:34.275870 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-01-07 16:50:34.280722 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2025-01-07 16:50:34.972484 | 2025-01-07 16:50:34.972620 | PLAY [Base post-logs] 2025-01-07 16:50:34.988089 | 2025-01-07 16:50:34.988218 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-01-07 16:50:35.389477 | localhost | changed 2025-01-07 16:50:35.393366 | 2025-01-07 16:50:35.393471 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-01-07 16:50:35.429755 | localhost | ok 2025-01-07 16:50:35.435470 | 2025-01-07 16:50:35.435553 | TASK [Set zuul-log-path fact] 2025-01-07 16:50:35.462683 | localhost | ok 2025-01-07 16:50:35.476953 | 2025-01-07 16:50:35.477046 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-01-07 16:50:35.530315 | localhost | ok 2025-01-07 16:50:35.535498 | 2025-01-07 16:50:35.535641 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-01-07 16:50:35.562187 | localhost | skipping: Conditional result was False 2025-01-07 16:50:35.570812 | 2025-01-07 16:50:35.570994 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-01-07 16:50:35.606752 | localhost | skipping: Conditional result was False 2025-01-07 16:50:35.615400 | 2025-01-07 16:50:35.615567 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-01-07 16:50:35.642079 | localhost | skipping: Conditional result was False 2025-01-07 16:50:35.648898 | 2025-01-07 16:50:35.649111 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-01-07 16:50:35.674203 | localhost | skipping: Conditional result was False 2025-01-07 16:50:35.681861 | 2025-01-07 16:50:35.682029 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-01-07 16:50:35.707780 | localhost | skipping: Conditional result was False 2025-01-07 16:50:35.718729 | 2025-01-07 16:50:35.718862 | TASK [upload-logs : Create log directories] 2025-01-07 16:50:36.196550 | localhost | changed 2025-01-07 16:50:36.200797 | 2025-01-07 16:50:36.200883 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-01-07 16:50:36.724605 | localhost -> localhost | ok: Runtime: 0:00:00.007542 2025-01-07 16:50:36.729854 | 2025-01-07 16:50:36.729986 | TASK [upload-logs : Upload logs to log server] 2025-01-07 16:50:37.321313 | localhost | Output suppressed because no_log was given 2025-01-07 16:50:37.326457 | 2025-01-07 16:50:37.326574 | LOOP [upload-logs : Compress console log and json output] 2025-01-07 16:50:37.405638 | localhost | skipping: Conditional result was False 2025-01-07 16:50:37.424625 | localhost | skipping: Conditional result was False 2025-01-07 16:50:37.430610 | 2025-01-07 16:50:37.430730 | LOOP [upload-logs : Upload compressed console log and json output] 2025-01-07 16:50:37.505471 | localhost | skipping: Conditional result was False 2025-01-07 16:50:37.506261 | 2025-01-07 16:50:37.517126 | localhost | skipping: Conditional result was False 2025-01-07 16:50:37.524046 | 2025-01-07 16:50:37.524204 | LOOP [upload-logs : Upload console log and json output]