2025-09-21 03:10:33.953211 | Job console starting 2025-09-21 03:10:33.993119 | Updating git repos 2025-09-21 03:10:34.038590 | Cloning repos into workspace 2025-09-21 03:10:34.138497 | Restoring repo states 2025-09-21 03:10:34.147972 | Merging changes 2025-09-21 03:10:34.147991 | Checking out repos 2025-09-21 03:10:34.209691 | Preparing playbooks 2025-09-21 03:10:35.237046 | Running Ansible setup 2025-09-21 03:10:42.055741 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-09-21 03:10:43.446965 | 2025-09-21 03:10:43.447077 | PLAY [Base pre] 2025-09-21 03:10:43.463702 | 2025-09-21 03:10:43.463797 | TASK [Setup log path fact] 2025-09-21 03:10:43.495094 | debian-bookworm | ok 2025-09-21 03:10:43.519122 | 2025-09-21 03:10:43.519234 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-09-21 03:10:43.560144 | debian-bookworm | ok 2025-09-21 03:10:43.581157 | 2025-09-21 03:10:43.581259 | TASK [emit-job-header : Print job information] 2025-09-21 03:10:43.619260 | # Job Information 2025-09-21 03:10:43.619404 | Ansible Version: 2.16.14 2025-09-21 03:10:43.619434 | Job: tox 2025-09-21 03:10:43.619461 | Pipeline: periodic-daily 2025-09-21 03:10:43.619480 | Executor: 521e9411259a 2025-09-21 03:10:43.619497 | Triggered by: https://github.com/osism/openstack-image-manager 2025-09-21 03:10:43.619516 | Event ID: 486c3a79a8cf4213b4ac0d5f762a4388 2025-09-21 03:10:43.629574 | 2025-09-21 03:10:43.629658 | LOOP [emit-job-header : Print node information] 2025-09-21 03:10:43.921527 | debian-bookworm | ok: 2025-09-21 03:10:43.921708 | debian-bookworm | # Node Information 2025-09-21 03:10:43.921737 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-09-21 03:10:43.921757 | debian-bookworm | Hostname: debian 2025-09-21 03:10:43.921776 | debian-bookworm | Username: zuul 2025-09-21 03:10:43.921793 | debian-bookworm | Distro: Debian 12.12 2025-09-21 03:10:43.921812 | debian-bookworm | Provider: regiocloud-a 2025-09-21 03:10:43.921829 | debian-bookworm | Region: 2025-09-21 03:10:43.921846 | debian-bookworm | Label: debian-bookworm 2025-09-21 03:10:43.921863 | debian-bookworm | Product Name: OpenStack Nova 2025-09-21 03:10:43.921879 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe13:5d3 2025-09-21 03:10:43.939806 | 2025-09-21 03:10:43.939902 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-09-21 03:10:45.084127 | debian-bookworm -> localhost | changed 2025-09-21 03:10:45.090017 | 2025-09-21 03:10:45.090100 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-09-21 03:10:47.349014 | debian-bookworm -> localhost | changed 2025-09-21 03:10:47.377463 | 2025-09-21 03:10:47.377609 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-09-21 03:10:47.972188 | debian-bookworm -> localhost | ok 2025-09-21 03:10:47.981321 | 2025-09-21 03:10:47.981501 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-09-21 03:10:48.010267 | debian-bookworm | ok 2025-09-21 03:10:48.047603 | debian-bookworm | included: /var/lib/zuul/builds/8d8647dbf93f42c6bb9e3faac3195b6d/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-09-21 03:10:48.053622 | 2025-09-21 03:10:48.053712 | TASK [add-build-sshkey : Create Temp SSH key] 2025-09-21 03:10:49.603962 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-09-21 03:10:49.604205 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/8d8647dbf93f42c6bb9e3faac3195b6d/work/8d8647dbf93f42c6bb9e3faac3195b6d_id_rsa 2025-09-21 03:10:49.604243 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/8d8647dbf93f42c6bb9e3faac3195b6d/work/8d8647dbf93f42c6bb9e3faac3195b6d_id_rsa.pub 2025-09-21 03:10:49.604269 | debian-bookworm -> localhost | The key fingerprint is: 2025-09-21 03:10:49.604295 | debian-bookworm -> localhost | SHA256:mTLJ7HNWCnkYpfVPfGhGkjqxAJMsF3IN+Rc0cg8ngh0 zuul-build-sshkey 2025-09-21 03:10:49.604316 | debian-bookworm -> localhost | The key's randomart image is: 2025-09-21 03:10:49.604345 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-09-21 03:10:49.604367 | debian-bookworm -> localhost | | ..*XEoO o.. | 2025-09-21 03:10:49.604388 | debian-bookworm -> localhost | | .o*.+BoB.+ . | 2025-09-21 03:10:49.604408 | debian-bookworm -> localhost | | o .o. =o * . | 2025-09-21 03:10:49.604428 | debian-bookworm -> localhost | | o.==o = . | 2025-09-21 03:10:49.604447 | debian-bookworm -> localhost | | X.S.. . | 2025-09-21 03:10:49.604469 | debian-bookworm -> localhost | | . = o | 2025-09-21 03:10:49.604490 | debian-bookworm -> localhost | | o + | 2025-09-21 03:10:49.604509 | debian-bookworm -> localhost | | + | 2025-09-21 03:10:49.604529 | debian-bookworm -> localhost | | | 2025-09-21 03:10:49.604549 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-09-21 03:10:49.604602 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.957635 2025-09-21 03:10:49.611057 | 2025-09-21 03:10:49.611152 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-09-21 03:10:49.635477 | debian-bookworm | ok 2025-09-21 03:10:49.644882 | debian-bookworm | included: /var/lib/zuul/builds/8d8647dbf93f42c6bb9e3faac3195b6d/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-09-21 03:10:49.660834 | 2025-09-21 03:10:49.660944 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-09-21 03:10:49.686170 | debian-bookworm | skipping: Conditional result was False 2025-09-21 03:10:49.692024 | 2025-09-21 03:10:49.692111 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-09-21 03:10:50.343095 | debian-bookworm | changed 2025-09-21 03:10:50.350302 | 2025-09-21 03:10:50.350378 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-09-21 03:10:50.649331 | debian-bookworm | ok 2025-09-21 03:10:50.655019 | 2025-09-21 03:10:50.655111 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-09-21 03:10:51.305443 | debian-bookworm | changed 2025-09-21 03:10:51.318885 | 2025-09-21 03:10:51.319354 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-09-21 03:10:51.950547 | debian-bookworm | changed 2025-09-21 03:10:51.956105 | 2025-09-21 03:10:51.956200 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-09-21 03:10:51.990816 | debian-bookworm | skipping: Conditional result was False 2025-09-21 03:10:51.997163 | 2025-09-21 03:10:51.997262 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-09-21 03:10:52.580265 | debian-bookworm -> localhost | changed 2025-09-21 03:10:52.596141 | 2025-09-21 03:10:52.596246 | TASK [add-build-sshkey : Add back temp key] 2025-09-21 03:10:52.962112 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/8d8647dbf93f42c6bb9e3faac3195b6d/work/8d8647dbf93f42c6bb9e3faac3195b6d_id_rsa (zuul-build-sshkey) 2025-09-21 03:10:52.962333 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.016038 2025-09-21 03:10:52.974800 | 2025-09-21 03:10:52.974910 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-09-21 03:10:53.352430 | debian-bookworm | ok 2025-09-21 03:10:53.361649 | 2025-09-21 03:10:53.362119 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-09-21 03:10:53.447276 | debian-bookworm | skipping: Conditional result was False 2025-09-21 03:10:53.461709 | 2025-09-21 03:10:53.462068 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-09-21 03:10:54.084332 | debian-bookworm | ok 2025-09-21 03:10:54.100187 | 2025-09-21 03:10:54.100308 | TASK [validate-host : Define zuul_info_dir fact] 2025-09-21 03:10:54.128557 | debian-bookworm | ok 2025-09-21 03:10:54.132967 | 2025-09-21 03:10:54.133046 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-09-21 03:10:54.840129 | debian-bookworm -> localhost | ok 2025-09-21 03:10:54.856724 | 2025-09-21 03:10:54.856837 | TASK [validate-host : Collect information about the host] 2025-09-21 03:10:56.404737 | debian-bookworm | ok 2025-09-21 03:10:56.423208 | 2025-09-21 03:10:56.423306 | TASK [validate-host : Sanitize hostname] 2025-09-21 03:10:56.649364 | debian-bookworm | ok 2025-09-21 03:10:56.717285 | 2025-09-21 03:10:56.717393 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-09-21 03:10:57.421049 | debian-bookworm -> localhost | changed 2025-09-21 03:10:57.425898 | 2025-09-21 03:10:57.426010 | TASK [validate-host : Collect information about zuul worker] 2025-09-21 03:10:57.810365 | debian-bookworm | ok 2025-09-21 03:10:57.821770 | 2025-09-21 03:10:57.821882 | TASK [validate-host : Write out all zuul information for each host] 2025-09-21 03:10:58.883142 | debian-bookworm -> localhost | changed 2025-09-21 03:10:58.892116 | 2025-09-21 03:10:58.892191 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-09-21 03:10:59.207357 | debian-bookworm | ok 2025-09-21 03:10:59.211793 | 2025-09-21 03:10:59.211868 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-09-21 03:11:00.799550 | debian-bookworm | changed: 2025-09-21 03:11:00.799886 | debian-bookworm | cd+++++++++ src/ 2025-09-21 03:11:00.800037 | debian-bookworm | cd+++++++++ src/github.com/ 2025-09-21 03:11:00.800082 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-09-21 03:11:00.800121 | debian-bookworm | cd+++++++++ src/github.com/osism/openstack-image-manager/ 2025-09-21 03:11:00.800156 | debian-bookworm | =6.1 2025-09-21 03:11:24.488989 | debian-bookworm | Downloading cachetools-6.2.0-py3-none-any.whl (11 kB) 2025-09-21 03:11:24.518324 | debian-bookworm | Collecting chardet>=5.2 2025-09-21 03:11:24.526151 | debian-bookworm | Downloading chardet-5.2.0-py3-none-any.whl (199 kB) 2025-09-21 03:11:24.550583 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 199.4/199.4 kB 9.0 MB/s eta 0:00:00 2025-09-21 03:11:24.582975 | debian-bookworm | Collecting colorama>=0.4.6 2025-09-21 03:11:24.590941 | debian-bookworm | Downloading colorama-0.4.6-py2.py3-none-any.whl (25 kB) 2025-09-21 03:11:24.656147 | debian-bookworm | Collecting filelock>=3.18 2025-09-21 03:11:24.663886 | debian-bookworm | Downloading filelock-3.19.1-py3-none-any.whl (15 kB) 2025-09-21 03:11:24.707331 | debian-bookworm | Collecting packaging>=25 2025-09-21 03:11:24.715181 | debian-bookworm | Downloading packaging-25.0-py3-none-any.whl (66 kB) 2025-09-21 03:11:24.725594 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 66.5/66.5 kB 9.6 MB/s eta 0:00:00 2025-09-21 03:11:24.778700 | debian-bookworm | Collecting platformdirs>=4.3.8 2025-09-21 03:11:24.787555 | debian-bookworm | Downloading platformdirs-4.4.0-py3-none-any.whl (18 kB) 2025-09-21 03:11:24.826284 | debian-bookworm | Collecting pluggy>=1.6 2025-09-21 03:11:24.835382 | debian-bookworm | Downloading pluggy-1.6.0-py3-none-any.whl (20 kB) 2025-09-21 03:11:24.871259 | debian-bookworm | Collecting pyproject-api>=1.9.1 2025-09-21 03:11:24.879330 | debian-bookworm | Downloading pyproject_api-1.9.1-py3-none-any.whl (13 kB) 2025-09-21 03:11:25.012409 | debian-bookworm | Collecting virtualenv>=20.31.2 2025-09-21 03:11:25.019970 | debian-bookworm | Downloading virtualenv-20.34.0-py3-none-any.whl (6.0 MB) 2025-09-21 03:11:25.300304 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 6.0/6.0 MB 21.7 MB/s eta 0:00:00 2025-09-21 03:11:25.398577 | debian-bookworm | Collecting distlib<1,>=0.3.7 2025-09-21 03:11:25.406037 | debian-bookworm | Downloading distlib-0.4.0-py2.py3-none-any.whl (469 kB) 2025-09-21 03:11:25.432623 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 469.0/469.0 kB 21.4 MB/s eta 0:00:00 2025-09-21 03:11:25.581874 | debian-bookworm | Installing collected packages: distlib, pluggy, platformdirs, packaging, filelock, colorama, chardet, cachetools, virtualenv, pyproject-api, tox 2025-09-21 03:11:26.442187 | debian-bookworm | Successfully installed cachetools-6.2.0 chardet-5.2.0 colorama-0.4.6 distlib-0.4.0 filelock-3.19.1 packaging-25.0 platformdirs-4.4.0 pluggy-1.6.0 pyproject-api-1.9.1 tox-4.30.2 virtualenv-20.34.0 2025-09-21 03:11:26.997925 | debian-bookworm | ok: Runtime: 0:00:02.777463 2025-09-21 03:11:27.003428 | 2025-09-21 03:11:27.003508 | TASK [ensure-tox : Export installed tox_executable path] 2025-09-21 03:11:27.042334 | debian-bookworm | ok 2025-09-21 03:11:27.049504 | 2025-09-21 03:11:27.049611 | TASK [ensure-tox : Output tox version] 2025-09-21 03:11:27.553825 | debian-bookworm | ROOT: No loadable tox.ini or setup.cfg or pyproject.toml or tox.toml found, assuming empty tox.ini at /home/zuul 2025-09-21 03:11:27.572321 | debian-bookworm | 4.30.2 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2025-09-21 03:11:28.076780 | debian-bookworm | ok: Runtime: 0:00:00.306631 2025-09-21 03:11:28.081249 | 2025-09-21 03:11:28.081321 | TASK [ensure-tox : Make global symlink] 2025-09-21 03:11:28.104511 | debian-bookworm | skipping: Conditional result was False 2025-09-21 03:11:28.110755 | 2025-09-21 03:11:28.111108 | PLAY RECAP 2025-09-21 03:11:28.111264 | debian-bookworm | ok: 13 changed: 9 unreachable: 0 failed: 0 skipped: 14 rescued: 0 ignored: 0 2025-09-21 03:11:28.111337 | 2025-09-21 03:11:28.216498 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/pre.yaml@master] 2025-09-21 03:11:28.217516 | RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2025-09-21 03:11:28.888469 | 2025-09-21 03:11:28.888588 | PLAY [all] 2025-09-21 03:11:28.916932 | 2025-09-21 03:11:28.917046 | TASK [revoke-sudo : Check if zuul is sudoer] 2025-09-21 03:11:29.392720 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2025-09-21 03:11:29.449938 | debian-bookworm | ok: Runtime: 0:00:00.026215 2025-09-21 03:11:29.455676 | 2025-09-21 03:11:29.455789 | TASK [revoke-sudo : Remove sudo access for zuul user.] 2025-09-21 03:11:29.985328 | debian-bookworm | ok: Runtime: 0:00:00.007423 2025-09-21 03:11:29.992356 | 2025-09-21 03:11:29.992504 | TASK [revoke-sudo : Prove that general sudo access is actually revoked.] 2025-09-21 03:11:30.291349 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2025-09-21 03:11:30.293137 | debian-bookworm | sudo: a password is required 2025-09-21 03:11:30.526543 | debian-bookworm | ok: Runtime: 0:00:00.019214 2025-09-21 03:11:30.537450 | 2025-09-21 03:11:30.537570 | TASK [tox : Check to see if the constraints file exists] 2025-09-21 03:11:30.574133 | debian-bookworm | skipping: Conditional result was False 2025-09-21 03:11:30.581011 | 2025-09-21 03:11:30.581151 | TASK [tox : Fail if constraints file is missing] 2025-09-21 03:11:30.636252 | debian-bookworm | skipping: Conditional result was False 2025-09-21 03:11:30.643778 | 2025-09-21 03:11:30.643927 | TASK [tox : Record file location] 2025-09-21 03:11:30.678722 | debian-bookworm | skipping: Conditional result was False 2025-09-21 03:11:30.685276 | 2025-09-21 03:11:30.685399 | TASK [tox : Install tox siblings] 2025-09-21 03:11:30.726293 | debian-bookworm | ok 2025-09-21 03:11:30.734908 | debian-bookworm | included: /var/lib/zuul/builds/8d8647dbf93f42c6bb9e3faac3195b6d/untrusted/project_0/github.com/osism/openinfra-zuul-jobs/roles/tox/tasks/siblings.yaml 2025-09-21 03:11:30.740609 | 2025-09-21 03:11:30.740722 | TASK [tox : Run tox without tests] 2025-09-21 03:11:31.825991 | debian-bookworm | test: install_deps> python -I -m pip install -r requirements.txt 2025-09-21 03:11:44.103473 | debian-bookworm | test: OK (12.77 seconds) 2025-09-21 03:11:44.103530 | debian-bookworm | congratulations :) (12.80 seconds) 2025-09-21 03:11:44.276277 | debian-bookworm | ok: Runtime: 0:00:13.092472 2025-09-21 03:11:44.281816 | 2025-09-21 03:11:44.281918 | TASK [tox : Create a tempfile to save tox showconfig] 2025-09-21 03:11:44.844858 | debian-bookworm | changed 2025-09-21 03:11:44.849970 | 2025-09-21 03:11:44.850059 | TASK [tox : Get tox envlist config] 2025-09-21 03:11:45.908733 | debian-bookworm | ok: Runtime: 0:00:00.343206 2025-09-21 03:11:45.913139 | 2025-09-21 03:11:45.913215 | TASK [tox : Install any sibling python packages] 2025-09-21 03:11:46.525963 | debian-bookworm | ok: No setup.cfg, no action needed 2025-09-21 03:11:46.530312 | 2025-09-21 03:11:46.530387 | TASK [tox : Remove tempfile] 2025-09-21 03:11:47.120927 | debian-bookworm | changed 2025-09-21 03:11:47.125359 | 2025-09-21 03:11:47.125436 | TASK [tox : Emit tox command] 2025-09-21 03:11:47.177137 | /home/zuul/.local/tox/bin/tox -etest -- test/unit 2025-09-21 03:11:47.181297 | 2025-09-21 03:11:47.181379 | TASK [tox : Run tox] 2025-09-21 03:11:47.885428 | debian-bookworm | test: commands[0]> python -m unittest discover test/unit 2025-09-21 03:11:48.511831 | debian-bookworm | ............... 2025-09-21 03:11:48.511908 | debian-bookworm | ---------------------------------------------------------------------- 2025-09-21 03:11:48.511936 | debian-bookworm | Ran 15 tests in 0.176s 2025-09-21 03:11:48.511950 | debian-bookworm | 2025-09-21 03:11:48.511969 | debian-bookworm | OK 2025-09-21 03:11:48.585141 | debian-bookworm | test: OK (0.74=setup[0.04]+cmd[0.70] seconds) 2025-09-21 03:11:48.585245 | debian-bookworm | congratulations :) (0.77 seconds) 2025-09-21 03:11:48.830225 | debian-bookworm | ok: Runtime: 0:00:01.031256 2025-09-21 03:11:48.839383 | 2025-09-21 03:11:48.839477 | TASK [tox : Look for output] 2025-09-21 03:11:49.250549 | debian-bookworm | ok 2025-09-21 03:11:49.264026 | 2025-09-21 03:11:49.264119 | TASK [tox : Return file comments to Zuul] 2025-09-21 03:11:49.297496 | debian-bookworm | skipping: Conditional result was False 2025-09-21 03:11:49.303016 | 2025-09-21 03:11:49.303074 | PLAY RECAP 2025-09-21 03:11:49.303116 | debian-bookworm | ok: 12 changed: 8 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 0 2025-09-21 03:11:49.303136 | 2025-09-21 03:11:49.429297 | RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2025-09-21 03:11:49.430178 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2025-09-21 03:11:50.638760 | 2025-09-21 03:11:50.638918 | PLAY [all] 2025-09-21 03:11:50.657830 | 2025-09-21 03:11:50.657962 | TASK [fetch-tox-output : Set tox log path for multiple nodes] 2025-09-21 03:11:50.712717 | debian-bookworm | skipping: Conditional result was False 2025-09-21 03:11:50.721228 | 2025-09-21 03:11:50.721472 | TASK [fetch-tox-output : Set tox log path for single node] 2025-09-21 03:11:50.801785 | debian-bookworm | ok 2025-09-21 03:11:50.809242 | 2025-09-21 03:11:50.809415 | TASK [fetch-tox-output : Ensure local tox dir] 2025-09-21 03:11:51.730629 | debian-bookworm -> localhost | changed 2025-09-21 03:11:51.748213 | 2025-09-21 03:11:51.748315 | TASK [fetch-tox-output : Ensure zuul-output tox dir] 2025-09-21 03:11:51.810540 | debian-bookworm | skipping: Conditional result was False 2025-09-21 03:11:51.816116 | 2025-09-21 03:11:51.816220 | TASK [fetch-tox-output : Set envlist fact] 2025-09-21 03:11:51.909415 | debian-bookworm | ok 2025-09-21 03:11:51.916914 | 2025-09-21 03:11:51.920745 | TASK [fetch-tox-output : Get tox version] 2025-09-21 03:11:52.928107 | debian-bookworm | ROOT: No loadable tox.ini or setup.cfg or pyproject.toml or tox.toml found, assuming empty tox.ini at /home/zuul 2025-09-21 03:11:52.946735 | debian-bookworm | 4.30.2 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2025-09-21 03:11:53.045635 | debian-bookworm | ok: Runtime: 0:00:00.296195 2025-09-21 03:11:53.058435 | 2025-09-21 03:11:53.059208 | TASK [fetch-tox-output : Find all default environments] 2025-09-21 03:11:53.661656 | debian-bookworm | skipping: Conditional result was False 2025-09-21 03:11:53.677736 | 2025-09-21 03:11:53.677824 | TASK [fetch-tox-output : Set envlist fact] 2025-09-21 03:11:53.740898 | debian-bookworm | skipping: Conditional result was False 2025-09-21 03:11:53.748729 | 2025-09-21 03:11:53.748818 | TASK [fetch-tox-output : Find all default environments] 2025-09-21 03:11:54.315600 | debian-bookworm | skipping: Conditional result was False 2025-09-21 03:11:54.320269 | 2025-09-21 03:11:54.320346 | TASK [fetch-tox-output : Set envlist fact] 2025-09-21 03:11:54.373627 | debian-bookworm | skipping: Conditional result was False 2025-09-21 03:11:54.379284 | 2025-09-21 03:11:54.379378 | LOOP [fetch-tox-output : Copy tox logs] 2025-09-21 03:11:54.509807 | debian-bookworm | skipping: Conditional result was False 2025-09-21 03:11:54.527121 | 2025-09-21 03:11:54.527220 | LOOP [fetch-tox-output : Collect tox logs] 2025-09-21 03:11:55.841381 | debian-bookworm | changed: 2025-09-21 03:11:55.841530 | debian-bookworm | created directory /var/lib/zuul/builds/8d8647dbf93f42c6bb9e3faac3195b6d/work/logs/tox/test 2025-09-21 03:11:55.841558 | debian-bookworm | cd+++++++++ ./ 2025-09-21 03:11:55.841578 | debian-bookworm | >f+++++++++ 1-commands[0].log 2025-09-21 03:11:55.841600 | 2025-09-21 03:11:55.849821 | 2025-09-21 03:11:55.849907 | PLAY RECAP 2025-09-21 03:11:55.849951 | debian-bookworm | ok: 5 changed: 3 unreachable: 0 failed: 0 skipped: 7 rescued: 0 ignored: 0 2025-09-21 03:11:55.849972 | 2025-09-21 03:11:56.127683 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2025-09-21 03:11:56.128474 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2025-09-21 03:11:57.546867 | 2025-09-21 03:11:57.546998 | PLAY [all] 2025-09-21 03:11:57.588763 | 2025-09-21 03:11:57.588900 | TASK [fetch-subunit-output : Find stestr or testr executable] 2025-09-21 03:11:57.885211 | debian-bookworm | changed: non-zero return code 2025-09-21 03:11:57.889799 | 2025-09-21 03:11:57.889909 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2025-09-21 03:11:57.916992 | debian-bookworm | skipping: Conditional result was False 2025-09-21 03:11:57.921557 | 2025-09-21 03:11:57.921639 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2025-09-21 03:11:57.949536 | 2025-09-21 03:11:57.949692 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2025-09-21 03:11:57.978185 | 2025-09-21 03:11:57.978331 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2025-09-21 03:11:58.011208 | debian-bookworm | skipping: Conditional result was False 2025-09-21 03:11:58.016096 | 2025-09-21 03:11:58.016184 | LOOP [fetch-subunit-output : Generate subunit file] 2025-09-21 03:11:58.043071 | 2025-09-21 03:11:58.043211 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2025-09-21 03:11:58.065570 | debian-bookworm | skipping: Conditional result was False 2025-09-21 03:11:58.070236 | 2025-09-21 03:11:58.070324 | TASK [fetch-subunit-output : Remove the temporary file] 2025-09-21 03:11:58.105202 | debian-bookworm | skipping: Conditional result was False 2025-09-21 03:11:58.110077 | 2025-09-21 03:11:58.110204 | TASK [fetch-subunit-output : Process and fetch subunit results] 2025-09-21 03:11:58.133081 | debian-bookworm | skipping: Conditional result was False 2025-09-21 03:11:58.138496 | 2025-09-21 03:11:58.138558 | PLAY RECAP 2025-09-21 03:11:58.138601 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2025-09-21 03:11:58.138622 | 2025-09-21 03:11:58.458572 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2025-09-21 03:11:58.459402 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-09-21 03:12:00.134402 | 2025-09-21 03:12:00.134567 | PLAY [Base post] 2025-09-21 03:12:00.195311 | 2025-09-21 03:12:00.195469 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-09-21 03:12:01.237714 | debian-bookworm | changed 2025-09-21 03:12:01.247126 | 2025-09-21 03:12:01.247205 | PLAY RECAP 2025-09-21 03:12:01.247256 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-09-21 03:12:01.247307 | 2025-09-21 03:12:01.540881 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-09-21 03:12:01.541745 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2025-09-21 03:12:03.972270 | 2025-09-21 03:12:03.972402 | PLAY [Base post-logs] 2025-09-21 03:12:04.021018 | 2025-09-21 03:12:04.021147 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-09-21 03:12:05.067665 | localhost | changed 2025-09-21 03:12:05.075340 | 2025-09-21 03:12:05.075443 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-09-21 03:12:05.116793 | localhost | ok 2025-09-21 03:12:05.119603 | 2025-09-21 03:12:05.119675 | TASK [Set zuul-log-path fact] 2025-09-21 03:12:05.137720 | localhost | ok 2025-09-21 03:12:05.153070 | 2025-09-21 03:12:05.153158 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-09-21 03:12:05.205017 | localhost | ok 2025-09-21 03:12:05.207746 | 2025-09-21 03:12:05.207864 | TASK [upload-logs : Create log directories] 2025-09-21 03:12:06.243033 | localhost | changed 2025-09-21 03:12:06.245274 | 2025-09-21 03:12:06.245354 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-09-21 03:12:06.994320 | localhost -> localhost | ok: Runtime: 0:00:00.020947 2025-09-21 03:12:06.997639 | 2025-09-21 03:12:06.997730 | TASK [upload-logs : Upload logs to log server] 2025-09-21 03:12:07.710874 | localhost | Output suppressed because no_log was given 2025-09-21 03:12:07.712526 | 2025-09-21 03:12:07.712618 | LOOP [upload-logs : Compress console log and json output] 2025-09-21 03:12:07.756913 | localhost | skipping: Conditional result was False 2025-09-21 03:12:07.762663 | localhost | skipping: Conditional result was False 2025-09-21 03:12:07.773180 | 2025-09-21 03:12:07.773284 | LOOP [upload-logs : Upload compressed console log and json output] 2025-09-21 03:12:07.820531 | localhost | skipping: Conditional result was False 2025-09-21 03:12:07.820765 | 2025-09-21 03:12:07.824426 | localhost | skipping: Conditional result was False 2025-09-21 03:12:07.835789 | 2025-09-21 03:12:07.835921 | LOOP [upload-logs : Upload console log and json output]