2026-01-06 00:18:41.681014 | Job console starting 2026-01-06 00:18:41.718272 | Updating git repos 2026-01-06 00:18:41.753211 | Cloning repos into workspace 2026-01-06 00:18:41.832748 | Restoring repo states 2026-01-06 00:18:41.858874 | Merging changes 2026-01-06 00:18:42.509999 | Checking out repos 2026-01-06 00:18:42.594799 | Preparing playbooks 2026-01-06 00:18:43.533434 | Running Ansible setup 2026-01-06 00:18:49.791895 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-01-06 00:18:51.563258 | 2026-01-06 00:18:51.563440 | PLAY [Base pre] 2026-01-06 00:18:51.617033 | 2026-01-06 00:18:51.617337 | TASK [Setup log path fact] 2026-01-06 00:18:51.651791 | debian-bookworm | ok 2026-01-06 00:18:51.673710 | 2026-01-06 00:18:51.673901 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-01-06 00:18:51.707698 | debian-bookworm | ok 2026-01-06 00:18:51.721712 | 2026-01-06 00:18:51.721852 | TASK [emit-job-header : Print job information] 2026-01-06 00:18:51.761231 | # Job Information 2026-01-06 00:18:51.761418 | Ansible Version: 2.16.14 2026-01-06 00:18:51.761453 | Job: tox 2026-01-06 00:18:51.761486 | Pipeline: check 2026-01-06 00:18:51.761509 | Executor: 521e9411259a 2026-01-06 00:18:51.761531 | Triggered by: https://github.com/osism/openstack-image-manager/pull/1075 2026-01-06 00:18:51.761553 | Event ID: 3b88e7a0-ea95-11f0-8faf-b9c880ed04a9 2026-01-06 00:18:51.766753 | 2026-01-06 00:18:51.766905 | LOOP [emit-job-header : Print node information] 2026-01-06 00:18:51.951366 | debian-bookworm | ok: 2026-01-06 00:18:51.951558 | debian-bookworm | # Node Information 2026-01-06 00:18:51.951593 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-01-06 00:18:51.951619 | debian-bookworm | Hostname: debian 2026-01-06 00:18:51.951641 | debian-bookworm | Username: zuul 2026-01-06 00:18:51.951662 | debian-bookworm | Distro: Debian 12.12 2026-01-06 00:18:51.951685 | debian-bookworm | Provider: regiocloud-a 2026-01-06 00:18:51.951705 | debian-bookworm | Region: 2026-01-06 00:18:51.951725 | debian-bookworm | Label: debian-bookworm 2026-01-06 00:18:51.951746 | debian-bookworm | Product Name: OpenStack Nova 2026-01-06 00:18:51.951765 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fec8:a27a 2026-01-06 00:18:51.974199 | 2026-01-06 00:18:51.974361 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-01-06 00:18:52.857997 | debian-bookworm -> localhost | changed 2026-01-06 00:18:52.865758 | 2026-01-06 00:18:52.865897 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-01-06 00:18:54.580680 | debian-bookworm -> localhost | changed 2026-01-06 00:18:54.597449 | 2026-01-06 00:18:54.597595 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-01-06 00:18:55.050899 | debian-bookworm -> localhost | ok 2026-01-06 00:18:55.057304 | 2026-01-06 00:18:55.057423 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-01-06 00:18:55.107572 | debian-bookworm | ok 2026-01-06 00:18:55.152693 | debian-bookworm | included: /var/lib/zuul/builds/b7ee7e7eb0ae43e39855255d1dfc8759/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-01-06 00:18:55.168493 | 2026-01-06 00:18:55.168640 | TASK [add-build-sshkey : Create Temp SSH key] 2026-01-06 00:18:56.642489 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-01-06 00:18:56.642723 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/b7ee7e7eb0ae43e39855255d1dfc8759/work/b7ee7e7eb0ae43e39855255d1dfc8759_id_rsa 2026-01-06 00:18:56.642763 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/b7ee7e7eb0ae43e39855255d1dfc8759/work/b7ee7e7eb0ae43e39855255d1dfc8759_id_rsa.pub 2026-01-06 00:18:56.642790 | debian-bookworm -> localhost | The key fingerprint is: 2026-01-06 00:18:56.642818 | debian-bookworm -> localhost | SHA256:34TJM+pwSTbp8i7/jfIK9SNwmu8n2eW8Sdt4kCnyZ1A zuul-build-sshkey 2026-01-06 00:18:56.642918 | debian-bookworm -> localhost | The key's randomart image is: 2026-01-06 00:18:56.642962 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-01-06 00:18:56.642986 | debian-bookworm -> localhost | | | 2026-01-06 00:18:56.643008 | debian-bookworm -> localhost | | | 2026-01-06 00:18:56.643030 | debian-bookworm -> localhost | | | 2026-01-06 00:18:56.643050 | debian-bookworm -> localhost | | o oE | 2026-01-06 00:18:56.643070 | debian-bookworm -> localhost | | . S *..o | 2026-01-06 00:18:56.643096 | debian-bookworm -> localhost | | X.*o== | 2026-01-06 00:18:56.643118 | debian-bookworm -> localhost | | * *=+*o. | 2026-01-06 00:18:56.643139 | debian-bookworm -> localhost | | .O+.+===. | 2026-01-06 00:18:56.643189 | debian-bookworm -> localhost | | =OB+o*o. | 2026-01-06 00:18:56.643213 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-01-06 00:18:56.643275 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.762329 2026-01-06 00:18:56.650088 | 2026-01-06 00:18:56.650228 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-01-06 00:18:56.700382 | debian-bookworm | ok 2026-01-06 00:18:56.710113 | debian-bookworm | included: /var/lib/zuul/builds/b7ee7e7eb0ae43e39855255d1dfc8759/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-01-06 00:18:56.720576 | 2026-01-06 00:18:56.720693 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-01-06 00:18:56.744971 | debian-bookworm | skipping: Conditional result was False 2026-01-06 00:18:56.751252 | 2026-01-06 00:18:56.751394 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-01-06 00:18:57.442354 | debian-bookworm | changed 2026-01-06 00:18:57.449108 | 2026-01-06 00:18:57.449443 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-01-06 00:18:57.720304 | debian-bookworm | ok 2026-01-06 00:18:57.728092 | 2026-01-06 00:18:57.728308 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-01-06 00:18:58.425904 | debian-bookworm | changed 2026-01-06 00:18:58.443107 | 2026-01-06 00:18:58.443403 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-01-06 00:18:59.155145 | debian-bookworm | changed 2026-01-06 00:18:59.164143 | 2026-01-06 00:18:59.164320 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-01-06 00:18:59.189530 | debian-bookworm | skipping: Conditional result was False 2026-01-06 00:18:59.197679 | 2026-01-06 00:18:59.197848 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-01-06 00:18:59.758035 | debian-bookworm -> localhost | changed 2026-01-06 00:18:59.772128 | 2026-01-06 00:18:59.772285 | TASK [add-build-sshkey : Add back temp key] 2026-01-06 00:19:00.158535 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/b7ee7e7eb0ae43e39855255d1dfc8759/work/b7ee7e7eb0ae43e39855255d1dfc8759_id_rsa (zuul-build-sshkey) 2026-01-06 00:19:00.158801 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.014684 2026-01-06 00:19:00.168830 | 2026-01-06 00:19:00.168962 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-01-06 00:19:00.596869 | debian-bookworm | ok 2026-01-06 00:19:00.602966 | 2026-01-06 00:19:00.603098 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-01-06 00:19:00.628690 | debian-bookworm | skipping: Conditional result was False 2026-01-06 00:19:00.640481 | 2026-01-06 00:19:00.640624 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-01-06 00:19:01.099629 | debian-bookworm | ok 2026-01-06 00:19:01.141499 | 2026-01-06 00:19:01.141643 | TASK [validate-host : Define zuul_info_dir fact] 2026-01-06 00:19:01.209136 | debian-bookworm | ok 2026-01-06 00:19:01.214606 | 2026-01-06 00:19:01.214736 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-01-06 00:19:01.712744 | debian-bookworm -> localhost | ok 2026-01-06 00:19:01.719757 | 2026-01-06 00:19:01.719881 | TASK [validate-host : Collect information about the host] 2026-01-06 00:19:02.987340 | debian-bookworm | ok 2026-01-06 00:19:02.998106 | 2026-01-06 00:19:02.998285 | TASK [validate-host : Sanitize hostname] 2026-01-06 00:19:03.120047 | debian-bookworm | ok 2026-01-06 00:19:03.166811 | 2026-01-06 00:19:03.166986 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-01-06 00:19:03.757751 | debian-bookworm -> localhost | changed 2026-01-06 00:19:03.764064 | 2026-01-06 00:19:03.764246 | TASK [validate-host : Collect information about zuul worker] 2026-01-06 00:19:04.208134 | debian-bookworm | ok 2026-01-06 00:19:04.215549 | 2026-01-06 00:19:04.215708 | TASK [validate-host : Write out all zuul information for each host] 2026-01-06 00:19:05.071106 | debian-bookworm -> localhost | changed 2026-01-06 00:19:05.081090 | 2026-01-06 00:19:05.081266 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-01-06 00:19:05.373982 | debian-bookworm | ok 2026-01-06 00:19:05.381041 | 2026-01-06 00:19:05.381220 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-01-06 00:19:06.526209 | debian-bookworm | changed: 2026-01-06 00:19:06.526431 | debian-bookworm | cd+++++++++ src/ 2026-01-06 00:19:06.526467 | debian-bookworm | cd+++++++++ src/github.com/ 2026-01-06 00:19:06.526492 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-01-06 00:19:06.526514 | debian-bookworm | cd+++++++++ src/github.com/osism/openstack-image-manager/ 2026-01-06 00:19:06.526534 | debian-bookworm | =6.2.4 2026-01-06 00:19:29.284614 | debian-bookworm | Downloading cachetools-6.2.4-py3-none-any.whl (11 kB) 2026-01-06 00:19:29.341135 | debian-bookworm | Collecting chardet>=5.2 2026-01-06 00:19:29.353063 | debian-bookworm | Downloading chardet-5.2.0-py3-none-any.whl (199 kB) 2026-01-06 00:19:29.381485 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 199.4/199.4 kB 8.6 MB/s eta 0:00:00 2026-01-06 00:19:29.433865 | debian-bookworm | Collecting colorama>=0.4.6 2026-01-06 00:19:29.446791 | debian-bookworm | Downloading colorama-0.4.6-py2.py3-none-any.whl (25 kB) 2026-01-06 00:19:29.516371 | debian-bookworm | Collecting filelock>=3.20.2 2026-01-06 00:19:29.527977 | debian-bookworm | Downloading filelock-3.20.2-py3-none-any.whl (16 kB) 2026-01-06 00:19:29.590039 | debian-bookworm | Collecting packaging>=25 2026-01-06 00:19:29.601726 | debian-bookworm | Downloading packaging-25.0-py3-none-any.whl (66 kB) 2026-01-06 00:19:29.609267 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 66.5/66.5 kB 11.4 MB/s eta 0:00:00 2026-01-06 00:19:29.652569 | debian-bookworm | Collecting platformdirs>=4.5.1 2026-01-06 00:19:29.663653 | debian-bookworm | Downloading platformdirs-4.5.1-py3-none-any.whl (18 kB) 2026-01-06 00:19:29.703254 | debian-bookworm | Collecting pluggy>=1.6 2026-01-06 00:19:29.714512 | debian-bookworm | Downloading pluggy-1.6.0-py3-none-any.whl (20 kB) 2026-01-06 00:19:29.760935 | debian-bookworm | Collecting pyproject-api>=1.10 2026-01-06 00:19:29.772990 | debian-bookworm | Downloading pyproject_api-1.10.0-py3-none-any.whl (13 kB) 2026-01-06 00:19:29.901270 | debian-bookworm | Collecting virtualenv>=20.35.4 2026-01-06 00:19:29.912607 | debian-bookworm | Downloading virtualenv-20.35.4-py3-none-any.whl (6.0 MB) 2026-01-06 00:19:30.172578 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 6.0/6.0 MB 23.6 MB/s eta 0:00:00 2026-01-06 00:19:30.279370 | debian-bookworm | Collecting distlib<1,>=0.3.7 2026-01-06 00:19:30.290007 | debian-bookworm | Downloading distlib-0.4.0-py2.py3-none-any.whl (469 kB) 2026-01-06 00:19:30.318823 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 469.0/469.0 kB 18.3 MB/s eta 0:00:00 2026-01-06 00:19:30.457349 | debian-bookworm | Installing collected packages: distlib, pluggy, platformdirs, packaging, filelock, colorama, chardet, cachetools, virtualenv, pyproject-api, tox 2026-01-06 00:19:31.236439 | debian-bookworm | Successfully installed cachetools-6.2.4 chardet-5.2.0 colorama-0.4.6 distlib-0.4.0 filelock-3.20.2 packaging-25.0 platformdirs-4.5.1 pluggy-1.6.0 pyproject-api-1.10.0 tox-4.33.0 virtualenv-20.35.4 2026-01-06 00:19:31.774576 | debian-bookworm | ok: Runtime: 0:00:02.796527 2026-01-06 00:19:31.787457 | 2026-01-06 00:19:31.787804 | TASK [ensure-tox : Export installed tox_executable path] 2026-01-06 00:19:31.843563 | debian-bookworm | ok 2026-01-06 00:19:31.848665 | 2026-01-06 00:19:31.848788 | TASK [ensure-tox : Output tox version] 2026-01-06 00:19:32.338943 | debian-bookworm | ROOT: No loadable tox.ini or setup.cfg or pyproject.toml or tox.toml found, assuming empty tox.ini at /home/zuul 2026-01-06 00:19:32.357009 | debian-bookworm | 4.33.0 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2026-01-06 00:19:32.887712 | debian-bookworm | ok: Runtime: 0:00:00.287487 2026-01-06 00:19:32.893584 | 2026-01-06 00:19:32.893709 | TASK [ensure-tox : Make global symlink] 2026-01-06 00:19:32.938468 | debian-bookworm | skipping: Conditional result was False 2026-01-06 00:19:32.945305 | 2026-01-06 00:19:32.945403 | PLAY RECAP 2026-01-06 00:19:32.945455 | debian-bookworm | ok: 13 changed: 9 unreachable: 0 failed: 0 skipped: 14 rescued: 0 ignored: 0 2026-01-06 00:19:32.945480 | 2026-01-06 00:19:33.273871 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/pre.yaml@master] 2026-01-06 00:19:33.275025 | RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2026-01-06 00:19:34.232234 | 2026-01-06 00:19:34.232399 | PLAY [all] 2026-01-06 00:19:34.261010 | 2026-01-06 00:19:34.261221 | TASK [revoke-sudo : Check if zuul is sudoer] 2026-01-06 00:19:35.017901 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2026-01-06 00:19:35.308894 | debian-bookworm | ok: Runtime: 0:00:00.023324 2026-01-06 00:19:35.318037 | 2026-01-06 00:19:35.318362 | TASK [revoke-sudo : Remove sudo access for zuul user.] 2026-01-06 00:19:35.853245 | debian-bookworm | ok: Runtime: 0:00:00.007142 2026-01-06 00:19:35.860667 | 2026-01-06 00:19:35.860793 | TASK [revoke-sudo : Prove that general sudo access is actually revoked.] 2026-01-06 00:19:36.165618 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2026-01-06 00:19:36.169581 | debian-bookworm | sudo: a password is required 2026-01-06 00:19:36.395784 | debian-bookworm | ok: Runtime: 0:00:00.028455 2026-01-06 00:19:36.415114 | 2026-01-06 00:19:36.415307 | TASK [tox : Check to see if the constraints file exists] 2026-01-06 00:19:36.451308 | debian-bookworm | skipping: Conditional result was False 2026-01-06 00:19:36.457661 | 2026-01-06 00:19:36.457785 | TASK [tox : Fail if constraints file is missing] 2026-01-06 00:19:36.492786 | debian-bookworm | skipping: Conditional result was False 2026-01-06 00:19:36.499951 | 2026-01-06 00:19:36.500082 | TASK [tox : Record file location] 2026-01-06 00:19:36.524832 | debian-bookworm | skipping: Conditional result was False 2026-01-06 00:19:36.531717 | 2026-01-06 00:19:36.531859 | TASK [tox : Install tox siblings] 2026-01-06 00:19:36.571960 | debian-bookworm | ok 2026-01-06 00:19:36.584672 | debian-bookworm | included: /var/lib/zuul/builds/b7ee7e7eb0ae43e39855255d1dfc8759/untrusted/project_0/github.com/osism/openinfra-zuul-jobs/roles/tox/tasks/siblings.yaml 2026-01-06 00:19:36.590784 | 2026-01-06 00:19:36.590936 | TASK [tox : Run tox without tests] 2026-01-06 00:19:37.746438 | debian-bookworm | test: install_deps> python -I -m pip install -r requirements.txt 2026-01-06 00:19:51.203934 | debian-bookworm | test: OK (14.04 seconds) 2026-01-06 00:19:51.204022 | debian-bookworm | congratulations :) (14.07 seconds) 2026-01-06 00:19:51.632271 | debian-bookworm | ok: Runtime: 0:00:14.336709 2026-01-06 00:19:51.649387 | 2026-01-06 00:19:51.649595 | TASK [tox : Create a tempfile to save tox showconfig] 2026-01-06 00:19:52.084789 | debian-bookworm | changed 2026-01-06 00:19:52.092960 | 2026-01-06 00:19:52.093128 | TASK [tox : Get tox envlist config] 2026-01-06 00:19:53.130385 | debian-bookworm | ok: Runtime: 0:00:00.297872 2026-01-06 00:19:53.143585 | 2026-01-06 00:19:53.143784 | TASK [tox : Install any sibling python packages] 2026-01-06 00:19:53.633632 | debian-bookworm | ok: No setup.cfg, no action needed 2026-01-06 00:19:53.640619 | 2026-01-06 00:19:53.640751 | TASK [tox : Remove tempfile] 2026-01-06 00:19:54.076891 | debian-bookworm | changed 2026-01-06 00:19:54.084727 | 2026-01-06 00:19:54.084931 | TASK [tox : Emit tox command] 2026-01-06 00:19:54.144355 | /home/zuul/.local/tox/bin/tox -etest -- test/unit 2026-01-06 00:19:54.155778 | 2026-01-06 00:19:54.156006 | TASK [tox : Run tox] 2026-01-06 00:19:54.754767 | debian-bookworm | test: commands[0]> python -m unittest discover test/unit 2026-01-06 00:19:55.353707 | debian-bookworm | ............... 2026-01-06 00:19:55.353745 | debian-bookworm | ---------------------------------------------------------------------- 2026-01-06 00:19:55.353758 | debian-bookworm | Ran 15 tests in 0.166s 2026-01-06 00:19:55.353763 | debian-bookworm | 2026-01-06 00:19:55.353770 | debian-bookworm | OK 2026-01-06 00:19:55.419636 | debian-bookworm | test: OK (0.70=setup[0.04]+cmd[0.66] seconds) 2026-01-06 00:19:55.419687 | debian-bookworm | congratulations :) (0.73 seconds) 2026-01-06 00:19:55.761484 | debian-bookworm | ok: Runtime: 0:00:01.022992 2026-01-06 00:19:55.767510 | 2026-01-06 00:19:55.767641 | TASK [tox : Look for output] 2026-01-06 00:19:56.192302 | debian-bookworm | ok 2026-01-06 00:19:56.206590 | 2026-01-06 00:19:56.206748 | TASK [tox : Return file comments to Zuul] 2026-01-06 00:19:56.251849 | debian-bookworm | skipping: Conditional result was False 2026-01-06 00:19:56.266678 | 2026-01-06 00:19:56.266900 | PLAY RECAP 2026-01-06 00:19:56.267026 | debian-bookworm | ok: 12 changed: 8 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 0 2026-01-06 00:19:56.267111 | 2026-01-06 00:19:56.414390 | RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2026-01-06 00:19:56.417139 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2026-01-06 00:19:57.202543 | 2026-01-06 00:19:57.202722 | PLAY [all] 2026-01-06 00:19:57.219016 | 2026-01-06 00:19:57.219201 | TASK [fetch-tox-output : Set tox log path for multiple nodes] 2026-01-06 00:19:57.274576 | debian-bookworm | skipping: Conditional result was False 2026-01-06 00:19:57.280608 | 2026-01-06 00:19:57.280778 | TASK [fetch-tox-output : Set tox log path for single node] 2026-01-06 00:19:57.320086 | debian-bookworm | ok 2026-01-06 00:19:57.325426 | 2026-01-06 00:19:57.325551 | TASK [fetch-tox-output : Ensure local tox dir] 2026-01-06 00:19:57.841001 | debian-bookworm -> localhost | changed 2026-01-06 00:19:57.849467 | 2026-01-06 00:19:57.849616 | TASK [fetch-tox-output : Ensure zuul-output tox dir] 2026-01-06 00:19:57.895403 | debian-bookworm | skipping: Conditional result was False 2026-01-06 00:19:57.906557 | 2026-01-06 00:19:57.906755 | TASK [fetch-tox-output : Set envlist fact] 2026-01-06 00:19:57.954521 | debian-bookworm | ok 2026-01-06 00:19:57.964390 | 2026-01-06 00:19:57.964543 | TASK [fetch-tox-output : Get tox version] 2026-01-06 00:19:58.633094 | debian-bookworm | ROOT: No loadable tox.ini or setup.cfg or pyproject.toml or tox.toml found, assuming empty tox.ini at /home/zuul 2026-01-06 00:19:58.650826 | debian-bookworm | 4.33.0 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2026-01-06 00:19:59.002147 | debian-bookworm | ok: Runtime: 0:00:00.256184 2026-01-06 00:19:59.011285 | 2026-01-06 00:19:59.011466 | TASK [fetch-tox-output : Find all default environments] 2026-01-06 00:19:59.542141 | debian-bookworm | skipping: Conditional result was False 2026-01-06 00:19:59.548913 | 2026-01-06 00:19:59.549059 | TASK [fetch-tox-output : Set envlist fact] 2026-01-06 00:19:59.573565 | debian-bookworm | skipping: Conditional result was False 2026-01-06 00:19:59.580661 | 2026-01-06 00:19:59.580819 | TASK [fetch-tox-output : Find all default environments] 2026-01-06 00:20:00.113465 | debian-bookworm | skipping: Conditional result was False 2026-01-06 00:20:00.119714 | 2026-01-06 00:20:00.119846 | TASK [fetch-tox-output : Set envlist fact] 2026-01-06 00:20:00.144136 | debian-bookworm | skipping: Conditional result was False 2026-01-06 00:20:00.152149 | 2026-01-06 00:20:00.152326 | LOOP [fetch-tox-output : Copy tox logs] 2026-01-06 00:20:00.201643 | debian-bookworm | skipping: Conditional result was False 2026-01-06 00:20:00.216663 | 2026-01-06 00:20:00.216801 | LOOP [fetch-tox-output : Collect tox logs] 2026-01-06 00:20:01.011058 | debian-bookworm | changed: 2026-01-06 00:20:01.011269 | debian-bookworm | created directory /var/lib/zuul/builds/b7ee7e7eb0ae43e39855255d1dfc8759/work/logs/tox/test 2026-01-06 00:20:01.011305 | debian-bookworm | cd+++++++++ ./ 2026-01-06 00:20:01.011330 | debian-bookworm | >f+++++++++ 1-commands[0].log 2026-01-06 00:20:01.011364 | 2026-01-06 00:20:01.020893 | 2026-01-06 00:20:01.021001 | PLAY RECAP 2026-01-06 00:20:01.021053 | debian-bookworm | ok: 5 changed: 3 unreachable: 0 failed: 0 skipped: 7 rescued: 0 ignored: 0 2026-01-06 00:20:01.021081 | 2026-01-06 00:20:01.173383 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2026-01-06 00:20:01.174465 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2026-01-06 00:20:01.993559 | 2026-01-06 00:20:01.993739 | PLAY [all] 2026-01-06 00:20:02.012047 | 2026-01-06 00:20:02.012252 | TASK [fetch-subunit-output : Find stestr or testr executable] 2026-01-06 00:20:02.256157 | debian-bookworm | changed: non-zero return code 2026-01-06 00:20:02.262621 | 2026-01-06 00:20:02.262793 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2026-01-06 00:20:02.287977 | debian-bookworm | skipping: Conditional result was False 2026-01-06 00:20:02.296069 | 2026-01-06 00:20:02.296269 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2026-01-06 00:20:02.328131 | 2026-01-06 00:20:02.328406 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2026-01-06 00:20:02.360910 | 2026-01-06 00:20:02.361237 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2026-01-06 00:20:02.387607 | debian-bookworm | skipping: Conditional result was False 2026-01-06 00:20:02.393879 | 2026-01-06 00:20:02.394014 | LOOP [fetch-subunit-output : Generate subunit file] 2026-01-06 00:20:02.424455 | 2026-01-06 00:20:02.424681 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2026-01-06 00:20:02.449103 | debian-bookworm | skipping: Conditional result was False 2026-01-06 00:20:02.455379 | 2026-01-06 00:20:02.455531 | TASK [fetch-subunit-output : Remove the temporary file] 2026-01-06 00:20:02.490375 | debian-bookworm | skipping: Conditional result was False 2026-01-06 00:20:02.496686 | 2026-01-06 00:20:02.496834 | TASK [fetch-subunit-output : Process and fetch subunit results] 2026-01-06 00:20:02.521596 | debian-bookworm | skipping: Conditional result was False 2026-01-06 00:20:02.528941 | 2026-01-06 00:20:02.529060 | PLAY RECAP 2026-01-06 00:20:02.529114 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2026-01-06 00:20:02.529142 | 2026-01-06 00:20:02.694656 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2026-01-06 00:20:02.695784 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-01-06 00:20:03.490011 | 2026-01-06 00:20:03.490247 | PLAY [Base post] 2026-01-06 00:20:03.510460 | 2026-01-06 00:20:03.510630 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-01-06 00:20:04.133955 | debian-bookworm | changed 2026-01-06 00:20:04.151844 | 2026-01-06 00:20:04.152006 | PLAY RECAP 2026-01-06 00:20:04.152076 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-01-06 00:20:04.152138 | 2026-01-06 00:20:04.319378 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-01-06 00:20:04.320493 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-01-06 00:20:05.351515 | 2026-01-06 00:20:05.351689 | PLAY [Base post-logs] 2026-01-06 00:20:05.365444 | 2026-01-06 00:20:05.365615 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-01-06 00:20:05.841783 | localhost | changed 2026-01-06 00:20:05.852022 | 2026-01-06 00:20:05.852237 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-01-06 00:20:05.890754 | localhost | ok 2026-01-06 00:20:05.896882 | 2026-01-06 00:20:05.897059 | TASK [Set zuul-log-path fact] 2026-01-06 00:20:05.925574 | localhost | ok 2026-01-06 00:20:05.934819 | 2026-01-06 00:20:05.934987 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-01-06 00:20:05.971767 | localhost | ok 2026-01-06 00:20:05.975534 | 2026-01-06 00:20:05.975658 | TASK [upload-logs : Create log directories] 2026-01-06 00:20:06.533766 | localhost | changed 2026-01-06 00:20:06.538459 | 2026-01-06 00:20:06.538620 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-01-06 00:20:07.169231 | localhost -> localhost | ok: Runtime: 0:00:00.009155 2026-01-06 00:20:07.176255 | 2026-01-06 00:20:07.176461 | TASK [upload-logs : Upload logs to log server] 2026-01-06 00:20:07.841761 | localhost | Output suppressed because no_log was given 2026-01-06 00:20:07.843705 | 2026-01-06 00:20:07.843815 | LOOP [upload-logs : Compress console log and json output] 2026-01-06 00:20:07.903405 | localhost | skipping: Conditional result was False 2026-01-06 00:20:07.909559 | localhost | skipping: Conditional result was False 2026-01-06 00:20:07.913213 | 2026-01-06 00:20:07.913338 | LOOP [upload-logs : Upload compressed console log and json output] 2026-01-06 00:20:07.966108 | localhost | skipping: Conditional result was False 2026-01-06 00:20:07.966454 | 2026-01-06 00:20:07.973226 | localhost | skipping: Conditional result was False 2026-01-06 00:20:07.980616 | 2026-01-06 00:20:07.980747 | LOOP [upload-logs : Upload console log and json output]