XUnit失败测试控制台输出含义解析及相关问题咨询

测试场景概述 #

我在Linux命令行通过dotnet new xunit创建了一个XUnit测试项目，修改UnitTest1.cs为最简失败测试代码：

namespace trash;

public class UnitTest1
{
    [Fact]
    public void Test1()
    {
        Assert.Equal(1,2);
    }
}

执行测试后得到输出：

Starting test execution, please wait...   
A total of 1 test files matched the specified pattern.  
[xUnit.net 00:00:00.43]     trash.UnitTest1.Test1 [FAIL]   
  Failed trash.UnitTest1.Test1 [4 ms]   
  Error Message:   
   Assert.Equal() Failure   
Expected: 1  
Actual:   2  
  Stack Trace:  
     at trash.UnitTest1.Test1() in      
 [pathtoMyProjects/Projects/trash/UnitTest1.cs:line 9   
   at System.RuntimeMethodHandle.InvokeMethod(Object target, Void** arguments, Signature sig, Boolean isConstructor)  
   at System.Reflection.MethodInvoker.Invoke(Object obj, IntPtr* args, BindingFlags invokeAttr)  
   
Failed!  - Failed:     1, Passed:     0, Skipped:     0, Total:     1, Duration: < 1 ms - trash.dll (net7.0)   

疑问解答 #

1. [xUnit.net 00:00:00.43]中的数字43代表什么？ #

这个时间是xUnit.net框架从启动测试执行到当前测试开始运行的累计耗时，单位是毫秒。00:00:00.43即43毫秒，包含了测试程序集加载、测试发现、初始化测试环境等前置操作的总时间，和单个测试的执行耗时（这里显示的[4 ms]）不是一回事。

2. 为何会显示堆栈跟踪？异常的抛出、捕获逻辑及使用方式？ #

• 异常抛出点：Assert.Equal(1,2)内部会判断两个值不相等，直接抛出Xunit.Sdk.EqualException（或继承自XunitException的断言失败异常），这个异常就是在Test1方法的第9行抛出的。
• 异常捕获逻辑：xUnit.net的测试运行器会自动捕获每个测试方法执行过程中抛出的所有异常（包括断言失败异常）。一旦捕获到异常，测试运行器就会标记该测试为失败，并收集异常的堆栈跟踪信息输出。
• 堆栈跟踪的用途：
  • 快速定位失败代码的精确位置：从堆栈最顶部的trash.UnitTest1.Test1() in ... line 9可以直接找到断言失败的代码行，这是最常用的用途。
  • 追踪断言失败的内部逻辑：如果是自定义断言或者复杂的测试逻辑，堆栈下方的框架级调用（比如System.Reflection.MethodInvoker.Invoke）可以帮你理解测试运行器如何执行测试方法，但一般排查业务测试失败时只需要关注最顶部的用户代码行即可。

内容的提问来源于stack exchange，提问作者Dr. Mumtaz Ali Shah