为什么发送到跟踪源的消息在除第一个单元测试之外的所有单元测试中都丢失了？

Question

我观察到 Visual Studio Enterprise 2015 Update 1 的奇怪行为。在单元测试中通过 .NET Framework TraceSource 使用日志记录时，只有第一个单元测试包含日志记录输出。

这是重现问题的方法：

一个空的单元测试项目包含 UnitTest1 class，如下所示：

using System;
using System.Diagnostics;
using Microsoft.VisualStudio.TestTools.UnitTesting;

[TestClass]
public class UnitTest1
{
    private readonly TraceSource trace = new TraceSource("Demo", SourceLevels.All);

    [TestMethod]
    public void TestMethod1()
    {
        this.trace.TraceEvent(TraceEventType.Information, 0, "Test 1 (trace source)");
        Console.WriteLine("Test 1 (console)");
    }

    [TestMethod]
    public void TestMethod2()
    {
        this.trace.TraceEvent(TraceEventType.Information, 0, "Test 2 (trace source)");
        Console.WriteLine("Test 2 (console)");
    }
}

App.config 文件如下所示：

<?xml version="1.0" encoding="utf-8" ?>
<configuration>
  <system.diagnostics>
    <trace autoflush="true" />
    <sources>
      <source name="Demo" switchValue="Critical, Error, Warning, ActivityTracing, Information, Verbose">
        <listeners>
          <add name="ConsoleTraceListener" />
          <add name="TextFileListener" />
        </listeners>
      </source>
    </sources>
    <sharedListeners>
      <add name="ConsoleTraceListener" type="System.Diagnostics.ConsoleTraceListener" />
      <add name="TextFileListener" type="System.Diagnostics.TextWriterTraceListener" initializeData="tests.log" />
    </sharedListeners>
  </system.diagnostics>
</configuration>

当运行来自 Visual Studio 的测试时：

第一个单元测试的输出是：

Test Name:  TestMethod1
Test Outcome:   Passed
Result StandardOutput:  
Demo Information: 0 : Test 1 (trace source)
Test 1 (console)

同时显示来自跟踪源和控制台的消息。

第二个单元测试的输出为：
```
Test Name:  TestMethod2
Test Outcome:   Passed
Result StandardOutput:  Test 2 (console)
```
虽然 Console.WriteLine 的输出效果很好，但未显示 TraceSource.TraceEvent 的输出。

运行通过执行 mstest /testcontainer:UnitTestProject1.dll 从控制台进行的测试也重现了该问题。第一次测试报告的 stdout 包含两行；第二个测试的 stdout 只有一行。

为什么除了第一个测试之外没有跟踪所有日志记录？

Answer 1

解决方法包括包装默认值 TextWriter，它使用底层 Console.Out 文本编写器来发送日志：

public class CustomTextWriter : TextWriter
{
    public override Encoding Encoding
    {
        get
        {
            return Console.Out.Encoding;
        }
    }

    public override void Write(string value)
    {
        Console.Out.Write(value);
    }

    public override void WriteLine(string value)
    {
        Console.Out.WriteLine(value);
    }
}

然后在类似于 one used in .NET Framework:

的自定义跟踪侦听器中使用编写器

public class ConsoleTraceListener : TextWriterTraceListener
{
    public ConsoleTraceListener() : base(new CustomTextWriter())
    {
    }

    public override void Close()
    {
    }
}

在 App.config 中声明如下：

<add name="ConsoleTraceListener"
     type="UnitTestProject1.ConsoleTraceListener, UnitTestProject1, Version=1.0.0.0, Culture=neutral, PublicKeyToken=null" />

这会产生预期的输出，尽管仍然不清楚为什么问题首先存在。通过检查 Console.Out 和底层流，似乎在第一次测试结束后没有处理或关闭某些东西（如果会，为什么 the current implementation of Out 什么都不做，为什么文本发送到 Console.WriteLine出现在日志中？）

为什么发送到跟踪源的消息在除第一个单元测试之外的所有单元测试中都丢失了？

Why are messages sent to trace source missing from all but the first unit test?

c#

unit-testing

mstest