2025-10-26 03:09:29.261757 | Job console starting 2025-10-26 03:09:29.272667 | Updating git repos 2025-10-26 03:09:29.319463 | Cloning repos into workspace 2025-10-26 03:09:29.403131 | Restoring repo states 2025-10-26 03:09:29.417486 | Merging changes 2025-10-26 03:09:29.417499 | Checking out repos 2025-10-26 03:09:29.494940 | Preparing playbooks 2025-10-26 03:09:30.577691 | Running Ansible setup 2025-10-26 03:09:36.963112 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-10-26 03:09:37.928689 | 2025-10-26 03:09:37.928800 | PLAY [Base pre] 2025-10-26 03:09:37.948121 | 2025-10-26 03:09:37.948226 | TASK [Setup log path fact] 2025-10-26 03:09:37.967168 | debian-bookworm | ok 2025-10-26 03:09:37.991086 | 2025-10-26 03:09:37.991199 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-26 03:09:38.022295 | debian-bookworm | ok 2025-10-26 03:09:38.038336 | 2025-10-26 03:09:38.038450 | TASK [emit-job-header : Print job information] 2025-10-26 03:09:38.104245 | # Job Information 2025-10-26 03:09:38.104402 | Ansible Version: 2.16.14 2025-10-26 03:09:38.104434 | Job: tox 2025-10-26 03:09:38.104460 | Pipeline: periodic-daily 2025-10-26 03:09:38.104478 | Executor: 521e9411259a 2025-10-26 03:09:38.104495 | Triggered by: https://github.com/osism/openstack-image-manager 2025-10-26 03:09:38.104513 | Event ID: ac67789462c842c1b40a0151293df3d5 2025-10-26 03:09:38.110652 | 2025-10-26 03:09:38.112454 | LOOP [emit-job-header : Print node information] 2025-10-26 03:09:38.225092 | debian-bookworm | ok: 2025-10-26 03:09:38.225339 | debian-bookworm | # Node Information 2025-10-26 03:09:38.225388 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-10-26 03:09:38.225414 | debian-bookworm | Hostname: debian 2025-10-26 03:09:38.225436 | debian-bookworm | Username: zuul 2025-10-26 03:09:38.225457 | debian-bookworm | Distro: Debian 12.12 2025-10-26 03:09:38.225480 | debian-bookworm | Provider: regiocloud-a 2025-10-26 03:09:38.225501 | debian-bookworm | Region: 2025-10-26 03:09:38.225521 | debian-bookworm | Label: debian-bookworm 2025-10-26 03:09:38.225541 | debian-bookworm | Product Name: OpenStack Nova 2025-10-26 03:09:38.225560 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe24:dc77 2025-10-26 03:09:38.246469 | 2025-10-26 03:09:38.246569 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-10-26 03:09:38.639226 | debian-bookworm -> localhost | changed 2025-10-26 03:09:38.646969 | 2025-10-26 03:09:38.647064 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-10-26 03:09:39.476591 | debian-bookworm -> localhost | changed 2025-10-26 03:09:39.486457 | 2025-10-26 03:09:39.486545 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-10-26 03:09:39.696565 | debian-bookworm -> localhost | ok 2025-10-26 03:09:39.701534 | 2025-10-26 03:09:39.701614 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-10-26 03:09:39.718397 | debian-bookworm | ok 2025-10-26 03:09:39.731640 | debian-bookworm | included: /var/lib/zuul/builds/1489049884c848468d88a854264f166f/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-10-26 03:09:39.736995 | 2025-10-26 03:09:39.737067 | TASK [add-build-sshkey : Create Temp SSH key] 2025-10-26 03:09:40.691315 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-10-26 03:09:40.691487 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/1489049884c848468d88a854264f166f/work/1489049884c848468d88a854264f166f_id_rsa 2025-10-26 03:09:40.691518 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/1489049884c848468d88a854264f166f/work/1489049884c848468d88a854264f166f_id_rsa.pub 2025-10-26 03:09:40.691540 | debian-bookworm -> localhost | The key fingerprint is: 2025-10-26 03:09:40.691560 | debian-bookworm -> localhost | SHA256:cZfjeQk/XtUNFVmiaY3/X8OJncTdiZbt88U1ntWZ274 zuul-build-sshkey 2025-10-26 03:09:40.691578 | debian-bookworm -> localhost | The key's randomart image is: 2025-10-26 03:09:40.691603 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-10-26 03:09:40.691621 | debian-bookworm -> localhost | | oo*| 2025-10-26 03:09:40.691639 | debian-bookworm -> localhost | | * +o| 2025-10-26 03:09:40.691656 | debian-bookworm -> localhost | | . . X . +| 2025-10-26 03:09:40.691673 | debian-bookworm -> localhost | | o + *=.O| 2025-10-26 03:09:40.691689 | debian-bookworm -> localhost | | S o+*OB| 2025-10-26 03:09:40.691710 | debian-bookworm -> localhost | | .oB=X| 2025-10-26 03:09:40.691727 | debian-bookworm -> localhost | | ..%*| 2025-10-26 03:09:40.691744 | debian-bookworm -> localhost | | .B| 2025-10-26 03:09:40.691761 | debian-bookworm -> localhost | | E=| 2025-10-26 03:09:40.691777 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-10-26 03:09:40.691815 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.468722 2025-10-26 03:09:40.697464 | 2025-10-26 03:09:40.697540 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-10-26 03:09:40.714056 | debian-bookworm | ok 2025-10-26 03:09:40.721227 | debian-bookworm | included: /var/lib/zuul/builds/1489049884c848468d88a854264f166f/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-10-26 03:09:40.728283 | 2025-10-26 03:09:40.728363 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-10-26 03:09:40.740827 | debian-bookworm | skipping: Conditional result was False 2025-10-26 03:09:40.745730 | 2025-10-26 03:09:40.745800 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-10-26 03:09:41.327023 | debian-bookworm | changed 2025-10-26 03:09:41.334647 | 2025-10-26 03:09:41.334761 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-10-26 03:09:41.605281 | debian-bookworm | ok 2025-10-26 03:09:41.610399 | 2025-10-26 03:09:41.610480 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-10-26 03:09:42.301018 | debian-bookworm | changed 2025-10-26 03:09:42.306322 | 2025-10-26 03:09:42.306425 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-10-26 03:09:42.922291 | debian-bookworm | changed 2025-10-26 03:09:42.927704 | 2025-10-26 03:09:42.927794 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-10-26 03:09:42.951441 | debian-bookworm | skipping: Conditional result was False 2025-10-26 03:09:42.957891 | 2025-10-26 03:09:42.957982 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-10-26 03:09:43.337574 | debian-bookworm -> localhost | changed 2025-10-26 03:09:43.347721 | 2025-10-26 03:09:43.347818 | TASK [add-build-sshkey : Add back temp key] 2025-10-26 03:09:43.724278 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/1489049884c848468d88a854264f166f/work/1489049884c848468d88a854264f166f_id_rsa (zuul-build-sshkey) 2025-10-26 03:09:43.724475 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.011945 2025-10-26 03:09:43.730763 | 2025-10-26 03:09:43.730907 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-10-26 03:09:44.092876 | debian-bookworm | ok 2025-10-26 03:09:44.097069 | 2025-10-26 03:09:44.097142 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-10-26 03:09:44.134283 | debian-bookworm | skipping: Conditional result was False 2025-10-26 03:09:44.142963 | 2025-10-26 03:09:44.143051 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-10-26 03:09:44.549135 | debian-bookworm | ok 2025-10-26 03:09:44.560428 | 2025-10-26 03:09:44.560520 | TASK [validate-host : Define zuul_info_dir fact] 2025-10-26 03:09:44.602596 | debian-bookworm | ok 2025-10-26 03:09:44.607439 | 2025-10-26 03:09:44.607523 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-10-26 03:09:44.944942 | debian-bookworm -> localhost | ok 2025-10-26 03:09:44.951315 | 2025-10-26 03:09:44.951419 | TASK [validate-host : Collect information about the host] 2025-10-26 03:09:46.120861 | debian-bookworm | ok 2025-10-26 03:09:46.140236 | 2025-10-26 03:09:46.140339 | TASK [validate-host : Sanitize hostname] 2025-10-26 03:09:46.229787 | debian-bookworm | ok 2025-10-26 03:09:46.265179 | 2025-10-26 03:09:46.265291 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-10-26 03:09:46.755476 | debian-bookworm -> localhost | changed 2025-10-26 03:09:46.759949 | 2025-10-26 03:09:46.760032 | TASK [validate-host : Collect information about zuul worker] 2025-10-26 03:09:47.151096 | debian-bookworm | ok 2025-10-26 03:09:47.155440 | 2025-10-26 03:09:47.155525 | TASK [validate-host : Write out all zuul information for each host] 2025-10-26 03:09:47.846709 | debian-bookworm -> localhost | changed 2025-10-26 03:09:47.855478 | 2025-10-26 03:09:47.855568 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-10-26 03:09:48.095821 | debian-bookworm | ok 2025-10-26 03:09:48.101048 | 2025-10-26 03:09:48.101140 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-10-26 03:09:48.964877 | debian-bookworm | changed: 2025-10-26 03:09:48.965073 | debian-bookworm | cd+++++++++ src/ 2025-10-26 03:09:48.965116 | debian-bookworm | cd+++++++++ src/github.com/ 2025-10-26 03:09:48.965146 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-10-26 03:09:48.965174 | debian-bookworm | cd+++++++++ src/github.com/osism/openstack-image-manager/ 2025-10-26 03:09:48.965200 | debian-bookworm | =6.2 2025-10-26 03:10:12.248921 | debian-bookworm | Downloading cachetools-6.2.1-py3-none-any.whl (11 kB) 2025-10-26 03:10:12.283607 | debian-bookworm | Collecting chardet>=5.2 2025-10-26 03:10:12.296507 | debian-bookworm | Downloading chardet-5.2.0-py3-none-any.whl (199 kB) 2025-10-26 03:10:12.312340 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 199.4/199.4 kB 22.5 MB/s eta 0:00:00 2025-10-26 03:10:12.372554 | debian-bookworm | Collecting colorama>=0.4.6 2025-10-26 03:10:12.384738 | debian-bookworm | Downloading colorama-0.4.6-py2.py3-none-any.whl (25 kB) 2025-10-26 03:10:12.439831 | debian-bookworm | Collecting filelock>=3.20 2025-10-26 03:10:12.451397 | debian-bookworm | Downloading filelock-3.20.0-py3-none-any.whl (16 kB) 2025-10-26 03:10:12.502751 | debian-bookworm | Collecting packaging>=25 2025-10-26 03:10:12.514363 | debian-bookworm | Downloading packaging-25.0-py3-none-any.whl (66 kB) 2025-10-26 03:10:12.522379 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 66.5/66.5 kB 11.5 MB/s eta 0:00:00 2025-10-26 03:10:12.570386 | debian-bookworm | Collecting platformdirs>=4.5 2025-10-26 03:10:12.581944 | debian-bookworm | Downloading platformdirs-4.5.0-py3-none-any.whl (18 kB) 2025-10-26 03:10:12.625074 | debian-bookworm | Collecting pluggy>=1.6 2025-10-26 03:10:12.636256 | debian-bookworm | Downloading pluggy-1.6.0-py3-none-any.whl (20 kB) 2025-10-26 03:10:12.676828 | debian-bookworm | Collecting pyproject-api>=1.9.1 2025-10-26 03:10:12.688887 | debian-bookworm | Downloading pyproject_api-1.10.0-py3-none-any.whl (13 kB) 2025-10-26 03:10:12.842500 | debian-bookworm | Collecting virtualenv>=20.34 2025-10-26 03:10:12.854767 | debian-bookworm | Downloading virtualenv-20.35.3-py3-none-any.whl (6.0 MB) 2025-10-26 03:10:13.080327 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 6.0/6.0 MB 27.2 MB/s eta 0:00:00 2025-10-26 03:10:13.187104 | debian-bookworm | Collecting distlib<1,>=0.3.7 2025-10-26 03:10:13.198777 | debian-bookworm | Downloading distlib-0.4.0-py2.py3-none-any.whl (469 kB) 2025-10-26 03:10:13.233501 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 469.0/469.0 kB 16.0 MB/s eta 0:00:00 2025-10-26 03:10:13.400816 | debian-bookworm | Installing collected packages: distlib, pluggy, platformdirs, packaging, filelock, colorama, chardet, cachetools, virtualenv, pyproject-api, tox 2025-10-26 03:10:14.230451 | debian-bookworm | Successfully installed cachetools-6.2.1 chardet-5.2.0 colorama-0.4.6 distlib-0.4.0 filelock-3.20.0 packaging-25.0 platformdirs-4.5.0 pluggy-1.6.0 pyproject-api-1.10.0 tox-4.32.0 virtualenv-20.35.3 2025-10-26 03:10:14.520976 | debian-bookworm | ok: Runtime: 0:00:02.954451 2025-10-26 03:10:14.527823 | 2025-10-26 03:10:14.527915 | TASK [ensure-tox : Export installed tox_executable path] 2025-10-26 03:10:14.577427 | debian-bookworm | ok 2025-10-26 03:10:14.581998 | 2025-10-26 03:10:14.582087 | TASK [ensure-tox : Output tox version] 2025-10-26 03:10:15.199900 | 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-10-26 03:10:15.219308 | debian-bookworm | 4.32.0 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2025-10-26 03:10:15.659434 | debian-bookworm | ok: Runtime: 0:00:00.307978 2025-10-26 03:10:15.663870 | 2025-10-26 03:10:15.663944 | TASK [ensure-tox : Make global symlink] 2025-10-26 03:10:15.706978 | debian-bookworm | skipping: Conditional result was False 2025-10-26 03:10:15.715337 | 2025-10-26 03:10:15.715418 | PLAY RECAP 2025-10-26 03:10:15.715469 | debian-bookworm | ok: 13 changed: 9 unreachable: 0 failed: 0 skipped: 14 rescued: 0 ignored: 0 2025-10-26 03:10:15.715494 | 2025-10-26 03:10:15.909575 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/pre.yaml@master] 2025-10-26 03:10:15.910375 | RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2025-10-26 03:10:17.213952 | 2025-10-26 03:10:17.214064 | PLAY [all] 2025-10-26 03:10:17.230287 | 2025-10-26 03:10:17.230413 | TASK [revoke-sudo : Check if zuul is sudoer] 2025-10-26 03:10:17.695865 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2025-10-26 03:10:17.783317 | debian-bookworm | ok: Runtime: 0:00:00.034013 2025-10-26 03:10:17.845089 | 2025-10-26 03:10:17.846398 | TASK [revoke-sudo : Remove sudo access for zuul user.] 2025-10-26 03:10:18.404631 | debian-bookworm | ok: Runtime: 0:00:00.007482 2025-10-26 03:10:18.410158 | 2025-10-26 03:10:18.410254 | TASK [revoke-sudo : Prove that general sudo access is actually revoked.] 2025-10-26 03:10:18.723575 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2025-10-26 03:10:18.727712 | debian-bookworm | sudo: a password is required 2025-10-26 03:10:18.965774 | debian-bookworm | ok: Runtime: 0:00:00.022287 2025-10-26 03:10:18.975176 | 2025-10-26 03:10:18.975262 | TASK [tox : Check to see if the constraints file exists] 2025-10-26 03:10:19.024830 | debian-bookworm | skipping: Conditional result was False 2025-10-26 03:10:19.030533 | 2025-10-26 03:10:19.030634 | TASK [tox : Fail if constraints file is missing] 2025-10-26 03:10:19.074092 | debian-bookworm | skipping: Conditional result was False 2025-10-26 03:10:19.080041 | 2025-10-26 03:10:19.080147 | TASK [tox : Record file location] 2025-10-26 03:10:19.113184 | debian-bookworm | skipping: Conditional result was False 2025-10-26 03:10:19.118707 | 2025-10-26 03:10:19.118807 | TASK [tox : Install tox siblings] 2025-10-26 03:10:19.139631 | debian-bookworm | ok 2025-10-26 03:10:19.155365 | debian-bookworm | included: /var/lib/zuul/builds/1489049884c848468d88a854264f166f/untrusted/project_0/github.com/osism/openinfra-zuul-jobs/roles/tox/tasks/siblings.yaml 2025-10-26 03:10:19.167872 | 2025-10-26 03:10:19.167975 | TASK [tox : Run tox without tests] 2025-10-26 03:10:20.333451 | debian-bookworm | test: install_deps> python -I -m pip install -r requirements.txt 2025-10-26 03:10:34.212394 | debian-bookworm | test: OK (14.45 seconds) 2025-10-26 03:10:34.212499 | debian-bookworm | congratulations :) (14.48 seconds) 2025-10-26 03:10:34.724353 | debian-bookworm | ok: Runtime: 0:00:14.769126 2025-10-26 03:10:34.729181 | 2025-10-26 03:10:34.729263 | TASK [tox : Create a tempfile to save tox showconfig] 2025-10-26 03:10:35.163983 | debian-bookworm | changed 2025-10-26 03:10:35.172890 | 2025-10-26 03:10:35.172992 | TASK [tox : Get tox envlist config] 2025-10-26 03:10:36.199462 | debian-bookworm | ok: Runtime: 0:00:00.343176 2025-10-26 03:10:36.203915 | 2025-10-26 03:10:36.203990 | TASK [tox : Install any sibling python packages] 2025-10-26 03:10:36.783697 | debian-bookworm | ok: No setup.cfg, no action needed 2025-10-26 03:10:36.789931 | 2025-10-26 03:10:36.790026 | TASK [tox : Remove tempfile] 2025-10-26 03:10:37.247389 | debian-bookworm | changed 2025-10-26 03:10:37.252811 | 2025-10-26 03:10:37.252903 | TASK [tox : Emit tox command] 2025-10-26 03:10:37.280973 | /home/zuul/.local/tox/bin/tox -etest -- test/unit 2025-10-26 03:10:37.285785 | 2025-10-26 03:10:37.285882 | TASK [tox : Run tox] 2025-10-26 03:10:37.889943 | debian-bookworm | test: commands[0]> python -m unittest discover test/unit 2025-10-26 03:10:38.619770 | debian-bookworm | ............... 2025-10-26 03:10:38.619852 | debian-bookworm | ---------------------------------------------------------------------- 2025-10-26 03:10:38.619871 | debian-bookworm | Ran 15 tests in 0.198s 2025-10-26 03:10:38.619877 | debian-bookworm | 2025-10-26 03:10:38.619885 | debian-bookworm | OK 2025-10-26 03:10:38.714708 | debian-bookworm | test: OK (0.86=setup[0.05]+cmd[0.82] seconds) 2025-10-26 03:10:38.714813 | debian-bookworm | congratulations :) (0.89 seconds) 2025-10-26 03:10:38.860698 | debian-bookworm | ok: Runtime: 0:00:01.188255 2025-10-26 03:10:38.879120 | 2025-10-26 03:10:38.879230 | TASK [tox : Look for output] 2025-10-26 03:10:39.283231 | debian-bookworm | ok 2025-10-26 03:10:39.297444 | 2025-10-26 03:10:39.297531 | TASK [tox : Return file comments to Zuul] 2025-10-26 03:10:39.365167 | debian-bookworm | skipping: Conditional result was False 2025-10-26 03:10:39.373451 | 2025-10-26 03:10:39.373522 | PLAY RECAP 2025-10-26 03:10:39.373569 | debian-bookworm | ok: 12 changed: 8 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 0 2025-10-26 03:10:39.373593 | 2025-10-26 03:10:39.587723 | RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2025-10-26 03:10:39.588660 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2025-10-26 03:10:40.604151 | 2025-10-26 03:10:40.604274 | PLAY [all] 2025-10-26 03:10:40.649393 | 2025-10-26 03:10:40.649545 | TASK [fetch-tox-output : Set tox log path for multiple nodes] 2025-10-26 03:10:40.703253 | debian-bookworm | skipping: Conditional result was False 2025-10-26 03:10:40.707828 | 2025-10-26 03:10:40.707970 | TASK [fetch-tox-output : Set tox log path for single node] 2025-10-26 03:10:40.754428 | debian-bookworm | ok 2025-10-26 03:10:40.758358 | 2025-10-26 03:10:40.758443 | TASK [fetch-tox-output : Ensure local tox dir] 2025-10-26 03:10:41.265393 | debian-bookworm -> localhost | changed 2025-10-26 03:10:41.272526 | 2025-10-26 03:10:41.272623 | TASK [fetch-tox-output : Ensure zuul-output tox dir] 2025-10-26 03:10:41.311960 | debian-bookworm | skipping: Conditional result was False 2025-10-26 03:10:41.317430 | 2025-10-26 03:10:41.322786 | TASK [fetch-tox-output : Set envlist fact] 2025-10-26 03:10:41.350099 | debian-bookworm | ok 2025-10-26 03:10:41.355519 | 2025-10-26 03:10:41.355607 | TASK [fetch-tox-output : Get tox version] 2025-10-26 03:10:42.117107 | 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-10-26 03:10:42.136140 | debian-bookworm | 4.32.0 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2025-10-26 03:10:42.384603 | debian-bookworm | ok: Runtime: 0:00:00.310829 2025-10-26 03:10:42.388987 | 2025-10-26 03:10:42.389067 | TASK [fetch-tox-output : Find all default environments] 2025-10-26 03:10:42.934265 | debian-bookworm | skipping: Conditional result was False 2025-10-26 03:10:42.939038 | 2025-10-26 03:10:42.939124 | TASK [fetch-tox-output : Set envlist fact] 2025-10-26 03:10:42.961642 | debian-bookworm | skipping: Conditional result was False 2025-10-26 03:10:42.965923 | 2025-10-26 03:10:42.965994 | TASK [fetch-tox-output : Find all default environments] 2025-10-26 03:10:43.524566 | debian-bookworm | skipping: Conditional result was False 2025-10-26 03:10:43.530065 | 2025-10-26 03:10:43.530148 | TASK [fetch-tox-output : Set envlist fact] 2025-10-26 03:10:43.573886 | debian-bookworm | skipping: Conditional result was False 2025-10-26 03:10:43.579138 | 2025-10-26 03:10:43.579216 | LOOP [fetch-tox-output : Copy tox logs] 2025-10-26 03:10:43.619199 | debian-bookworm | skipping: Conditional result was False 2025-10-26 03:10:43.629556 | 2025-10-26 03:10:43.629641 | LOOP [fetch-tox-output : Collect tox logs] 2025-10-26 03:10:44.530630 | debian-bookworm | changed: 2025-10-26 03:10:44.530783 | debian-bookworm | created directory /var/lib/zuul/builds/1489049884c848468d88a854264f166f/work/logs/tox/test 2025-10-26 03:10:44.530817 | debian-bookworm | cd+++++++++ ./ 2025-10-26 03:10:44.530871 | debian-bookworm | >f+++++++++ 1-commands[0].log 2025-10-26 03:10:44.530901 | 2025-10-26 03:10:44.542877 | 2025-10-26 03:10:44.542945 | PLAY RECAP 2025-10-26 03:10:44.542994 | debian-bookworm | ok: 5 changed: 3 unreachable: 0 failed: 0 skipped: 7 rescued: 0 ignored: 0 2025-10-26 03:10:44.543018 | 2025-10-26 03:10:44.633531 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2025-10-26 03:10:44.635353 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2025-10-26 03:10:45.250035 | 2025-10-26 03:10:45.250141 | PLAY [all] 2025-10-26 03:10:45.263048 | 2025-10-26 03:10:45.263138 | TASK [fetch-subunit-output : Find stestr or testr executable] 2025-10-26 03:10:45.451348 | debian-bookworm | changed: non-zero return code 2025-10-26 03:10:45.456444 | 2025-10-26 03:10:45.456547 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2025-10-26 03:10:45.469740 | debian-bookworm | skipping: Conditional result was False 2025-10-26 03:10:45.479048 | 2025-10-26 03:10:45.479137 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2025-10-26 03:10:45.496833 | 2025-10-26 03:10:45.496960 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2025-10-26 03:10:45.515438 | 2025-10-26 03:10:45.515563 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2025-10-26 03:10:45.527820 | debian-bookworm | skipping: Conditional result was False 2025-10-26 03:10:45.532298 | 2025-10-26 03:10:45.532370 | LOOP [fetch-subunit-output : Generate subunit file] 2025-10-26 03:10:45.559793 | 2025-10-26 03:10:45.559955 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2025-10-26 03:10:45.582538 | debian-bookworm | skipping: Conditional result was False 2025-10-26 03:10:45.587906 | 2025-10-26 03:10:45.587999 | TASK [fetch-subunit-output : Remove the temporary file] 2025-10-26 03:10:45.610515 | debian-bookworm | skipping: Conditional result was False 2025-10-26 03:10:45.616369 | 2025-10-26 03:10:45.616500 | TASK [fetch-subunit-output : Process and fetch subunit results] 2025-10-26 03:10:45.629887 | debian-bookworm | skipping: Conditional result was False 2025-10-26 03:10:45.643995 | 2025-10-26 03:10:45.644078 | PLAY RECAP 2025-10-26 03:10:45.644125 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2025-10-26 03:10:45.644150 | 2025-10-26 03:10:45.778969 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2025-10-26 03:10:45.780366 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-10-26 03:10:47.220528 | 2025-10-26 03:10:47.220663 | PLAY [Base post] 2025-10-26 03:10:47.254279 | 2025-10-26 03:10:47.256841 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-10-26 03:10:47.952396 | debian-bookworm | changed 2025-10-26 03:10:47.964145 | 2025-10-26 03:10:47.964245 | PLAY RECAP 2025-10-26 03:10:47.964304 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-10-26 03:10:47.964364 | 2025-10-26 03:10:48.125317 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-10-26 03:10:48.126549 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2025-10-26 03:10:49.261329 | 2025-10-26 03:10:49.261491 | PLAY [Base post-logs] 2025-10-26 03:10:49.292829 | 2025-10-26 03:10:49.292949 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-10-26 03:10:49.805718 | localhost | changed 2025-10-26 03:10:49.816678 | 2025-10-26 03:10:49.816799 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-10-26 03:10:49.881909 | localhost | ok 2025-10-26 03:10:49.884769 | 2025-10-26 03:10:49.884856 | TASK [Set zuul-log-path fact] 2025-10-26 03:10:49.901991 | localhost | ok 2025-10-26 03:10:49.913318 | 2025-10-26 03:10:49.913451 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-26 03:10:49.948747 | localhost | ok 2025-10-26 03:10:49.951511 | 2025-10-26 03:10:49.951593 | TASK [upload-logs : Create log directories] 2025-10-26 03:10:50.435949 | localhost | changed 2025-10-26 03:10:50.438684 | 2025-10-26 03:10:50.438771 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-10-26 03:10:51.052184 | localhost -> localhost | ok: Runtime: 0:00:00.014760 2025-10-26 03:10:51.055629 | 2025-10-26 03:10:51.055718 | TASK [upload-logs : Upload logs to log server] 2025-10-26 03:10:51.828566 | localhost | Output suppressed because no_log was given 2025-10-26 03:10:51.830129 | 2025-10-26 03:10:51.830212 | LOOP [upload-logs : Compress console log and json output] 2025-10-26 03:10:51.872973 | localhost | skipping: Conditional result was False 2025-10-26 03:10:51.877791 | localhost | skipping: Conditional result was False 2025-10-26 03:10:51.885368 | 2025-10-26 03:10:51.885452 | LOOP [upload-logs : Upload compressed console log and json output] 2025-10-26 03:10:51.926657 | localhost | skipping: Conditional result was False 2025-10-26 03:10:51.926955 | 2025-10-26 03:10:51.932008 | localhost | skipping: Conditional result was False 2025-10-26 03:10:51.939800 | 2025-10-26 03:10:51.939880 | LOOP [upload-logs : Upload console log and json output]