2025-01-08 14:05:49.118639 | Job console starting... 2025-01-08 14:05:49.185289 | Updating repositories 2025-01-08 14:05:49.260768 | Preparing job workspace 2025-01-08 14:05:51.525150 | Running Ansible setup... 2025-01-08 14:05:59.404778 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-01-08 14:06:00.473537 | 2025-01-08 14:06:00.473707 | PLAY [Base pre] 2025-01-08 14:06:00.510794 | 2025-01-08 14:06:00.510957 | TASK [Setup log path fact] 2025-01-08 14:06:00.552621 | ubuntu-noble | ok 2025-01-08 14:06:00.581714 | 2025-01-08 14:06:00.581874 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-01-08 14:06:00.656318 | ubuntu-noble | ok 2025-01-08 14:06:00.663372 | 2025-01-08 14:06:00.663498 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-01-08 14:06:00.752018 | ubuntu-noble | skipping: Conditional result was False 2025-01-08 14:06:00.759785 | 2025-01-08 14:06:00.759918 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-01-08 14:06:00.871573 | ubuntu-noble | skipping: Conditional result was False 2025-01-08 14:06:00.889902 | 2025-01-08 14:06:00.890908 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-01-08 14:06:00.923442 | ubuntu-noble | skipping: Conditional result was False 2025-01-08 14:06:00.931138 | 2025-01-08 14:06:00.931265 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-01-08 14:06:00.966010 | ubuntu-noble | skipping: Conditional result was False 2025-01-08 14:06:00.974894 | 2025-01-08 14:06:00.975017 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-01-08 14:06:01.032051 | ubuntu-noble | skipping: Conditional result was False 2025-01-08 14:06:01.048624 | 2025-01-08 14:06:01.048760 | TASK [emit-job-header : Print job information] 2025-01-08 14:06:01.160534 | # Job Information 2025-01-08 14:06:01.160728 | Ansible Version: 2.15.3 2025-01-08 14:06:01.160760 | Job: tox 2025-01-08 14:06:01.160790 | Pipeline: check 2025-01-08 14:06:01.160811 | Executor: 7d211f194f6a 2025-01-08 14:06:01.160829 | Triggered by: https://github.com/osism/openstack-image-manager/pull/866 2025-01-08 14:06:01.160847 | Event ID: a429d420-cdc9-11ef-8ae6-04912797aca1 2025-01-08 14:06:01.169500 | 2025-01-08 14:06:01.169628 | LOOP [emit-job-header : Print node information] 2025-01-08 14:06:01.374599 | ubuntu-noble | ok: 2025-01-08 14:06:01.374855 | ubuntu-noble | # Node Information 2025-01-08 14:06:01.374896 | ubuntu-noble | Inventory Hostname: ubuntu-noble 2025-01-08 14:06:01.374921 | ubuntu-noble | Hostname: ubuntu 2025-01-08 14:06:01.374945 | ubuntu-noble | Username: zuul 2025-01-08 14:06:01.374966 | ubuntu-noble | Distro: Ubuntu 24.04 2025-01-08 14:06:01.374986 | ubuntu-noble | Provider: regiocloud-a 2025-01-08 14:06:01.375005 | ubuntu-noble | Label: ubuntu-noble 2025-01-08 14:06:01.375024 | ubuntu-noble | Product Name: OpenStack Nova 2025-01-08 14:06:01.375045 | ubuntu-noble | Interface IP: 81.163.193.191 2025-01-08 14:06:01.411154 | 2025-01-08 14:06:01.411304 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-01-08 14:06:02.146434 | ubuntu-noble -> localhost | changed 2025-01-08 14:06:02.153171 | 2025-01-08 14:06:02.153261 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-01-08 14:06:03.526442 | ubuntu-noble -> localhost | changed 2025-01-08 14:06:03.543841 | 2025-01-08 14:06:03.543931 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-01-08 14:06:04.059438 | ubuntu-noble -> localhost | ok 2025-01-08 14:06:04.065772 | 2025-01-08 14:06:04.065848 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-01-08 14:06:04.104328 | ubuntu-noble | ok 2025-01-08 14:06:04.128508 | ubuntu-noble | included: /var/lib/zuul/builds/973ec428574942b7813435cc31a28a94/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-01-08 14:06:04.144969 | 2025-01-08 14:06:04.145053 | TASK [add-build-sshkey : Create Temp SSH key] 2025-01-08 14:06:04.862258 | ubuntu-noble -> localhost | Generating public/private rsa key pair. 2025-01-08 14:06:04.862434 | ubuntu-noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/973ec428574942b7813435cc31a28a94/work/973ec428574942b7813435cc31a28a94_id_rsa 2025-01-08 14:06:04.862466 | ubuntu-noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/973ec428574942b7813435cc31a28a94/work/973ec428574942b7813435cc31a28a94_id_rsa.pub 2025-01-08 14:06:04.862486 | ubuntu-noble -> localhost | The key fingerprint is: 2025-01-08 14:06:04.862505 | ubuntu-noble -> localhost | SHA256:+zESu0FC+dRcpZTs6ulIRwn9Lmk74mRTbYXDsVaL10Y zuul-build-sshkey 2025-01-08 14:06:04.862522 | ubuntu-noble -> localhost | The key's randomart image is: 2025-01-08 14:06:04.862541 | ubuntu-noble -> localhost | +---[RSA 3072]----+ 2025-01-08 14:06:04.862558 | ubuntu-noble -> localhost | | .+oo E| 2025-01-08 14:06:04.862574 | ubuntu-noble -> localhost | | . + +oB + | 2025-01-08 14:06:04.862589 | ubuntu-noble -> localhost | | o o +.O + o| 2025-01-08 14:06:04.862604 | ubuntu-noble -> localhost | | . o . =.+ . | 2025-01-08 14:06:04.862619 | ubuntu-noble -> localhost | | . S +.+ | 2025-01-08 14:06:04.862634 | ubuntu-noble -> localhost | | o *.+ | 2025-01-08 14:06:04.862650 | ubuntu-noble -> localhost | | X.B.. | 2025-01-08 14:06:04.862665 | ubuntu-noble -> localhost | | +.X+= | 2025-01-08 14:06:04.862680 | ubuntu-noble -> localhost | | .+o+. | 2025-01-08 14:06:04.862695 | ubuntu-noble -> localhost | +----[SHA256]-----+ 2025-01-08 14:06:04.862732 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.243007 2025-01-08 14:06:04.869015 | 2025-01-08 14:06:04.869095 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-01-08 14:06:04.903476 | ubuntu-noble | ok 2025-01-08 14:06:04.912215 | ubuntu-noble | included: /var/lib/zuul/builds/973ec428574942b7813435cc31a28a94/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-01-08 14:06:04.926385 | 2025-01-08 14:06:04.926478 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-01-08 14:06:04.950173 | ubuntu-noble | skipping: Conditional result was False 2025-01-08 14:06:04.967480 | 2025-01-08 14:06:04.967575 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-01-08 14:06:05.719151 | ubuntu-noble | changed 2025-01-08 14:06:05.729302 | 2025-01-08 14:06:05.729429 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-01-08 14:06:06.055139 | ubuntu-noble | ok 2025-01-08 14:06:06.063978 | 2025-01-08 14:06:06.064083 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-01-08 14:06:06.810088 | ubuntu-noble | changed 2025-01-08 14:06:06.821612 | 2025-01-08 14:06:06.821723 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-01-08 14:06:07.654029 | ubuntu-noble | changed 2025-01-08 14:06:07.663845 | 2025-01-08 14:06:07.663939 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-01-08 14:06:07.717351 | ubuntu-noble | skipping: Conditional result was False 2025-01-08 14:06:07.725012 | 2025-01-08 14:06:07.725104 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-01-08 14:06:08.429511 | ubuntu-noble -> localhost | changed 2025-01-08 14:06:08.474965 | 2025-01-08 14:06:08.475069 | TASK [add-build-sshkey : Add back temp key] 2025-01-08 14:06:08.893754 | ubuntu-noble -> localhost | Identity added: /var/lib/zuul/builds/973ec428574942b7813435cc31a28a94/work/973ec428574942b7813435cc31a28a94_id_rsa (zuul-build-sshkey) 2025-01-08 14:06:08.893959 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.011247 2025-01-08 14:06:08.900200 | 2025-01-08 14:06:08.900288 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-01-08 14:06:09.367719 | ubuntu-noble | ok 2025-01-08 14:06:09.375649 | 2025-01-08 14:06:09.375735 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-01-08 14:06:09.419662 | ubuntu-noble | skipping: Conditional result was False 2025-01-08 14:06:09.435893 | 2025-01-08 14:06:09.435994 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-01-08 14:06:09.848283 | ubuntu-noble | ok 2025-01-08 14:06:09.859679 | 2025-01-08 14:06:09.859881 | TASK [validate-host : Define zuul_info_dir fact] 2025-01-08 14:06:09.918842 | ubuntu-noble | ok 2025-01-08 14:06:09.928898 | 2025-01-08 14:06:09.928987 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-01-08 14:06:10.166247 | ubuntu-noble -> localhost | ok 2025-01-08 14:06:10.172556 | 2025-01-08 14:06:10.172636 | TASK [validate-host : Collect information about the host] 2025-01-08 14:06:11.377476 | ubuntu-noble | ok 2025-01-08 14:06:11.397335 | 2025-01-08 14:06:11.397467 | TASK [validate-host : Sanitize hostname] 2025-01-08 14:06:11.494661 | ubuntu-noble | ok 2025-01-08 14:06:11.500423 | 2025-01-08 14:06:11.500514 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-01-08 14:06:12.136677 | ubuntu-noble -> localhost | changed 2025-01-08 14:06:12.142698 | 2025-01-08 14:06:12.142778 | TASK [validate-host : Collect information about zuul worker] 2025-01-08 14:06:12.716336 | ubuntu-noble | ok 2025-01-08 14:06:12.724197 | 2025-01-08 14:06:12.724280 | TASK [validate-host : Write out all zuul information for each host] 2025-01-08 14:06:13.459008 | ubuntu-noble -> localhost | changed 2025-01-08 14:06:13.476653 | 2025-01-08 14:06:13.476748 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-01-08 14:06:13.841342 | ubuntu-noble | ok 2025-01-08 14:06:13.850782 | 2025-01-08 14:06:13.850876 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-01-08 14:06:14.809200 | ubuntu-noble | changed: 2025-01-08 14:06:14.809340 | ubuntu-noble | cd+++++++++ src/ 2025-01-08 14:06:14.809365 | ubuntu-noble | cd+++++++++ src/github.com/ 2025-01-08 14:06:14.809395 | ubuntu-noble | cd+++++++++ src/github.com/osism/ 2025-01-08 14:06:14.809412 | ubuntu-noble | cd+++++++++ src/github.com/osism/openstack-image-manager/ 2025-01-08 14:06:14.809429 | ubuntu-noble | =5.5 (from tox) 2025-01-08 14:06:45.446656 | ubuntu-noble | Downloading cachetools-5.5.0-py3-none-any.whl.metadata (5.3 kB) 2025-01-08 14:06:45.504204 | ubuntu-noble | Collecting chardet>=5.2 (from tox) 2025-01-08 14:06:45.517561 | ubuntu-noble | Downloading chardet-5.2.0-py3-none-any.whl.metadata (3.4 kB) 2025-01-08 14:06:45.591457 | ubuntu-noble | Collecting colorama>=0.4.6 (from tox) 2025-01-08 14:06:45.604518 | ubuntu-noble | Downloading colorama-0.4.6-py2.py3-none-any.whl.metadata (17 kB) 2025-01-08 14:06:45.679321 | ubuntu-noble | Collecting filelock>=3.16.1 (from tox) 2025-01-08 14:06:45.691595 | ubuntu-noble | Downloading filelock-3.16.1-py3-none-any.whl.metadata (2.9 kB) 2025-01-08 14:06:45.769696 | ubuntu-noble | Collecting packaging>=24.1 (from tox) 2025-01-08 14:06:45.783685 | ubuntu-noble | Downloading packaging-24.2-py3-none-any.whl.metadata (3.2 kB) 2025-01-08 14:06:45.877372 | ubuntu-noble | Collecting platformdirs>=4.3.6 (from tox) 2025-01-08 14:06:45.891110 | ubuntu-noble | Downloading platformdirs-4.3.6-py3-none-any.whl.metadata (11 kB) 2025-01-08 14:06:45.967217 | ubuntu-noble | Collecting pluggy>=1.5 (from tox) 2025-01-08 14:06:45.981543 | ubuntu-noble | Downloading pluggy-1.5.0-py3-none-any.whl.metadata (4.8 kB) 2025-01-08 14:06:46.045751 | ubuntu-noble | Collecting pyproject-api>=1.8 (from tox) 2025-01-08 14:06:46.068414 | ubuntu-noble | Downloading pyproject_api-1.8.0-py3-none-any.whl.metadata (2.7 kB) 2025-01-08 14:06:46.219675 | ubuntu-noble | Collecting virtualenv>=20.26.6 (from tox) 2025-01-08 14:06:46.232853 | ubuntu-noble | Downloading virtualenv-20.28.1-py3-none-any.whl.metadata (4.5 kB) 2025-01-08 14:06:46.348997 | ubuntu-noble | Collecting distlib<1,>=0.3.7 (from virtualenv>=20.26.6->tox) 2025-01-08 14:06:46.363985 | ubuntu-noble | Downloading distlib-0.3.9-py2.py3-none-any.whl.metadata (5.2 kB) 2025-01-08 14:06:46.432418 | ubuntu-noble | Downloading tox-4.23.2-py3-none-any.whl (166 kB) 2025-01-08 14:06:46.474226 | ubuntu-noble | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 166.8/166.8 kB 5.1 MB/s eta 0:00:00 2025-01-08 14:06:46.486426 | ubuntu-noble | Downloading cachetools-5.5.0-py3-none-any.whl (9.5 kB) 2025-01-08 14:06:46.509216 | ubuntu-noble | Downloading chardet-5.2.0-py3-none-any.whl (199 kB) 2025-01-08 14:06:46.530568 | ubuntu-noble | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 199.4/199.4 kB 11.1 MB/s eta 0:00:00 2025-01-08 14:06:46.544255 | ubuntu-noble | Downloading colorama-0.4.6-py2.py3-none-any.whl (25 kB) 2025-01-08 14:06:46.566880 | ubuntu-noble | Downloading filelock-3.16.1-py3-none-any.whl (16 kB) 2025-01-08 14:06:46.592221 | ubuntu-noble | Downloading packaging-24.2-py3-none-any.whl (65 kB) 2025-01-08 14:06:46.607169 | ubuntu-noble | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 65.5/65.5 kB 5.8 MB/s eta 0:00:00 2025-01-08 14:06:46.621959 | ubuntu-noble | Downloading platformdirs-4.3.6-py3-none-any.whl (18 kB) 2025-01-08 14:06:46.651378 | ubuntu-noble | Downloading pluggy-1.5.0-py3-none-any.whl (20 kB) 2025-01-08 14:06:46.675873 | ubuntu-noble | Downloading pyproject_api-1.8.0-py3-none-any.whl (13 kB) 2025-01-08 14:06:46.700470 | ubuntu-noble | Downloading virtualenv-20.28.1-py3-none-any.whl (4.3 MB) 2025-01-08 14:06:46.869257 | ubuntu-noble | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 4.3/4.3 MB 27.0 MB/s eta 0:00:00 2025-01-08 14:06:46.885999 | ubuntu-noble | Downloading distlib-0.3.9-py2.py3-none-any.whl (468 kB) 2025-01-08 14:06:46.917868 | ubuntu-noble | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 469.0/469.0 kB 18.6 MB/s eta 0:00:00 2025-01-08 14:06:47.046254 | ubuntu-noble | Installing collected packages: distlib, pluggy, platformdirs, packaging, filelock, colorama, chardet, cachetools, virtualenv, pyproject-api, tox 2025-01-08 14:06:48.070382 | 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 14:06:48.514598 | ubuntu-noble | ok: Runtime: 0:00:03.707455 2025-01-08 14:06:48.530733 | 2025-01-08 14:06:48.530894 | TASK [ensure-tox : Export installed tox_executable path] 2025-01-08 14:06:48.593956 | ubuntu-noble | ok 2025-01-08 14:06:48.602847 | 2025-01-08 14:06:48.602994 | TASK [ensure-tox : Output tox version] 2025-01-08 14:06:49.568591 | 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 14:06:49.604202 | ubuntu-noble | 4.23.2 from /home/zuul/.local/tox/lib/python3.12/site-packages/tox/__init__.py 2025-01-08 14:06:50.155691 | ubuntu-noble | ok: Runtime: 0:00:00.616450 2025-01-08 14:06:50.168482 | 2025-01-08 14:06:50.168638 | TASK [ensure-tox : Make global symlink] 2025-01-08 14:06:50.289198 | ubuntu-noble | skipping: Conditional result was False 2025-01-08 14:06:50.319242 | 2025-01-08 14:06:50.319338 | PLAY RECAP 2025-01-08 14:06:50.319390 | ubuntu-noble | ok: 13 changed: 9 unreachable: 0 failed: 0 skipped: 14 rescued: 0 ignored: 0 2025-01-08 14:06:50.319415 | 2025-01-08 14:06:50.450066 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/tox/pre.yaml@master] 2025-01-08 14:06:50.455458 | RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/tox/run.yaml@master] 2025-01-08 14:06:51.178873 | 2025-01-08 14:06:51.179057 | PLAY [all] 2025-01-08 14:06:51.208401 | 2025-01-08 14:06:51.208582 | TASK [revoke-sudo : Check if zuul is sudoer] 2025-01-08 14:06:52.249600 | ubuntu-noble | ok: Runtime: 0:00:00.022374 2025-01-08 14:06:52.257396 | 2025-01-08 14:06:52.257566 | TASK [revoke-sudo : Remove sudo access for zuul user.] 2025-01-08 14:06:52.803874 | ubuntu-noble | ok: Runtime: 0:00:00.006569 2025-01-08 14:06:52.816740 | 2025-01-08 14:06:52.816893 | TASK [revoke-sudo : Prove that general sudo access is actually revoked.] 2025-01-08 14:06:53.220443 | ubuntu-noble | sudo: a password is required 2025-01-08 14:06:53.358547 | ubuntu-noble | ok: Runtime: 0:00:00.021227 2025-01-08 14:06:53.373304 | 2025-01-08 14:06:53.373530 | TASK [tox : Check to see if the constraints file exists] 2025-01-08 14:06:53.422174 | ubuntu-noble | skipping: Conditional result was False 2025-01-08 14:06:53.437261 | 2025-01-08 14:06:53.437416 | TASK [tox : Fail if constraints file is missing] 2025-01-08 14:06:53.494565 | ubuntu-noble | skipping: Conditional result was False 2025-01-08 14:06:53.507454 | 2025-01-08 14:06:53.507585 | TASK [tox : Record file location] 2025-01-08 14:06:53.554759 | ubuntu-noble | skipping: Conditional result was False 2025-01-08 14:06:53.563315 | 2025-01-08 14:06:53.563471 | TASK [tox : Install tox siblings] 2025-01-08 14:06:53.595407 | ubuntu-noble | ok 2025-01-08 14:06:53.606199 | ubuntu-noble | included: /var/lib/zuul/builds/973ec428574942b7813435cc31a28a94/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/tox/tasks/siblings.yaml 2025-01-08 14:06:53.614110 | 2025-01-08 14:06:53.614237 | TASK [tox : Run tox without tests] 2025-01-08 14:06:54.969247 | ubuntu-noble | test: install_deps> python -I -m pip install -r requirements.txt 2025-01-08 14:07:15.529745 | ubuntu-noble | test: OK (20.94 seconds) 2025-01-08 14:07:15.604613 | ubuntu-noble | congratulations :) (21.05 seconds) 2025-01-08 14:07:15.699918 | ubuntu-noble | ok: Runtime: 0:00:21.429948 2025-01-08 14:07:15.714121 | 2025-01-08 14:07:15.714270 | TASK [tox : Create a tempfile to save tox showconfig] 2025-01-08 14:07:16.121310 | ubuntu-noble | changed 2025-01-08 14:07:16.130532 | 2025-01-08 14:07:16.130669 | TASK [tox : Get tox envlist config] 2025-01-08 14:07:17.169589 | ubuntu-noble | ok: Runtime: 0:00:00.448243 2025-01-08 14:07:17.186535 | 2025-01-08 14:07:17.186711 | TASK [tox : Install any sibling python packages] 2025-01-08 14:07:17.798221 | ubuntu-noble | ok: No setup.cfg, no action needed 2025-01-08 14:07:17.808051 | 2025-01-08 14:07:17.808194 | TASK [tox : Remove tempfile] 2025-01-08 14:07:18.452901 | ubuntu-noble | changed 2025-01-08 14:07:18.461547 | 2025-01-08 14:07:18.461662 | TASK [tox : Emit tox command] 2025-01-08 14:07:18.518923 | /home/zuul/.local/tox/bin/tox -etest -- test/unit 2025-01-08 14:07:18.527041 | 2025-01-08 14:07:18.527161 | TASK [tox : Run tox] 2025-01-08 14:07:19.738514 | ubuntu-noble | test: commands[0]> python -m unittest discover test/unit 2025-01-08 14:07:21.278433 | ubuntu-noble | ............... 2025-01-08 14:07:21.575936 | ubuntu-noble | ---------------------------------------------------------------------- 2025-01-08 14:07:21.575991 | ubuntu-noble | Ran 15 tests in 0.254s 2025-01-08 14:07:21.576001 | ubuntu-noble | 2025-01-08 14:07:21.576007 | ubuntu-noble | OK 2025-01-08 14:07:21.576022 | ubuntu-noble | test: OK (1.88=setup[0.05]+cmd[1.83] seconds) 2025-01-08 14:07:21.702984 | ubuntu-noble | congratulations :) (2.03 seconds) 2025-01-08 14:07:22.069540 | ubuntu-noble | ok: Runtime: 0:00:02.583842 2025-01-08 14:07:22.083678 | 2025-01-08 14:07:22.083829 | TASK [tox : Look for output] 2025-01-08 14:07:22.833310 | ubuntu-noble | ok 2025-01-08 14:07:22.842492 | 2025-01-08 14:07:22.842610 | TASK [tox : Return file comments to Zuul] 2025-01-08 14:07:22.888450 | ubuntu-noble | skipping: Conditional result was False 2025-01-08 14:07:22.928038 | 2025-01-08 14:07:22.928150 | PLAY RECAP 2025-01-08 14:07:22.928205 | ubuntu-noble | ok: 12 changed: 8 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 0 2025-01-08 14:07:22.928232 | 2025-01-08 14:07:23.049045 | RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/tox/run.yaml@master] 2025-01-08 14:07:23.057090 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/tox/post.yaml@master] 2025-01-08 14:07:23.813327 | 2025-01-08 14:07:23.813502 | PLAY [all] 2025-01-08 14:07:23.841752 | 2025-01-08 14:07:23.841890 | TASK [fetch-tox-output : Set tox log path for multiple nodes] 2025-01-08 14:07:23.909597 | ubuntu-noble | skipping: Conditional result was False 2025-01-08 14:07:23.925962 | 2025-01-08 14:07:23.926153 | TASK [fetch-tox-output : Set tox log path for single node] 2025-01-08 14:07:23.987766 | ubuntu-noble | ok 2025-01-08 14:07:23.999552 | 2025-01-08 14:07:23.999713 | TASK [fetch-tox-output : Ensure local tox dir] 2025-01-08 14:07:24.498938 | ubuntu-noble -> localhost | changed 2025-01-08 14:07:24.507744 | 2025-01-08 14:07:24.507867 | TASK [fetch-tox-output : Ensure zuul-output tox dir] 2025-01-08 14:07:24.573869 | ubuntu-noble | skipping: Conditional result was False 2025-01-08 14:07:24.586215 | 2025-01-08 14:07:24.586369 | TASK [fetch-tox-output : Set envlist fact] 2025-01-08 14:07:24.659852 | ubuntu-noble | ok 2025-01-08 14:07:24.669820 | 2025-01-08 14:07:24.670010 | TASK [fetch-tox-output : Get tox version] 2025-01-08 14:07:25.767255 | 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 14:07:25.800474 | ubuntu-noble | 4.23.2 from /home/zuul/.local/tox/lib/python3.12/site-packages/tox/__init__.py 2025-01-08 14:07:26.230803 | ubuntu-noble | ok: Runtime: 0:00:00.670637 2025-01-08 14:07:26.238789 | 2025-01-08 14:07:26.238906 | TASK [fetch-tox-output : Find all default environments] 2025-01-08 14:07:26.319895 | ubuntu-noble | skipping: Conditional result was False 2025-01-08 14:07:26.330827 | 2025-01-08 14:07:26.330986 | TASK [fetch-tox-output : Set envlist fact] 2025-01-08 14:07:26.369072 | ubuntu-noble | skipping: Conditional result was False 2025-01-08 14:07:26.376528 | 2025-01-08 14:07:26.376651 | TASK [fetch-tox-output : Find all default environments] 2025-01-08 14:07:26.459956 | ubuntu-noble | skipping: Conditional result was False 2025-01-08 14:07:26.471182 | 2025-01-08 14:07:26.471337 | TASK [fetch-tox-output : Set envlist fact] 2025-01-08 14:07:26.517300 | ubuntu-noble | skipping: Conditional result was False 2025-01-08 14:07:26.534225 | 2025-01-08 14:07:26.534391 | LOOP [fetch-tox-output : Copy tox logs] 2025-01-08 14:07:26.591969 | ubuntu-noble | skipping: Conditional result was False 2025-01-08 14:07:26.619145 | 2025-01-08 14:07:26.619307 | LOOP [fetch-tox-output : Collect tox logs] 2025-01-08 14:07:27.392382 | ubuntu-noble | changed: 2025-01-08 14:07:27.392613 | ubuntu-noble | cd+++++++++ ./ 2025-01-08 14:07:27.392650 | ubuntu-noble | >f+++++++++ 1-commands[0].log 2025-01-08 14:07:27.392683 | 2025-01-08 14:07:27.448098 | 2025-01-08 14:07:27.448207 | PLAY RECAP 2025-01-08 14:07:27.448264 | ubuntu-noble | ok: 5 changed: 3 unreachable: 0 failed: 0 skipped: 7 rescued: 0 ignored: 0 2025-01-08 14:07:27.448290 | 2025-01-08 14:07:27.584147 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/tox/post.yaml@master] 2025-01-08 14:07:27.587707 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2025-01-08 14:07:28.306593 | 2025-01-08 14:07:28.306752 | PLAY [all] 2025-01-08 14:07:28.334201 | 2025-01-08 14:07:28.334348 | TASK [fetch-subunit-output : Find stestr or testr executable] 2025-01-08 14:07:28.541120 | ubuntu-noble | changed: non-zero return code 2025-01-08 14:07:28.548731 | 2025-01-08 14:07:28.548891 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2025-01-08 14:07:28.574514 | ubuntu-noble | skipping: Conditional result was False 2025-01-08 14:07:28.583309 | 2025-01-08 14:07:28.583444 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2025-01-08 14:07:28.617801 | 2025-01-08 14:07:28.618038 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2025-01-08 14:07:28.660166 | 2025-01-08 14:07:28.660401 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2025-01-08 14:07:28.686373 | ubuntu-noble | skipping: Conditional result was False 2025-01-08 14:07:28.701618 | 2025-01-08 14:07:28.703313 | LOOP [fetch-subunit-output : Generate subunit file] 2025-01-08 14:07:28.738049 | 2025-01-08 14:07:28.738457 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2025-01-08 14:07:28.763861 | ubuntu-noble | skipping: Conditional result was False 2025-01-08 14:07:28.771717 | 2025-01-08 14:07:28.771843 | TASK [fetch-subunit-output : Remove the temporary file] 2025-01-08 14:07:28.796141 | ubuntu-noble | skipping: Conditional result was False 2025-01-08 14:07:28.803249 | 2025-01-08 14:07:28.803356 | TASK [fetch-subunit-output : Process and fetch subunit results] 2025-01-08 14:07:28.827446 | ubuntu-noble | skipping: Conditional result was False 2025-01-08 14:07:28.862263 | 2025-01-08 14:07:28.862360 | PLAY RECAP 2025-01-08 14:07:28.862415 | ubuntu-noble | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2025-01-08 14:07:28.862442 | 2025-01-08 14:07:28.980869 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2025-01-08 14:07:28.989861 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-01-08 14:07:29.679818 | 2025-01-08 14:07:29.679979 | PLAY [Base post] 2025-01-08 14:07:29.705765 | 2025-01-08 14:07:29.705962 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-01-08 14:07:30.583096 | ubuntu-noble | changed 2025-01-08 14:07:30.618766 | 2025-01-08 14:07:30.618913 | PLAY RECAP 2025-01-08 14:07:30.618983 | ubuntu-noble | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-01-08 14:07:30.619047 | 2025-01-08 14:07:30.736351 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-01-08 14:07:30.742767 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2025-01-08 14:07:31.481749 | 2025-01-08 14:07:31.481947 | PLAY [Base post-logs] 2025-01-08 14:07:31.497871 | 2025-01-08 14:07:31.498033 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-01-08 14:07:31.979360 | localhost | changed 2025-01-08 14:07:31.985233 | 2025-01-08 14:07:31.985402 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-01-08 14:07:32.015629 | localhost | ok 2025-01-08 14:07:32.023427 | 2025-01-08 14:07:32.023607 | TASK [Set zuul-log-path fact] 2025-01-08 14:07:32.054972 | localhost | ok 2025-01-08 14:07:32.075133 | 2025-01-08 14:07:32.076699 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-01-08 14:07:32.121025 | localhost | ok 2025-01-08 14:07:32.127858 | 2025-01-08 14:07:32.128039 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-01-08 14:07:32.165111 | localhost | skipping: Conditional result was False 2025-01-08 14:07:32.173689 | 2025-01-08 14:07:32.173872 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-01-08 14:07:32.222520 | localhost | skipping: Conditional result was False 2025-01-08 14:07:32.231716 | 2025-01-08 14:07:32.231940 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-01-08 14:07:32.259869 | localhost | skipping: Conditional result was False 2025-01-08 14:07:32.266391 | 2025-01-08 14:07:32.266592 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-01-08 14:07:32.293123 | localhost | skipping: Conditional result was False 2025-01-08 14:07:32.302274 | 2025-01-08 14:07:32.302456 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-01-08 14:07:32.328841 | localhost | skipping: Conditional result was False 2025-01-08 14:07:32.339458 | 2025-01-08 14:07:32.339624 | TASK [upload-logs : Create log directories] 2025-01-08 14:07:32.885422 | localhost | changed 2025-01-08 14:07:32.894021 | 2025-01-08 14:07:32.894178 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-01-08 14:07:33.400611 | localhost -> localhost | ok: Runtime: 0:00:00.007369 2025-01-08 14:07:33.405856 | 2025-01-08 14:07:33.406008 | TASK [upload-logs : Upload logs to log server] 2025-01-08 14:07:33.992593 | localhost | Output suppressed because no_log was given 2025-01-08 14:07:33.998957 | 2025-01-08 14:07:33.999122 | LOOP [upload-logs : Compress console log and json output] 2025-01-08 14:07:34.077969 | localhost | skipping: Conditional result was False 2025-01-08 14:07:34.095386 | localhost | skipping: Conditional result was False 2025-01-08 14:07:34.108851 | 2025-01-08 14:07:34.109037 | LOOP [upload-logs : Upload compressed console log and json output] 2025-01-08 14:07:34.182142 | localhost | skipping: Conditional result was False 2025-01-08 14:07:34.182816 | 2025-01-08 14:07:34.194829 | localhost | skipping: Conditional result was False 2025-01-08 14:07:34.207861 | 2025-01-08 14:07:34.208075 | LOOP [upload-logs : Upload console log and json output]