2026-05-18 03:02:54.056468 | Job console starting 2026-05-18 03:02:54.088243 | Updating git repos 2026-05-18 03:02:54.145148 | Cloning repos into workspace 2026-05-18 03:02:54.250294 | Restoring repo states 2026-05-18 03:02:54.280175 | Merging changes 2026-05-18 03:02:54.280578 | Checking out repos 2026-05-18 03:02:54.382708 | Preparing playbooks 2026-05-18 03:02:56.157004 | Running Ansible setup 2026-05-18 03:03:05.046826 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-05-18 03:03:06.612839 | 2026-05-18 03:03:06.612985 | PLAY [Base pre] 2026-05-18 03:03:06.641090 | 2026-05-18 03:03:06.641231 | TASK [Setup log path fact] 2026-05-18 03:03:06.660096 | debian-bookworm | ok 2026-05-18 03:03:06.693786 | 2026-05-18 03:03:06.693931 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-18 03:03:06.773539 | debian-bookworm | ok 2026-05-18 03:03:06.791421 | 2026-05-18 03:03:06.791536 | TASK [emit-job-header : Print job information] 2026-05-18 03:03:06.835892 | # Job Information 2026-05-18 03:03:06.836051 | Ansible Version: 2.16.14 2026-05-18 03:03:06.836098 | Job: tox 2026-05-18 03:03:06.836132 | Pipeline: periodic-daily 2026-05-18 03:03:06.836154 | Executor: 521e9411259a 2026-05-18 03:03:06.836175 | Triggered by: https://github.com/osism/openstack-image-manager 2026-05-18 03:03:06.836198 | Event ID: 8672d863767d4aa78a1277547960fa70 2026-05-18 03:03:06.841883 | 2026-05-18 03:03:06.841987 | LOOP [emit-job-header : Print node information] 2026-05-18 03:03:06.984374 | debian-bookworm | ok: 2026-05-18 03:03:06.984537 | debian-bookworm | # Node Information 2026-05-18 03:03:06.984570 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-05-18 03:03:06.984595 | debian-bookworm | Hostname: debian 2026-05-18 03:03:06.984617 | debian-bookworm | Username: zuul 2026-05-18 03:03:06.984639 | debian-bookworm | Distro: Debian 12.14 2026-05-18 03:03:06.984664 | debian-bookworm | Provider: regiocloud-a 2026-05-18 03:03:06.984686 | debian-bookworm | Region: 2026-05-18 03:03:06.984707 | debian-bookworm | Label: debian-bookworm 2026-05-18 03:03:06.984727 | debian-bookworm | Product Name: OpenStack Nova 2026-05-18 03:03:06.984746 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe10:3640 2026-05-18 03:03:07.003455 | 2026-05-18 03:03:07.003554 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-05-18 03:03:07.646719 | debian-bookworm -> localhost | changed 2026-05-18 03:03:07.655014 | 2026-05-18 03:03:07.655665 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-05-18 03:03:09.404044 | debian-bookworm -> localhost | changed 2026-05-18 03:03:09.420374 | 2026-05-18 03:03:09.420514 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-05-18 03:03:09.917080 | debian-bookworm -> localhost | ok 2026-05-18 03:03:09.923586 | 2026-05-18 03:03:09.923699 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-05-18 03:03:09.956845 | debian-bookworm | ok 2026-05-18 03:03:09.988896 | debian-bookworm | included: /var/lib/zuul/builds/9f1f23c39b1b43ada084a91f627ed8e1/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-05-18 03:03:09.999848 | 2026-05-18 03:03:09.999988 | TASK [add-build-sshkey : Create Temp SSH key] 2026-05-18 03:03:11.728271 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-05-18 03:03:11.728459 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/9f1f23c39b1b43ada084a91f627ed8e1/work/9f1f23c39b1b43ada084a91f627ed8e1_id_rsa 2026-05-18 03:03:11.728496 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/9f1f23c39b1b43ada084a91f627ed8e1/work/9f1f23c39b1b43ada084a91f627ed8e1_id_rsa.pub 2026-05-18 03:03:11.728523 | debian-bookworm -> localhost | The key fingerprint is: 2026-05-18 03:03:11.728548 | debian-bookworm -> localhost | SHA256:xf4MAYgJdUOkj2OPRMGDan3pAEb3ky8brwT4OR48mdc zuul-build-sshkey 2026-05-18 03:03:11.728570 | debian-bookworm -> localhost | The key's randomart image is: 2026-05-18 03:03:11.728603 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-05-18 03:03:11.728627 | debian-bookworm -> localhost | |...*o==.. | 2026-05-18 03:03:11.728649 | debian-bookworm -> localhost | | oo *+o. o | 2026-05-18 03:03:11.728669 | debian-bookworm -> localhost | |..o o=. + | 2026-05-18 03:03:11.728690 | debian-bookworm -> localhost | |...+ =o o . | 2026-05-18 03:03:11.728710 | debian-bookworm -> localhost | |.. .Oo..S o | 2026-05-18 03:03:11.728740 | debian-bookworm -> localhost | | oo==* + | 2026-05-18 03:03:11.728762 | debian-bookworm -> localhost | | X.+.E o | 2026-05-18 03:03:11.728783 | debian-bookworm -> localhost | | . * . | 2026-05-18 03:03:11.728804 | debian-bookworm -> localhost | | . . | 2026-05-18 03:03:11.728826 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-05-18 03:03:11.728877 | debian-bookworm -> localhost | ok: Runtime: 0:00:01.055043 2026-05-18 03:03:11.735265 | 2026-05-18 03:03:11.735367 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-05-18 03:03:11.763713 | debian-bookworm | ok 2026-05-18 03:03:11.776840 | debian-bookworm | included: /var/lib/zuul/builds/9f1f23c39b1b43ada084a91f627ed8e1/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-05-18 03:03:11.792859 | 2026-05-18 03:03:11.792982 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-05-18 03:03:11.816271 | debian-bookworm | skipping: Conditional result was False 2026-05-18 03:03:11.822645 | 2026-05-18 03:03:11.822756 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-05-18 03:03:12.524782 | debian-bookworm | changed 2026-05-18 03:03:12.533952 | 2026-05-18 03:03:12.534045 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-05-18 03:03:12.785127 | debian-bookworm | ok 2026-05-18 03:03:12.793751 | 2026-05-18 03:03:12.793843 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-05-18 03:03:13.473817 | debian-bookworm | changed 2026-05-18 03:03:13.479381 | 2026-05-18 03:03:13.479458 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-05-18 03:03:14.227094 | debian-bookworm | changed 2026-05-18 03:03:14.246949 | 2026-05-18 03:03:14.247137 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-05-18 03:03:14.270610 | debian-bookworm | skipping: Conditional result was False 2026-05-18 03:03:14.276112 | 2026-05-18 03:03:14.276191 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-05-18 03:03:15.158560 | debian-bookworm -> localhost | changed 2026-05-18 03:03:15.196345 | 2026-05-18 03:03:15.196456 | TASK [add-build-sshkey : Add back temp key] 2026-05-18 03:03:15.715640 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/9f1f23c39b1b43ada084a91f627ed8e1/work/9f1f23c39b1b43ada084a91f627ed8e1_id_rsa (zuul-build-sshkey) 2026-05-18 03:03:15.715866 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.031566 2026-05-18 03:03:15.724847 | 2026-05-18 03:03:15.724949 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-05-18 03:03:16.174766 | debian-bookworm | ok 2026-05-18 03:03:16.179530 | 2026-05-18 03:03:16.179615 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-05-18 03:03:16.202615 | debian-bookworm | skipping: Conditional result was False 2026-05-18 03:03:16.211502 | 2026-05-18 03:03:16.211592 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-05-18 03:03:16.681512 | debian-bookworm | ok 2026-05-18 03:03:16.696896 | 2026-05-18 03:03:16.696993 | TASK [validate-host : Define zuul_info_dir fact] 2026-05-18 03:03:16.746294 | debian-bookworm | ok 2026-05-18 03:03:16.750529 | 2026-05-18 03:03:16.750607 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-05-18 03:03:17.101597 | debian-bookworm -> localhost | ok 2026-05-18 03:03:17.106973 | 2026-05-18 03:03:17.107079 | TASK [validate-host : Collect information about the host] 2026-05-18 03:03:18.464267 | debian-bookworm | ok 2026-05-18 03:03:18.476662 | 2026-05-18 03:03:18.476764 | TASK [validate-host : Sanitize hostname] 2026-05-18 03:03:18.659966 | debian-bookworm | ok 2026-05-18 03:03:18.690455 | 2026-05-18 03:03:18.690551 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-05-18 03:03:19.198180 | debian-bookworm -> localhost | changed 2026-05-18 03:03:19.203692 | 2026-05-18 03:03:19.203788 | TASK [validate-host : Collect information about zuul worker] 2026-05-18 03:03:19.616293 | debian-bookworm | ok 2026-05-18 03:03:19.634129 | 2026-05-18 03:03:19.634286 | TASK [validate-host : Write out all zuul information for each host] 2026-05-18 03:03:20.332493 | debian-bookworm -> localhost | changed 2026-05-18 03:03:20.341690 | 2026-05-18 03:03:20.341797 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-05-18 03:03:20.623908 | debian-bookworm | ok 2026-05-18 03:03:20.629152 | 2026-05-18 03:03:20.629237 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-05-18 03:03:21.762256 | debian-bookworm | changed: 2026-05-18 03:03:21.762506 | debian-bookworm | cd+++++++++ src/ 2026-05-18 03:03:21.762549 | debian-bookworm | cd+++++++++ src/github.com/ 2026-05-18 03:03:21.762574 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-05-18 03:03:21.762597 | debian-bookworm | cd+++++++++ src/github.com/osism/openstack-image-manager/ 2026-05-18 03:03:21.762618 | debian-bookworm | =7.0.3 2026-05-18 03:03:49.051191 | debian-bookworm | Downloading cachetools-7.1.2-py3-none-any.whl (16 kB) 2026-05-18 03:03:49.107481 | debian-bookworm | Collecting colorama>=0.4.6 2026-05-18 03:03:49.120283 | debian-bookworm | Downloading colorama-0.4.6-py2.py3-none-any.whl (25 kB) 2026-05-18 03:03:49.195572 | debian-bookworm | Collecting filelock>=3.25 2026-05-18 03:03:49.208150 | debian-bookworm | Downloading filelock-3.29.0-py3-none-any.whl (39 kB) 2026-05-18 03:03:49.279733 | debian-bookworm | Collecting packaging>=26 2026-05-18 03:03:49.291401 | debian-bookworm | Downloading packaging-26.2-py3-none-any.whl (100 kB) 2026-05-18 03:03:49.303473 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 100.2/100.2 kB 10.9 MB/s eta 0:00:00 2026-05-18 03:03:49.375539 | debian-bookworm | Collecting platformdirs>=4.9.4 2026-05-18 03:03:49.387617 | debian-bookworm | Downloading platformdirs-4.9.6-py3-none-any.whl (21 kB) 2026-05-18 03:03:49.430567 | debian-bookworm | Collecting pluggy>=1.6 2026-05-18 03:03:49.442118 | debian-bookworm | Downloading pluggy-1.6.0-py3-none-any.whl (20 kB) 2026-05-18 03:03:49.490513 | debian-bookworm | Collecting pyproject-api>=1.10 2026-05-18 03:03:49.502665 | debian-bookworm | Downloading pyproject_api-1.10.0-py3-none-any.whl (13 kB) 2026-05-18 03:03:49.544980 | debian-bookworm | Collecting python-discovery>=1.2.2 2026-05-18 03:03:49.556806 | debian-bookworm | Downloading python_discovery-1.3.1-py3-none-any.whl (33 kB) 2026-05-18 03:03:49.599670 | debian-bookworm | Collecting tomli-w>=1.2 2026-05-18 03:03:49.611759 | debian-bookworm | Downloading tomli_w-1.2.0-py3-none-any.whl (6.7 kB) 2026-05-18 03:03:49.771881 | debian-bookworm | Collecting virtualenv>=21.1 2026-05-18 03:03:49.785513 | debian-bookworm | Downloading virtualenv-21.3.3-py3-none-any.whl (7.6 MB) 2026-05-18 03:03:50.197037 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 7.6/7.6 MB 18.7 MB/s eta 0:00:00 2026-05-18 03:03:50.330781 | debian-bookworm | Collecting distlib<1,>=0.3.7 2026-05-18 03:03:50.343394 | debian-bookworm | Downloading distlib-0.4.0-py2.py3-none-any.whl (469 kB) 2026-05-18 03:03:50.380815 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 469.0/469.0 kB 14.8 MB/s eta 0:00:00 2026-05-18 03:03:50.519020 | debian-bookworm | Installing collected packages: distlib, tomli-w, pluggy, platformdirs, packaging, filelock, colorama, cachetools, python-discovery, pyproject-api, virtualenv, tox 2026-05-18 03:03:51.360366 | debian-bookworm | Successfully installed cachetools-7.1.2 colorama-0.4.6 distlib-0.4.0 filelock-3.29.0 packaging-26.2 platformdirs-4.9.6 pluggy-1.6.0 pyproject-api-1.10.0 python-discovery-1.3.1 tomli-w-1.2.0 tox-4.54.0 virtualenv-21.3.3 2026-05-18 03:03:51.764755 | debian-bookworm | ok: Runtime: 0:00:03.460442 2026-05-18 03:03:51.770790 | 2026-05-18 03:03:51.770912 | TASK [ensure-tox : Export installed tox_executable path] 2026-05-18 03:03:51.823719 | debian-bookworm | ok 2026-05-18 03:03:51.828522 | 2026-05-18 03:03:51.828653 | TASK [ensure-tox : Output tox version] 2026-05-18 03:03:52.454752 | 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-05-18 03:03:52.472434 | debian-bookworm | 4.54.0 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2026-05-18 03:03:52.869292 | debian-bookworm | ok: Runtime: 0:00:00.320973 2026-05-18 03:03:52.875655 | 2026-05-18 03:03:52.875753 | TASK [ensure-tox : Make global symlink] 2026-05-18 03:03:52.940796 | debian-bookworm | skipping: Conditional result was False 2026-05-18 03:03:52.949522 | 2026-05-18 03:03:52.949641 | PLAY RECAP 2026-05-18 03:03:52.949712 | debian-bookworm | ok: 13 changed: 9 unreachable: 0 failed: 0 skipped: 14 rescued: 0 ignored: 0 2026-05-18 03:03:52.949740 | 2026-05-18 03:03:53.436881 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/pre.yaml@master] 2026-05-18 03:03:53.437726 | RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2026-05-18 03:03:54.610710 | 2026-05-18 03:03:54.610846 | PLAY [all] 2026-05-18 03:03:54.636795 | 2026-05-18 03:03:54.636909 | TASK [revoke-sudo : Check if zuul is sudoer] 2026-05-18 03:03:55.383895 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2026-05-18 03:03:55.703359 | debian-bookworm | ok: Runtime: 0:00:00.032826 2026-05-18 03:03:55.707985 | 2026-05-18 03:03:55.708093 | TASK [revoke-sudo : Remove sudo access for zuul user.] 2026-05-18 03:03:56.249102 | debian-bookworm | ok: Runtime: 0:00:00.009284 2026-05-18 03:03:56.255135 | 2026-05-18 03:03:56.255250 | TASK [revoke-sudo : Prove that general sudo access is actually revoked.] 2026-05-18 03:03:56.632758 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2026-05-18 03:03:56.634574 | debian-bookworm | sudo: a password is required 2026-05-18 03:03:56.804426 | debian-bookworm | ok: Runtime: 0:00:00.025725 2026-05-18 03:03:56.814719 | 2026-05-18 03:03:56.814848 | TASK [tox : Check to see if the constraints file exists] 2026-05-18 03:03:56.868588 | debian-bookworm | skipping: Conditional result was False 2026-05-18 03:03:56.878651 | 2026-05-18 03:03:56.878792 | TASK [tox : Fail if constraints file is missing] 2026-05-18 03:03:56.943693 | debian-bookworm | skipping: Conditional result was False 2026-05-18 03:03:56.950514 | 2026-05-18 03:03:56.950645 | TASK [tox : Record file location] 2026-05-18 03:03:56.996990 | debian-bookworm | skipping: Conditional result was False 2026-05-18 03:03:57.002919 | 2026-05-18 03:03:57.003029 | TASK [tox : Install tox siblings] 2026-05-18 03:03:57.053156 | debian-bookworm | ok 2026-05-18 03:03:57.061960 | debian-bookworm | included: /var/lib/zuul/builds/9f1f23c39b1b43ada084a91f627ed8e1/untrusted/project_0/github.com/osism/openinfra-zuul-jobs/roles/tox/tasks/siblings.yaml 2026-05-18 03:03:57.090255 | 2026-05-18 03:03:57.090379 | TASK [tox : Run tox without tests] 2026-05-18 03:03:58.764053 | debian-bookworm | test: install_deps> python -I -m pip install -r requirements.txt 2026-05-18 03:04:12.843274 | debian-bookworm | test: OK (14.93 seconds) 2026-05-18 03:04:12.843341 | debian-bookworm | congratulations :) (14.96 seconds) 2026-05-18 03:04:13.189427 | debian-bookworm | ok: Runtime: 0:00:15.296830 2026-05-18 03:04:13.195258 | 2026-05-18 03:04:13.195351 | TASK [tox : Create a tempfile to save tox showconfig] 2026-05-18 03:04:13.647938 | debian-bookworm | changed 2026-05-18 03:04:13.653491 | 2026-05-18 03:04:13.653598 | TASK [tox : Detect tox version] 2026-05-18 03:04:14.212536 | debian-bookworm | 4.54.0 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2026-05-18 03:04:14.687660 | debian-bookworm | ok: Runtime: 0:00:00.320996 2026-05-18 03:04:14.694192 | 2026-05-18 03:04:14.694285 | TASK [tox : Get tox envlist config] 2026-05-18 03:04:15.762638 | debian-bookworm | ok: Runtime: 0:00:00.315635 2026-05-18 03:04:15.773515 | 2026-05-18 03:04:15.773626 | TASK [tox : Install any sibling python packages] 2026-05-18 03:04:16.950628 | debian-bookworm | ok: No setup.cfg, no action needed 2026-05-18 03:04:16.961709 | 2026-05-18 03:04:16.961811 | TASK [tox : Remove tempfile] 2026-05-18 03:04:17.801834 | debian-bookworm | changed 2026-05-18 03:04:17.829557 | 2026-05-18 03:04:17.829688 | TASK [tox : Emit tox command] 2026-05-18 03:04:17.869293 | /home/zuul/.local/tox/bin/tox -etest -- test/unit 2026-05-18 03:04:17.875575 | 2026-05-18 03:04:17.875685 | TASK [tox : Run tox] 2026-05-18 03:04:18.493615 | debian-bookworm | test: commands[0]> python -m unittest discover test/unit 2026-05-18 03:04:19.188618 | debian-bookworm | ............... 2026-05-18 03:04:19.188665 | debian-bookworm | ---------------------------------------------------------------------- 2026-05-18 03:04:19.188678 | debian-bookworm | Ran 15 tests in 0.165s 2026-05-18 03:04:19.188683 | debian-bookworm | 2026-05-18 03:04:19.188690 | debian-bookworm | OK 2026-05-18 03:04:19.257740 | debian-bookworm | test: OK (0.81=setup[0.04]+cmd[0.76] seconds) 2026-05-18 03:04:19.257790 | debian-bookworm | congratulations :) (0.83 seconds) 2026-05-18 03:04:19.445694 | debian-bookworm | ok: Runtime: 0:00:01.119170 2026-05-18 03:04:19.452917 | 2026-05-18 03:04:19.453056 | TASK [tox : Look for output] 2026-05-18 03:04:19.902225 | debian-bookworm | ok 2026-05-18 03:04:19.915890 | 2026-05-18 03:04:19.916038 | TASK [tox : Return file comments to Zuul] 2026-05-18 03:04:19.950412 | debian-bookworm | skipping: Conditional result was False 2026-05-18 03:04:19.964560 | 2026-05-18 03:04:19.964716 | PLAY RECAP 2026-05-18 03:04:19.964819 | debian-bookworm | ok: 13 changed: 9 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 0 2026-05-18 03:04:19.964860 | 2026-05-18 03:04:20.132732 | RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2026-05-18 03:04:20.136021 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2026-05-18 03:04:20.826998 | 2026-05-18 03:04:20.827127 | PLAY [all] 2026-05-18 03:04:20.849663 | 2026-05-18 03:04:20.849785 | TASK [fetch-tox-output : Set tox log path for multiple nodes] 2026-05-18 03:04:20.904060 | debian-bookworm | skipping: Conditional result was False 2026-05-18 03:04:20.911344 | 2026-05-18 03:04:20.911498 | TASK [fetch-tox-output : Set tox log path for single node] 2026-05-18 03:04:20.961243 | debian-bookworm | ok 2026-05-18 03:04:20.965999 | 2026-05-18 03:04:20.966112 | TASK [fetch-tox-output : Ensure local tox dir] 2026-05-18 03:04:21.575758 | debian-bookworm -> localhost | changed 2026-05-18 03:04:21.583735 | 2026-05-18 03:04:21.583831 | TASK [fetch-tox-output : Ensure zuul-output tox dir] 2026-05-18 03:04:21.657067 | debian-bookworm | skipping: Conditional result was False 2026-05-18 03:04:21.661740 | 2026-05-18 03:04:21.661828 | TASK [fetch-tox-output : Set envlist fact] 2026-05-18 03:04:21.689763 | debian-bookworm | ok 2026-05-18 03:04:21.694353 | 2026-05-18 03:04:21.694435 | TASK [fetch-tox-output : Get tox version] 2026-05-18 03:04:22.447238 | 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-05-18 03:04:22.463829 | debian-bookworm | 4.54.0 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2026-05-18 03:04:22.756662 | debian-bookworm | ok: Runtime: 0:00:00.274342 2026-05-18 03:04:22.761223 | 2026-05-18 03:04:22.761313 | TASK [fetch-tox-output : Find all default environments] 2026-05-18 03:04:23.286815 | debian-bookworm | skipping: Conditional result was False 2026-05-18 03:04:23.292646 | 2026-05-18 03:04:23.292748 | TASK [fetch-tox-output : Set envlist fact] 2026-05-18 03:04:23.336033 | debian-bookworm | skipping: Conditional result was False 2026-05-18 03:04:23.341615 | 2026-05-18 03:04:23.341708 | TASK [fetch-tox-output : Find all default environments] 2026-05-18 03:04:23.889895 | debian-bookworm | skipping: Conditional result was False 2026-05-18 03:04:23.896175 | 2026-05-18 03:04:23.896280 | TASK [fetch-tox-output : Set envlist fact] 2026-05-18 03:04:23.972563 | debian-bookworm | skipping: Conditional result was False 2026-05-18 03:04:23.978942 | 2026-05-18 03:04:23.979040 | LOOP [fetch-tox-output : Copy tox logs] 2026-05-18 03:04:24.056143 | debian-bookworm | skipping: Conditional result was False 2026-05-18 03:04:24.069674 | 2026-05-18 03:04:24.069787 | LOOP [fetch-tox-output : Collect tox logs] 2026-05-18 03:04:25.151774 | debian-bookworm | changed: 2026-05-18 03:04:25.151984 | debian-bookworm | created directory /var/lib/zuul/builds/9f1f23c39b1b43ada084a91f627ed8e1/work/logs/tox/test 2026-05-18 03:04:25.152019 | debian-bookworm | cd+++++++++ ./ 2026-05-18 03:04:25.152058 | debian-bookworm | >f+++++++++ 1-commands[0].log 2026-05-18 03:04:25.152103 | 2026-05-18 03:04:25.179887 | 2026-05-18 03:04:25.180058 | PLAY RECAP 2026-05-18 03:04:25.180142 | debian-bookworm | ok: 5 changed: 3 unreachable: 0 failed: 0 skipped: 7 rescued: 0 ignored: 0 2026-05-18 03:04:25.180169 | 2026-05-18 03:04:25.604371 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2026-05-18 03:04:25.606185 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2026-05-18 03:04:26.888580 | 2026-05-18 03:04:26.888835 | PLAY [all] 2026-05-18 03:04:26.911692 | 2026-05-18 03:04:26.911823 | TASK [fetch-subunit-output : Find stestr or testr executable] 2026-05-18 03:04:27.093706 | debian-bookworm | changed: non-zero return code 2026-05-18 03:04:27.103281 | 2026-05-18 03:04:27.103405 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2026-05-18 03:04:27.122096 | debian-bookworm | skipping: Conditional result was False 2026-05-18 03:04:27.128726 | 2026-05-18 03:04:27.128866 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2026-05-18 03:04:27.160808 | 2026-05-18 03:04:27.160985 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2026-05-18 03:04:27.211214 | 2026-05-18 03:04:27.211394 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2026-05-18 03:04:27.244967 | debian-bookworm | skipping: Conditional result was False 2026-05-18 03:04:27.262878 | 2026-05-18 03:04:27.263012 | LOOP [fetch-subunit-output : Generate subunit file] 2026-05-18 03:04:27.302433 | 2026-05-18 03:04:27.302612 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2026-05-18 03:04:27.326281 | debian-bookworm | skipping: Conditional result was False 2026-05-18 03:04:27.332355 | 2026-05-18 03:04:27.332465 | TASK [fetch-subunit-output : Remove the temporary file] 2026-05-18 03:04:27.355989 | debian-bookworm | skipping: Conditional result was False 2026-05-18 03:04:27.361730 | 2026-05-18 03:04:27.361825 | TASK [fetch-subunit-output : Process and fetch subunit results] 2026-05-18 03:04:27.394995 | debian-bookworm | skipping: Conditional result was False 2026-05-18 03:04:27.401437 | 2026-05-18 03:04:27.401520 | PLAY RECAP 2026-05-18 03:04:27.401569 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2026-05-18 03:04:27.401594 | 2026-05-18 03:04:27.536845 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2026-05-18 03:04:27.537933 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-05-18 03:04:29.045057 | 2026-05-18 03:04:29.045216 | PLAY [Base post-fetch] 2026-05-18 03:04:29.071215 | 2026-05-18 03:04:29.071379 | TASK [fetch-output : Set log path for multiple nodes] 2026-05-18 03:04:29.155895 | debian-bookworm | skipping: Conditional result was False 2026-05-18 03:04:29.162157 | 2026-05-18 03:04:29.162310 | TASK [fetch-output : Set log path for single node] 2026-05-18 03:04:29.232140 | debian-bookworm | ok 2026-05-18 03:04:29.236957 | 2026-05-18 03:04:29.237056 | LOOP [fetch-output : Ensure local output dirs] 2026-05-18 03:04:30.394768 | debian-bookworm -> localhost | ok: "/var/lib/zuul/builds/9f1f23c39b1b43ada084a91f627ed8e1/work/logs" 2026-05-18 03:04:31.055633 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/9f1f23c39b1b43ada084a91f627ed8e1/work/artifacts" 2026-05-18 03:04:31.830655 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/9f1f23c39b1b43ada084a91f627ed8e1/work/docs" 2026-05-18 03:04:31.849275 | 2026-05-18 03:04:31.849378 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-05-18 03:04:33.423742 | debian-bookworm | changed: 2026-05-18 03:04:33.423913 | debian-bookworm | .d..t...... ./ 2026-05-18 03:04:33.423942 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-05-18 03:04:33.423972 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-05-18 03:04:33.424002 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-05-18 03:04:33.424038 | debian-bookworm | changed: All items complete 2026-05-18 03:04:33.425330 | 2026-05-18 03:04:34.516557 | debian-bookworm | changed: .d..t...... ./ 2026-05-18 03:04:35.405767 | debian-bookworm | changed: .d..t...... ./ 2026-05-18 03:04:35.428665 | 2026-05-18 03:04:35.428778 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-05-18 03:04:35.462640 | debian-bookworm | skipping: Conditional result was False 2026-05-18 03:04:35.465329 | debian-bookworm | skipping: Conditional result was False 2026-05-18 03:04:35.475623 | 2026-05-18 03:04:35.475691 | PLAY RECAP 2026-05-18 03:04:35.475740 | debian-bookworm | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0 2026-05-18 03:04:35.475765 | 2026-05-18 03:04:35.635631 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-05-18 03:04:35.636679 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-18 03:04:36.956794 | 2026-05-18 03:04:36.956968 | PLAY [Base post] 2026-05-18 03:04:36.971020 | 2026-05-18 03:04:36.971181 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-05-18 03:04:37.767271 | debian-bookworm | changed 2026-05-18 03:04:37.772815 | 2026-05-18 03:04:37.772898 | PLAY RECAP 2026-05-18 03:04:37.772949 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-05-18 03:04:37.773001 | 2026-05-18 03:04:38.001759 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-18 03:04:38.003054 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-05-18 03:04:39.577237 | 2026-05-18 03:04:39.577367 | PLAY [Base post-logs] 2026-05-18 03:04:39.585838 | 2026-05-18 03:04:39.585930 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-05-18 03:04:40.100134 | localhost | changed 2026-05-18 03:04:40.107799 | 2026-05-18 03:04:40.107913 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-05-18 03:04:40.162287 | localhost | ok 2026-05-18 03:04:40.164828 | 2026-05-18 03:04:40.164903 | TASK [Set zuul-log-path fact] 2026-05-18 03:04:40.188807 | localhost | ok 2026-05-18 03:04:40.195409 | 2026-05-18 03:04:40.195501 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-18 03:04:40.219948 | localhost | ok 2026-05-18 03:04:40.222260 | 2026-05-18 03:04:40.222333 | TASK [upload-logs : Create log directories] 2026-05-18 03:04:40.772273 | localhost | changed 2026-05-18 03:04:40.775337 | 2026-05-18 03:04:40.775435 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-05-18 03:04:41.509644 | localhost -> localhost | ok: Runtime: 0:00:00.014844 2026-05-18 03:04:41.513957 | 2026-05-18 03:04:41.514063 | TASK [upload-logs : Upload logs to log server] 2026-05-18 03:04:42.235412 | localhost | Output suppressed because no_log was given 2026-05-18 03:04:42.237227 | 2026-05-18 03:04:42.237319 | LOOP [upload-logs : Compress console log and json output] 2026-05-18 03:04:42.330466 | localhost | skipping: Conditional result was False 2026-05-18 03:04:42.346289 | localhost | skipping: Conditional result was False 2026-05-18 03:04:42.353595 | 2026-05-18 03:04:42.353702 | LOOP [upload-logs : Upload compressed console log and json output] 2026-05-18 03:04:42.438759 | localhost | skipping: Conditional result was False 2026-05-18 03:04:42.439010 | 2026-05-18 03:04:42.443694 | localhost | skipping: Conditional result was False 2026-05-18 03:04:42.449994 | 2026-05-18 03:04:42.450135 | LOOP [upload-logs : Upload console log and json output]