2025-05-31 03:14:32.433463 | Job console starting 2025-05-31 03:14:32.442691 | Updating git repos 2025-05-31 03:14:32.506562 | Cloning repos into workspace 2025-05-31 03:14:32.566097 | Restoring repo states 2025-05-31 03:14:32.580704 | Merging changes 2025-05-31 03:14:32.580720 | Checking out repos 2025-05-31 03:14:32.646438 | Preparing playbooks 2025-05-31 03:14:33.488971 | Running Ansible setup 2025-05-31 03:14:38.078973 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-05-31 03:14:39.000675 | 2025-05-31 03:14:39.000825 | PLAY [Base pre] 2025-05-31 03:14:39.029183 | 2025-05-31 03:14:39.029328 | TASK [Setup log path fact] 2025-05-31 03:14:39.074025 | debian-bookworm | ok 2025-05-31 03:14:39.098968 | 2025-05-31 03:14:39.099171 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-05-31 03:14:39.139305 | debian-bookworm | ok 2025-05-31 03:14:39.152049 | 2025-05-31 03:14:39.152205 | TASK [emit-job-header : Print job information] 2025-05-31 03:14:39.211980 | # Job Information 2025-05-31 03:14:39.212200 | Ansible Version: 2.16.14 2025-05-31 03:14:39.212237 | Job: tox 2025-05-31 03:14:39.212270 | Pipeline: periodic-daily 2025-05-31 03:14:39.212293 | Executor: 521e9411259a 2025-05-31 03:14:39.212313 | Triggered by: https://github.com/osism/openstack-image-manager 2025-05-31 03:14:39.212338 | Event ID: 67adcfab85b44a75b80921cff8dadff4 2025-05-31 03:14:39.217467 | 2025-05-31 03:14:39.217593 | LOOP [emit-job-header : Print node information] 2025-05-31 03:14:39.408059 | debian-bookworm | ok: 2025-05-31 03:14:39.408368 | debian-bookworm | # Node Information 2025-05-31 03:14:39.408407 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-05-31 03:14:39.408433 | debian-bookworm | Hostname: debian 2025-05-31 03:14:39.408456 | debian-bookworm | Username: zuul 2025-05-31 03:14:39.408477 | debian-bookworm | Distro: Debian 12.11 2025-05-31 03:14:39.408500 | debian-bookworm | Provider: regiocloud-a 2025-05-31 03:14:39.408521 | debian-bookworm | Region: 2025-05-31 03:14:39.408542 | debian-bookworm | Label: debian-bookworm 2025-05-31 03:14:39.408563 | debian-bookworm | Product Name: OpenStack Nova 2025-05-31 03:14:39.408582 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fef4:2e1a 2025-05-31 03:14:39.430033 | 2025-05-31 03:14:39.430239 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-05-31 03:14:40.350356 | debian-bookworm -> localhost | changed 2025-05-31 03:14:40.358497 | 2025-05-31 03:14:40.358637 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-05-31 03:14:41.953054 | debian-bookworm -> localhost | changed 2025-05-31 03:14:41.967838 | 2025-05-31 03:14:41.968113 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-05-31 03:14:42.401884 | debian-bookworm -> localhost | ok 2025-05-31 03:14:42.408501 | 2025-05-31 03:14:42.408631 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-05-31 03:14:42.462619 | debian-bookworm | ok 2025-05-31 03:14:42.488334 | debian-bookworm | included: /var/lib/zuul/builds/0eab4d2fc56b453fa8b38eefb48beee3/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-05-31 03:14:42.495400 | 2025-05-31 03:14:42.495523 | TASK [add-build-sshkey : Create Temp SSH key] 2025-05-31 03:14:43.953238 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-05-31 03:14:43.953577 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/0eab4d2fc56b453fa8b38eefb48beee3/work/0eab4d2fc56b453fa8b38eefb48beee3_id_rsa 2025-05-31 03:14:43.953619 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/0eab4d2fc56b453fa8b38eefb48beee3/work/0eab4d2fc56b453fa8b38eefb48beee3_id_rsa.pub 2025-05-31 03:14:43.953646 | debian-bookworm -> localhost | The key fingerprint is: 2025-05-31 03:14:43.953673 | debian-bookworm -> localhost | SHA256:RuYIN524ltEBj/0AcibqYkS8QTsJPhLaAgJCQ1LWDq4 zuul-build-sshkey 2025-05-31 03:14:43.953697 | debian-bookworm -> localhost | The key's randomart image is: 2025-05-31 03:14:43.953734 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-05-31 03:14:43.953757 | debian-bookworm -> localhost | |#Bo.o =.. | 2025-05-31 03:14:43.953780 | debian-bookworm -> localhost | |X*=..= O o | 2025-05-31 03:14:43.953801 | debian-bookworm -> localhost | |+O=o. * X | 2025-05-31 03:14:43.953822 | debian-bookworm -> localhost | |o++ .o X o | 2025-05-31 03:14:43.953842 | debian-bookworm -> localhost | |.o. = S . | 2025-05-31 03:14:43.953867 | debian-bookworm -> localhost | |E. . . | 2025-05-31 03:14:43.953888 | debian-bookworm -> localhost | | | 2025-05-31 03:14:43.953908 | debian-bookworm -> localhost | | | 2025-05-31 03:14:43.953929 | debian-bookworm -> localhost | | | 2025-05-31 03:14:43.953950 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-05-31 03:14:43.954011 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.806180 2025-05-31 03:14:43.961672 | 2025-05-31 03:14:43.961798 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-05-31 03:14:43.993382 | debian-bookworm | ok 2025-05-31 03:14:44.003538 | debian-bookworm | included: /var/lib/zuul/builds/0eab4d2fc56b453fa8b38eefb48beee3/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-05-31 03:14:44.013190 | 2025-05-31 03:14:44.013295 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-05-31 03:14:44.047306 | debian-bookworm | skipping: Conditional result was False 2025-05-31 03:14:44.053649 | 2025-05-31 03:14:44.053761 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-05-31 03:14:44.736680 | debian-bookworm | changed 2025-05-31 03:14:44.747408 | 2025-05-31 03:14:44.747566 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-05-31 03:14:45.038888 | debian-bookworm | ok 2025-05-31 03:14:45.048429 | 2025-05-31 03:14:45.048567 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-05-31 03:14:45.725780 | debian-bookworm | changed 2025-05-31 03:14:45.734336 | 2025-05-31 03:14:45.734494 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-05-31 03:14:46.454371 | debian-bookworm | changed 2025-05-31 03:14:46.465302 | 2025-05-31 03:14:46.467144 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-05-31 03:14:46.493281 | debian-bookworm | skipping: Conditional result was False 2025-05-31 03:14:46.502433 | 2025-05-31 03:14:46.502554 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-05-31 03:14:47.047641 | debian-bookworm -> localhost | changed 2025-05-31 03:14:47.091861 | 2025-05-31 03:14:47.092148 | TASK [add-build-sshkey : Add back temp key] 2025-05-31 03:14:47.450345 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/0eab4d2fc56b453fa8b38eefb48beee3/work/0eab4d2fc56b453fa8b38eefb48beee3_id_rsa (zuul-build-sshkey) 2025-05-31 03:14:47.450966 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.013134 2025-05-31 03:14:47.462484 | 2025-05-31 03:14:47.462642 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-05-31 03:14:47.908138 | debian-bookworm | ok 2025-05-31 03:14:47.917745 | 2025-05-31 03:14:47.917920 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-05-31 03:14:47.964834 | debian-bookworm | skipping: Conditional result was False 2025-05-31 03:14:47.986352 | 2025-05-31 03:14:47.986537 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-05-31 03:14:48.452028 | debian-bookworm | ok 2025-05-31 03:14:48.466422 | 2025-05-31 03:14:48.466553 | TASK [validate-host : Define zuul_info_dir fact] 2025-05-31 03:14:48.515284 | debian-bookworm | ok 2025-05-31 03:14:48.524097 | 2025-05-31 03:14:48.524236 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-05-31 03:14:48.843652 | debian-bookworm -> localhost | ok 2025-05-31 03:14:48.851009 | 2025-05-31 03:14:48.851151 | TASK [validate-host : Collect information about the host] 2025-05-31 03:14:50.078705 | debian-bookworm | ok 2025-05-31 03:14:50.094034 | 2025-05-31 03:14:50.094193 | TASK [validate-host : Sanitize hostname] 2025-05-31 03:14:50.229611 | debian-bookworm | ok 2025-05-31 03:14:50.288285 | 2025-05-31 03:14:50.288467 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-05-31 03:14:50.883910 | debian-bookworm -> localhost | changed 2025-05-31 03:14:50.897052 | 2025-05-31 03:14:50.898617 | TASK [validate-host : Collect information about zuul worker] 2025-05-31 03:14:51.325338 | debian-bookworm | ok 2025-05-31 03:14:51.336606 | 2025-05-31 03:14:51.336807 | TASK [validate-host : Write out all zuul information for each host] 2025-05-31 03:14:52.093453 | debian-bookworm -> localhost | changed 2025-05-31 03:14:52.103752 | 2025-05-31 03:14:52.103874 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-05-31 03:14:52.431432 | debian-bookworm | ok 2025-05-31 03:14:52.440362 | 2025-05-31 03:14:52.440490 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-05-31 03:14:53.585491 | debian-bookworm | changed: 2025-05-31 03:14:53.585731 | debian-bookworm | cd+++++++++ src/ 2025-05-31 03:14:53.585769 | debian-bookworm | cd+++++++++ src/github.com/ 2025-05-31 03:14:53.585796 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-05-31 03:14:53.585867 | debian-bookworm | cd+++++++++ src/github.com/osism/openstack-image-manager/ 2025-05-31 03:14:53.585892 | debian-bookworm | =5.5.1 2025-05-31 03:15:15.803839 | debian-bookworm | Downloading cachetools-6.0.0-py3-none-any.whl (10 kB) 2025-05-31 03:15:15.845712 | debian-bookworm | Collecting chardet>=5.2 2025-05-31 03:15:15.854202 | debian-bookworm | Downloading chardet-5.2.0-py3-none-any.whl (199 kB) 2025-05-31 03:15:15.871154 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 199.4/199.4 kB 14.4 MB/s eta 0:00:00 2025-05-31 03:15:15.906146 | debian-bookworm | Collecting colorama>=0.4.6 2025-05-31 03:15:15.913686 | debian-bookworm | Downloading colorama-0.4.6-py2.py3-none-any.whl (25 kB) 2025-05-31 03:15:15.958153 | debian-bookworm | Collecting filelock>=3.16.1 2025-05-31 03:15:15.966557 | debian-bookworm | Downloading filelock-3.18.0-py3-none-any.whl (16 kB) 2025-05-31 03:15:16.009083 | debian-bookworm | Collecting packaging>=24.2 2025-05-31 03:15:16.016619 | debian-bookworm | Downloading packaging-25.0-py3-none-any.whl (66 kB) 2025-05-31 03:15:16.026499 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 66.5/66.5 kB 7.6 MB/s eta 0:00:00 2025-05-31 03:15:16.065843 | debian-bookworm | Collecting platformdirs>=4.3.6 2025-05-31 03:15:16.073207 | debian-bookworm | Downloading platformdirs-4.3.8-py3-none-any.whl (18 kB) 2025-05-31 03:15:16.107132 | debian-bookworm | Collecting pluggy>=1.5 2025-05-31 03:15:16.116272 | debian-bookworm | Downloading pluggy-1.6.0-py3-none-any.whl (20 kB) 2025-05-31 03:15:16.161996 | debian-bookworm | Collecting pyproject-api>=1.8 2025-05-31 03:15:16.169864 | debian-bookworm | Downloading pyproject_api-1.9.1-py3-none-any.whl (13 kB) 2025-05-31 03:15:16.288169 | debian-bookworm | Collecting virtualenv>=20.31 2025-05-31 03:15:16.295756 | debian-bookworm | Downloading virtualenv-20.31.2-py3-none-any.whl (6.1 MB) 2025-05-31 03:15:16.555978 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 6.1/6.1 MB 23.7 MB/s eta 0:00:00 2025-05-31 03:15:16.679188 | debian-bookworm | Collecting distlib<1,>=0.3.7 2025-05-31 03:15:16.687043 | debian-bookworm | Downloading distlib-0.3.9-py2.py3-none-any.whl (468 kB) 2025-05-31 03:15:16.713060 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 469.0/469.0 kB 19.9 MB/s eta 0:00:00 2025-05-31 03:15:16.860436 | debian-bookworm | Installing collected packages: distlib, pluggy, platformdirs, packaging, filelock, colorama, chardet, cachetools, virtualenv, pyproject-api, tox 2025-05-31 03:15:17.618382 | debian-bookworm | Successfully installed cachetools-6.0.0 chardet-5.2.0 colorama-0.4.6 distlib-0.3.9 filelock-3.18.0 packaging-25.0 platformdirs-4.3.8 pluggy-1.6.0 pyproject-api-1.9.1 tox-4.26.0 virtualenv-20.31.2 2025-05-31 03:15:17.879461 | debian-bookworm | ok: Runtime: 0:00:02.596867 2025-05-31 03:15:17.901814 | 2025-05-31 03:15:17.902006 | TASK [ensure-tox : Export installed tox_executable path] 2025-05-31 03:15:17.938544 | debian-bookworm | ok 2025-05-31 03:15:17.945605 | 2025-05-31 03:15:17.945782 | TASK [ensure-tox : Output tox version] 2025-05-31 03:15:18.426066 | debian-bookworm | ROOT: No tox.ini or setup.cfg or pyproject.toml or tox.toml found, assuming empty tox.ini at /home/zuul 2025-05-31 03:15:18.443931 | debian-bookworm | 4.26.0 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2025-05-31 03:15:18.531213 | debian-bookworm | ok: Runtime: 0:00:00.266886 2025-05-31 03:15:18.540148 | 2025-05-31 03:15:18.540306 | TASK [ensure-tox : Make global symlink] 2025-05-31 03:15:18.567295 | debian-bookworm | skipping: Conditional result was False 2025-05-31 03:15:18.578882 | 2025-05-31 03:15:18.578996 | PLAY RECAP 2025-05-31 03:15:18.579091 | debian-bookworm | ok: 13 changed: 9 unreachable: 0 failed: 0 skipped: 14 rescued: 0 ignored: 0 2025-05-31 03:15:18.579131 | 2025-05-31 03:15:18.735921 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/pre.yaml@master] 2025-05-31 03:15:18.737839 | RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2025-05-31 03:15:19.516241 | 2025-05-31 03:15:19.516438 | PLAY [all] 2025-05-31 03:15:19.533091 | 2025-05-31 03:15:19.533263 | TASK [revoke-sudo : Check if zuul is sudoer] 2025-05-31 03:15:20.013235 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2025-05-31 03:15:20.065110 | debian-bookworm | ok: Runtime: 0:00:00.021120 2025-05-31 03:15:20.075358 | 2025-05-31 03:15:20.075556 | TASK [revoke-sudo : Remove sudo access for zuul user.] 2025-05-31 03:15:20.606955 | debian-bookworm | ok: Runtime: 0:00:00.006559 2025-05-31 03:15:20.614131 | 2025-05-31 03:15:20.614269 | TASK [revoke-sudo : Prove that general sudo access is actually revoked.] 2025-05-31 03:15:20.932117 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2025-05-31 03:15:20.933775 | debian-bookworm | sudo: a password is required 2025-05-31 03:15:21.165761 | debian-bookworm | ok: Runtime: 0:00:00.017066 2025-05-31 03:15:21.184141 | 2025-05-31 03:15:21.184282 | TASK [tox : Check to see if the constraints file exists] 2025-05-31 03:15:21.220629 | debian-bookworm | skipping: Conditional result was False 2025-05-31 03:15:21.234010 | 2025-05-31 03:15:21.234219 | TASK [tox : Fail if constraints file is missing] 2025-05-31 03:15:21.273363 | debian-bookworm | skipping: Conditional result was False 2025-05-31 03:15:21.289722 | 2025-05-31 03:15:21.289984 | TASK [tox : Record file location] 2025-05-31 03:15:21.326903 | debian-bookworm | skipping: Conditional result was False 2025-05-31 03:15:21.340736 | 2025-05-31 03:15:21.340955 | TASK [tox : Install tox siblings] 2025-05-31 03:15:21.381170 | debian-bookworm | ok 2025-05-31 03:15:21.393982 | debian-bookworm | included: /var/lib/zuul/builds/0eab4d2fc56b453fa8b38eefb48beee3/untrusted/project_0/github.com/osism/openinfra-zuul-jobs/roles/tox/tasks/siblings.yaml 2025-05-31 03:15:21.401355 | 2025-05-31 03:15:21.401496 | TASK [tox : Run tox without tests] 2025-05-31 03:15:22.441599 | debian-bookworm | test: install_deps> python -I -m pip install -r requirements.txt 2025-05-31 03:15:35.638300 | debian-bookworm | test: OK (13.69 seconds) 2025-05-31 03:15:35.638372 | debian-bookworm | congratulations :) (13.76 seconds) 2025-05-31 03:15:35.941193 | debian-bookworm | ok: Runtime: 0:00:13.975233 2025-05-31 03:15:35.975677 | 2025-05-31 03:15:35.975819 | TASK [tox : Create a tempfile to save tox showconfig] 2025-05-31 03:15:36.858148 | debian-bookworm | changed 2025-05-31 03:15:36.863945 | 2025-05-31 03:15:36.864084 | TASK [tox : Get tox envlist config] 2025-05-31 03:15:37.900221 | debian-bookworm | ok: Runtime: 0:00:00.278654 2025-05-31 03:15:37.908882 | 2025-05-31 03:15:37.909013 | TASK [tox : Install any sibling python packages] 2025-05-31 03:15:38.687267 | debian-bookworm | ok: No setup.cfg, no action needed 2025-05-31 03:15:38.711787 | 2025-05-31 03:15:38.711988 | TASK [tox : Remove tempfile] 2025-05-31 03:15:39.487125 | debian-bookworm | changed 2025-05-31 03:15:39.496190 | 2025-05-31 03:15:39.496308 | TASK [tox : Emit tox command] 2025-05-31 03:15:39.602616 | /home/zuul/.local/tox/bin/tox -etest -- test/unit 2025-05-31 03:15:39.609358 | 2025-05-31 03:15:39.609754 | TASK [tox : Run tox] 2025-05-31 03:15:40.351968 | debian-bookworm | test: commands[0]> python -m unittest discover test/unit 2025-05-31 03:15:40.834542 | debian-bookworm | /home/zuul/src/github.com/osism/openstack-image-manager/openstack_image_manager/__init__.py:5: UserWarning: pkg_resources is deprecated as an API. See https://setuptools.pypa.io/en/latest/pkg_resources.html. The pkg_resources package is slated for removal as early as 2025-11-30. Refrain from using this package or pin to Setuptools<81. 2025-05-31 03:15:40.834600 | debian-bookworm | from pkg_resources import get_distribution, DistributionNotFound 2025-05-31 03:15:41.149333 | debian-bookworm | ............... 2025-05-31 03:15:41.149382 | debian-bookworm | ---------------------------------------------------------------------- 2025-05-31 03:15:41.149413 | debian-bookworm | Ran 15 tests in 0.170s 2025-05-31 03:15:41.149419 | debian-bookworm | 2025-05-31 03:15:41.149439 | debian-bookworm | OK 2025-05-31 03:15:41.266367 | debian-bookworm | test: OK (0.96=setup[0.04]+cmd[0.91] seconds) 2025-05-31 03:15:41.266435 | debian-bookworm | congratulations :) (1.03 seconds) 2025-05-31 03:15:41.746720 | debian-bookworm | ok: Runtime: 0:00:01.269707 2025-05-31 03:15:41.754318 | 2025-05-31 03:15:41.754420 | TASK [tox : Look for output] 2025-05-31 03:15:42.414484 | debian-bookworm | ok 2025-05-31 03:15:42.449710 | 2025-05-31 03:15:42.449852 | TASK [tox : Return file comments to Zuul] 2025-05-31 03:15:42.590915 | debian-bookworm | skipping: Conditional result was False 2025-05-31 03:15:42.599621 | 2025-05-31 03:15:42.599821 | PLAY RECAP 2025-05-31 03:15:42.599892 | debian-bookworm | ok: 12 changed: 8 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 0 2025-05-31 03:15:42.599918 | 2025-05-31 03:15:42.978851 | RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2025-05-31 03:15:42.980623 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2025-05-31 03:15:44.027623 | 2025-05-31 03:15:44.027775 | PLAY [all] 2025-05-31 03:15:44.049730 | 2025-05-31 03:15:44.049883 | TASK [fetch-tox-output : Set tox log path for multiple nodes] 2025-05-31 03:15:44.187212 | debian-bookworm | skipping: Conditional result was False 2025-05-31 03:15:44.195187 | 2025-05-31 03:15:44.195343 | TASK [fetch-tox-output : Set tox log path for single node] 2025-05-31 03:15:44.296136 | debian-bookworm | ok 2025-05-31 03:15:44.301867 | 2025-05-31 03:15:44.301987 | TASK [fetch-tox-output : Ensure local tox dir] 2025-05-31 03:15:45.385291 | debian-bookworm -> localhost | changed 2025-05-31 03:15:45.394445 | 2025-05-31 03:15:45.394599 | TASK [fetch-tox-output : Ensure zuul-output tox dir] 2025-05-31 03:15:45.469782 | debian-bookworm | skipping: Conditional result was False 2025-05-31 03:15:45.476882 | 2025-05-31 03:15:45.477020 | TASK [fetch-tox-output : Set envlist fact] 2025-05-31 03:15:45.527108 | debian-bookworm | ok 2025-05-31 03:15:45.533416 | 2025-05-31 03:15:45.533550 | TASK [fetch-tox-output : Get tox version] 2025-05-31 03:15:46.269727 | debian-bookworm | ROOT: No tox.ini or setup.cfg or pyproject.toml or tox.toml found, assuming empty tox.ini at /home/zuul 2025-05-31 03:15:46.288153 | debian-bookworm | 4.26.0 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2025-05-31 03:15:46.575757 | debian-bookworm | ok: Runtime: 0:00:00.284881 2025-05-31 03:15:46.582086 | 2025-05-31 03:15:46.582236 | TASK [fetch-tox-output : Find all default environments] 2025-05-31 03:15:47.117417 | debian-bookworm | skipping: Conditional result was False 2025-05-31 03:15:47.125300 | 2025-05-31 03:15:47.125480 | TASK [fetch-tox-output : Set envlist fact] 2025-05-31 03:15:47.189650 | debian-bookworm | skipping: Conditional result was False 2025-05-31 03:15:47.197391 | 2025-05-31 03:15:47.197532 | TASK [fetch-tox-output : Find all default environments] 2025-05-31 03:15:47.745307 | debian-bookworm | skipping: Conditional result was False 2025-05-31 03:15:47.756296 | 2025-05-31 03:15:47.756502 | TASK [fetch-tox-output : Set envlist fact] 2025-05-31 03:15:47.792858 | debian-bookworm | skipping: Conditional result was False 2025-05-31 03:15:47.802738 | 2025-05-31 03:15:47.803120 | LOOP [fetch-tox-output : Copy tox logs] 2025-05-31 03:15:47.882211 | debian-bookworm | skipping: Conditional result was False 2025-05-31 03:15:47.902352 | 2025-05-31 03:15:47.902665 | LOOP [fetch-tox-output : Collect tox logs] 2025-05-31 03:15:49.383299 | debian-bookworm | changed: 2025-05-31 03:15:49.383599 | debian-bookworm | created directory /var/lib/zuul/builds/0eab4d2fc56b453fa8b38eefb48beee3/work/logs/tox/test 2025-05-31 03:15:49.383640 | debian-bookworm | cd+++++++++ ./ 2025-05-31 03:15:49.383666 | debian-bookworm | >f+++++++++ 1-commands[0].log 2025-05-31 03:15:49.383697 | 2025-05-31 03:15:49.411633 | 2025-05-31 03:15:49.411895 | PLAY RECAP 2025-05-31 03:15:49.411972 | debian-bookworm | ok: 5 changed: 3 unreachable: 0 failed: 0 skipped: 7 rescued: 0 ignored: 0 2025-05-31 03:15:49.412115 | 2025-05-31 03:15:50.132166 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2025-05-31 03:15:50.133286 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2025-05-31 03:15:52.880357 | 2025-05-31 03:15:52.880519 | PLAY [all] 2025-05-31 03:15:52.922576 | 2025-05-31 03:15:52.922729 | TASK [fetch-subunit-output : Find stestr or testr executable] 2025-05-31 03:15:53.220731 | debian-bookworm | changed: non-zero return code 2025-05-31 03:15:53.244438 | 2025-05-31 03:15:53.244991 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2025-05-31 03:15:53.303677 | debian-bookworm | skipping: Conditional result was False 2025-05-31 03:15:53.309416 | 2025-05-31 03:15:53.309515 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2025-05-31 03:15:53.350164 | 2025-05-31 03:15:53.350517 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2025-05-31 03:15:53.393437 | 2025-05-31 03:15:53.393608 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2025-05-31 03:15:53.418003 | debian-bookworm | skipping: Conditional result was False 2025-05-31 03:15:53.424125 | 2025-05-31 03:15:53.424278 | LOOP [fetch-subunit-output : Generate subunit file] 2025-05-31 03:15:53.453183 | 2025-05-31 03:15:53.453363 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2025-05-31 03:15:53.527305 | debian-bookworm | skipping: Conditional result was False 2025-05-31 03:15:53.533113 | 2025-05-31 03:15:53.533230 | TASK [fetch-subunit-output : Remove the temporary file] 2025-05-31 03:15:53.556671 | debian-bookworm | skipping: Conditional result was False 2025-05-31 03:15:53.562069 | 2025-05-31 03:15:53.562175 | TASK [fetch-subunit-output : Process and fetch subunit results] 2025-05-31 03:15:53.576700 | debian-bookworm | skipping: Conditional result was False 2025-05-31 03:15:53.591748 | 2025-05-31 03:15:53.591899 | PLAY RECAP 2025-05-31 03:15:53.591982 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2025-05-31 03:15:53.592025 | 2025-05-31 03:15:53.707101 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2025-05-31 03:15:53.709464 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-05-31 03:15:54.440771 | 2025-05-31 03:15:54.440916 | PLAY [Base post] 2025-05-31 03:15:54.481159 | 2025-05-31 03:15:54.481325 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-05-31 03:15:55.057796 | debian-bookworm | changed 2025-05-31 03:15:55.064791 | 2025-05-31 03:15:55.064907 | PLAY RECAP 2025-05-31 03:15:55.064969 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-05-31 03:15:55.065048 | 2025-05-31 03:15:55.154561 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-05-31 03:15:55.155542 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2025-05-31 03:15:56.050924 | 2025-05-31 03:15:56.051100 | PLAY [Base post-logs] 2025-05-31 03:15:56.060816 | 2025-05-31 03:15:56.060947 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-05-31 03:15:56.731734 | localhost | changed 2025-05-31 03:15:56.743721 | 2025-05-31 03:15:56.743930 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-05-31 03:15:56.769167 | localhost | ok 2025-05-31 03:15:56.772480 | 2025-05-31 03:15:56.772583 | TASK [Set zuul-log-path fact] 2025-05-31 03:15:56.788441 | localhost | ok 2025-05-31 03:15:56.795864 | 2025-05-31 03:15:56.795966 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-05-31 03:15:56.823998 | localhost | ok 2025-05-31 03:15:56.826922 | 2025-05-31 03:15:56.827011 | TASK [upload-logs : Create log directories] 2025-05-31 03:15:57.591008 | localhost | changed 2025-05-31 03:15:57.593626 | 2025-05-31 03:15:57.593712 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-05-31 03:15:58.557697 | localhost -> localhost | ok: Runtime: 0:00:00.013837 2025-05-31 03:15:58.561690 | 2025-05-31 03:15:58.561796 | TASK [upload-logs : Upload logs to log server] 2025-05-31 03:15:59.583667 | localhost | Output suppressed because no_log was given 2025-05-31 03:15:59.585331 | 2025-05-31 03:15:59.585522 | LOOP [upload-logs : Compress console log and json output] 2025-05-31 03:15:59.683553 | localhost | skipping: Conditional result was False 2025-05-31 03:15:59.684595 | localhost | skipping: Conditional result was False 2025-05-31 03:15:59.689949 | 2025-05-31 03:15:59.690073 | LOOP [upload-logs : Upload compressed console log and json output] 2025-05-31 03:15:59.775082 | localhost | skipping: Conditional result was False 2025-05-31 03:15:59.775426 | 2025-05-31 03:15:59.788560 | localhost | skipping: Conditional result was False 2025-05-31 03:15:59.798136 | 2025-05-31 03:15:59.798282 | LOOP [upload-logs : Upload console log and json output]