Add timestamp to the log

[fanyang-mono]

I am trying to investigate a test timeout issue. It would be helpful to have timestamps in the log. Right now xharness print logs like this:

  Discovering: reflection.SetValue.XUnitWrapper
  Discovered:  reflection.SetValue.XUnitWrapper
  Discovering: reflection.DefaultInterfaceMethods.XUnitWrapper
  Discovered:  reflection.DefaultInterfaceMethods.XUnitWrapper
  Discovering: reflection.Modifiers.XUnitWrapper
  Discovered:  reflection.Modifiers.XUnitWrapper
  Discovering: reflection.StaticInterfaceMembers.XUnitWrapper
  Discovered:  reflection.StaticInterfaceMembers.XUnitWrapper
  Starting:    reflection.StaticInterfaceMembers.XUnitWrapper

It would be nice to have logs like this

 [yyyy/MM/dd HH:mm:ss.ff] Discovering: reflection.SetValue.XUnitWrapper
 [yyyy/MM/dd HH:mm:ss.ff] Discovered:  reflection.SetValue.XUnitWrapper
 [yyyy/MM/dd HH:mm:ss.ff] Discovering: reflection.DefaultInterfaceMethods.XUnitWrapper
 [yyyy/MM/dd HH:mm:ss.ff] Discovered:  reflection.DefaultInterfaceMethods.XUnitWrapper
 [yyyy/MM/dd HH:mm:ss.ff] Discovering: reflection.Modifiers.XUnitWrapper
 [yyyy/MM/dd HH:mm:ss.ff] Discovered:  reflection.Modifiers.XUnitWrapper
 [yyyy/MM/dd HH:mm:ss.ff] Discovering: reflection.StaticInterfaceMembers.XUnitWrapper
 [yyyy/MM/dd HH:mm:ss.ff] Discovered:  reflection.StaticInterfaceMembers.XUnitWrapper
 [yyyy/MM/dd HH:mm:ss.ff] Starting:    reflection.StaticInterfaceMembers.XUnitWrapper

To achieve this, DateTime.Now.ToString("yyyy/MM/dd HH:mm:ss.ff") should be added to the following places

xharness/src/Microsoft.DotNet.XHarness.TestRunners.Xunit/ThreadlessXunitTestRunner.cs

Line 35 in b2297d6

Console.WriteLine($"Discovering: {assemblyFileName} (method display = {discoveryOptions.GetMethodDisplayOrDefault()}, method display options = {discoveryOptions.GetMethodDisplayOptionsOrDefault()})");

xharness/src/Microsoft.DotNet.XHarness.TestRunners.Xunit/ThreadlessXunitTestRunner.cs

Line 43 in b2297d6

Console.WriteLine($"Discovered: {assemblyFileName} (found {testCasesToRun.Count} of {discoverySink.TestCases.Count} test cases)");

xharness/src/Microsoft.DotNet.XHarness.TestRunners.Xunit/ThreadlessXunitTestRunner.cs

Lines 57 to 58 in b2297d6

	testSink.Execution.TestAssemblyStartingEvent += args => { Console.WriteLine($"Starting: {assemblyFileName}"); };
	testSink.Execution.TestAssemblyFinishedEvent += args => { Console.WriteLine($"Finished: {assemblyFileName}"); };

[premun]

@fanyang-mono I am not sure you are using this ThreadlessXunitTestRunner in your tests. This one is only used in WASM flows and is a copy of the regular Xunit test runner and the code for that is not in this repo.

I think you should try configuring the test runner you are using (if it has options like that) on your side. If I am correct, you invoke the Helix job via running:

dotnet C:\h\w\A7A008E0\p\xunit\xunit.console.dll

Where the test runner comes from a NuGet?

[fanyang-mono]

@premun This is one of the logs that you could take a look.

https://helixre8s23ayyeko0k025g8.blob.core.windows.net/dotnet-runtime-refs-pull-49662-merge-231998ab087a41dcac/PayloadGroup0/console.d239c696.log?sv=2019-07-07&se=2021-06-13T22%3A51%3A55Z&sr=c&sp=rl&sig=wEFDfBxSxLT2W9ShoHLaz4nlGeIC7JKwVJXreJG5fRE%3D

[premun]

We discussed this offline. Nothing we can do as this is not the test runner in question