System.Data.SqlClient.Tests (eg ExecuteScalarAsyncTest) test break CI with "The server was not found or was not accessible" #22028

stephentoub · 2017-05-30T04:16:45Z

https://ci.dot.net/job/dotnet_corefx/job/release_2.0.0/job/ubuntu14.04_release_prtest/185/consoleText

Exception from RemoteExecutorConsoleApp(System.Data.SqlClient.Tests, Version=4.2.0.0, Culture=neutral, PublicKeyToken=9d77cc7ad39b68eb, System.Data.SqlClient.Tests.DiagnosticTest+<>c, <ExecuteScalarAsyncTest>b__12_0):
  Assembly: System.Data.SqlClient.Tests, Version=4.2.0.0, Culture=neutral, PublicKeyToken=9d77cc7ad39b68eb
  Type: System.Data.SqlClient.Tests.DiagnosticTest+<>c
  Method: Int32 <ExecuteScalarAsyncTest>b__12_0()
  Using as the test runtime folder.
  Running tests... Start time: 01:44:29
  Commands:
  source Dumpling.sh
  chmod +x /mnt/j/workspace/dotnet_corefx/release_2.0.0/ubuntu14.04_release_prtest/bin/testhost/netcoreapp-Linux-Release-x64//dotnet
  /mnt/j/workspace/dotnet_corefx/release_2.0.0/ubuntu14.04_release_prtest/bin/testhost/netcoreapp-Linux-Release-x64//dotnet xunit.console.netcore.exe System.Diagnostics.Tracing.Tests.dll  -xml testResults.xml -notrait Benchmark=true -notrait category=nonnetcoreapptests  -notrait category=IgnoreForCI -notrait category=OuterLoop -notrait category=failing -notrait category=nonlinuxtests
  CollectDumps 0 System.Diagnostics.Tracing.Tests
  /mnt/j/workspace/dotnet_corefx/release_2.0.0/ubuntu14.04_release_prtest/bin/AnyOS.AnyCPU.Release/System.Diagnostics.Tracing.Tests/netcoreapp /mnt/j/workspace/dotnet_corefx/release_2.0.0/ubuntu14.04_release_prtest/src/System.Diagnostics.Tracing/tests
  executing ulimit -c unlimited
  Exception: System.Reflection.TargetInvocationException: Exception has been thrown by the target of an invocation. ---> System.Data.SqlClient.SqlException: A network-related or instance-specific error occurred while establishing a connection to SQL Server. The server was not found or was not accessible. Verify that the instance name is correct and that SQL Server is configured to allow remote connections. (provider: TCP Provider, error: 40 - Could not open a connection to SQL Server)
     at System.Data.SqlClient.SqlInternalConnectionTds..ctor(DbConnectionPoolIdentity identity, SqlConnectionString connectionOptions, Object providerInfo, Boolean redirectedUserInstance, SqlConnectionString userConnectionOptions, SessionData reconnectSessionData, Boolean applyTransientFaultHandling) in /mnt/j/workspace/dotnet_corefx/release_2.0.0/ubuntu14.04_release_prtest/src/System.Data.SqlClient/src/System/Data/SqlClient/SqlInternalConnectionTds.cs:line 373
     at System.Data.SqlClient.SqlConnectionFactory.CreateConnection(DbConnectionOptions options, DbConnectionPoolKey poolKey, Object poolGroupProviderInfo, DbConnectionPool pool, DbConnection owningConnection, DbConnectionOptions userOptions) in /mnt/j/workspace/dotnet_corefx/release_2.0.0/ubuntu14.04_release_prtest/src/System.Data.SqlClient/src/System/Data/SqlClient/SqlConnectionFactory.cs:line 136
     at System.Data.ProviderBase.DbConnectionFactory.CreatePooledConnection(DbConnectionPool pool, DbConnection owningObject, DbConnectionOptions options, DbConnectionPoolKey poolKey, DbConnectionOptions userOptions) in /mnt/j/workspace/dotnet_corefx/release_2.0.0/ubuntu14.04_release_prtest/src/System.Data.SqlClient/src/System/Data/ProviderBase/DbConnectionFactory.cs:line 114
     at System.Data.ProviderBase.DbConnectionPool.CreateObject(DbConnection owningObject, DbConnectionOptions userOptions, DbConnectionInternal oldConnection) in /mnt/j/workspace/dotnet_corefx/release_2.0.0/ubuntu14.04_release_prtest/src/System.Data.SqlClient/src/System/Data/ProviderBase/DbConnectionPool.cs:line 476
     at System.Data.ProviderBase.DbConnectionPool.UserCreateRequest(DbConnection owningObject, DbConnectionOptions userOptions, DbConnectionInternal oldConnection) in /mnt/j/workspace/dotnet_corefx/release_2.0.0/ubuntu14.04_release_prtest/src/System.Data.SqlClient/src/System/Data/ProviderBase/DbConnectionPool.cs:line 1187
     at System.Data.ProviderBase.DbConnectionPool.TryGetConnection(DbConnection owningObject, UInt32 waitForMultipleObjectsTimeout, Boolean allowCreate, Boolean onlyOneCheckConnection, DbConnectionOptions userOptions, DbConnectionInternal& connection) in /mnt/j/workspace/dotnet_corefx/release_2.0.0/ubuntu14.04_release_prtest/src/System.Data.SqlClient/src/System/Data/ProviderBase/DbConnectionPool.cs:line 792
     at System.Data.ProviderBase.DbConnectionPool.TryGetConnection(DbConnection owningObject, TaskCompletionSource`1 retry, DbConnectionOptions userOptions, DbConnectionInternal& connection) in /mnt/j/workspace/dotnet_corefx/release_2.0.0/ubuntu14.04_release_prtest/src/System.Data.SqlClient/src/System/Data/ProviderBase/DbConnectionPool.cs:line 711
     at System.Data.ProviderBase.DbConnectionFactory.TryGetConnection(DbConnection owningConnection, TaskCompletionSource`1 retry, DbConnectionOptions userOptions, DbConnectionInternal oldConnection, DbConnectionInternal& connection) in /mnt/j/workspace/dotnet_corefx/release_2.0.0/ubuntu14.04_release_prtest/src/System.Data.SqlClient/src/System/Data/ProviderBase/DbConnectionFactory.cs:line 281
     at System.Data.ProviderBase.DbConnectionInternal.TryOpenConnectionInternal(DbConnection outerConnection, DbConnectionFactory connectionFactory, TaskCompletionSource`1 retry, DbConnectionOptions userOptions) in /mnt/j/workspace/dotnet_corefx/release_2.0.0/ubuntu14.04_release_prtest/src/System.Data.SqlClient/src/System/Data/ProviderBase/DbConnectionInternal.cs:line 453
     at System.Data.SqlClient.SqlConnection.TryOpen(TaskCompletionSource`1 retry) in /mnt/j/workspace/dotnet_corefx/release_2.0.0/ubuntu14.04_release_prtest/src/System.Data.SqlClient/src/System/Data/SqlClient/SqlConnection.cs:line 1062
     at System.Data.SqlClient.SqlConnection.Open() in /mnt/j/workspace/dotnet_corefx/release_2.0.0/ubuntu14.04_release_prtest/src/System.Data.SqlClient/src/System/Data/SqlClient/SqlConnection.cs:line 598
     at System.Data.SqlClient.Tests.DiagnosticTest.<>c.<<ExecuteScalarAsyncTest>b__12_1>d.MoveNext() in /mnt/j/workspace/dotnet_corefx/release_2.0.0/ubuntu14.04_release_prtest/src/System.Data.SqlClient/tests/FunctionalTests/DiagnosticTest.cs:line 269
  --- End of stack trace from previous location where exception was thrown ---
     at System.Runtime.ExceptionServices.ExceptionDispatchInfo.Throw()
     at System.Runtime.CompilerServices.TaskAwaiter.HandleNonSuccessAndDebuggerNotification(Task task)
     at System.Data.SqlClient.Tests.DiagnosticTest.<CollectStatisticsDiagnosticsAsync>d__25.MoveNext() in /mnt/j/workspace/dotnet_corefx/release_2.0.0/ubuntu14.04_release_prtest/src/System.Data.SqlClient/tests/FunctionalTests/DiagnosticTest.cs:line 893
  --- End of stack trace from previous location where exception was thrown ---
     at System.Runtime.ExceptionServices.ExceptionDispatchInfo.Throw()
     at System.Runtime.CompilerServices.TaskAwaiter.HandleNonSuccessAndDebuggerNotification(Task task)
     at System.Data.SqlClient.Tests.DiagnosticTest.<>c.<ExecuteScalarAsyncTest>b__12_0() in /mnt/j/workspace/dotnet_corefx/release_2.0.0/ubuntu14.04_release_prtest/src/System.Data.SqlClient/tests/FunctionalTests/DiagnosticTest.cs:line 272
  xUnit.net console test runner (64-bit .NET Core)
     --- End of inner exception stack trace ---
  Copyright (C) 2014 Outercurve Foundation.
     at System.RuntimeMethodHandle.InvokeMethod(Object target, Object[] arguments, Signature sig, Boolean constructor)
  
     at System.Reflection.RuntimeMethodInfo.UnsafeInvokeInternal(Object obj, Object[] parameters, Object[] arguments)
     at RemoteExecutorConsoleApp.Program.Main(String[] args) in /mnt/j/workspace/dotnet_corefx/release_2.0.0/ubuntu14.04_release_prtest/src/Common/tests/System/Diagnostics/RemoteExecutorConsoleApp/RemoteExecutorConsoleApp.cs:line 51
  
  Unhandled Exception: System.Reflection.TargetInvocationException: Exception has been thrown by the target of an invocation. ---> System.Data.SqlClient.SqlException: A network-related or instance-specific error occurred while establishing a connection to SQL Server. The server was not found or was not accessible. Verify that the instance name is correct and that SQL Server is configured to allow remote connections. (provider: TCP Provider, error: 40 - Could not open a connection to SQL Server)
     at System.Data.SqlClient.SqlInternalConnectionTds..ctor(DbConnectionPoolIdentity identity, SqlConnectionString connectionOptions, Object providerInfo, Boolean redirectedUserInstance, SqlConnectionString userConnectionOptions, SessionData reconnectSessionData, Boolean applyTransientFaultHandling) in /mnt/j/workspace/dotnet_corefx/release_2.0.0/ubuntu14.04_release_prtest/src/System.Data.SqlClient/src/System/Data/SqlClient/SqlInternalConnectionTds.cs:line 373
     at System.Data.SqlClient.SqlConnectionFactory.CreateConnection(DbConnectionOptions options, DbConnectionPoolKey poolKey, Object poolGroupProviderInfo, DbConnectionPool pool, DbConnection owningConnection, DbConnectionOptions userOptions) in /mnt/j/workspace/dotnet_corefx/release_2.0.0/ubuntu14.04_release_prtest/src/System.Data.SqlClient/src/System/Data/SqlClient/SqlConnectionFactory.cs:line 136
     at System.Data.ProviderBase.DbConnectionFactory.CreatePooledConnection(DbConnectionPool pool, DbConnection owningObject, DbConnectionOptions options, DbConnectionPoolKey poolKey, DbConnectionOptions userOptions) in /mnt/j/workspace/dotnet_corefx/release_2.0.0/ubuntu14.04_release_prtest/src/System.Data.SqlClient/src/System/Data/ProviderBase/DbConnectionFactory.cs:line 114
     at System.Data.ProviderBase.DbConnectionPool.CreateObject(DbConnection owningObject, DbConnectionOptions userOptions, DbConnectionInternal oldConnection) in /mnt/j/workspace/dotnet_corefx/release_2.0.0/ubuntu14.04_release_prtest/src/System.Data.SqlClient/src/System/Data/ProviderBase/DbConnectionPool.cs:line 476
     at System.Data.ProviderBase.DbConnectionPool.UserCreateRequest(DbConnection owningObject, DbConnectionOptions userOptions, DbConnectionInternal oldConnection) in /mnt/j/workspace/dotnet_corefx/release_2.0.0/ubuntu14.04_release_prtest/src/System.Data.SqlClient/src/System/Data/ProviderBase/DbConnectionPool.cs:line 1187
     at System.Data.ProviderBase.DbConnectionPool.TryGetConnection(DbConnection owningObject, UInt32 waitForMultipleObjectsTimeout, Boolean allowCreate, Boolean onlyOneCheckConnection, DbConnectionOptions userOptions, DbConnectionInternal& connection) in /mnt/j/workspace/dotnet_corefx/release_2.0.0/ubuntu14.04_release_prtest/src/System.Data.SqlClient/src/System/Data/ProviderBase/DbConnectionPool.cs:line 792
     at System.Data.ProviderBase.DbConnectionPool.TryGetConnection(DbConnection owningObject, TaskCompletionSource`1 retry, DbConnectionOptions userOptions, DbConnectionInternal& connection) in /mnt/j/workspace/dotnet_corefx/release_2.0.0/ubuntu14.04_release_prtest/src/System.Data.SqlClient/src/System/Data/ProviderBase/DbConnectionPool.cs:line 711
     at System.Data.ProviderBase.DbConnectionFactory.TryGetConnection(DbConnection owningConnection, TaskCompletionSource`1 retry, DbConnectionOptions userOptions, DbConnectionInternal oldConnection, DbConnectionInternal& connection) in /mnt/j/workspace/dotnet_corefx/release_2.0.0/ubuntu14.04_release_prtest/src/System.Data.SqlClient/src/System/Data/ProviderBase/DbConnectionFactory.cs:line 281
     at System.Data.ProviderBase.DbConnectionInternal.TryOpenConnectionInternal(DbConnection outerConnection, DbConnectionFactory connectionFactory, TaskCompletionSource`1 retry, DbConnectionOptions userOptions) in /mnt/j/workspace/dotnet_corefx/release_2.0.0/ubuntu14.04_release_prtest/src/System.Data.SqlClient/src/System/Data/ProviderBase/DbConnectionInternal.cs:line 453
     at System.Data.SqlClient.SqlConnection.TryOpen(TaskCompletionSource`1 retry) in /mnt/j/workspace/dotnet_corefx/release_2.0.0/ubuntu14.04_release_prtest/src/System.Data.SqlClient/src/System/Data/SqlClient/SqlConnection.cs:line 1062
     at System.Data.SqlClient.SqlConnection.Open() in /mnt/j/workspace/dotnet_corefx/release_2.0.0/ubuntu14.04_release_prtest/src/System.Data.SqlClient/src/System/Data/SqlClient/SqlConnection.cs:line 598
     at System.Data.SqlClient.Tests.DiagnosticTest.<>c.<<ExecuteScalarAsyncTest>b__12_1>d.MoveNext() in /mnt/j/workspace/dotnet_corefx/release_2.0.0/ubuntu14.04_release_prtest/src/System.Data.SqlClient/tests/FunctionalTests/DiagnosticTest.cs:line 269
  --- End of stack trace from previous location where exception was thrown ---
     at System.Runtime.ExceptionServices.ExceptionDispatchInfo.Throw()
     at System.Runtime.CompilerServices.TaskAwaiter.HandleNonSuccessAndDebuggerNotification(Task task)
     at System.Data.SqlClient.Tests.DiagnosticTest.<CollectStatisticsDiagnosticsAsync>d__25.MoveNext() in /mnt/j/workspace/dotnet_corefx/release_2.0.0/ubuntu14.04_release_prtest/src/System.Data.SqlClient/tests/FunctionalTests/DiagnosticTest.cs:line 893
  --- End of stack trace from previous location where exception was thrown ---
     at System.Runtime.ExceptionServices.ExceptionDispatchInfo.Throw()
     at System.Runtime.CompilerServices.TaskAwaiter.HandleNonSuccessAndDebuggerNotification(Task task)
     at System.Data.SqlClient.Tests.DiagnosticTest.<>c.<ExecuteScalarAsyncTest>b__12_0() in /mnt/j/workspace/dotnet_corefx/release_2.0.0/ubuntu14.04_release_prtest/src/System.Data.SqlClient/tests/FunctionalTests/DiagnosticTest.cs:line 272
     --- End of inner exception stack trace ---
     at RemoteExecutorConsoleApp.Program.Main(String[] args) in /mnt/j/workspace/dotnet_corefx/release_2.0.0/ubuntu14.04_release_prtest/src/Common/tests/System/Diagnostics/RemoteExecutorConsoleApp/RemoteExecutorConsoleApp.cs:line 67
  Discovering: System.Diagnostics.Tracing.Tests
  Discovered:  System.Diagnostics.Tracing.Tests
  Starting:    System.Diagnostics.Tracing.Tests
     System.Data.SqlClient.Tests.DiagnosticTest.ExecuteScalarAsyncTest [FAIL]
        Assert.Equal() Failure
        Expected: 42
        Actual:   134

The text was updated successfully, but these errors were encountered:

danmoseley · 2017-06-06T21:55:10Z

@saurabh500 @corivera as discussed this is the one that needs logging added. It keeps getting hit in CI - note some of the hits are tracked in https://github.com/dotnet/corefx/issues/17925 (although that may cover other issuses.. logging should untangle all this)

saurabh500 · 2017-06-06T23:53:44Z

@danmosemsft ack. Looking into the logging.

danmoseley · 2017-06-07T23:11:20Z

https://mc.dot.net/#/user/JeremyKuhne/pr~2Fjenkins~2Fdotnet~2Fcorefx~2Fmaster~2F/test~2Ffunctional~2Fcli~2F/5ec45c34fc6a1d9b9ec03d8bd737678a62221347/workItem/System.Data.SqlClient.Tests/wilogs

danmoseley · 2017-06-07T23:13:23Z

Here's another test failure showing a server connection failure .. hopefully the logging will help all tests that hit this.

MESSAGE:
System.Data.SqlClient.SqlException : A network-related or instance-specific error occurred while establishing a connection to SQL Server. The server was not found or was not accessible. Verify that the instance name is correct and that SQL Server is configured to allow remote connections. (provider: TCP Provider, error: 40 - Could not open a connection to SQL Server)
+++++++++++++++++++
STACK TRACE:
at System.Data.SqlClient.SqlInternalConnectionTds..ctor(DbConnectionPoolIdentity identity, SqlConnectionString connectionOptions, Object providerInfo, Boolean redirectedUserInstance, SqlConnectionString userConnectionOptions, SessionData reconnectSessionData, Boolean applyTransientFaultHandling) in /Users/dotnet-

System.Data.SqlClient.Tests.SqlConnectionBasicTests.ConnectionTest

https://ci.dot.net/job/dotnet_corefx/job/master/job/osx10.12_release_prtest/4316/testReport/junit/System.Data.SqlClient.Tests/SqlConnectionBasicTests/ConnectionTest/

danmoseley · 2017-06-08T03:46:31Z

@saurabh500 here's a hit with your new logging

2017-06-08 00:53:35,287: INFO: proc(54): run_and_log_output: Output: Test: ExecuteScalarAsyncErrorTest Listeners Disposed Successfully
2017-06-08 00:53:35,320: INFO: proc(54): run_and_log_output: Output:    System.Data.SqlClient.Tests.DiagnosticTest.ExecuteXmlReaderAsyncErrorTest [SKIP]
2017-06-08 00:53:35,320: INFO: proc(54): run_and_log_output: Output:       Condition(s) not met: \"IsConnectionStringConfigured\"
2017-06-08 00:53:35,403: INFO: proc(54): run_and_log_output: Output: Test: ExecuteScalarErrorTest Enabled Listeners
2017-06-08 00:53:35,605: INFO: proc(54): run_and_log_output: Output: Test: ExecuteScalarErrorTest Started Server
2017-06-08 00:53:40,763: INFO: proc(54): run_and_log_output: Output: Exception from RemoteExecutorConsoleApp(System.Data.SqlClient.Tests, Version=4.3.0.0, Culture=neutral, PublicKeyToken=9d77cc7ad39b68eb, System.Data.SqlClient.Tests.DiagnosticTest+<>c, <ExecuteScalarErrorTest>b__4_0):
2017-06-08 00:53:40,763: INFO: proc(54): run_and_log_output: Output: Assembly: System.Data.SqlClient.Tests, Version=4.3.0.0, Culture=neutral, PublicKeyToken=9d77cc7ad39b68eb
2017-06-08 00:53:40,763: INFO: proc(54): run_and_log_output: Output: Type: System.Data.SqlClient.Tests.DiagnosticTest+<>c
2017-06-08 00:53:40,764: INFO: proc(54): run_and_log_output: Output: Method: Int32 <ExecuteScalarErrorTest>b__4_0()
2017-06-08 00:53:40,890: INFO: proc(54): run_and_log_output: Output: Exception: System.Reflection.TargetInvocationException: Exception has been thrown by the target of an invocation. ---> System.Data.SqlClient.SqlException: A network-related or instance-specific error occurred while establishing a connection to SQL Server. The server was not found or was not accessible. Verify that the instance name is correct and that SQL Server is configured to allow remote connections. (provider: TCP Provider, error: 40 - Could not open a connection to SQL Server)
2017-06-08 00:53:40,890: INFO: proc(54): run_and_log_output: Output:    at System.Data.SqlClient.SqlInternalConnectionTds..ctor(DbConnectionPoolIdentity identity, SqlConnectionString connectionOptions, Object providerInfo, Boolean redirectedUserInstance, SqlConnectionString userConnectionOptions, SessionData reconnectSessionData, Boolean applyTransientFaultHandling) in /mnt/j/workspace/dotnet_corefx/master/portable-linux-Config_Release+OuterLoop_false_prtest/src/System.Data.SqlClient/src/System/Data/SqlClient/SqlInternalConnectionTds.cs:line 373
2017-06-08 00:53:40,891: INFO: proc(54): run_and_log_output: Output:    at System.Data.SqlClient.SqlConnectionFactory.CreateConnection(DbConnectionOptions options, DbConnectionPoolKey poolKey, Object poolGroupProviderInfo, DbConnectionPool pool, DbConnection owningConnection, DbConnectionOptions userOptions) in /mnt/j/workspace/dotnet_corefx/master/portable-linux-Config_Release+OuterLoop_false_prtest/src/System.Data.SqlClient/src/System/Data/SqlClient/SqlConnectionFactory.cs:line 136
2017-06-08 00:53:40,891: INFO: proc(54): run_and_log_output: Output:    at System.Data.ProviderBase.DbConnectionFactory.CreatePooledConnection(DbConnectionPool pool, DbConnection owningObject, DbConnectionOptions options, DbConnectionPoolKey poolKey, DbConnectionOptions userOptions) in /mnt/j/workspace/dotnet_corefx/master/portable-linux-Config_Release+OuterLoop_false_prtest/src/System.Data.SqlClient/src/System/Data/ProviderBase/DbConnectionFactory.cs:line 114
2017-06-08 00:53:40,891: INFO: proc(54): run_and_log_output: Output:    at System.Data.ProviderBase.DbConnectionPool.CreateObject(DbConnection owningObject, DbConnectionOptions userOptions, DbConnectionInternal oldConnection) in /mnt/j/workspace/dotnet_corefx/master/portable-linux-Config_Release+OuterLoop_false_prtest/src/System.Data.SqlClient/src/System/Data/ProviderBase/DbConnectionPool.cs:line 476
2017-06-08 00:53:40,891: INFO: proc(54): run_and_log_output: Output:    at System.Data.ProviderBase.DbConnectionPool.UserCreateRequest(DbConnection owningObject, DbConnectionOptions userOptions, DbConnectionInternal oldConnection) in /mnt/j/workspace/dotnet_corefx/master/portable-linux-Config_Release+OuterLoop_false_prtest/src/System.Data.SqlClient/src/System/Data/ProviderBase/DbConnectionPool.cs:line 1187
2017-06-08 00:53:40,891: INFO: proc(54): run_and_log_output: Output:    at System.Data.ProviderBase.DbConnectionPool.TryGetConnection(DbConnection owningObject, UInt32 waitForMultipleObjectsTimeout, Boolean allowCreate, Boolean onlyOneCheckConnection, DbConnectionOptions userOptions, DbConnectionInternal& connection) in /mnt/j/workspace/dotnet_corefx/master/portable-linux-Config_Release+OuterLoop_false_prtest/src/System.Data.SqlClient/src/System/Data/ProviderBase/DbConnectionPool.cs:line 792
2017-06-08 00:53:40,891: INFO: proc(54): run_and_log_output: Output:    at System.Data.ProviderBase.DbConnectionPool.TryGetConnection(DbConnection owningObject, TaskCompletionSource`1 retry, DbConnectionOptions userOptions, DbConnectionInternal& connection) in /mnt/j/workspace/dotnet_corefx/master/portable-linux-Config_Release+OuterLoop_false_prtest/src/System.Data.SqlClient/src/System/Data/ProviderBase/DbConnectionPool.cs:line 711
2017-06-08 00:53:40,891: INFO: proc(54): run_and_log_output: Output:    at System.Data.ProviderBase.DbConnectionFactory.TryGetConnection(DbConnection owningConnection, TaskCompletionSource`1 retry, DbConnectionOptions userOptions, DbConnectionInternal oldConnection, DbConnectionInternal& connection) in /mnt/j/workspace/dotnet_corefx/master/portable-linux-Config_Release+OuterLoop_false_prtest/src/System.Data.SqlClient/src/System/Data/ProviderBase/DbConnectionFactory.cs:line 281
2017-06-08 00:53:40,891: INFO: proc(54): run_and_log_output: Output:    at System.Data.ProviderBase.DbConnectionInternal.TryOpenConnectionInternal(DbConnection outerConnection, DbConnectionFactory connectionFactory, TaskCompletionSource`1 retry, DbConnectionOptions userOptions) in /mnt/j/workspace/dotnet_corefx/master/portable-linux-Config_Release+OuterLoop_false_prtest/src/System.Data.SqlClient/src/System/Data/ProviderBase/DbConnectionInternal.cs:line 453
2017-06-08 00:53:40,892: INFO: proc(54): run_and_log_output: Output:    at System.Data.SqlClient.SqlConnection.TryOpen(TaskCompletionSource`1 retry) in /mnt/j/workspace/dotnet_corefx/master/portable-linux-Config_Release+OuterLoop_false_prtest/src/System.Data.SqlClient/src/System/Data/SqlClient/SqlConnection.cs:line 1062
2017-06-08 00:53:40,892: INFO: proc(54): run_and_log_output: Output:    at System.Data.SqlClient.SqlConnection.Open() in /mnt/j/workspace/dotnet_corefx/master/portable-linux-Config_Release+OuterLoop_false_prtest/src/System.Data.SqlClient/src/System/Data/SqlClient/SqlConnection.cs:line 598
2017-06-08 00:53:40,892: INFO: proc(54): run_and_log_output: Output:    at System.Data.SqlClient.Tests.DiagnosticTest.<>c.<ExecuteScalarErrorTest>b__4_1(String connectionString) in /mnt/j/workspace/dotnet_corefx/master/portable-linux-Config_Release+OuterLoop_false_prtest/src/System.Data.SqlClient/tests/FunctionalTests/DiagnosticTest.cs:line 64
2017-06-08 00:53:40,892: INFO: proc(54): run_and_log_output: Output:    at System.Data.SqlClient.Tests.DiagnosticTest.CollectStatisticsDiagnostics(Action`1 sqlOperation, Boolean enableServerLogging, String methodName) in /mnt/j/workspace/dotnet_corefx/master/portable-linux-Config_Release+OuterLoop_false_prtest/src/System.Data.SqlClient/tests/FunctionalTests/DiagnosticTest.cs:line 705
2017-06-08 00:53:40,892: INFO: proc(54): run_and_log_output: Output:    at System.Data.SqlClient.Tests.DiagnosticTest.<>c.<ExecuteScalarErrorTest>b__4_0() in /mnt/j/workspace/dotnet_corefx/master/portable-linux-Config_Release+OuterLoop_false_prtest/src/System.Data.SqlClient/tests/FunctionalTests/DiagnosticTest.cs:line 70
2017-06-08 00:53:40,892: INFO: proc(54): run_and_log_output: Output:    --- End of inner exception stack trace ---
2017-06-08 00:53:40,892: INFO: proc(54): run_and_log_output: Output:    at System.RuntimeMethodHandle.InvokeMethod(Object target, Object[] arguments, Signature sig, Boolean constructor)
2017-06-08 00:53:40,892: INFO: proc(54): run_and_log_output: Output:    at System.Reflection.RuntimeMethodInfo.UnsafeInvokeInternal(Object obj, Object[] parameters, Object[] arguments)
2017-06-08 00:53:40,892: INFO: proc(54): run_and_log_output: Output:    at RemoteExecutorConsoleApp.Program.Main(String[] args) in /mnt/j/workspace/dotnet_corefx/master/portable-linux-Config_Release+OuterLoop_false_prtest/src/Common/tests/System/Diagnostics/RemoteExecutorConsoleApp/RemoteExecutorConsoleApp.cs:line 51
2017-06-08 00:53:40,892: INFO: proc(54): run_and_log_output: Output: 
2017-06-08 00:53:40,892: INFO: proc(54): run_and_log_output: Output: Unhandled Exception: System.Reflection.TargetInvocationException: Exception has been thrown by the target of an invocation. ---> System.Data.SqlClient.SqlException: A network-related or instance-specific error occurred while establishing a connection to SQL Server. The server was not found or was not accessible. Verify that the instance name is correct and that SQL Server is configured to allow remote connections. (provider: TCP Provider, error: 40 - Could not open a connection to SQL Server)
2017-06-08 00:53:40,893: INFO: proc(54): run_and_log_output: Output:    at System.Data.SqlClient.SqlInternalConnectionTds..ctor(DbConnectionPoolIdentity identity, SqlConnectionString connectionOptions, Object providerInfo, Boolean redirectedUserInstance, SqlConnectionString userConnectionOptions, SessionData reconnectSessionData, Boolean applyTransientFaultHandling) in /mnt/j/workspace/dotnet_corefx/master/portable-linux-Config_Release+OuterLoop_false_prtest/src/System.Data.SqlClient/src/System/Data/SqlClient/SqlInternalConnectionTds.cs:line 373
2017-06-08 00:53:40,893: INFO: proc(54): run_and_log_output: Output:    at System.Data.SqlClient.SqlConnectionFactory.CreateConnection(DbConnectionOptions options, DbConnectionPoolKey poolKey, Object poolGroupProviderInfo, DbConnectionPool pool, DbConnection owningConnection, DbConnectionOptions userOptions) in /mnt/j/workspace/dotnet_corefx/master/portable-linux-Config_Release+OuterLoop_false_prtest/src/System.Data.SqlClient/src/System/Data/SqlClient/SqlConnectionFactory.cs:line 136
2017-06-08 00:53:40,893: INFO: proc(54): run_and_log_output: Output:    at System.Data.ProviderBase.DbConnectionFactory.CreatePooledConnection(DbConnectionPool pool, DbConnection owningObject, DbConnectionOptions options, DbConnectionPoolKey poolKey, DbConnectionOptions userOptions) in /mnt/j/workspace/dotnet_corefx/master/portable-linux-Config_Release+OuterLoop_false_prtest/src/System.Data.SqlClient/src/System/Data/ProviderBase/DbConnectionFactory.cs:line 114
2017-06-08 00:53:40,893: INFO: proc(54): run_and_log_output: Output:    at System.Data.ProviderBase.DbConnectionPool.CreateObject(DbConnection owningObject, DbConnectionOptions userOptions, DbConnectionInternal oldConnection) in /mnt/j/workspace/dotnet_corefx/master/portable-linux-Config_Release+OuterLoop_false_prtest/src/System.Data.SqlClient/src/System/Data/ProviderBase/DbConnectionPool.cs:line 476
2017-06-08 00:53:40,893: INFO: proc(54): run_and_log_output: Output:    at System.Data.ProviderBase.DbConnectionPool.UserCreateRequest(DbConnection owningObject, DbConnectionOptions userOptions, DbConnectionInternal oldConnection) in /mnt/j/workspace/dotnet_corefx/master/portable-linux-Config_Release+OuterLoop_false_prtest/src/System.Data.SqlClient/src/System/Data/ProviderBase/DbConnectionPool.cs:line 1187
2017-06-08 00:53:40,893: INFO: proc(54): run_and_log_output: Output:    at System.Data.ProviderBase.DbConnectionPool.TryGetConnection(DbConnection owningObject, UInt32 waitForMultipleObjectsTimeout, Boolean allowCreate, Boolean onlyOneCheckConnection, DbConnectionOptions userOptions, DbConnectionInternal& connection) in /mnt/j/workspace/dotnet_corefx/master/portable-linux-Config_Release+OuterLoop_false_prtest/src/System.Data.SqlClient/src/System/Data/ProviderBase/DbConnectionPool.cs:line 792
2017-06-08 00:53:40,893: INFO: proc(54): run_and_log_output: Output:    at System.Data.ProviderBase.DbConnectionPool.TryGetConnection(DbConnection owningObject, TaskCompletionSource`1 retry, DbConnectionOptions userOptions, DbConnectionInternal& connection) in /mnt/j/workspace/dotnet_corefx/master/portable-linux-Config_Release+OuterLoop_false_prtest/src/System.Data.SqlClient/src/System/Data/ProviderBase/DbConnectionPool.cs:line 711
2017-06-08 00:53:40,894: INFO: proc(54): run_and_log_output: Output:    at System.Data.ProviderBase.DbConnectionFactory.TryGetConnection(DbConnection owningConnection, TaskCompletionSource`1 retry, DbConnectionOptions userOptions, DbConnectionInternal oldConnection, DbConnectionInternal& connection) in /mnt/j/workspace/dotnet_corefx/master/portable-linux-Config_Release+OuterLoop_false_prtest/src/System.Data.SqlClient/src/System/Data/ProviderBase/DbConnectionFactory.cs:line 281
2017-06-08 00:53:40,894: INFO: proc(54): run_and_log_output: Output:    at System.Data.ProviderBase.DbConnectionInternal.TryOpenConnectionInternal(DbConnection outerConnection, DbConnectionFactory connectionFactory, TaskCompletionSource`1 retry, DbConnectionOptions userOptions) in /mnt/j/workspace/dotnet_corefx/master/portable-linux-Config_Release+OuterLoop_false_prtest/src/System.Data.SqlClient/src/System/Data/ProviderBase/DbConnectionInternal.cs:line 453
2017-06-08 00:53:40,894: INFO: proc(54): run_and_log_output: Output:    at System.Data.SqlClient.SqlConnection.TryOpen(TaskCompletionSource`1 retry) in /mnt/j/workspace/dotnet_corefx/master/portable-linux-Config_Release+OuterLoop_false_prtest/src/System.Data.SqlClient/src/System/Data/SqlClient/SqlConnection.cs:line 1062
2017-06-08 00:53:40,894: INFO: proc(54): run_and_log_output: Output:    at System.Data.SqlClient.SqlConnection.Open() in /mnt/j/workspace/dotnet_corefx/master/portable-linux-Config_Release+OuterLoop_false_prtest/src/System.Data.SqlClient/src/System/Data/SqlClient/SqlConnection.cs:line 598
2017-06-08 00:53:40,894: INFO: proc(54): run_and_log_output: Output:    at System.Data.SqlClient.Tests.DiagnosticTest.<>c.<ExecuteScalarErrorTest>b__4_1(String connectionString) in /mnt/j/workspace/dotnet_corefx/master/portable-linux-Config_Release+OuterLoop_false_prtest/src/System.Data.SqlClient/tests/FunctionalTests/DiagnosticTest.cs:line 64
2017-06-08 00:53:40,894: INFO: proc(54): run_and_log_output: Output:    at System.Data.SqlClient.Tests.DiagnosticTest.CollectStatisticsDiagnostics(Action`1 sqlOperation, Boolean enableServerLogging, String methodName) in /mnt/j/workspace/dotnet_corefx/master/portable-linux-Config_Release+OuterLoop_false_prtest/src/System.Data.SqlClient/tests/FunctionalTests/DiagnosticTest.cs:line 705
2017-06-08 00:53:40,894: INFO: proc(54): run_and_log_output: Output:    at System.Data.SqlClient.Tests.DiagnosticTest.<>c.<ExecuteScalarErrorTest>b__4_0() in /mnt/j/workspace/dotnet_corefx/master/portable-linux-Config_Release+OuterLoop_false_prtest/src/System.Data.SqlClient/tests/FunctionalTests/DiagnosticTest.cs:line 70
2017-06-08 00:53:40,894: INFO: proc(54): run_and_log_output: Output:    --- End of inner exception stack trace ---
2017-06-08 00:53:40,894: INFO: proc(54): run_and_log_output: Output:    at RemoteExecutorConsoleApp.Program.Main(String[] args) in /mnt/j/workspace/dotnet_corefx/master/portable-linux-Config_Release+OuterLoop_false_prtest/src/Common/tests/System/Diagnostics/RemoteExecutorConsoleApp/RemoteExecutorConsoleApp.cs:line 67
2017-06-08 00:53:42,295: INFO: proc(54): run_and_log_output: Output:    System.Data.SqlClient.Tests.DiagnosticTest.ExecuteScalarErrorTest [FAIL]
2017-06-08 00:53:42,300: INFO: proc(54): run_and_log_output: Output:       Assert.Equal() Failure
2017-06-08 00:53:42,300: INFO: proc(54): run_and_log_output: Output:       Expected: 42
2017-06-08 00:53:42,300: INFO: proc(54): run_and_log_output: Output:       Actual:   134
2017-06-08 00:53:42,302: INFO: proc(54): run_and_log_output: Output:       Stack Trace:
2017-06-08 00:53:42,313: INFO: proc(54): run_and_log_output: Output:          /mnt/j/workspace/dotnet_corefx/master/portable-linux-Config_Release+OuterLoop_false_prtest/src/Common/tests/System/Diagnostics/RemoteExecutorTestBase.cs(273,0): at System.Diagnostics.RemoteExecutorTestBase.RemoteInvokeHandle.Dispose()
2017-06-08 00:53:42,395: INFO: proc(54): run_and_log_output: Output: Test: ConnectionOpenTest Enabled Listeners
2017-06-08 00:53:42,578: INFO: proc(54): run_and_log_output: Output: Test: ConnectionOpenTest Started Server
2017-06-08 00:53:42,839: INFO: proc(54): run_and_log_output: Output: [6/8/17 12:53:42 AM] Request.TDSPreLoginToken.Version: 4.6.25408
2017-06-08 00:53:42,840: INFO: proc(54): run_and_log_output: Output: [6/8/17 12:53:42 AM] Request.TDSPreLoginToken.SubBuild: 0
2017-06-08 00:53:42,840: INFO: proc(54): run_and_log_output: Output: [6/8/17 12:53:42 AM] Request.TDSPreLoginToken.Encryption: Off
2017-06-08 00:53:42,840: INFO: proc(54): run_and_log_output: Output: [6/8/17 12:53:42 AM] Request.TDSPreLoginToken.ThreadID: 33554432
2017-06-08 00:53:42,840: INFO: proc(54): run_and_log_output: Output: [6/8/17 12:53:42 AM] Request.TDSPreLoginToken.IsMARS: False
2017-06-08 00:53:42,841: INFO: proc(54): run_and_log_output: Output: [6/8/17 12:53:42 AM] Request.TDSPreLoginToken.ClientTraceID: [53 2F 1D E4 93 93 ED 4E BE F3 A4 C4 6E 14 7B F5 ]
2017-06-08 00:53:42,842: INFO: proc(54): run_and_log_output: Output: [6/8/17 12:53:42 AM] Request.TDSPreLoginToken.ActivityID: [FC 6D 9C 3E EB E8 B5 49 8F 20 44 6B 62 BD 5D E7 1 0 0 0 ]
2017-06-08 00:53:42,842: INFO: proc(54): run_and_log_output: Output: [6/8/17 12:53:42 AM] Request.TDSPreLoginToken.Nonce: <null>
2017-06-08 00:53:42,842: INFO: proc(54): run_and_log_output: Output: [6/8/17 12:53:42 AM] Request.TDSPreLoginToken.FedAuthRequired: FedAuthNotRequired
2017-06-08 00:53:42,843: INFO: proc(54): run_and_log_output: Output: [6/8/17 12:53:42 AM] Response.TDSPreLoginToken.Version: 11.0.1083
2017-06-08 00:53:42,843: INFO: proc(54): run_and_log_output: Output: [6/8/17 12:53:42 AM] Response.TDSPreLoginToken.SubBuild: 0
2017-06-08 00:53:42,843: INFO: proc(54): run_and_log_output: Output: [6/8/17 12:53:42 AM] Response.TDSPreLoginToken.Encryption: NotSupported
2017-06-08 00:53:42,843: INFO: proc(54): run_and_log_output: Output: [6/8/17 12:53:42 AM] Response.TDSPreLoginToken.ThreadID: 0
2017-06-08 00:53:42,844: INFO: proc(54): run_and_log_output: Output: [6/8/17 12:53:42 AM] Response.TDSPreLoginToken.IsMARS: False
2017-06-08 00:53:42,844: INFO: proc(54): run_and_log_output: Output: [6/8/17 12:53:42 AM] Response.TDSPreLoginToken.ClientTraceID: <null>
2017-06-08 00:53:42,844: INFO: proc(54): run_and_log_output: Output: [6/8/17 12:53:42 AM] Response.TDSPreLoginToken.ActivityID: <null>
2017-06-08 00:53:42,844: INFO: proc(54): run_and_log_output: Output: [6/8/17 12:53:42 AM] Response.TDSPreLoginToken.Nonce: <null>
2017-06-08 00:53:42,844: INFO: proc(54): run_and_log_output: Output: [6/8/17 12:53:42 AM] Response.TDSPreLoginToken.FedAuthRequired: FedAuthNotRequired
2017-06-08 00:53:42,892: INFO: proc(54): run_and_log_output: Output: [6/8/17 12:53:42 AM] Request.TDSLogin7Token.TDSVersion: 7.4.0.4
2017-06-08 00:53:42,892: INFO: proc(54): run_and_log_output: Output: [6/8/17 12:53:42 AM] Request.TDSLogin7Token.PacketSize: 8000
2017-06-08 00:53:42,892: INFO: proc(54): run_and_log_output: Output: [6/8/17 12:53:42 AM] Request.TDSLogin7Token.ClientProgramVersion: 100663296
2017-06-08 00:53:42,893: INFO: proc(54): run_and_log_output: Output: [6/8/17 12:53:42 AM] Request.TDSLogin7Token.ClientPID: 98073343
2017-06-08 00:53:42,893: INFO: proc(54): run_and_log_output: Output: [6/8/17 12:53:42 AM] Request.TDSLogin7Token.ConnectionID: 0
2017-06-08 00:53:42,893: INFO: proc(54): run_and_log_output: Output: [6/8/17 12:53:42 AM] Request.TDSLogin7Token.OptionalFlags1.TDSLogin7TokenOptionalFlags1.Order: OrderX86
2017-06-08 00:53:42,893: INFO: proc(54): run_and_log_output: Output: [6/8/17 12:53:42 AM] Request.TDSLogin7Token.OptionalFlags1.TDSLogin7TokenOptionalFlags1.CharacterSet: Ascii
2017-06-08 00:53:42,893: INFO: proc(54): run_and_log_output: Output: [6/8/17 12:53:42 AM] Request.TDSLogin7Token.OptionalFlags1.TDSLogin7TokenOptionalFlags1.FloatingPoint: IEEE754
2017-06-08 00:53:42,894: INFO: proc(54): run_and_log_output: Output: [6/8/17 12:53:42 AM] Request.TDSLogin7Token.OptionalFlags1.TDSLogin7TokenOptionalFlags1.DumpLoad: On
2017-06-08 00:53:42,894: INFO: proc(54): run_and_log_output: Output: [6/8/17 12:53:42 AM] Request.TDSLogin7Token.OptionalFlags1.TDSLogin7TokenOptionalFlags1.UseDB: Off
2017-06-08 00:53:42,894: INFO: proc(54): run_and_log_output: Output: [6/8/17 12:53:42 AM] Request.TDSLogin7Token.OptionalFlags1.TDSLogin7TokenOptionalFlags1.Database: Fatal
2017-06-08 00:53:42,894: INFO: proc(54): run_and_log_output: Output: [6/8/17 12:53:42 AM] Request.TDSLogin7Token.OptionalFlags1.TDSLogin7TokenOptionalFlags1.Language: On
2017-06-08 00:53:42,895: INFO: proc(54): run_and_log_output: Output: [6/8/17 12:53:42 AM] Request.TDSLogin7Token.OptionalFlags2.TDSLogin7TokenOptionalFlags2.Language: Fatal
2017-06-08 00:53:42,895: INFO: proc(54): run_and_log_output: Output: [6/8/17 12:53:42 AM] Request.TDSLogin7Token.OptionalFlags2.TDSLogin7TokenOptionalFlags2.Odbc: On
2017-06-08 00:53:42,895: INFO: proc(54): run_and_log_output: Output: [6/8/17 12:53:42 AM] Request.TDSLogin7Token.OptionalFlags2.TDSLogin7TokenOptionalFlags2.UserType: Normal
2017-06-08 00:53:42,895: INFO: proc(54): run_and_log_output: Output: [6/8/17 12:53:42 AM] Request.TDSLogin7Token.OptionalFlags2.TDSLogin7TokenOptionalFlags2.IntegratedSecurity: Off
2017-06-08 00:53:42,896: INFO: proc(54): run_and_log_output: Output: [6/8/17 12:53:42 AM] Request.TDSLogin7Token.OptionalFlags3.TDSLogin7TokenOptionalFlags3.ChangePassword: No
2017-06-08 00:53:42,896: INFO: proc(54): run_and_log_output: Output: [6/8/17 12:53:42 AM] Request.TDSLogin7Token.OptionalFlags3.TDSLogin7TokenOptionalFlags3.IsUserInstance: False
2017-06-08 00:53:42,896: INFO: proc(54): run_and_log_output: Output: [6/8/17 12:53:42 AM] Request.TDSLogin7Token.OptionalFlags3.TDSLogin7TokenOptionalFlags3.SendYukonBinaryXML: False
2017-06-08 00:53:42,896: INFO: proc(54): run_and_log_output: Output: [6/8/17 12:53:42 AM] Request.TDSLogin7Token.OptionalFlags3.TDSLogin7TokenOptionalFlags3.UnknownCollation: Must
2017-06-08 00:53:42,897: INFO: proc(54): run_and_log_output: Output: [6/8/17 12:53:42 AM] Request.TDSLogin7Token.OptionalFlags3.TDSLogin7TokenOptionalFlags3.ExtensionFlag: True
2017-06-08 00:53:42,897: INFO: proc(54): run_and_log_output: Output: [6/8/17 12:53:42 AM] Request.TDSLogin7Token.TypeFlags.TDSLogin7TokenTypeFlags.SQL: Default
2017-06-08 00:53:42,897: INFO: proc(54): run_and_log_output: Output: [6/8/17 12:53:42 AM] Request.TDSLogin7Token.TypeFlags.TDSLogin7TokenTypeFlags.OleDb: Off
2017-06-08 00:53:42,897: INFO: proc(54): run_and_log_output: Output: [6/8/17 12:53:42 AM] Request.TDSLogin7Token.TypeFlags.TDSLogin7TokenTypeFlags.ReadOnlyIntent: ReadWrite
2017-06-08 00:53:42,898: INFO: proc(54): run_and_log_output: Output: [6/8/17 12:53:42 AM] Request.TDSLogin7Token.ClientTimeZone: 0
2017-06-08 00:53:42,898: INFO: proc(54): run_and_log_output: Output: [6/8/17 12:53:42 AM] Request.TDSLogin7Token.ClientLCID: 0
2017-06-08 00:53:42,898: INFO: proc(54): run_and_log_output: Output: [6/8/17 12:53:42 AM] Request.TDSLogin7Token.HostName: dnblup1400007HR
2017-06-08 00:53:42,898: INFO: proc(54): run_and_log_output: Output: [6/8/17 12:53:42 AM] Request.TDSLogin7Token.UserID: <null>
2017-06-08 00:53:42,898: INFO: proc(54): run_and_log_output: Output: [6/8/17 12:53:42 AM] Request.TDSLogin7Token.Password: <null>
2017-06-08 00:53:42,899: INFO: proc(54): run_and_log_output: Output: [6/8/17 12:53:42 AM] Request.TDSLogin7Token.ApplicationName: Core .Net SqlClient Data Provider
2017-06-08 00:53:42,899: INFO: proc(54): run_and_log_output: Output: [6/8/17 12:53:42 AM] Request.TDSLogin7Token.ServerName: localhost,34271
2017-06-08 00:53:42,899: INFO: proc(54): run_and_log_output: Output: [6/8/17 12:53:42 AM] Request.TDSLogin7Token.LibraryName: Core .Net SqlClient Data Provider
2017-06-08 00:53:42,899: INFO: proc(54): run_and_log_output: Output: [6/8/17 12:53:42 AM] Request.TDSLogin7Token.Language: <null>
2017-06-08 00:53:42,900: INFO: proc(54): run_and_log_output: Output: [6/8/17 12:53:42 AM] Request.TDSLogin7Token.Database: <null>
2017-06-08 00:53:42,900: INFO: proc(54): run_and_log_output: Output: [6/8/17 12:53:42 AM] Request.TDSLogin7Token.ClientID: [34 16 47 A4 D1 2D ]
2017-06-08 00:53:42,900: INFO: proc(54): run_and_log_output: Output: [6/8/17 12:53:42 AM] Request.TDSLogin7Token.AttachDatabaseFile: <null>
2017-06-08 00:53:42,900: INFO: proc(54): run_and_log_output: Output: [6/8/17 12:53:42 AM] Request.TDSLogin7Token.ChangePassword: <null>
2017-06-08 00:53:42,900: INFO: proc(54): run_and_log_output: Output: [6/8/17 12:53:42 AM] Request.TDSLogin7Token.SSPI: <null>
2017-06-08 00:53:42,905: INFO: proc(54): run_and_log_output: Output: [6/8/17 12:53:42 AM] Request.TDSLogin7Token.FeatureExt[0].TDSLogin7SessionRecoveryOptionToken.FeatureID: SessionRecovery
2017-06-08 00:53:42,905: INFO: proc(54): run_and_log_output: Output: [6/8/17 12:53:42 AM] Request.TDSLogin7Token.FeatureExt[0].TDSLogin7SessionRecoveryOptionToken.Initial: <null>
2017-06-08 00:53:42,905: INFO: proc(54): run_and_log_output: Output: [6/8/17 12:53:42 AM] Request.TDSLogin7Token.FeatureExt[0].TDSLogin7SessionRecoveryOptionToken.Current: <null>
2017-06-08 00:53:42,912: INFO: proc(54): run_and_log_output: Output: [6/8/17 12:53:42 AM] Response.TDSEnvChangeToken.Type: Database
2017-06-08 00:53:42,912: INFO: proc(54): run_and_log_output: Output: [6/8/17 12:53:42 AM] Response.TDSEnvChangeToken.OldValue: master
2017-06-08 00:53:42,912: INFO: proc(54): run_and_log_output: Output: [6/8/17 12:53:42 AM] Response.TDSEnvChangeToken.NewValue: master
2017-06-08 00:53:42,912: INFO: proc(54): run_and_log_output: Output: [6/8/17 12:53:42 AM] Response.TDSInfoToken.Number: 5701
2017-06-08 00:53:42,912: INFO: proc(54): run_and_log_output: Output: [6/8/17 12:53:42 AM] Response.TDSInfoToken.State: 2
2017-06-08 00:53:42,912: INFO: proc(54): run_and_log_output: Output: [6/8/17 12:53:42 AM] Response.TDSInfoToken.Class: 0
2017-06-08 00:53:42,912: INFO: proc(54): run_and_log_output: Output: [6/8/17 12:53:42 AM] Response.TDSInfoToken.Message: Changed database context to 'master'
2017-06-08 00:53:42,913: INFO: proc(54): run_and_log_output: Output: [6/8/17 12:53:42 AM] Response.TDSInfoToken.ServerName: dnblup1400007HR
2017-06-08 00:53:42,913: INFO: proc(54): run_and_log_output: Output: [6/8/17 12:53:42 AM] Response.TDSInfoToken.ProcedureName: <null>
2017-06-08 00:53:42,913: INFO: proc(54): run_and_log_output: Output: [6/8/17 12:53:42 AM] Response.TDSInfoToken.Line: 0
2017-06-08 00:53:42,913: INFO: proc(54): run_and_log_output: Output: [6/8/17 12:53:42 AM] Response.TDSEnvChangeToken.Type: SQLCollation
2017-06-08 00:53:42,913: INFO: proc(54): run_and_log_output: Output: [6/8/17 12:53:42 AM] Response.TDSEnvChangeToken.OldValue: <null>
2017-06-08 00:53:42,913: INFO: proc(54): run_and_log_output: Output: [6/8/17 12:53:42 AM] Response.TDSEnvChangeToken.NewValue: [9 4 D0 0 34 ]
2017-06-08 00:53:42,914: INFO: proc(54): run_and_log_output: Output: [6/8/17 12:53:42 AM] Response.TDSEnvChangeToken.Type: Language
2017-06-08 00:53:42,914: INFO: proc(54): run_and_log_output: Output: [6/8/17 12:53:42 AM] Response.TDSEnvChangeToken.OldValue: <null>
2017-06-08 00:53:42,914: INFO: proc(54): run_and_log_output: Output: [6/8/17 12:53:42 AM] Response.TDSEnvChangeToken.NewValue: us_english
2017-06-08 00:53:42,914: INFO: proc(54): run_and_log_output: Output: [6/8/17 12:53:42 AM] Response.TDSInfoToken.Number: 5703
2017-06-08 00:53:42,914: INFO: proc(54): run_and_log_output: Output: [6/8/17 12:53:42 AM] Response.TDSInfoToken.State: 1
2017-06-08 00:53:42,914: INFO: proc(54): run_and_log_output: Output: [6/8/17 12:53:42 AM] Response.TDSInfoToken.Class: 0
2017-06-08 00:53:42,914: INFO: proc(54): run_and_log_output: Output: [6/8/17 12:53:42 AM] Response.TDSInfoToken.Message: Changed language setting to us_english
2017-06-08 00:53:42,914: INFO: proc(54): run_and_log_output: Output: [6/8/17 12:53:42 AM] Response.TDSInfoToken.ServerName: dnblup1400007HR
2017-06-08 00:53:42,914: INFO: proc(54): run_and_log_output: Output: [6/8/17 12:53:42 AM] Response.TDSInfoToken.ProcedureName: <null>
2017-06-08 00:53:42,914: INFO: proc(54): run_and_log_output: Output: [6/8/17 12:53:42 AM] Response.TDSInfoToken.Line: 0
2017-06-08 00:53:42,914: INFO: proc(54): run_and_log_output: Output: [6/8/17 12:53:42 AM] Response.TDSEnvChangeToken.Type: PacketSize
2017-06-08 00:53:42,914: INFO: proc(54): run_and_log_output: Output: [6/8/17 12:53:42 AM] Response.TDSEnvChangeToken.OldValue: 4096
2017-06-08 00:53:42,914: INFO: proc(54): run_and_log_output: Output: [6/8/17 12:53:42 AM] Response.TDSEnvChangeToken.NewValue: 4096
2017-06-08 00:53:42,914: INFO: proc(54): run_and_log_output: Output: [6/8/17 12:53:42 AM] Response.TDSLoginAckToken.TDSVersion: 7.4.0.4
2017-06-08 00:53:42,915: INFO: proc(54): run_and_log_output: Output: [6/8/17 12:53:42 AM] Response.TDSLoginAckToken.Interface: SQL
2017-06-08 00:53:42,915: INFO: proc(54): run_and_log_output: Output: [6/8/17 12:53:42 AM] Response.TDSLoginAckToken.ServerName: Microsoft SQL Server
2017-06-08 00:53:42,915: INFO: proc(54): run_and_log_output: Output: [6/8/17 12:53:42 AM] Response.TDSLoginAckToken.ServerVersion: 11.0.1083
2017-06-08 00:53:42,917: INFO: proc(54): run_and_log_output: Output: [6/8/17 12:53:42 AM] Response.TDSFeatureExtAckToken.Options[0].TDSFeatureExtAckSessionStateOption.Options[0].TDSSessionStateUserOptionsOption.AnsiNullDefaultOn: False
2017-06-08 00:53:42,917: INFO: proc(54): run_and_log_output: Output: [6/8/17 12:53:42 AM] Response.TDSFeatureExtAckToken.Options[0].TDSFeatureExtAckSessionStateOption.Options[0].TDSSessionStateUserOptionsOption.AnsiNulls: False
2017-06-08 00:53:42,917: INFO: proc(54): run_and_log_output: Output: [6/8/17 12:53:42 AM] Response.TDSFeatureExtAckToken.Options[0].TDSFeatureExtAckSessionStateOption.Options[0].TDSSessionStateUserOptionsOption.AnsiPadding: False
2017-06-08 00:53:42,917: INFO: proc(54): run_and_log_output: Output: [6/8/17 12:53:42 AM] Response.TDSFeatureExtAckToken.Options[0].TDSFeatureExtAckSessionStateOption.Options[0].TDSSessionStateUserOptionsOption.AnsiWarnings: False
2017-06-08 00:53:42,917: INFO: proc(54): run_and_log_output: Output: [6/8/17 12:53:42 AM] Response.TDSFeatureExtAckToken.Options[0].TDSFeatureExtAckSessionStateOption.Options[0].TDSSessionStateUserOptionsOption.ArithAbort: False
2017-06-08 00:53:42,917: INFO: proc(54): run_and_log_output: Output: [6/8/17 12:53:42 AM] Response.TDSFeatureExtAckToken.Options[0].TDSFeatureExtAckSessionStateOption.Options[0].TDSSessionStateUserOptionsOption.ArithIgnore: False
2017-06-08 00:53:42,917: INFO: proc(54): run_and_log_output: Output: [6/8/17 12:53:42 AM] Response.TDSFeatureExtAckToken.Options[0].TDSFeatureExtAckSessionStateOption.Options[0].TDSSessionStateUserOptionsOption.ConcatNullYieldsNull: False
2017-06-08 00:53:42,917: INFO: proc(54): run_and_log_output: Output: [6/8/17 12:53:42 AM] Response.TDSFeatureExtAckToken.Options[0].TDSFeatureExtAckSessionStateOption.Options[0].TDSSessionStateUserOptionsOption.CursorCloseOnCommit: False
2017-06-08 00:53:42,917: INFO: proc(54): run_and_log_output: Output: [6/8/17 12:53:42 AM] Response.TDSFeatureExtAckToken.Options[0].TDSFeatureExtAckSessionStateOption.Options[0].TDSSessionStateUserOptionsOption.ImplicitTransactions: False
2017-06-08 00:53:42,917: INFO: proc(54): run_and_log_output: Output: [6/8/17 12:53:42 AM] Response.TDSFeatureExtAckToken.Options[0].TDSFeatureExtAckSessionStateOption.Options[0].TDSSessionStateUserOptionsOption.NoCount: False
2017-06-08 00:53:42,917: INFO: proc(54): run_and_log_output: Output: [6/8/17 12:53:42 AM] Response.TDSFeatureExtAckToken.Options[0].TDSFeatureExtAckSessionStateOption.Options[0].TDSSessionStateUserOptionsOption.NumericRoundAbort: False
2017-06-08 00:53:42,917: INFO: proc(54): run_and_log_output: Output: [6/8/17 12:53:42 AM] Response.TDSFeatureExtAckToken.Options[0].TDSFeatureExtAckSessionStateOption.Options[0].TDSSessionStateUserOptionsOption.QuotedIdentifier: False
2017-06-08 00:53:42,918: INFO: proc(54): run_and_log_output: Output: [6/8/17 12:53:42 AM] Response.TDSFeatureExtAckToken.Options[0].TDSFeatureExtAckSessionStateOption.Options[0].TDSSessionStateUserOptionsOption.TransactionAbortOnError: False
2017-06-08 00:53:42,918: INFO: proc(54): run_and_log_output: Output: [6/8/17 12:53:42 AM] Response.TDSFeatureExtAckToken.Options[0].TDSFeatureExtAckSessionStateOption.Options[0].TDSSessionStateUserOptionsOption.StateID: 0
2017-06-08 00:53:42,918: INFO: proc(54): run_and_log_output: Output: [6/8/17 12:53:42 AM] Response.TDSFeatureExtAckToken.Options[0].TDSFeatureExtAckSessionStateOption.Options[1].TDSSessionStateDateFirstDateFormatOption.DateFirst: 7
2017-06-08 00:53:42,918: INFO: proc(54): run_and_log_output: Output: [6/8/17 12:53:42 AM] Response.TDSFeatureExtAckToken.Options[0].TDSFeatureExtAckSessionStateOption.Options[1].TDSSessionStateDateFirstDateFormatOption.DateFormat: MonthDayYear
2017-06-08 00:53:42,918: INFO: proc(54): run_and_log_output: Output: [6/8/17 12:53:42 AM] Response.TDSFeatureExtAckToken.Options[0].TDSFeatureExtAckSessionStateOption.Options[1].TDSSessionStateDateFirstDateFormatOption.StateID: 2
2017-06-08 00:53:42,918: INFO: proc(54): run_and_log_output: Output: [6/8/17 12:53:42 AM] Response.TDSFeatureExtAckToken.Options[0].TDSFeatureExtAckSessionStateOption.Options[2].TDSSessionStateDeadlockPriorityOption.Value: 0
2017-06-08 00:53:42,918: INFO: proc(54): run_and_log_output: Output: [6/8/17 12:53:42 AM] Response.TDSFeatureExtAckToken.Options[0].TDSFeatureExtAckSessionStateOption.Options[2].TDSSessionStateDeadlockPriorityOption.StateID: 4
2017-06-08 00:53:42,919: INFO: proc(54): run_and_log_output: Output: [6/8/17 12:53:42 AM] Response.TDSFeatureExtAckToken.Options[0].TDSFeatureExtAckSessionStateOption.Options[3].TDSSessionStateLockTimeoutOption.Value: 0
2017-06-08 00:53:42,919: INFO: proc(54): run_and_log_output: Output: [6/8/17 12:53:42 AM] Response.TDSFeatureExtAckToken.Options[0].TDSFeatureExtAckSessionStateOption.Options[3].TDSSessionStateLockTimeoutOption.StateID: 5
2017-06-08 00:53:42,919: INFO: proc(54): run_and_log_output: Output: [6/8/17 12:53:42 AM] Response.TDSFeatureExtAckToken.Options[0].TDSFeatureExtAckSessionStateOption.Options[4].TDSSessionStateISOFipsOption.TransactionIsolationLevel: ReadCommited
2017-06-08 00:53:42,919: INFO: proc(54): run_and_log_output: Output: [6/8/17 12:53:42 AM] Response.TDSFeatureExtAckToken.Options[0].TDSFeatureExtAckSessionStateOption.Options[4].TDSSessionStateISOFipsOption.StateID: 7
2017-06-08 00:53:42,919: INFO: proc(54): run_and_log_output: Output: [6/8/17 12:53:42 AM] Response.TDSFeatureExtAckToken.Options[0].TDSFeatureExtAckSessionStateOption.Options[5].TDSSessionStateTextSizeOption.Value: -1
2017-06-08 00:53:42,919: INFO: proc(54): run_and_log_output: Output: [6/8/17 12:53:42 AM] Response.TDSFeatureExtAckToken.Options[0].TDSFeatureExtAckSessionStateOption.Options[5].TDSSessionStateTextSizeOption.StateID: 9
2017-06-08 00:53:42,919: INFO: proc(54): run_and_log_output: Output: [6/8/17 12:53:42 AM] Response.TDSFeatureExtAckToken.Options[0].TDSFeatureExtAckSessionStateOption.FeatureID: SessionRecovery
2017-06-08 00:53:42,920: INFO: proc(54): run_and_log_output: Output: [6/8/17 12:53:42 AM] Response.TDSDoneToken.Status: Final
2017-06-08 00:53:42,920: INFO: proc(54): run_and_log_output: Output: [6/8/17 12:53:42 AM] Response.TDSDoneToken.Command: 0
2017-06-08 00:53:42,920: INFO: proc(54): run_and_log_output: Output: [6/8/17 12:53:42 AM] Response.TDSDoneToken.RowCount: 0
2017-06-08 00:53:42,972: INFO: proc(54): run_and_log_output: Output: SqlClient.DiagnosticsTest.ConnectionOpenTest:: Connection Opened 
2017-06-08 00:53:42,984: INFO: proc(54): run_and_log_output: Output: SqlClient.DiagnosticsTest.ConnectionOpenTest:: Connection Should Be Disposed
2017-06-08 00:53:42,984: INFO: proc(54): run_and_log_output: Output: Test: ConnectionOpenTest SqlOperation Successful

https://mc.dot.net/#/user/danmosemsft/pr~2Fjenkins~2Fdotnet~2Fcorefx~2Fmaster~2F/test~2Ffunctional~2Fcli~2F/f8b7bd8f5e58354e4e659ad01a49ae7a622f7c27/workItem/System.Data.SqlClient.Tests/wilogs
Ubuntu.1404.Amd64.Open:Release-x64 console output shows this.

Thoughts?

danmoseley · 2017-06-08T03:49:14Z

Uh - actually by the timestamps this logging may correspond to the subsequent, successful test...

saurabh500 · 2017-06-08T04:01:21Z

The logs pasted don't have anything interesting. They do point to a failure. They don't have the logs from my changes. Also the link has all successful executions. Can you point me to the error logs for the failure above?

danmoseley · 2017-06-08T04:16:27Z

@saurabh500 go to https://mc.dot.net/#/user/danmosemsft/pr~2Fjenkins~2Fdotnet~2Fcorefx~2Fmaster~2F/test~2Ffunctional~2Fcli~2F/f8b7bd8f5e58354e4e659ad01a49ae7a622f7c27/workItem/System.Data.SqlClient.Tests/wilogs and open the sectoin marked Ubuntu.1404.Amd64.Open:Release-x64. In there is the console output with the output above.

danmoseley · 2017-06-08T18:45:58Z

@saurabh500 if we don't think we can figure this out imminently it might be worth changing some of these to outer loop so they don't keep breaking CI.

https://mc.dot.net/#/user/JeremyKuhne/pr~2Fjenkins~2Fdotnet~2Fcorefx~2Fmaster~2F/test~2Ffunctional~2Fcli~2F/6aa5f84bde5be8fb260f63f554f4cc7ae02f9b9e/workItem/System.Data.SqlClient.Tests/analysis/xunit/System.Data.SqlClient.Tests.DiagnosticTest~2FExecuteReaderAsyncTest

saurabh500 · 2017-06-08T19:29:41Z

@danmosemsft The link above has my logs. It seems like the TcpListener.Start() call may have succeeded for the server, but the socket may not by open.
I am preparing a PR to open a connection to the server from inside the server as soon as the listener is created to see if there is something in networking layer which causes these issues.

danmoseley · 2017-06-14T04:03:28Z

@saurabh500 it broke again in CI --
https://mc.dot.net/#/user/caesar1995/pr~2Fjenkins~2Fdotnet~2Fcorefx~2Fmaster~2F/test~2Ffunctional~2Fcli~2F/30193fbeb605e0a97347b38589f57bf522fab40e/workItem/System.Data.SqlClient.Tests/wilogs

log uploaded here

attached above. Can you please take a look? Hopefully we can knock this one on the head.

danmoseley · 2017-06-17T23:38:56Z

@saurabh500 here's a failure on ExecuteScalarTest, with logging. Ubuntu1404. Can you please look?

https://mc.dot.net/#/user/caesar1995/pr~2Fjenkins~2Fdotnet~2Fcorefx~2Fmaster~2F/test~2Ffunctional~2Fcli~2F/e05866aa1852f9998cd629df531515d82e39b968/workItem/System.Data.SqlClient.Tests/wilogs

2017-06-17 21:39:42,921: INFO: proc(54): run_and_log_output: Output: Starting:    System.Data.SqlClient.Tests
2017-06-17 21:39:44,416: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ConnectionTest Is Server Socket Bound: True Testing connectivity to the endpoint created for the server.
2017-06-17 21:39:44,449: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ConnectionTest Endpoint test successful.
2017-06-17 21:39:44,454: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ConnectionTest Listener Thread Started 
2017-06-17 21:39:44,498: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ConnectionTest Connection Closed
2017-06-17 21:39:44,666: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ConnectionTest Connection Closed
2017-06-17 21:39:44,811: INFO: proc(54): run_and_log_output: Output: Test: ExecuteReaderErrorTest Enabled Listeners
2017-06-17 21:39:44,865: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteReaderErrorTest Is Server Socket Bound: True Testing connectivity to the endpoint created for the server.
2017-06-17 21:39:44,902: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteReaderErrorTest Endpoint test successful.
2017-06-17 21:39:44,906: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteReaderErrorTest Listener Thread Started 
2017-06-17 21:39:44,937: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteReaderErrorTest Connection Closed
2017-06-17 21:39:45,061: INFO: proc(54): run_and_log_output: Output: Test: ExecuteReaderErrorTest Started Server
2017-06-17 21:39:45,121: INFO: proc(54): run_and_log_output: Output: Test: ExecuteReaderErrorTest SqlOperation Successful
2017-06-17 21:39:45,121: INFO: proc(54): run_and_log_output: Output: Test: ExecuteReaderErrorTest Listeners Disabled
2017-06-17 21:39:45,130: INFO: proc(54): run_and_log_output: Output: Test: ExecuteReaderErrorTest Server Disposed
2017-06-17 21:39:45,131: INFO: proc(54): run_and_log_output: Output: Test: ExecuteReaderErrorTest Listeners Disposed Successfully
2017-06-17 21:39:45,284: INFO: proc(54): run_and_log_output: Output: Test: ExecuteScalarAsyncErrorTest Enabled Listeners
2017-06-17 21:39:45,337: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteScalarAsyncErrorTest Is Server Socket Bound: True Testing connectivity to the endpoint created for the server.
2017-06-17 21:39:45,374: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteScalarAsyncErrorTest Endpoint test successful.
2017-06-17 21:39:45,375: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteScalarAsyncErrorTest Listener Thread Started 
2017-06-17 21:39:45,410: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteScalarAsyncErrorTest Connection Closed
2017-06-17 21:39:45,525: INFO: proc(54): run_and_log_output: Output: Test: ExecuteScalarAsyncErrorTest Started Server
2017-06-17 21:39:45,941: INFO: proc(54): run_and_log_output: Output: Test: ExecuteScalarAsyncErrorTest SqlOperation Successful
2017-06-17 21:39:45,941: INFO: proc(54): run_and_log_output: Output: Test: ExecuteScalarAsyncErrorTest Listeners Disabled
2017-06-17 21:39:45,953: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteScalarAsyncErrorTest Connection Closed
2017-06-17 21:39:45,954: INFO: proc(54): run_and_log_output: Output: Test: ExecuteScalarAsyncErrorTest Server Disposed
2017-06-17 21:39:45,955: INFO: proc(54): run_and_log_output: Output: Test: ExecuteScalarAsyncErrorTest Listeners Disposed Successfully
2017-06-17 21:39:46,719: INFO: proc(54): run_and_log_output: Output:    System.Data.SqlClient.Tests.DiagnosticTest.ExecuteXmlReaderAsyncErrorTest [SKIP]
2017-06-17 21:39:46,721: INFO: proc(54): run_and_log_output: Output:       Condition(s) not met: \"IsConnectionStringConfigured\"
2017-06-17 21:39:46,812: INFO: proc(54): run_and_log_output: Output: Test: ExecuteScalarErrorTest Enabled Listeners
2017-06-17 21:39:46,866: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteScalarErrorTest Is Server Socket Bound: True Testing connectivity to the endpoint created for the server.
2017-06-17 21:39:46,903: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteScalarErrorTest Endpoint test successful.
2017-06-17 21:39:46,906: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteScalarErrorTest Listener Thread Started 
2017-06-17 21:39:46,937: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteScalarErrorTest Connection Closed
2017-06-17 21:39:47,060: INFO: proc(54): run_and_log_output: Output: Test: ExecuteScalarErrorTest Started Server
2017-06-17 21:39:47,436: INFO: proc(54): run_and_log_output: Output: Test: ExecuteScalarErrorTest SqlOperation Successful
2017-06-17 21:39:47,437: INFO: proc(54): run_and_log_output: Output: Test: ExecuteScalarErrorTest Listeners Disabled
2017-06-17 21:39:47,447: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteScalarErrorTest Connection Closed
2017-06-17 21:39:47,448: INFO: proc(54): run_and_log_output: Output: Test: ExecuteScalarErrorTest Server Disposed
2017-06-17 21:39:47,448: INFO: proc(54): run_and_log_output: Output: Test: ExecuteScalarErrorTest Listeners Disposed Successfully
2017-06-17 21:39:47,550: INFO: proc(54): run_and_log_output: Output: Test: ConnectionOpenTest Enabled Listeners
2017-06-17 21:39:47,599: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ConnectionOpenTest Is Server Socket Bound: True Testing connectivity to the endpoint created for the server.
2017-06-17 21:39:47,636: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ConnectionOpenTest Endpoint test successful.
2017-06-17 21:39:47,636: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ConnectionOpenTest Listener Thread Started 
2017-06-17 21:39:47,671: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ConnectionOpenTest Connection Closed
2017-06-17 21:39:47,781: INFO: proc(54): run_and_log_output: Output: Test: ConnectionOpenTest Started Server
2017-06-17 21:39:48,010: INFO: proc(54): run_and_log_output: Output: [6/17/17 9:39:48 PM] Request.TDSPreLoginToken.Version: 4.6.25417
2017-06-17 21:39:48,010: INFO: proc(54): run_and_log_output: Output: [6/17/17 9:39:48 PM] Request.TDSPreLoginToken.SubBuild: 0
2017-06-17 21:39:48,010: INFO: proc(54): run_and_log_output: Output: [6/17/17 9:39:48 PM] Request.TDSPreLoginToken.Encryption: Off
2017-06-17 21:39:48,010: INFO: proc(54): run_and_log_output: Output: [6/17/17 9:39:48 PM] Request.TDSPreLoginToken.ThreadID: 33554432
2017-06-17 21:39:48,010: INFO: proc(54): run_and_log_output: Output: [6/17/17 9:39:48 PM] Request.TDSPreLoginToken.IsMARS: False
2017-06-17 21:39:48,010: INFO: proc(54): run_and_log_output: Output: [6/17/17 9:39:48 PM] Request.TDSPreLoginToken.ClientTraceID: [C8 83 F2 69 91 1F 3C 44 8F 1F 95 B2 7F 5B 6D 1A ]
2017-06-17 21:39:48,011: INFO: proc(54): run_and_log_output: Output: [6/17/17 9:39:48 PM] Request.TDSPreLoginToken.ActivityID: [F0 13 10 2 2 CD 8 48 BB 37 DA B1 D3 C3 42 F7 1 0 0 0 ]
2017-06-17 21:39:48,011: INFO: proc(54): run_and_log_output: Output: [6/17/17 9:39:48 PM] Request.TDSPreLoginToken.Nonce: <null>
2017-06-17 21:39:48,011: INFO: proc(54): run_and_log_output: Output: [6/17/17 9:39:48 PM] Request.TDSPreLoginToken.FedAuthRequired: FedAuthNotRequired
2017-06-17 21:39:48,012: INFO: proc(54): run_and_log_output: Output: [6/17/17 9:39:48 PM] Response.TDSPreLoginToken.Version: 11.0.1083
2017-06-17 21:39:48,012: INFO: proc(54): run_and_log_output: Output: [6/17/17 9:39:48 PM] Response.TDSPreLoginToken.SubBuild: 0
2017-06-17 21:39:48,012: INFO: proc(54): run_and_log_output: Output: [6/17/17 9:39:48 PM] Response.TDSPreLoginToken.Encryption: NotSupported
2017-06-17 21:39:48,012: INFO: proc(54): run_and_log_output: Output: [6/17/17 9:39:48 PM] Response.TDSPreLoginToken.ThreadID: 0
2017-06-17 21:39:48,012: INFO: proc(54): run_and_log_output: Output: [6/17/17 9:39:48 PM] Response.TDSPreLoginToken.IsMARS: False
2017-06-17 21:39:48,012: INFO: proc(54): run_and_log_output: Output: [6/17/17 9:39:48 PM] Response.TDSPreLoginToken.ClientTraceID: <null>
2017-06-17 21:39:48,012: INFO: proc(54): run_and_log_output: Output: [6/17/17 9:39:48 PM] Response.TDSPreLoginToken.ActivityID: <null>
2017-06-17 21:39:48,012: INFO: proc(54): run_and_log_output: Output: [6/17/17 9:39:48 PM] Response.TDSPreLoginToken.Nonce: <null>
2017-06-17 21:39:48,012: INFO: proc(54): run_and_log_output: Output: [6/17/17 9:39:48 PM] Response.TDSPreLoginToken.FedAuthRequired: FedAuthNotRequired
2017-06-17 21:39:48,056: INFO: proc(54): run_and_log_output: Output: [6/17/17 9:39:48 PM] Request.TDSLogin7Token.TDSVersion: 7.4.0.4
2017-06-17 21:39:48,056: INFO: proc(54): run_and_log_output: Output: [6/17/17 9:39:48 PM] Request.TDSLogin7Token.PacketSize: 8000
2017-06-17 21:39:48,056: INFO: proc(54): run_and_log_output: Output: [6/17/17 9:39:48 PM] Request.TDSLogin7Token.ClientProgramVersion: 100663296
2017-06-17 21:39:48,056: INFO: proc(54): run_and_log_output: Output: [6/17/17 9:39:48 PM] Request.TDSLogin7Token.ClientPID: 716383286
2017-06-17 21:39:48,056: INFO: proc(54): run_and_log_output: Output: [6/17/17 9:39:48 PM] Request.TDSLogin7Token.ConnectionID: 0
2017-06-17 21:39:48,056: INFO: proc(54): run_and_log_output: Output: [6/17/17 9:39:48 PM] Request.TDSLogin7Token.OptionalFlags1.TDSLogin7TokenOptionalFlags1.Order: OrderX86
2017-06-17 21:39:48,056: INFO: proc(54): run_and_log_output: Output: [6/17/17 9:39:48 PM] Request.TDSLogin7Token.OptionalFlags1.TDSLogin7TokenOptionalFlags1.CharacterSet: Ascii
2017-06-17 21:39:48,056: INFO: proc(54): run_and_log_output: Output: [6/17/17 9:39:48 PM] Request.TDSLogin7Token.OptionalFlags1.TDSLogin7TokenOptionalFlags1.FloatingPoint: IEEE754
2017-06-17 21:39:48,057: INFO: proc(54): run_and_log_output: Output: [6/17/17 9:39:48 PM] Request.TDSLogin7Token.OptionalFlags1.TDSLogin7TokenOptionalFlags1.DumpLoad: On
2017-06-17 21:39:48,057: INFO: proc(54): run_and_log_output: Output: [6/17/17 9:39:48 PM] Request.TDSLogin7Token.OptionalFlags1.TDSLogin7TokenOptionalFlags1.UseDB: Off
2017-06-17 21:39:48,057: INFO: proc(54): run_and_log_output: Output: [6/17/17 9:39:48 PM] Request.TDSLogin7Token.OptionalFlags1.TDSLogin7TokenOptionalFlags1.Database: Fatal
2017-06-17 21:39:48,057: INFO: proc(54): run_and_log_output: Output: [6/17/17 9:39:48 PM] Request.TDSLogin7Token.OptionalFlags1.TDSLogin7TokenOptionalFlags1.Language: On
2017-06-17 21:39:48,057: INFO: proc(54): run_and_log_output: Output: [6/17/17 9:39:48 PM] Request.TDSLogin7Token.OptionalFlags2.TDSLogin7TokenOptionalFlags2.Language: Fatal
2017-06-17 21:39:48,057: INFO: proc(54): run_and_log_output: Output: [6/17/17 9:39:48 PM] Request.TDSLogin7Token.OptionalFlags2.TDSLogin7TokenOptionalFlags2.Odbc: On
2017-06-17 21:39:48,057: INFO: proc(54): run_and_log_output: Output: [6/17/17 9:39:48 PM] Request.TDSLogin7Token.OptionalFlags2.TDSLogin7TokenOptionalFlags2.UserType: Normal
2017-06-17 21:39:48,057: INFO: proc(54): run_and_log_output: Output: [6/17/17 9:39:48 PM] Request.TDSLogin7Token.OptionalFlags2.TDSLogin7TokenOptionalFlags2.IntegratedSecurity: Off
2017-06-17 21:39:48,058: INFO: proc(54): run_and_log_output: Output: [6/17/17 9:39:48 PM] Request.TDSLogin7Token.OptionalFlags3.TDSLogin7TokenOptionalFlags3.ChangePassword: No
2017-06-17 21:39:48,058: INFO: proc(54): run_and_log_output: Output: [6/17/17 9:39:48 PM] Request.TDSLogin7Token.OptionalFlags3.TDSLogin7TokenOptionalFlags3.IsUserInstance: False
2017-06-17 21:39:48,058: INFO: proc(54): run_and_log_output: Output: [6/17/17 9:39:48 PM] Request.TDSLogin7Token.OptionalFlags3.TDSLogin7TokenOptionalFlags3.SendYukonBinaryXML: False
2017-06-17 21:39:48,058: INFO: proc(54): run_and_log_output: Output: [6/17/17 9:39:48 PM] Request.TDSLogin7Token.OptionalFlags3.TDSLogin7TokenOptionalFlags3.UnknownCollation: Must
2017-06-17 21:39:48,058: INFO: proc(54): run_and_log_output: Output: [6/17/17 9:39:48 PM] Request.TDSLogin7Token.OptionalFlags3.TDSLogin7TokenOptionalFlags3.ExtensionFlag: True
2017-06-17 21:39:48,058: INFO: proc(54): run_and_log_output: Output: [6/17/17 9:39:48 PM] Request.TDSLogin7Token.TypeFlags.TDSLogin7TokenTypeFlags.SQL: Default
2017-06-17 21:39:48,058: INFO: proc(54): run_and_log_output: Output: [6/17/17 9:39:48 PM] Request.TDSLogin7Token.TypeFlags.TDSLogin7TokenTypeFlags.OleDb: Off
2017-06-17 21:39:48,058: INFO: proc(54): run_and_log_output: Output: [6/17/17 9:39:48 PM] Request.TDSLogin7Token.TypeFlags.TDSLogin7TokenTypeFlags.ReadOnlyIntent: ReadWrite
2017-06-17 21:39:48,059: INFO: proc(54): run_and_log_output: Output: [6/17/17 9:39:48 PM] Request.TDSLogin7Token.ClientTimeZone: 0
2017-06-17 21:39:48,059: INFO: proc(54): run_and_log_output: Output: [6/17/17 9:39:48 PM] Request.TDSLogin7Token.ClientLCID: 0
2017-06-17 21:39:48,059: INFO: proc(54): run_and_log_output: Output: [6/17/17 9:39:48 PM] Request.TDSLogin7Token.HostName: dnblup1400002A9
2017-06-17 21:39:48,059: INFO: proc(54): run_and_log_output: Output: [6/17/17 9:39:48 PM] Request.TDSLogin7Token.UserID: <null>
2017-06-17 21:39:48,059: INFO: proc(54): run_and_log_output: Output: [6/17/17 9:39:48 PM] Request.TDSLogin7Token.Password: <null>
2017-06-17 21:39:48,059: INFO: proc(54): run_and_log_output: Output: [6/17/17 9:39:48 PM] Request.TDSLogin7Token.ApplicationName: Core .Net SqlClient Data Provider
2017-06-17 21:39:48,059: INFO: proc(54): run_and_log_output: Output: [6/17/17 9:39:48 PM] Request.TDSLogin7Token.ServerName: localhost,37701
2017-06-17 21:39:48,059: INFO: proc(54): run_and_log_output: Output: [6/17/17 9:39:48 PM] Request.TDSLogin7Token.LibraryName: Core .Net SqlClient Data Provider
2017-06-17 21:39:48,059: INFO: proc(54): run_and_log_output: Output: [6/17/17 9:39:48 PM] Request.TDSLogin7Token.Language: <null>
2017-06-17 21:39:48,059: INFO: proc(54): run_and_log_output: Output: [6/17/17 9:39:48 PM] Request.TDSLogin7Token.Database: <null>
2017-06-17 21:39:48,059: INFO: proc(54): run_and_log_output: Output: [6/17/17 9:39:48 PM] Request.TDSLogin7Token.ClientID: [7E 10 7B EB B9 8F ]
2017-06-17 21:39:48,060: INFO: proc(54): run_and_log_output: Output: [6/17/17 9:39:48 PM] Request.TDSLogin7Token.AttachDatabaseFile: <null>
2017-06-17 21:39:48,060: INFO: proc(54): run_and_log_output: Output: [6/17/17 9:39:48 PM] Request.TDSLogin7Token.ChangePassword: <null>
2017-06-17 21:39:48,060: INFO: proc(54): run_and_log_output: Output: [6/17/17 9:39:48 PM] Request.TDSLogin7Token.SSPI: <null>
2017-06-17 21:39:48,060: INFO: proc(54): run_and_log_output: Output: [6/17/17 9:39:48 PM] Request.TDSLogin7Token.FeatureExt[0].TDSLogin7SessionRecoveryOptionToken.FeatureID: SessionRecovery
2017-06-17 21:39:48,060: INFO: proc(54): run_and_log_output: Output: [6/17/17 9:39:48 PM] Request.TDSLogin7Token.FeatureExt[0].TDSLogin7SessionRecoveryOptionToken.Initial: <null>
2017-06-17 21:39:48,060: INFO: proc(54): run_and_log_output: Output: [6/17/17 9:39:48 PM] Request.TDSLogin7Token.FeatureExt[0].TDSLogin7SessionRecoveryOptionToken.Current: <null>
2017-06-17 21:39:48,067: INFO: proc(54): run_and_log_output: Output: [6/17/17 9:39:48 PM] Response.TDSEnvChangeToken.Type: Database
2017-06-17 21:39:48,067: INFO: proc(54): run_and_log_output: Output: [6/17/17 9:39:48 PM] Response.TDSEnvChangeToken.OldValue: master
2017-06-17 21:39:48,067: INFO: proc(54): run_and_log_output: Output: [6/17/17 9:39:48 PM] Response.TDSEnvChangeToken.NewValue: master
2017-06-17 21:39:48,067: INFO: proc(54): run_and_log_output: Output: [6/17/17 9:39:48 PM] Response.TDSInfoToken.Number: 5701
2017-06-17 21:39:48,067: INFO: proc(54): run_and_log_output: Output: [6/17/17 9:39:48 PM] Response.TDSInfoToken.State: 2
2017-06-17 21:39:48,067: INFO: proc(54): run_and_log_output: Output: [6/17/17 9:39:48 PM] Response.TDSInfoToken.Class: 0
2017-06-17 21:39:48,067: INFO: proc(54): run_and_log_output: Output: [6/17/17 9:39:48 PM] Response.TDSInfoToken.Message: Changed database context to 'master'
2017-06-17 21:39:48,067: INFO: proc(54): run_and_log_output: Output: [6/17/17 9:39:48 PM] Response.TDSInfoToken.ServerName: dnblup1400002A9
2017-06-17 21:39:48,068: INFO: proc(54): run_and_log_output: Output: [6/17/17 9:39:48 PM] Response.TDSInfoToken.ProcedureName: <null>
2017-06-17 21:39:48,068: INFO: proc(54): run_and_log_output: Output: [6/17/17 9:39:48 PM] Response.TDSInfoToken.Line: 0
2017-06-17 21:39:48,068: INFO: proc(54): run_and_log_output: Output: [6/17/17 9:39:48 PM] Response.TDSEnvChangeToken.Type: SQLCollation
2017-06-17 21:39:48,068: INFO: proc(54): run_and_log_output: Output: [6/17/17 9:39:48 PM] Response.TDSEnvChangeToken.OldValue: <null>
2017-06-17 21:39:48,068: INFO: proc(54): run_and_log_output: Output: [6/17/17 9:39:48 PM] Response.TDSEnvChangeToken.NewValue: [9 4 D0 0 34 ]
2017-06-17 21:39:48,068: INFO: proc(54): run_and_log_output: Output: [6/17/17 9:39:48 PM] Response.TDSEnvChangeToken.Type: Language
2017-06-17 21:39:48,069: INFO: proc(54): run_and_log_output: Output: [6/17/17 9:39:48 PM] Response.TDSEnvChangeToken.OldValue: <null>
2017-06-17 21:39:48,069: INFO: proc(54): run_and_log_output: Output: [6/17/17 9:39:48 PM] Response.TDSEnvChangeToken.NewValue: us_english
2017-06-17 21:39:48,069: INFO: proc(54): run_and_log_output: Output: [6/17/17 9:39:48 PM] Response.TDSInfoToken.Number: 5703
2017-06-17 21:39:48,069: INFO: proc(54): run_and_log_output: Output: [6/17/17 9:39:48 PM] Response.TDSInfoToken.State: 1
2017-06-17 21:39:48,069: INFO: proc(54): run_and_log_output: Output: [6/17/17 9:39:48 PM] Response.TDSInfoToken.Class: 0
2017-06-17 21:39:48,069: INFO: proc(54): run_and_log_output: Output: [6/17/17 9:39:48 PM] Response.TDSInfoToken.Message: Changed language setting to us_english
2017-06-17 21:39:48,069: INFO: proc(54): run_and_log_output: Output: [6/17/17 9:39:48 PM] Response.TDSInfoToken.ServerName: dnblup1400002A9
2017-06-17 21:39:48,069: INFO: proc(54): run_and_log_output: Output: [6/17/17 9:39:48 PM] Response.TDSInfoToken.ProcedureName: <null>
2017-06-17 21:39:48,069: INFO: proc(54): run_and_log_output: Output: [6/17/17 9:39:48 PM] Response.TDSInfoToken.Line: 0
2017-06-17 21:39:48,069: INFO: proc(54): run_and_log_output: Output: [6/17/17 9:39:48 PM] Response.TDSEnvChangeToken.Type: PacketSize
2017-06-17 21:39:48,069: INFO: proc(54): run_and_log_output: Output: [6/17/17 9:39:48 PM] Response.TDSEnvChangeToken.OldValue: 4096
2017-06-17 21:39:48,069: INFO: proc(54): run_and_log_output: Output: [6/17/17 9:39:48 PM] Response.TDSEnvChangeToken.NewValue: 4096
2017-06-17 21:39:48,069: INFO: proc(54): run_and_log_output: Output: [6/17/17 9:39:48 PM] Response.TDSLoginAckToken.TDSVersion: 7.4.0.4
2017-06-17 21:39:48,069: INFO: proc(54): run_and_log_output: Output: [6/17/17 9:39:48 PM] Response.TDSLoginAckToken.Interface: SQL
2017-06-17 21:39:48,070: INFO: proc(54): run_and_log_output: Output: [6/17/17 9:39:48 PM] Response.TDSLoginAckToken.ServerName: Microsoft SQL Server
2017-06-17 21:39:48,070: INFO: proc(54): run_and_log_output: Output: [6/17/17 9:39:48 PM] Response.TDSLoginAckToken.ServerVersion: 11.0.1083
2017-06-17 21:39:48,072: INFO: proc(54): run_and_log_output: Output: [6/17/17 9:39:48 PM] Response.TDSFeatureExtAckToken.Options[0].TDSFeatureExtAckSessionStateOption.Options[0].TDSSessionStateUserOptionsOption.AnsiNullDefaultOn: False
2017-06-17 21:39:48,072: INFO: proc(54): run_and_log_output: Output: [6/17/17 9:39:48 PM] Response.TDSFeatureExtAckToken.Options[0].TDSFeatureExtAckSessionStateOption.Options[0].TDSSessionStateUserOptionsOption.AnsiNulls: False
2017-06-17 21:39:48,072: INFO: proc(54): run_and_log_output: Output: [6/17/17 9:39:48 PM] Response.TDSFeatureExtAckToken.Options[0].TDSFeatureExtAckSessionStateOption.Options[0].TDSSessionStateUserOptionsOption.AnsiPadding: False
2017-06-17 21:39:48,072: INFO: proc(54): run_and_log_output: Output: [6/17/17 9:39:48 PM] Response.TDSFeatureExtAckToken.Options[0].TDSFeatureExtAckSessionStateOption.Options[0].TDSSessionStateUserOptionsOption.AnsiWarnings: False
2017-06-17 21:39:48,072: INFO: proc(54): run_and_log_output: Output: [6/17/17 9:39:48 PM] Response.TDSFeatureExtAckToken.Options[0].TDSFeatureExtAckSessionStateOption.Options[0].TDSSessionStateUserOptionsOption.ArithAbort: False
2017-06-17 21:39:48,072: INFO: proc(54): run_and_log_output: Output: [6/17/17 9:39:48 PM] Response.TDSFeatureExtAckToken.Options[0].TDSFeatureExtAckSessionStateOption.Options[0].TDSSessionStateUserOptionsOption.ArithIgnore: False
2017-06-17 21:39:48,072: INFO: proc(54): run_and_log_output: Output: [6/17/17 9:39:48 PM] Response.TDSFeatureExtAckToken.Options[0].TDSFeatureExtAckSessionStateOption.Options[0].TDSSessionStateUserOptionsOption.ConcatNullYieldsNull: False
2017-06-17 21:39:48,072: INFO: proc(54): run_and_log_output: Output: [6/17/17 9:39:48 PM] Response.TDSFeatureExtAckToken.Options[0].TDSFeatureExtAckSessionStateOption.Options[0].TDSSessionStateUserOptionsOption.CursorCloseOnCommit: False
2017-06-17 21:39:48,073: INFO: proc(54): run_and_log_output: Output: [6/17/17 9:39:48 PM] Response.TDSFeatureExtAckToken.Options[0].TDSFeatureExtAckSessionStateOption.Options[0].TDSSessionStateUserOptionsOption.ImplicitTransactions: False
2017-06-17 21:39:48,073: INFO: proc(54): run_and_log_output: Output: [6/17/17 9:39:48 PM] Response.TDSFeatureExtAckToken.Options[0].TDSFeatureExtAckSessionStateOption.Options[0].TDSSessionStateUserOptionsOption.NoCount: False
2017-06-17 21:39:48,073: INFO: proc(54): run_and_log_output: Output: [6/17/17 9:39:48 PM] Response.TDSFeatureExtAckToken.Options[0].TDSFeatureExtAckSessionStateOption.Options[0].TDSSessionStateUserOptionsOption.NumericRoundAbort: False
2017-06-17 21:39:48,073: INFO: proc(54): run_and_log_output: Output: [6/17/17 9:39:48 PM] Response.TDSFeatureExtAckToken.Options[0].TDSFeatureExtAckSessionStateOption.Options[0].TDSSessionStateUserOptionsOption.QuotedIdentifier: False
2017-06-17 21:39:48,073: INFO: proc(54): run_and_log_output: Output: [6/17/17 9:39:48 PM] Response.TDSFeatureExtAckToken.Options[0].TDSFeatureExtAckSessionStateOption.Options[0].TDSSessionStateUserOptionsOption.TransactionAbortOnError: False
2017-06-17 21:39:48,073: INFO: proc(54): run_and_log_output: Output: [6/17/17 9:39:48 PM] Response.TDSFeatureExtAckToken.Options[0].TDSFeatureExtAckSessionStateOption.Options[0].TDSSessionStateUserOptionsOption.StateID: 0
2017-06-17 21:39:48,073: INFO: proc(54): run_and_log_output: Output: [6/17/17 9:39:48 PM] Response.TDSFeatureExtAckToken.Options[0].TDSFeatureExtAckSessionStateOption.Options[1].TDSSessionStateDateFirstDateFormatOption.DateFirst: 7
2017-06-17 21:39:48,073: INFO: proc(54): run_and_log_output: Output: [6/17/17 9:39:48 PM] Response.TDSFeatureExtAckToken.Options[0].TDSFeatureExtAckSessionStateOption.Options[1].TDSSessionStateDateFirstDateFormatOption.DateFormat: MonthDayYear
2017-06-17 21:39:48,073: INFO: proc(54): run_and_log_output: Output: [6/17/17 9:39:48 PM] Response.TDSFeatureExtAckToken.Options[0].TDSFeatureExtAckSessionStateOption.Options[1].TDSSessionStateDateFirstDateFormatOption.StateID: 2
2017-06-17 21:39:48,073: INFO: proc(54): run_and_log_output: Output: [6/17/17 9:39:48 PM] Response.TDSFeatureExtAckToken.Options[0].TDSFeatureExtAckSessionStateOption.Options[2].TDSSessionStateDeadlockPriorityOption.Value: 0
2017-06-17 21:39:48,073: INFO: proc(54): run_and_log_output: Output: [6/17/17 9:39:48 PM] Response.TDSFeatureExtAckToken.Options[0].TDSFeatureExtAckSessionStateOption.Options[2].TDSSessionStateDeadlockPriorityOption.StateID: 4
2017-06-17 21:39:48,073: INFO: proc(54): run_and_log_output: Output: [6/17/17 9:39:48 PM] Response.TDSFeatureExtAckToken.Options[0].TDSFeatureExtAckSessionStateOption.Options[3].TDSSessionStateLockTimeoutOption.Value: 0
2017-06-17 21:39:48,074: INFO: proc(54): run_and_log_output: Output: [6/17/17 9:39:48 PM] Response.TDSFeatureExtAckToken.Options[0].TDSFeatureExtAckSessionStateOption.Options[3].TDSSessionStateLockTimeoutOption.StateID: 5
2017-06-17 21:39:48,074: INFO: proc(54): run_and_log_output: Output: [6/17/17 9:39:48 PM] Response.TDSFeatureExtAckToken.Options[0].TDSFeatureExtAckSessionStateOption.Options[4].TDSSessionStateISOFipsOption.TransactionIsolationLevel: ReadCommited
2017-06-17 21:39:48,074: INFO: proc(54): run_and_log_output: Output: [6/17/17 9:39:48 PM] Response.TDSFeatureExtAckToken.Options[0].TDSFeatureExtAckSessionStateOption.Options[4].TDSSessionStateISOFipsOption.StateID: 7
2017-06-17 21:39:48,074: INFO: proc(54): run_and_log_output: Output: [6/17/17 9:39:48 PM] Response.TDSFeatureExtAckToken.Options[0].TDSFeatureExtAckSessionStateOption.Options[5].TDSSessionStateTextSizeOption.Value: -1
2017-06-17 21:39:48,074: INFO: proc(54): run_and_log_output: Output: [6/17/17 9:39:48 PM] Response.TDSFeatureExtAckToken.Options[0].TDSFeatureExtAckSessionStateOption.Options[5].TDSSessionStateTextSizeOption.StateID: 9
2017-06-17 21:39:48,074: INFO: proc(54): run_and_log_output: Output: [6/17/17 9:39:48 PM] Response.TDSFeatureExtAckToken.Options[0].TDSFeatureExtAckSessionStateOption.FeatureID: SessionRecovery
2017-06-17 21:39:48,074: INFO: proc(54): run_and_log_output: Output: [6/17/17 9:39:48 PM] Response.TDSDoneToken.Status: Final
2017-06-17 21:39:48,074: INFO: proc(54): run_and_log_output: Output: [6/17/17 9:39:48 PM] Response.TDSDoneToken.Command: 0
2017-06-17 21:39:48,074: INFO: proc(54): run_and_log_output: Output: [6/17/17 9:39:48 PM] Response.TDSDoneToken.RowCount: 0
2017-06-17 21:39:48,124: INFO: proc(54): run_and_log_output: Output: SqlClient.DiagnosticsTest.ConnectionOpenTest:: Connection Opened 
2017-06-17 21:39:48,135: INFO: proc(54): run_and_log_output: Output: SqlClient.DiagnosticsTest.ConnectionOpenTest:: Connection Should Be Disposed
2017-06-17 21:39:48,135: INFO: proc(54): run_and_log_output: Output: Test: ConnectionOpenTest SqlOperation Successful
2017-06-17 21:39:48,135: INFO: proc(54): run_and_log_output: Output: Test: ConnectionOpenTest Listeners Disabled
2017-06-17 21:39:48,143: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ConnectionOpenTest Connection Closed
2017-06-17 21:39:48,144: INFO: proc(54): run_and_log_output: Output: Test: ConnectionOpenTest Server Disposed
2017-06-17 21:39:48,145: INFO: proc(54): run_and_log_output: Output: Test: ConnectionOpenTest Listeners Disposed Successfully
2017-06-17 21:39:48,145: INFO: proc(54): run_and_log_output: Output: SqlClient.DiagnosticsTest.ConnectionOpenTest:: Done with Diagnostics collection
2017-06-17 21:39:48,812: INFO: proc(54): run_and_log_output: Output: Test: ExecuteReaderWithCommandBehaviorTest Enabled Listeners
2017-06-17 21:39:48,876: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteReaderWithCommandBehaviorTest Is Server Socket Bound: True Testing connectivity to the endpoint created for the server.
2017-06-17 21:39:48,917: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteReaderWithCommandBehaviorTest Endpoint test successful.
2017-06-17 21:39:48,918: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteReaderWithCommandBehaviorTest Listener Thread Started 
2017-06-17 21:39:48,969: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteReaderWithCommandBehaviorTest Connection Closed
2017-06-17 21:39:49,111: INFO: proc(54): run_and_log_output: Output: Test: ExecuteReaderWithCommandBehaviorTest Started Server
2017-06-17 21:39:49,513: INFO: proc(54): run_and_log_output: Output: Test: ExecuteReaderWithCommandBehaviorTest SqlOperation Successful
2017-06-17 21:39:49,513: INFO: proc(54): run_and_log_output: Output: Test: ExecuteReaderWithCommandBehaviorTest Listeners Disabled
2017-06-17 21:39:49,516: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteReaderWithCommandBehaviorTest Connection Closed
2017-06-17 21:39:49,519: INFO: proc(54): run_and_log_output: Output: Test: ExecuteReaderWithCommandBehaviorTest Server Disposed
2017-06-17 21:39:49,520: INFO: proc(54): run_and_log_output: Output: Test: ExecuteReaderWithCommandBehaviorTest Listeners Disposed Successfully
2017-06-17 21:39:49,813: INFO: proc(54): run_and_log_output: Output: Test: ExecuteXmlReaderErrorTest Enabled Listeners
2017-06-17 21:39:49,868: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteXmlReaderErrorTest Is Server Socket Bound: True Testing connectivity to the endpoint created for the server.
2017-06-17 21:39:49,906: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteXmlReaderErrorTest Endpoint test successful.
2017-06-17 21:39:49,907: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteXmlReaderErrorTest Listener Thread Started 
2017-06-17 21:39:49,944: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteXmlReaderErrorTest Connection Closed
2017-06-17 21:39:50,068: INFO: proc(54): run_and_log_output: Output: Test: ExecuteXmlReaderErrorTest Started Server
2017-06-17 21:39:50,137: INFO: proc(54): run_and_log_output: Output: Test: ExecuteXmlReaderErrorTest SqlOperation Successful
2017-06-17 21:39:50,137: INFO: proc(54): run_and_log_output: Output: Test: ExecuteXmlReaderErrorTest Listeners Disabled
2017-06-17 21:39:50,147: INFO: proc(54): run_and_log_output: Output: Test: ExecuteXmlReaderErrorTest Server Disposed
2017-06-17 21:39:50,148: INFO: proc(54): run_and_log_output: Output: Test: ExecuteXmlReaderErrorTest Listeners Disposed Successfully
2017-06-17 21:39:50,798: INFO: proc(54): run_and_log_output: Output: Test: ExecuteScalarTest Enabled Listeners
2017-06-17 21:39:50,852: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteScalarTest Is Server Socket Bound: True Testing connectivity to the endpoint created for the server.
2017-06-17 21:39:50,890: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteScalarTest Endpoint test successful.
2017-06-17 21:39:50,894: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteScalarTest Listener Thread Started 
2017-06-17 21:39:50,926: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteScalarTest Connection Closed
2017-06-17 21:39:51,047: INFO: proc(54): run_and_log_output: Output: Test: ExecuteScalarTest Started Server
2017-06-17 21:39:56,186: INFO: proc(54): run_and_log_output: Output: Exception from RemoteExecutorConsoleApp(System.Data.SqlClient.Tests, Version=4.3.0.0, Culture=neutral, PublicKeyToken=9d77cc7ad39b68eb, System.Data.SqlClient.Tests.DiagnosticTest+<>c, <ExecuteScalarTest>b__3_0):
2017-06-17 21:39:56,187: INFO: proc(54): run_and_log_output: Output: Assembly: System.Data.SqlClient.Tests, Version=4.3.0.0, Culture=neutral, PublicKeyToken=9d77cc7ad39b68eb
2017-06-17 21:39:56,187: INFO: proc(54): run_and_log_output: Output: Type: System.Data.SqlClient.Tests.DiagnosticTest+<>c
2017-06-17 21:39:56,187: INFO: proc(54): run_and_log_output: Output: Method: Int32 <ExecuteScalarTest>b__3_0()
2017-06-17 21:39:56,321: INFO: proc(54): run_and_log_output: Output: Exception: System.Reflection.TargetInvocationException: Exception has been thrown by the target of an invocation. ---> System.Data.SqlClient.SqlException: A network-related or instance-specific error occurred while establishing a connection to SQL Server. The server was not found or was not accessible. Verify that the instance name is correct and that SQL Server is configured to allow remote connections. (provider: TCP Provider, error: 40 - Could not open a connection to SQL Server)
2017-06-17 21:39:56,321: INFO: proc(54): run_and_log_output: Output:    at System.Data.SqlClient.SqlInternalConnectionTds..ctor(DbConnectionPoolIdentity identity, SqlConnectionString connectionOptions, Object providerInfo, Boolean redirectedUserInstance, SqlConnectionString userConnectionOptions, SessionData reconnectSessionData, Boolean applyTransientFaultHandling) in /mnt/j/workspace/dotnet_corefx/master/portable-linux-Config_Release+OuterLoop_false_prtest/src/System.Data.SqlClient/src/System/Data/SqlClient/SqlInternalConnectionTds.cs:line 373
2017-06-17 21:39:56,322: INFO: proc(54): run_and_log_output: Output:    at System.Data.SqlClient.SqlConnectionFactory.CreateConnection(DbConnectionOptions options, DbConnectionPoolKey poolKey, Object poolGroupProviderInfo, DbConnectionPool pool, DbConnection owningConnection, DbConnectionOptions userOptions) in /mnt/j/workspace/dotnet_corefx/master/portable-linux-Config_Release+OuterLoop_false_prtest/src/System.Data.SqlClient/src/System/Data/SqlClient/SqlConnectionFactory.cs:line 136
2017-06-17 21:39:56,322: INFO: proc(54): run_and_log_output: Output:    at System.Data.ProviderBase.DbConnectionFactory.CreatePooledConnection(DbConnectionPool pool, DbConnection owningObject, DbConnectionOptions options, DbConnectionPoolKey poolKey, DbConnectionOptions userOptions) in /mnt/j/workspace/dotnet_corefx/master/portable-linux-Config_Release+OuterLoop_false_prtest/src/System.Data.SqlClient/src/System/Data/ProviderBase/DbConnectionFactory.cs:line 114
2017-06-17 21:39:56,322: INFO: proc(54): run_and_log_output: Output:    at System.Data.ProviderBase.DbConnectionPool.CreateObject(DbConnection owningObject, DbConnectionOptions userOptions, DbConnectionInternal oldConnection) in /mnt/j/workspace/dotnet_corefx/master/portable-linux-Config_Release+OuterLoop_false_prtest/src/System.Data.SqlClient/src/System/Data/ProviderBase/DbConnectionPool.cs:line 476
2017-06-17 21:39:56,322: INFO: proc(54): run_and_log_output: Output:    at System.Data.ProviderBase.DbConnectionPool.UserCreateRequest(DbConnection owningObject, DbConnectionOptions userOptions, DbConnectionInternal oldConnection) in /mnt/j/workspace/dotnet_corefx/master/portable-linux-Config_Release+OuterLoop_false_prtest/src/System.Data.SqlClient/src/System/Data/ProviderBase/DbConnectionPool.cs:line 1187
2017-06-17 21:39:56,322: INFO: proc(54): run_and_log_output: Output:    at System.Data.ProviderBase.DbConnectionPool.TryGetConnection(DbConnection owningObject, UInt32 waitForMultipleObjectsTimeout, Boolean allowCreate, Boolean onlyOneCheckConnection, DbConnectionOptions userOptions, DbConnectionInternal& connection) in /mnt/j/workspace/dotnet_corefx/master/portable-linux-Config_Release+OuterLoop_false_prtest/src/System.Data.SqlClient/src/System/Data/ProviderBase/DbConnectionPool.cs:line 792
2017-06-17 21:39:56,322: INFO: proc(54): run_and_log_output: Output:    at System.Data.ProviderBase.DbConnectionPool.TryGetConnection(DbConnection owningObject, TaskCompletionSource`1 retry, DbConnectionOptions userOptions, DbConnectionInternal& connection) in /mnt/j/workspace/dotnet_corefx/master/portable-linux-Config_Release+OuterLoop_false_prtest/src/System.Data.SqlClient/src/System/Data/ProviderBase/DbConnectionPool.cs:line 711
2017-06-17 21:39:56,322: INFO: proc(54): run_and_log_output: Output:    at System.Data.ProviderBase.DbConnectionFactory.TryGetConnection(DbConnection owningConnection, TaskCompletionSource`1 retry, DbConnectionOptions userOptions, DbConnectionInternal oldConnection, DbConnectionInternal& connection) in /mnt/j/workspace/dotnet_corefx/master/portable-linux-Config_Release+OuterLoop_false_prtest/src/System.Data.SqlClient/src/System/Data/ProviderBase/DbConnectionFactory.cs:line 281
2017-06-17 21:39:56,323: INFO: proc(54): run_and_log_output: Output:    at System.Data.ProviderBase.DbConnectionInternal.TryOpenConnectionInternal(DbConnection outerConnection, DbConnectionFactory connectionFactory, TaskCompletionSource`1 retry, DbConnectionOptions userOptions) in /mnt/j/workspace/dotnet_corefx/master/portable-linux-Config_Release+OuterLoop_false_prtest/src/System.Data.SqlClient/src/System/Data/ProviderBase/DbConnectionInternal.cs:line 453
2017-06-17 21:39:56,323: INFO: proc(54): run_and_log_output: Output:    at System.Data.SqlClient.SqlConnection.TryOpen(TaskCompletionSource`1 retry) in /mnt/j/workspace/dotnet_corefx/master/portable-linux-Config_Release+OuterLoop_false_prtest/src/System.Data.SqlClient/src/System/Data/SqlClient/SqlConnection.cs:line 1062
2017-06-17 21:39:56,323: INFO: proc(54): run_and_log_output: Output:    at System.Data.SqlClient.SqlConnection.Open() in /mnt/j/workspace/dotnet_corefx/master/portable-linux-Config_Release+OuterLoop_false_prtest/src/System.Data.SqlClient/src/System/Data/SqlClient/SqlConnection.cs:line 598
2017-06-17 21:39:56,323: INFO: proc(54): run_and_log_output: Output:    at System.Data.SqlClient.Tests.DiagnosticTest.<>c.<ExecuteScalarTest>b__3_1(String connectionString) in /mnt/j/workspace/dotnet_corefx/master/portable-linux-Config_Release+OuterLoop_false_prtest/src/System.Data.SqlClient/tests/FunctionalTests/DiagnosticTest.cs:line 43
2017-06-17 21:39:56,323: INFO: proc(54): run_and_log_output: Output:    at System.Data.SqlClient.Tests.DiagnosticTest.CollectStatisticsDiagnostics(Action`1 sqlOperation, Boolean enableServerLogging, String methodName) in /mnt/j/workspace/dotnet_corefx/master/portable-linux-Config_Release+OuterLoop_false_prtest/src/System.Data.SqlClient/tests/FunctionalTests/DiagnosticTest.cs:line 705
2017-06-17 21:39:56,323: INFO: proc(54): run_and_log_output: Output:    at System.Data.SqlClient.Tests.DiagnosticTest.<>c.<ExecuteScalarTest>b__3_0() in /mnt/j/workspace/dotnet_corefx/master/portable-linux-Config_Release+OuterLoop_false_prtest/src/System.Data.SqlClient/tests/FunctionalTests/DiagnosticTest.cs:line 46
2017-06-17 21:39:56,323: INFO: proc(54): run_and_log_output: Output:    --- End of inner exception stack trace ---
2017-06-17 21:39:56,323: INFO: proc(54): run_and_log_output: Output:    at System.RuntimeMethodHandle.InvokeMethod(Object target, Object[] arguments, Signature sig, Boolean constructor)
2017-06-17 21:39:56,323: INFO: proc(54): run_and_log_output: Output:    at System.Reflection.RuntimeMethodInfo.UnsafeInvokeInternal(Object obj, Object[] parameters, Object[] arguments)
2017-06-17 21:39:56,323: INFO: proc(54): run_and_log_output: Output:    at RemoteExecutorConsoleApp.Program.Main(String[] args) in /mnt/j/workspace/dotnet_corefx/master/portable-linux-Config_Release+OuterLoop_false_prtest/src/Common/tests/System/Diagnostics/RemoteExecutorConsoleApp/RemoteExecutorConsoleApp.cs:line 51
2017-06-17 21:39:56,324: INFO: proc(54): run_and_log_output: Output: 
2017-06-17 21:39:56,324: INFO: proc(54): run_and_log_output: Output: Unhandled Exception: System.Reflection.TargetInvocationException: Exception has been thrown by the target of an invocation. ---> System.Data.SqlClient.SqlException: A network-related or instance-specific error occurred while establishing a connection to SQL Server. The server was not found or was not accessible. Verify that the instance name is correct and that SQL Server is configured to allow remote connections. (provider: TCP Provider, error: 40 - Could not open a connection to SQL Server)
2017-06-17 21:39:56,324: INFO: proc(54): run_and_log_output: Output:    at System.Data.SqlClient.SqlInternalConnectionTds..ctor(DbConnectionPoolIdentity identity, SqlConnectionString connectionOptions, Object providerInfo, Boolean redirectedUserInstance, SqlConnectionString userConnectionOptions, SessionData reconnectSessionData, Boolean applyTransientFaultHandling) in /mnt/j/workspace/dotnet_corefx/master/portable-linux-Config_Release+OuterLoop_false_prtest/src/System.Data.SqlClient/src/System/Data/SqlClient/SqlInternalConnectionTds.cs:line 373
2017-06-17 21:39:56,324: INFO: proc(54): run_and_log_output: Output:    at System.Data.SqlClient.SqlConnectionFactory.CreateConnection(DbConnectionOptions options, DbConnectionPoolKey poolKey, Object poolGroupProviderInfo, DbConnectionPool pool, DbConnection owningConnection, DbConnectionOptions userOptions) in /mnt/j/workspace/dotnet_corefx/master/portable-linux-Config_Release+OuterLoop_false_prtest/src/System.Data.SqlClient/src/System/Data/SqlClient/SqlConnectionFactory.cs:line 136
2017-06-17 21:39:56,324: INFO: proc(54): run_and_log_output: Output:    at System.Data.ProviderBase.DbConnectionFactory.CreatePooledConnection(DbConnectionPool pool, DbConnection owningObject, DbConnectionOptions options, DbConnectionPoolKey poolKey, DbConnectionOptions userOptions) in /mnt/j/workspace/dotnet_corefx/master/portable-linux-Config_Release+OuterLoop_false_prtest/src/System.Data.SqlClient/src/System/Data/ProviderBase/DbConnectionFactory.cs:line 114
2017-06-17 21:39:56,324: INFO: proc(54): run_and_log_output: Output:    at System.Data.ProviderBase.DbConnectionPool.CreateObject(DbConnection owningObject, DbConnectionOptions userOptions, DbConnectionInternal oldConnection) in /mnt/j/workspace/dotnet_corefx/master/portable-linux-Config_Release+OuterLoop_false_prtest/src/System.Data.SqlClient/src/System/Data/ProviderBase/DbConnectionPool.cs:line 476
2017-06-17 21:39:56,324: INFO: proc(54): run_and_log_output: Output:    at System.Data.ProviderBase.DbConnectionPool.UserCreateRequest(DbConnection owningObject, DbConnectionOptions userOptions, DbConnectionInternal oldConnection) in /mnt/j/workspace/dotnet_corefx/master/portable-linux-Config_Release+OuterLoop_false_prtest/src/System.Data.SqlClient/src/System/Data/ProviderBase/DbConnectionPool.cs:line 1187
2017-06-17 21:39:56,325: INFO: proc(54): run_and_log_output: Output:    at System.Data.ProviderBase.DbConnectionPool.TryGetConnection(DbConnection owningObject, UInt32 waitForMultipleObjectsTimeout, Boolean allowCreate, Boolean onlyOneCheckConnection, DbConnectionOptions userOptions, DbConnectionInternal& connection) in /mnt/j/workspace/dotnet_corefx/master/portable-linux-Config_Release+OuterLoop_false_prtest/src/System.Data.SqlClient/src/System/Data/ProviderBase/DbConnectionPool.cs:line 792
2017-06-17 21:39:56,325: INFO: proc(54): run_and_log_output: Output:    at System.Data.ProviderBase.DbConnectionPool.TryGetConnection(DbConnection owningObject, TaskCompletionSource`1 retry, DbConnectionOptions userOptions, DbConnectionInternal& connection) in /mnt/j/workspace/dotnet_corefx/master/portable-linux-Config_Release+OuterLoop_false_prtest/src/System.Data.SqlClient/src/System/Data/ProviderBase/DbConnectionPool.cs:line 711
2017-06-17 21:39:56,325: INFO: proc(54): run_and_log_output: Output:    at System.Data.ProviderBase.DbConnectionFactory.TryGetConnection(DbConnection owningConnection, TaskCompletionSource`1 retry, DbConnectionOptions userOptions, DbConnectionInternal oldConnection, DbConnectionInternal& connection) in /mnt/j/workspace/dotnet_corefx/master/portable-linux-Config_Release+OuterLoop_false_prtest/src/System.Data.SqlClient/src/System/Data/ProviderBase/DbConnectionFactory.cs:line 281
2017-06-17 21:39:56,325: INFO: proc(54): run_and_log_output: Output:    at System.Data.ProviderBase.DbConnectionInternal.TryOpenConnectionInternal(DbConnection outerConnection, DbConnectionFactory connectionFactory, TaskCompletionSource`1 retry, DbConnectionOptions userOptions) in /mnt/j/workspace/dotnet_corefx/master/portable-linux-Config_Release+OuterLoop_false_prtest/src/System.Data.SqlClient/src/System/Data/ProviderBase/DbConnectionInternal.cs:line 453
2017-06-17 21:39:56,325: INFO: proc(54): run_and_log_output: Output:    at System.Data.SqlClient.SqlConnection.TryOpen(TaskCompletionSource`1 retry) in /mnt/j/workspace/dotnet_corefx/master/portable-linux-Config_Release+OuterLoop_false_prtest/src/System.Data.SqlClient/src/System/Data/SqlClient/SqlConnection.cs:line 1062
2017-06-17 21:39:56,325: INFO: proc(54): run_and_log_output: Output:    at System.Data.SqlClient.SqlConnection.Open() in /mnt/j/workspace/dotnet_corefx/master/portable-linux-Config_Release+OuterLoop_false_prtest/src/System.Data.SqlClient/src/System/Data/SqlClient/SqlConnection.cs:line 598
2017-06-17 21:39:56,325: INFO: proc(54): run_and_log_output: Output:    at System.Data.SqlClient.Tests.DiagnosticTest.<>c.<ExecuteScalarTest>b__3_1(String connectionString) in /mnt/j/workspace/dotnet_corefx/master/portable-linux-Config_Release+OuterLoop_false_prtest/src/System.Data.SqlClient/tests/FunctionalTests/DiagnosticTest.cs:line 43
2017-06-17 21:39:56,325: INFO: proc(54): run_and_log_output: Output:    at System.Data.SqlClient.Tests.DiagnosticTest.CollectStatisticsDiagnostics(Action`1 sqlOperation, Boolean enableServerLogging, String methodName) in /mnt/j/workspace/dotnet_corefx/master/portable-linux-Config_Release+OuterLoop_false_prtest/src/System.Data.SqlClient/tests/FunctionalTests/DiagnosticTest.cs:line 705
2017-06-17 21:39:56,325: INFO: proc(54): run_and_log_output: Output:    at System.Data.SqlClient.Tests.DiagnosticTest.<>c.<ExecuteScalarTest>b__3_0() in /mnt/j/workspace/dotnet_corefx/master/portable-linux-Config_Release+OuterLoop_false_prtest/src/System.Data.SqlClient/tests/FunctionalTests/DiagnosticTest.cs:line 46
2017-06-17 21:39:56,325: INFO: proc(54): run_and_log_output: Output:    --- End of inner exception stack trace ---
2017-06-17 21:39:56,326: INFO: proc(54): run_and_log_output: Output:    at RemoteExecutorConsoleApp.Program.Main(String[] args) in /mnt/j/workspace/dotnet_corefx/master/portable-linux-Config_Release+OuterLoop_false_prtest/src/Common/tests/System/Diagnostics/RemoteExecutorConsoleApp/RemoteExecutorConsoleApp.cs:line 67
2017-06-17 21:39:57,998: INFO: proc(54): run_and_log_output: Output:    System.Data.SqlClient.Tests.DiagnosticTest.ExecuteScalarTest [FAIL]
2017-06-17 21:39:57,998: INFO: proc(54): run_and_log_output: Output:       Assert.Equal() Failure
2017-06-17 21:39:57,998: INFO: proc(54): run_and_log_output: Output:       Expected: 42
2017-06-17 21:39:57,998: INFO: proc(54): run_and_log_output: Output:       Actual:   134
2017-06-17 21:39:58,003: INFO: proc(54): run_and_log_output: Output:       Stack Trace:
2017-06-17 21:39:58,014: INFO: proc(54): run_and_log_output: Output:          /mnt/j/workspace/dotnet_corefx/master/portable-linux-Config_Release+OuterLoop_false_prtest/src/CoreFx.Private.TestUtilities/src/System/Diagnostics/RemoteExecutorTestBase.cs(162,0): at System.Diagnostics.RemoteExecutorTestBase.RemoteInvokeHandle.Dispose()
2017-06-17 21:39:58,094: INFO: proc(54): run_and_log_output: Output: Test: ConnectionOpenErrorTest Enabled Listeners
2017-06-17 21:39:58,151: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ConnectionOpenErrorTest Is Server Socket Bound: True Testing connectivity to the endpoint created for the server.
2017-06-17 21:39:58,188: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ConnectionOpenErrorTest Endpoint test successful.
2017-06-17 21:39:58,190: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ConnectionOpenErrorTest Listener Thread Started 
2017-06-17 21:39:58,224: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ConnectionOpenErrorTest Connection Closed
2017-06-17 21:39:58,339: INFO: proc(54): run_and_log_output: Output: Test: ConnectionOpenErrorTest Started Server
2017-06-17 21:39:59,239: INFO: proc(54): run_and_log_output: Output: Test: ConnectionOpenErrorTest SqlOperation Successful
2017-06-17 21:39:59,240: INFO: proc(54): run_and_log_output: Output: Test: ConnectionOpenErrorTest Listeners Disabled
2017-06-17 21:39:59,246: INFO: proc(54): run_and_log_output: Output: Test: ConnectionOpenErrorTest Server Disposed
2017-06-17 21:39:59,247: INFO: proc(54): run_and_log_output: Output: Test: ConnectionOpenErrorTest Listeners Disposed Successfully
2017-06-17 21:39:59,439: INFO: proc(54): run_and_log_output: Output: Test: ExecuteNonQueryErrorTest Enabled Listeners
2017-06-17 21:39:59,491: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteNonQueryErrorTest Is Server Socket Bound: True Testing connectivity to the endpoint created for the server.
2017-06-17 21:39:59,527: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteNonQueryErrorTest Endpoint test successful.
2017-06-17 21:39:59,530: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteNonQueryErrorTest Listener Thread Started 
2017-06-17 21:39:59,568: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteNonQueryErrorTest Connection Closed
2017-06-17 21:39:59,678: INFO: proc(54): run_and_log_output: Output: Test: ExecuteNonQueryErrorTest Started Server
2017-06-17 21:39:59,994: INFO: proc(54): run_and_log_output: Output: SqlClient.DiagnosticTest.ExecuteNonQueryErrorTest Connection Open Successful
2017-06-17 21:40:00,035: INFO: proc(54): run_and_log_output: Output: SqlClient.DiagnosticTest.ExecuteNonQueryErrorTest Divide by zero error encountered.
2017-06-17 21:40:00,035: INFO: proc(54): run_and_log_output: Output: SqlClient.DiagnosticTest.ExecuteNonQueryErrorTest Command Executed
2017-06-17 21:40:00,036: INFO: proc(54): run_and_log_output: Output: SqlClient.DiagnosticTest.ExecuteNonQueryErrorTest Command Disposed
2017-06-17 21:40:00,044: INFO: proc(54): run_and_log_output: Output: SqlClient.DiagnosticTest.ExecuteNonQueryErrorTest Connection Disposed
2017-06-17 21:40:00,044: INFO: proc(54): run_and_log_output: Output: Test: ExecuteNonQueryErrorTest SqlOperation Successful
2017-06-17 21:40:00,044: INFO: proc(54): run_and_log_output: Output: Test: ExecuteNonQueryErrorTest Listeners Disabled
2017-06-17 21:40:00,050: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteNonQueryErrorTest Connection Closed
2017-06-17 21:40:00,053: INFO: proc(54): run_and_log_output: Output: Test: ExecuteNonQueryErrorTest Server Disposed
2017-06-17 21:40:00,054: INFO: proc(54): run_and_log_output: Output: Test: ExecuteNonQueryErrorTest Listeners Disposed Successfully
2017-06-17 21:40:00,173: INFO: proc(54): run_and_log_output: Output: Test: ExecuteNonQueryTest Enabled Listeners
2017-06-17 21:40:00,225: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteNonQueryTest Is Server Socket Bound: True Testing connectivity to the endpoint created for the server.
2017-06-17 21:40:00,261: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteNonQueryTest Endpoint test successful.
2017-06-17 21:40:00,266: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteNonQueryTest Listener Thread Started 
2017-06-17 21:40:00,295: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteNonQueryTest Connection Closed
2017-06-17 21:40:00,410: INFO: proc(54): run_and_log_output: Output: Test: ExecuteNonQueryTest Started Server
2017-06-17 21:40:00,815: INFO: proc(54): run_and_log_output: Output: Test: ExecuteNonQueryTest SqlOperation Successful
2017-06-17 21:40:00,815: INFO: proc(54): run_and_log_output: Output: Test: ExecuteNonQueryTest Listeners Disabled
2017-06-17 21:40:00,827: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteNonQueryTest Connection Closed
2017-06-17 21:40:00,828: INFO: proc(54): run_and_log_output: Output: Test: ExecuteNonQueryTest Server Disposed
2017-06-17 21:40:00,829: INFO: proc(54): run_and_log_output: Output: Test: ExecuteNonQueryTest Listeners Disposed Successfully
2017-06-17 21:40:01,013: INFO: proc(54): run_and_log_output: Output: Test: ExecuteNonQueryAsyncTest Enabled Listeners
2017-06-17 21:40:01,065: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteNonQueryAsyncTest Is Server Socket Bound: True Testing connectivity to the endpoint created for the server.
2017-06-17 21:40:01,103: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteNonQueryAsyncTest Endpoint test successful.
2017-06-17 21:40:01,114: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteNonQueryAsyncTest Listener Thread Started 
2017-06-17 21:40:01,164: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteNonQueryAsyncTest Connection Closed
2017-06-17 21:40:01,286: INFO: proc(54): run_and_log_output: Output: Test: ExecuteNonQueryAsyncTest Started Server
2017-06-17 21:40:01,707: INFO: proc(54): run_and_log_output: Output: Test: ExecuteNonQueryAsyncTest SqlOperation Successful
2017-06-17 21:40:01,708: INFO: proc(54): run_and_log_output: Output: Test: ExecuteNonQueryAsyncTest Listeners Disabled
2017-06-17 21:40:01,714: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteNonQueryAsyncTest Connection Closed
2017-06-17 21:40:01,720: INFO: proc(54): run_and_log_output: Output: Test: ExecuteNonQueryAsyncTest Server Disposed
2017-06-17 21:40:01,721: INFO: proc(54): run_and_log_output: Output: Test: ExecuteNonQueryAsyncTest Listeners Disposed Successfully
2017-06-17 21:40:01,850: INFO: proc(54): run_and_log_output: Output: Test: ConnectionOpenAsyncErrorTest Enabled Listeners
2017-06-17 21:40:01,901: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ConnectionOpenAsyncErrorTest Is Server Socket Bound: True Testing connectivity to the endpoint created for the server.
2017-06-17 21:40:01,937: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ConnectionOpenAsyncErrorTest Endpoint test successful.
2017-06-17 21:40:01,942: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ConnectionOpenAsyncErrorTest Listener Thread Started 
2017-06-17 21:40:01,974: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ConnectionOpenAsyncErrorTest Connection Closed
2017-06-17 21:40:02,090: INFO: proc(54): run_and_log_output: Output: Test: ConnectionOpenAsyncErrorTest Started Server
2017-06-17 21:40:03,076: INFO: proc(54): run_and_log_output: Output: Test: ConnectionOpenAsyncErrorTest SqlOperation Successful
2017-06-17 21:40:03,077: INFO: proc(54): run_and_log_output: Output: Test: ConnectionOpenAsyncErrorTest Listeners Disabled
2017-06-17 21:40:03,085: INFO: proc(54): run_and_log_output: Output: Test: ConnectionOpenAsyncErrorTest Server Disposed
2017-06-17 21:40:03,086: INFO: proc(54): run_and_log_output: Output: Test: ConnectionOpenAsyncErrorTest Listeners Disposed Successfully
2017-06-17 21:40:03,197: INFO: proc(54): run_and_log_output: Output: Test: ExecuteReaderAsyncTest Enabled Listeners
2017-06-17 21:40:03,249: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteReaderAsyncTest Is Server Socket Bound: True Testing connectivity to the endpoint created for the server.
2017-06-17 21:40:03,285: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteReaderAsyncTest Endpoint test successful.
2017-06-17 21:40:03,286: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteReaderAsyncTest Listener Thread Started 
2017-06-17 21:40:03,320: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteReaderAsyncTest Connection Closed
2017-06-17 21:40:03,434: INFO: proc(54): run_and_log_output: Output: Test: ExecuteReaderAsyncTest Started Server
2017-06-17 21:40:03,875: INFO: proc(54): run_and_log_output: Output: Test: ExecuteReaderAsyncTest SqlOperation Successful
2017-06-17 21:40:03,875: INFO: proc(54): run_and_log_output: Output: Test: ExecuteReaderAsyncTest Listeners Disabled
2017-06-17 21:40:03,883: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteReaderAsyncTest Connection Closed
2017-06-17 21:40:03,883: INFO: proc(54): run_and_log_output: Output: Test: ExecuteReaderAsyncTest Server Disposed
2017-06-17 21:40:03,884: INFO: proc(54): run_and_log_output: Output: Test: ExecuteReaderAsyncTest Listeners Disposed Successfully
2017-06-17 21:40:04,030: INFO: proc(54): run_and_log_output: Output: Test: ExecuteReaderTest Enabled Listeners
2017-06-17 21:40:04,083: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteReaderTest Is Server Socket Bound: True Testing connectivity to the endpoint created for the server.
2017-06-17 21:40:04,120: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteReaderTest Endpoint test successful.
2017-06-17 21:40:04,122: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteReaderTest Listener Thread Started 
2017-06-17 21:40:04,157: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteReaderTest Connection Closed
2017-06-17 21:40:04,276: INFO: proc(54): run_and_log_output: Output: Test: ExecuteReaderTest Started Server
2017-06-17 21:40:04,688: INFO: proc(54): run_and_log_output: Output: Test: ExecuteReaderTest SqlOperation Successful
2017-06-17 21:40:04,688: INFO: proc(54): run_and_log_output: Output: Test: ExecuteReaderTest Listeners Disabled
2017-06-17 21:40:04,692: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteReaderTest Connection Closed
2017-06-17 21:40:04,698: INFO: proc(54): run_and_log_output: Output: Test: ExecuteReaderTest Server Disposed
2017-06-17 21:40:04,698: INFO: proc(54): run_and_log_output: Output: Test: ExecuteReaderTest Listeners Disposed Successfully
2017-06-17 21:40:04,774: INFO: proc(54): run_and_log_output: Output:    System.Data.SqlClient.Tests.DiagnosticTest.ExecuteXmlReaderTest [SKIP]
2017-06-17 21:40:04,774: INFO: proc(54): run_and_log_output: Output:       Condition(s) not met: \"IsConnectionStringConfigured\"
2017-06-17 21:40:04,869: INFO: proc(54): run_and_log_output: Output: Test: ExecuteNonQueryAsyncErrorTest Enabled Listeners
2017-06-17 21:40:04,921: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteNonQueryAsyncErrorTest Is Server Socket Bound: True Testing connectivity to the endpoint created for the server.
2017-06-17 21:40:04,958: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteNonQueryAsyncErrorTest Endpoint test successful.
2017-06-17 21:40:04,962: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteNonQueryAsyncErrorTest Listener Thread Started 
2017-06-17 21:40:04,994: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteNonQueryAsyncErrorTest Connection Closed
2017-06-17 21:40:05,120: INFO: proc(54): run_and_log_output: Output: Test: ExecuteNonQueryAsyncErrorTest Started Server
2017-06-17 21:40:05,537: INFO: proc(54): run_and_log_output: Output: Test: ExecuteNonQueryAsyncErrorTest SqlOperation Successful
2017-06-17 21:40:05,537: INFO: proc(54): run_and_log_output: Output: Test: ExecuteNonQueryAsyncErrorTest Listeners Disabled
2017-06-17 21:40:05,545: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteNonQueryAsyncErrorTest Connection Closed
2017-06-17 21:40:05,546: INFO: proc(54): run_and_log_output: Output: Test: ExecuteNonQueryAsyncErrorTest Server Disposed
2017-06-17 21:40:05,547: INFO: proc(54): run_and_log_output: Output: Test: ExecuteNonQueryAsyncErrorTest Listeners Disposed Successfully
2017-06-17 21:40:05,611: INFO: proc(54): run_and_log_output: Output:    System.Data.SqlClient.Tests.DiagnosticTest.ExecuteXmlReaderAsyncTest [SKIP]
2017-06-17 21:40:05,611: INFO: proc(54): run_and_log_output: Output:       Condition(s) not met: \"IsConnectionStringConfigured\"
2017-06-17 21:40:05,704: INFO: proc(54): run_and_log_output: Output: Test: ExecuteReaderAsyncErrorTest Enabled Listeners
2017-06-17 21:40:05,756: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteReaderAsyncErrorTest Is Server Socket Bound: True Testing connectivity to the endpoint created for the server.
2017-06-17 21:40:05,792: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteReaderAsyncErrorTest Endpoint test successful.
2017-06-17 21:40:05,793: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteReaderAsyncErrorTest Listener Thread Started 
2017-06-17 21:40:05,829: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteReaderAsyncErrorTest Connection Closed
2017-06-17 21:40:05,942: INFO: proc(54): run_and_log_output: Output: Test: ExecuteReaderAsyncErrorTest Started Server
2017-06-17 21:40:06,010: INFO: proc(54): run_and_log_output: Output: Test: ExecuteReaderAsyncErrorTest SqlOperation Successful
2017-06-17 21:40:06,010: INFO: proc(54): run_and_log_output: Output: Test: ExecuteReaderAsyncErrorTest Listeners Disabled
2017-06-17 21:40:06,022: INFO: proc(54): run_and_log_output: Output: Test: ExecuteReaderAsyncErrorTest Server Disposed
2017-06-17 21:40:06,022: INFO: proc(54): run_and_log_output: Output: Test: ExecuteReaderAsyncErrorTest Listeners Disposed Successfully
2017-06-17 21:40:06,142: INFO: proc(54): run_and_log_output: Output: Test: ConnectionOpenAsyncTest Enabled Listeners
2017-06-17 21:40:06,194: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ConnectionOpenAsyncTest Is Server Socket Bound: True Testing connectivity to the endpoint created for the server.
2017-06-17 21:40:06,231: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ConnectionOpenAsyncTest Endpoint test successful.
2017-06-17 21:40:06,234: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ConnectionOpenAsyncTest Listener Thread Started 
2017-06-17 21:40:06,267: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ConnectionOpenAsyncTest Connection Closed
2017-06-17 21:40:06,387: INFO: proc(54): run_and_log_output: Output: Test: ConnectionOpenAsyncTest Started Server
2017-06-17 21:40:06,718: INFO: proc(54): run_and_log_output: Output: Test: ConnectionOpenAsyncTest SqlOperation Successful
2017-06-17 21:40:06,718: INFO: proc(54): run_and_log_output: Output: Test: ConnectionOpenAsyncTest Listeners Disabled
2017-06-17 21:40:06,728: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ConnectionOpenAsyncTest Connection Closed
2017-06-17 21:40:06,728: INFO: proc(54): run_and_log_output: Output: Test: ConnectionOpenAsyncTest Server Disposed
2017-06-17 21:40:06,729: INFO: proc(54): run_and_log_output: Output: Test: ConnectionOpenAsyncTest Listeners Disposed Successfully
2017-06-17 21:40:06,877: INFO: proc(54): run_and_log_output: Output: Test: ExecuteScalarAsyncTest Enabled Listeners
2017-06-17 21:40:06,928: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteScalarAsyncTest Is Server Socket Bound: True Testing connectivity to the endpoint created for the server.
2017-06-17 21:40:06,964: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteScalarAsyncTest Endpoint test successful.
2017-06-17 21:40:06,966: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteScalarAsyncTest Listener Thread Started 
2017-06-17 21:40:07,002: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteScalarAsyncTest Connection Closed
2017-06-17 21:40:07,114: INFO: proc(54): run_and_log_output: Output: Test: ExecuteScalarAsyncTest Started Server
2017-06-17 21:40:07,567: INFO: proc(54): run_and_log_output: Output: Test: ExecuteScalarAsyncTest SqlOperation Successful
2017-06-17 21:40:07,568: INFO: proc(54): run_and_log_output: Output: Test: ExecuteScalarAsyncTest Listeners Disabled
2017-06-17 21:40:07,573: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteScalarAsyncTest Connection Closed
2017-06-17 21:40:07,577: INFO: proc(54): run_and_log_output: Output: Test: ExecuteScalarAsyncTest Server Disposed
2017-06-17 21:40:07,578: INFO: proc(54): run_and_log_output: Output: Test: ExecuteScalarAsyncTest Listeners Disposed Successfully
2017-06-17 21:40:07,624: INFO: proc(54): run_and_log_output: Output: Finished:    System.Data.SqlClient.Tests
2017-06-17 21:40:07,632: INFO: proc(54): run_and_log_output: Output: 
2017-06-17 21:40:07,632: INFO: proc(54): run_and_log_output: Output: === TEST EXECUTION SUMMARY ===
2017-06-17 21:40:07,645: INFO: proc(54): run_and_log_output: Output:    System.Data.SqlClient.Tests  Total: 123, Errors: 0, Failed: 1, Skipped: 3, Time: 24.691s

danmoseley · 2017-06-19T01:55:53Z

@saurabh500 here's another, different test, on Centos

2017-06-19 00:45:46,237: INFO: proc(54): run_and_log_output: Output: Discovering: System.Data.SqlClient.Tests
2017-06-19 00:45:46,421: INFO: proc(54): run_and_log_output: Output: Discovered:  System.Data.SqlClient.Tests
2017-06-19 00:45:46,507: INFO: proc(54): run_and_log_output: Output: Starting:    System.Data.SqlClient.Tests
2017-06-19 00:45:54,730: INFO: proc(54): run_and_log_output: Output: Test: ExecuteReaderErrorTest Enabled Listeners
2017-06-19 00:45:54,779: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteReaderErrorTest Is Server Socket Bound: True Testing connectivity to the endpoint created for the server.
2017-06-19 00:45:54,811: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteReaderErrorTest Endpoint test successful.
2017-06-19 00:45:54,815: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteReaderErrorTest Listener Thread Started 
2017-06-19 00:45:54,842: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteReaderErrorTest Connection Closed
2017-06-19 00:45:54,939: INFO: proc(54): run_and_log_output: Output: Test: ExecuteReaderErrorTest Started Server
2017-06-19 00:45:55,001: INFO: proc(54): run_and_log_output: Output: Test: ExecuteReaderErrorTest SqlOperation Successful
2017-06-19 00:45:55,002: INFO: proc(54): run_and_log_output: Output: Test: ExecuteReaderErrorTest Listeners Disabled
2017-06-19 00:45:55,009: INFO: proc(54): run_and_log_output: Output: Test: ExecuteReaderErrorTest Server Disposed
2017-06-19 00:45:55,009: INFO: proc(54): run_and_log_output: Output: Test: ExecuteReaderErrorTest Listeners Disposed Successfully
2017-06-19 00:45:55,217: INFO: proc(54): run_and_log_output: Output: Test: ExecuteScalarAsyncErrorTest Enabled Listeners
2017-06-19 00:45:55,265: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteScalarAsyncErrorTest Is Server Socket Bound: True Testing connectivity to the endpoint created for the server.
2017-06-19 00:45:55,297: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteScalarAsyncErrorTest Endpoint test successful.
2017-06-19 00:45:55,298: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteScalarAsyncErrorTest Listener Thread Started 
2017-06-19 00:45:55,328: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteScalarAsyncErrorTest Connection Closed
2017-06-19 00:45:55,399: INFO: proc(54): run_and_log_output: Output: Test: ExecuteScalarAsyncErrorTest Started Server
2017-06-19 00:45:55,748: INFO: proc(54): run_and_log_output: Output: Test: ExecuteScalarAsyncErrorTest SqlOperation Successful
2017-06-19 00:45:55,749: INFO: proc(54): run_and_log_output: Output: Test: ExecuteScalarAsyncErrorTest Listeners Disabled
2017-06-19 00:45:55,752: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteScalarAsyncErrorTest Connection Closed
2017-06-19 00:45:55,759: INFO: proc(54): run_and_log_output: Output: Test: ExecuteScalarAsyncErrorTest Server Disposed
2017-06-19 00:45:55,759: INFO: proc(54): run_and_log_output: Output: Test: ExecuteScalarAsyncErrorTest Listeners Disposed Successfully
2017-06-19 00:45:55,853: INFO: proc(54): run_and_log_output: Output:    System.Data.SqlClient.Tests.DiagnosticTest.ExecuteXmlReaderAsyncErrorTest [SKIP]
2017-06-19 00:45:55,853: INFO: proc(54): run_and_log_output: Output:       Condition(s) not met: \"IsConnectionStringConfigured\"
2017-06-19 00:45:55,997: INFO: proc(54): run_and_log_output: Output: Test: ExecuteScalarErrorTest Enabled Listeners
2017-06-19 00:45:56,058: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteScalarErrorTest Is Server Socket Bound: True Testing connectivity to the endpoint created for the server.
2017-06-19 00:45:56,098: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteScalarErrorTest Endpoint test successful.
2017-06-19 00:45:56,099: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteScalarErrorTest Listener Thread Started 
2017-06-19 00:45:56,132: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteScalarErrorTest Connection Closed
2017-06-19 00:45:56,225: INFO: proc(54): run_and_log_output: Output: Test: ExecuteScalarErrorTest Started Server
2017-06-19 00:45:56,380: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ConnectionTest Is Server Socket Bound: True Testing connectivity to the endpoint created for the server.
2017-06-19 00:45:56,402: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ConnectionTest Endpoint test successful.
2017-06-19 00:45:56,414: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ConnectionTest Listener Thread Started 
2017-06-19 00:45:56,462: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ConnectionTest Connection Closed
2017-06-19 00:45:56,561: INFO: proc(54): run_and_log_output: Output: Test: ExecuteScalarErrorTest SqlOperation Successful
2017-06-19 00:45:56,561: INFO: proc(54): run_and_log_output: Output: Test: ExecuteScalarErrorTest Listeners Disabled
2017-06-19 00:45:56,564: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteScalarErrorTest Connection Closed
2017-06-19 00:45:56,565: INFO: proc(54): run_and_log_output: Output: Test: ExecuteScalarErrorTest Server Disposed
2017-06-19 00:45:56,566: INFO: proc(54): run_and_log_output: Output: Test: ExecuteScalarErrorTest Listeners Disposed Successfully
2017-06-19 00:45:56,655: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ConnectionTest Connection Closed
2017-06-19 00:45:56,718: INFO: proc(54): run_and_log_output: Output: Test: ConnectionOpenTest Enabled Listeners
2017-06-19 00:45:56,767: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ConnectionOpenTest Is Server Socket Bound: True Testing connectivity to the endpoint created for the server.
2017-06-19 00:45:56,800: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ConnectionOpenTest Endpoint test successful.
2017-06-19 00:45:56,801: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ConnectionOpenTest Listener Thread Started 
2017-06-19 00:45:56,830: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ConnectionOpenTest Connection Closed
2017-06-19 00:45:56,925: INFO: proc(54): run_and_log_output: Output: Test: ConnectionOpenTest Started Server
2017-06-19 00:45:57,131: INFO: proc(54): run_and_log_output: Output: [6/19/17 12:45:57 AM] Request.TDSPreLoginToken.Version: 4.6.25419
2017-06-19 00:45:57,131: INFO: proc(54): run_and_log_output: Output: [6/19/17 12:45:57 AM] Request.TDSPreLoginToken.SubBuild: 1
2017-06-19 00:45:57,131: INFO: proc(54): run_and_log_output: Output: [6/19/17 12:45:57 AM] Request.TDSPreLoginToken.Encryption: Off
2017-06-19 00:45:57,138: INFO: proc(54): run_and_log_output: Output: [6/19/17 12:45:57 AM] Request.TDSPreLoginToken.ThreadID: 33554432
2017-06-19 00:45:57,138: INFO: proc(54): run_and_log_output: Output: [6/19/17 12:45:57 AM] Request.TDSPreLoginToken.IsMARS: False
2017-06-19 00:45:57,138: INFO: proc(54): run_and_log_output: Output: [6/19/17 12:45:57 AM] Request.TDSPreLoginToken.ClientTraceID: [21 80 F 67 F7 2 8A 44 AA 90 3 91 95 79 76 5C ]
2017-06-19 00:45:57,138: INFO: proc(54): run_and_log_output: Output: [6/19/17 12:45:57 AM] Request.TDSPreLoginToken.ActivityID: [EE E7 7B A6 3F C4 38 44 95 FE EC 92 EF BE 47 9A 1 0 0 0 ]
2017-06-19 00:45:57,138: INFO: proc(54): run_and_log_output: Output: [6/19/17 12:45:57 AM] Request.TDSPreLoginToken.Nonce: <null>
2017-06-19 00:45:57,138: INFO: proc(54): run_and_log_output: Output: [6/19/17 12:45:57 AM] Request.TDSPreLoginToken.FedAuthRequired: FedAuthNotRequired
2017-06-19 00:45:57,138: INFO: proc(54): run_and_log_output: Output: [6/19/17 12:45:57 AM] Response.TDSPreLoginToken.Version: 11.0.1083
2017-06-19 00:45:57,138: INFO: proc(54): run_and_log_output: Output: [6/19/17 12:45:57 AM] Response.TDSPreLoginToken.SubBuild: 0
2017-06-19 00:45:57,138: INFO: proc(54): run_and_log_output: Output: [6/19/17 12:45:57 AM] Response.TDSPreLoginToken.Encryption: NotSupported
2017-06-19 00:45:57,138: INFO: proc(54): run_and_log_output: Output: [6/19/17 12:45:57 AM] Response.TDSPreLoginToken.ThreadID: 0
2017-06-19 00:45:57,139: INFO: proc(54): run_and_log_output: Output: [6/19/17 12:45:57 AM] Response.TDSPreLoginToken.IsMARS: False
2017-06-19 00:45:57,139: INFO: proc(54): run_and_log_output: Output: [6/19/17 12:45:57 AM] Response.TDSPreLoginToken.ClientTraceID: <null>
2017-06-19 00:45:57,139: INFO: proc(54): run_and_log_output: Output: [6/19/17 12:45:57 AM] Response.TDSPreLoginToken.ActivityID: <null>
2017-06-19 00:45:57,139: INFO: proc(54): run_and_log_output: Output: [6/19/17 12:45:57 AM] Response.TDSPreLoginToken.Nonce: <null>
2017-06-19 00:45:57,139: INFO: proc(54): run_and_log_output: Output: [6/19/17 12:45:57 AM] Response.TDSPreLoginToken.FedAuthRequired: FedAuthNotRequired
2017-06-19 00:45:57,180: INFO: proc(54): run_and_log_output: Output: [6/19/17 12:45:57 AM] Request.TDSLogin7Token.TDSVersion: 7.4.0.4
2017-06-19 00:45:57,180: INFO: proc(54): run_and_log_output: Output: [6/19/17 12:45:57 AM] Request.TDSLogin7Token.PacketSize: 8000
2017-06-19 00:45:57,180: INFO: proc(54): run_and_log_output: Output: [6/19/17 12:45:57 AM] Request.TDSLogin7Token.ClientProgramVersion: 100663296
2017-06-19 00:45:57,180: INFO: proc(54): run_and_log_output: Output: [6/19/17 12:45:57 AM] Request.TDSLogin7Token.ClientPID: 335085177
2017-06-19 00:45:57,180: INFO: proc(54): run_and_log_output: Output: [6/19/17 12:45:57 AM] Request.TDSLogin7Token.ConnectionID: 0
2017-06-19 00:45:57,180: INFO: proc(54): run_and_log_output: Output: [6/19/17 12:45:57 AM] Request.TDSLogin7Token.OptionalFlags1.TDSLogin7TokenOptionalFlags1.Order: OrderX86
2017-06-19 00:45:57,180: INFO: proc(54): run_and_log_output: Output: [6/19/17 12:45:57 AM] Request.TDSLogin7Token.OptionalFlags1.TDSLogin7TokenOptionalFlags1.CharacterSet: Ascii
2017-06-19 00:45:57,180: INFO: proc(54): run_and_log_output: Output: [6/19/17 12:45:57 AM] Request.TDSLogin7Token.OptionalFlags1.TDSLogin7TokenOptionalFlags1.FloatingPoint: IEEE754
2017-06-19 00:45:57,180: INFO: proc(54): run_and_log_output: Output: [6/19/17 12:45:57 AM] Request.TDSLogin7Token.OptionalFlags1.TDSLogin7TokenOptionalFlags1.DumpLoad: On
2017-06-19 00:45:57,180: INFO: proc(54): run_and_log_output: Output: [6/19/17 12:45:57 AM] Request.TDSLogin7Token.OptionalFlags1.TDSLogin7TokenOptionalFlags1.UseDB: Off
2017-06-19 00:45:57,180: INFO: proc(54): run_and_log_output: Output: [6/19/17 12:45:57 AM] Request.TDSLogin7Token.OptionalFlags1.TDSLogin7TokenOptionalFlags1.Database: Fatal
2017-06-19 00:45:57,181: INFO: proc(54): run_and_log_output: Output: [6/19/17 12:45:57 AM] Request.TDSLogin7Token.OptionalFlags1.TDSLogin7TokenOptionalFlags1.Language: On
2017-06-19 00:45:57,181: INFO: proc(54): run_and_log_output: Output: [6/19/17 12:45:57 AM] Request.TDSLogin7Token.OptionalFlags2.TDSLogin7TokenOptionalFlags2.Language: Fatal
2017-06-19 00:45:57,181: INFO: proc(54): run_and_log_output: Output: [6/19/17 12:45:57 AM] Request.TDSLogin7Token.OptionalFlags2.TDSLogin7TokenOptionalFlags2.Odbc: On
2017-06-19 00:45:57,181: INFO: proc(54): run_and_log_output: Output: [6/19/17 12:45:57 AM] Request.TDSLogin7Token.OptionalFlags2.TDSLogin7TokenOptionalFlags2.UserType: Normal
2017-06-19 00:45:57,181: INFO: proc(54): run_and_log_output: Output: [6/19/17 12:45:57 AM] Request.TDSLogin7Token.OptionalFlags2.TDSLogin7TokenOptionalFlags2.IntegratedSecurity: Off
2017-06-19 00:45:57,181: INFO: proc(54): run_and_log_output: Output: [6/19/17 12:45:57 AM] Request.TDSLogin7Token.OptionalFlags3.TDSLogin7TokenOptionalFlags3.ChangePassword: No
2017-06-19 00:45:57,181: INFO: proc(54): run_and_log_output: Output: [6/19/17 12:45:57 AM] Request.TDSLogin7Token.OptionalFlags3.TDSLogin7TokenOptionalFlags3.IsUserInstance: False
2017-06-19 00:45:57,181: INFO: proc(54): run_and_log_output: Output: [6/19/17 12:45:57 AM] Request.TDSLogin7Token.OptionalFlags3.TDSLogin7TokenOptionalFlags3.SendYukonBinaryXML: False
2017-06-19 00:45:57,181: INFO: proc(54): run_and_log_output: Output: [6/19/17 12:45:57 AM] Request.TDSLogin7Token.OptionalFlags3.TDSLogin7TokenOptionalFlags3.UnknownCollation: Must
2017-06-19 00:45:57,181: INFO: proc(54): run_and_log_output: Output: [6/19/17 12:45:57 AM] Request.TDSLogin7Token.OptionalFlags3.TDSLogin7TokenOptionalFlags3.ExtensionFlag: True
2017-06-19 00:45:57,181: INFO: proc(54): run_and_log_output: Output: [6/19/17 12:45:57 AM] Request.TDSLogin7Token.TypeFlags.TDSLogin7TokenTypeFlags.SQL: Default
2017-06-19 00:45:57,182: INFO: proc(54): run_and_log_output: Output: [6/19/17 12:45:57 AM] Request.TDSLogin7Token.TypeFlags.TDSLogin7TokenTypeFlags.OleDb: Off
2017-06-19 00:45:57,182: INFO: proc(54): run_and_log_output: Output: [6/19/17 12:45:57 AM] Request.TDSLogin7Token.TypeFlags.TDSLogin7TokenTypeFlags.ReadOnlyIntent: ReadWrite
2017-06-19 00:45:57,182: INFO: proc(54): run_and_log_output: Output: [6/19/17 12:45:57 AM] Request.TDSLogin7Token.ClientTimeZone: 0
2017-06-19 00:45:57,182: INFO: proc(54): run_and_log_output: Output: [6/19/17 12:45:57 AM] Request.TDSLogin7Token.ClientLCID: 0
2017-06-19 00:45:57,182: INFO: proc(54): run_and_log_output: Output: [6/19/17 12:45:57 AM] Request.TDSLogin7Token.HostName: dnblcp07300023T
2017-06-19 00:45:57,182: INFO: proc(54): run_and_log_output: Output: [6/19/17 12:45:57 AM] Request.TDSLogin7Token.UserID: <null>
2017-06-19 00:45:57,182: INFO: proc(54): run_and_log_output: Output: [6/19/17 12:45:57 AM] Request.TDSLogin7Token.Password: <null>
2017-06-19 00:45:57,182: INFO: proc(54): run_and_log_output: Output: [6/19/17 12:45:57 AM] Request.TDSLogin7Token.ApplicationName: Core .Net SqlClient Data Provider
2017-06-19 00:45:57,182: INFO: proc(54): run_and_log_output: Output: [6/19/17 12:45:57 AM] Request.TDSLogin7Token.ServerName: localhost,35574
2017-06-19 00:45:57,182: INFO: proc(54): run_and_log_output: Output: [6/19/17 12:45:57 AM] Request.TDSLogin7Token.LibraryName: Core .Net SqlClient Data Provider
2017-06-19 00:45:57,182: INFO: proc(54): run_and_log_output: Output: [6/19/17 12:45:57 AM] Request.TDSLogin7Token.Language: <null>
2017-06-19 00:45:57,182: INFO: proc(54): run_and_log_output: Output: [6/19/17 12:45:57 AM] Request.TDSLogin7Token.Database: <null>
2017-06-19 00:45:57,182: INFO: proc(54): run_and_log_output: Output: [6/19/17 12:45:57 AM] Request.TDSLogin7Token.ClientID: [3A 97 86 A4 DF CF ]
2017-06-19 00:45:57,182: INFO: proc(54): run_and_log_output: Output: [6/19/17 12:45:57 AM] Request.TDSLogin7Token.AttachDatabaseFile: <null>
2017-06-19 00:45:57,183: INFO: proc(54): run_and_log_output: Output: [6/19/17 12:45:57 AM] Request.TDSLogin7Token.ChangePassword: <null>
2017-06-19 00:45:57,183: INFO: proc(54): run_and_log_output: Output: [6/19/17 12:45:57 AM] Request.TDSLogin7Token.SSPI: <null>
2017-06-19 00:45:57,183: INFO: proc(54): run_and_log_output: Output: [6/19/17 12:45:57 AM] Request.TDSLogin7Token.FeatureExt[0].TDSLogin7SessionRecoveryOptionToken.FeatureID: SessionRecovery
2017-06-19 00:45:57,183: INFO: proc(54): run_and_log_output: Output: [6/19/17 12:45:57 AM] Request.TDSLogin7Token.FeatureExt[0].TDSLogin7SessionRecoveryOptionToken.Initial: <null>
2017-06-19 00:45:57,183: INFO: proc(54): run_and_log_output: Output: [6/19/17 12:45:57 AM] Request.TDSLogin7Token.FeatureExt[0].TDSLogin7SessionRecoveryOptionToken.Current: <null>
2017-06-19 00:45:57,190: INFO: proc(54): run_and_log_output: Output: [6/19/17 12:45:57 AM] Response.TDSEnvChangeToken.Type: Database
2017-06-19 00:45:57,190: INFO: proc(54): run_and_log_output: Output: [6/19/17 12:45:57 AM] Response.TDSEnvChangeToken.OldValue: master
2017-06-19 00:45:57,190: INFO: proc(54): run_and_log_output: Output: [6/19/17 12:45:57 AM] Response.TDSEnvChangeToken.NewValue: master
2017-06-19 00:45:57,190: INFO: proc(54): run_and_log_output: Output: [6/19/17 12:45:57 AM] Response.TDSInfoToken.Number: 5701
2017-06-19 00:45:57,190: INFO: proc(54): run_and_log_output: Output: [6/19/17 12:45:57 AM] Response.TDSInfoToken.State: 2
2017-06-19 00:45:57,190: INFO: proc(54): run_and_log_output: Output: [6/19/17 12:45:57 AM] Response.TDSInfoToken.Class: 0
2017-06-19 00:45:57,190: INFO: proc(54): run_and_log_output: Output: [6/19/17 12:45:57 AM] Response.TDSInfoToken.Message: Changed database context to 'master'
2017-06-19 00:45:57,190: INFO: proc(54): run_and_log_output: Output: [6/19/17 12:45:57 AM] Response.TDSInfoToken.ServerName: dnblcp07300023T
2017-06-19 00:45:57,190: INFO: proc(54): run_and_log_output: Output: [6/19/17 12:45:57 AM] Response.TDSInfoToken.ProcedureName: <null>
2017-06-19 00:45:57,190: INFO: proc(54): run_and_log_output: Output: [6/19/17 12:45:57 AM] Response.TDSInfoToken.Line: 0
2017-06-19 00:45:57,191: INFO: proc(54): run_and_log_output: Output: [6/19/17 12:45:57 AM] Response.TDSEnvChangeToken.Type: SQLCollation
2017-06-19 00:45:57,191: INFO: proc(54): run_and_log_output: Output: [6/19/17 12:45:57 AM] Response.TDSEnvChangeToken.OldValue: <null>
2017-06-19 00:45:57,191: INFO: proc(54): run_and_log_output: Output: [6/19/17 12:45:57 AM] Response.TDSEnvChangeToken.NewValue: [9 4 D0 0 34 ]
2017-06-19 00:45:57,191: INFO: proc(54): run_and_log_output: Output: [6/19/17 12:45:57 AM] Response.TDSEnvChangeToken.Type: Language
2017-06-19 00:45:57,191: INFO: proc(54): run_and_log_output: Output: [6/19/17 12:45:57 AM] Response.TDSEnvChangeToken.OldValue: <null>
2017-06-19 00:45:57,191: INFO: proc(54): run_and_log_output: Output: [6/19/17 12:45:57 AM] Response.TDSEnvChangeToken.NewValue: us_english
2017-06-19 00:45:57,191: INFO: proc(54): run_and_log_output: Output: [6/19/17 12:45:57 AM] Response.TDSInfoToken.Number: 5703
2017-06-19 00:45:57,191: INFO: proc(54): run_and_log_output: Output: [6/19/17 12:45:57 AM] Response.TDSInfoToken.State: 1
2017-06-19 00:45:57,191: INFO: proc(54): run_and_log_output: Output: [6/19/17 12:45:57 AM] Response.TDSInfoToken.Class: 0
2017-06-19 00:45:57,191: INFO: proc(54): run_and_log_output: Output: [6/19/17 12:45:57 AM] Response.TDSInfoToken.Message: Changed language setting to us_english
2017-06-19 00:45:57,191: INFO: proc(54): run_and_log_output: Output: [6/19/17 12:45:57 AM] Response.TDSInfoToken.ServerName: dnblcp07300023T
2017-06-19 00:45:57,191: INFO: proc(54): run_and_log_output: Output: [6/19/17 12:45:57 AM] Response.TDSInfoToken.ProcedureName: <null>
2017-06-19 00:45:57,191: INFO: proc(54): run_and_log_output: Output: [6/19/17 12:45:57 AM] Response.TDSInfoToken.Line: 0
2017-06-19 00:45:57,192: INFO: proc(54): run_and_log_output: Output: [6/19/17 12:45:57 AM] Response.TDSEnvChangeToken.Type: PacketSize
2017-06-19 00:45:57,192: INFO: proc(54): run_and_log_output: Output: [6/19/17 12:45:57 AM] Response.TDSEnvChangeToken.OldValue: 4096
2017-06-19 00:45:57,192: INFO: proc(54): run_and_log_output: Output: [6/19/17 12:45:57 AM] Response.TDSEnvChangeToken.NewValue: 4096
2017-06-19 00:45:57,192: INFO: proc(54): run_and_log_output: Output: [6/19/17 12:45:57 AM] Response.TDSLoginAckToken.TDSVersion: 7.4.0.4
2017-06-19 00:45:57,192: INFO: proc(54): run_and_log_output: Output: [6/19/17 12:45:57 AM] Response.TDSLoginAckToken.Interface: SQL
2017-06-19 00:45:57,192: INFO: proc(54): run_and_log_output: Output: [6/19/17 12:45:57 AM] Response.TDSLoginAckToken.ServerName: Microsoft SQL Server
2017-06-19 00:45:57,192: INFO: proc(54): run_and_log_output: Output: [6/19/17 12:45:57 AM] Response.TDSLoginAckToken.ServerVersion: 11.0.1083
2017-06-19 00:45:57,203: INFO: proc(54): run_and_log_output: Output: [6/19/17 12:45:57 AM] Response.TDSFeatureExtAckToken.Options[0].TDSFeatureExtAckSessionStateOption.Options[0].TDSSessionStateUserOptionsOption.AnsiNullDefaultOn: False
2017-06-19 00:45:57,203: INFO: proc(54): run_and_log_output: Output: [6/19/17 12:45:57 AM] Response.TDSFeatureExtAckToken.Options[0].TDSFeatureExtAckSessionStateOption.Options[0].TDSSessionStateUserOptionsOption.AnsiNulls: False
2017-06-19 00:45:57,203: INFO: proc(54): run_and_log_output: Output: [6/19/17 12:45:57 AM] Response.TDSFeatureExtAckToken.Options[0].TDSFeatureExtAckSessionStateOption.Options[0].TDSSessionStateUserOptionsOption.AnsiPadding: False
2017-06-19 00:45:57,203: INFO: proc(54): run_and_log_output: Output: [6/19/17 12:45:57 AM] Response.TDSFeatureExtAckToken.Options[0].TDSFeatureExtAckSessionStateOption.Options[0].TDSSessionStateUserOptionsOption.AnsiWarnings: False
2017-06-19 00:45:57,203: INFO: proc(54): run_and_log_output: Output: [6/19/17 12:45:57 AM] Response.TDSFeatureExtAckToken.Options[0].TDSFeatureExtAckSessionStateOption.Options[0].TDSSessionStateUserOptionsOption.ArithAbort: False
2017-06-19 00:45:57,203: INFO: proc(54): run_and_log_output: Output: [6/19/17 12:45:57 AM] Response.TDSFeatureExtAckToken.Options[0].TDSFeatureExtAckSessionStateOption.Options[0].TDSSessionStateUserOptionsOption.ArithIgnore: False
2017-06-19 00:45:57,203: INFO: proc(54): run_and_log_output: Output: [6/19/17 12:45:57 AM] Response.TDSFeatureExtAckToken.Options[0].TDSFeatureExtAckSessionStateOption.Options[0].TDSSessionStateUserOptionsOption.ConcatNullYieldsNull: False
2017-06-19 00:45:57,203: INFO: proc(54): run_and_log_output: Output: [6/19/17 12:45:57 AM] Response.TDSFeatureExtAckToken.Options[0].TDSFeatureExtAckSessionStateOption.Options[0].TDSSessionStateUserOptionsOption.CursorCloseOnCommit: False
2017-06-19 00:45:57,204: INFO: proc(54): run_and_log_output: Output: [6/19/17 12:45:57 AM] Response.TDSFeatureExtAckToken.Options[0].TDSFeatureExtAckSessionStateOption.Options[0].TDSSessionStateUserOptionsOption.ImplicitTransactions: False
2017-06-19 00:45:57,204: INFO: proc(54): run_and_log_output: Output: [6/19/17 12:45:57 AM] Response.TDSFeatureExtAckToken.Options[0].TDSFeatureExtAckSessionStateOption.Options[0].TDSSessionStateUserOptionsOption.NoCount: False
2017-06-19 00:45:57,204: INFO: proc(54): run_and_log_output: Output: [6/19/17 12:45:57 AM] Response.TDSFeatureExtAckToken.Options[0].TDSFeatureExtAckSessionStateOption.Options[0].TDSSessionStateUserOptionsOption.NumericRoundAbort: False
2017-06-19 00:45:57,204: INFO: proc(54): run_and_log_output: Output: [6/19/17 12:45:57 AM] Response.TDSFeatureExtAckToken.Options[0].TDSFeatureExtAckSessionStateOption.Options[0].TDSSessionStateUserOptionsOption.QuotedIdentifier: False
2017-06-19 00:45:57,204: INFO: proc(54): run_and_log_output: Output: [6/19/17 12:45:57 AM] Response.TDSFeatureExtAckToken.Options[0].TDSFeatureExtAckSessionStateOption.Options[0].TDSSessionStateUserOptionsOption.TransactionAbortOnError: False
2017-06-19 00:45:57,204: INFO: proc(54): run_and_log_output: Output: [6/19/17 12:45:57 AM] Response.TDSFeatureExtAckToken.Options[0].TDSFeatureExtAckSessionStateOption.Options[0].TDSSessionStateUserOptionsOption.StateID: 0
2017-06-19 00:45:57,204: INFO: proc(54): run_and_log_output: Output: [6/19/17 12:45:57 AM] Response.TDSFeatureExtAckToken.Options[0].TDSFeatureExtAckSessionStateOption.Options[1].TDSSessionStateDateFirstDateFormatOption.DateFirst: 7
2017-06-19 00:45:57,204: INFO: proc(54): run_and_log_output: Output: [6/19/17 12:45:57 AM] Response.TDSFeatureExtAckToken.Options[0].TDSFeatureExtAckSessionStateOption.Options[1].TDSSessionStateDateFirstDateFormatOption.DateFormat: MonthDayYear
2017-06-19 00:45:57,204: INFO: proc(54): run_and_log_output: Output: [6/19/17 12:45:57 AM] Response.TDSFeatureExtAckToken.Options[0].TDSFeatureExtAckSessionStateOption.Options[1].TDSSessionStateDateFirstDateFormatOption.StateID: 2
2017-06-19 00:45:57,204: INFO: proc(54): run_and_log_output: Output: [6/19/17 12:45:57 AM] Response.TDSFeatureExtAckToken.Options[0].TDSFeatureExtAckSessionStateOption.Options[2].TDSSessionStateDeadlockPriorityOption.Value: 0
2017-06-19 00:45:57,204: INFO: proc(54): run_and_log_output: Output: [6/19/17 12:45:57 AM] Response.TDSFeatureExtAckToken.Options[0].TDSFeatureExtAckSessionStateOption.Options[2].TDSSessionStateDeadlockPriorityOption.StateID: 4
2017-06-19 00:45:57,205: INFO: proc(54): run_and_log_output: Output: [6/19/17 12:45:57 AM] Response.TDSFeatureExtAckToken.Options[0].TDSFeatureExtAckSessionStateOption.Options[3].TDSSessionStateLockTimeoutOption.Value: 0
2017-06-19 00:45:57,205: INFO: proc(54): run_and_log_output: Output: [6/19/17 12:45:57 AM] Response.TDSFeatureExtAckToken.Options[0].TDSFeatureExtAckSessionStateOption.Options[3].TDSSessionStateLockTimeoutOption.StateID: 5
2017-06-19 00:45:57,205: INFO: proc(54): run_and_log_output: Output: [6/19/17 12:45:57 AM] Response.TDSFeatureExtAckToken.Options[0].TDSFeatureExtAckSessionStateOption.Options[4].TDSSessionStateISOFipsOption.TransactionIsolationLevel: ReadCommited
2017-06-19 00:45:57,205: INFO: proc(54): run_and_log_output: Output: [6/19/17 12:45:57 AM] Response.TDSFeatureExtAckToken.Options[0].TDSFeatureExtAckSessionStateOption.Options[4].TDSSessionStateISOFipsOption.StateID: 7
2017-06-19 00:45:57,205: INFO: proc(54): run_and_log_output: Output: [6/19/17 12:45:57 AM] Response.TDSFeatureExtAckToken.Options[0].TDSFeatureExtAckSessionStateOption.Options[5].TDSSessionStateTextSizeOption.Value: -1
2017-06-19 00:45:57,205: INFO: proc(54): run_and_log_output: Output: [6/19/17 12:45:57 AM] Response.TDSFeatureExtAckToken.Options[0].TDSFeatureExtAckSessionStateOption.Options[5].TDSSessionStateTextSizeOption.StateID: 9
2017-06-19 00:45:57,205: INFO: proc(54): run_and_log_output: Output: [6/19/17 12:45:57 AM] Response.TDSFeatureExtAckToken.Options[0].TDSFeatureExtAckSessionStateOption.FeatureID: SessionRecovery
2017-06-19 00:45:57,205: INFO: proc(54): run_and_log_output: Output: [6/19/17 12:45:57 AM] Response.TDSDoneToken.Status: Final
2017-06-19 00:45:57,205: INFO: proc(54): run_and_log_output: Output: [6/19/17 12:45:57 AM] Response.TDSDoneToken.Command: 0
2017-06-19 00:45:57,206: INFO: proc(54): run_and_log_output: Output: [6/19/17 12:45:57 AM] Response.TDSDoneToken.RowCount: 0
2017-06-19 00:45:57,253: INFO: proc(54): run_and_log_output: Output: SqlClient.DiagnosticsTest.ConnectionOpenTest:: Connection Opened 
2017-06-19 00:45:57,263: INFO: proc(54): run_and_log_output: Output: SqlClient.DiagnosticsTest.ConnectionOpenTest:: Connection Should Be Disposed
2017-06-19 00:45:57,263: INFO: proc(54): run_and_log_output: Output: Test: ConnectionOpenTest SqlOperation Successful
2017-06-19 00:45:57,265: INFO: proc(54): run_and_log_output: Output: Test: ConnectionOpenTest Listeners Disabled
2017-06-19 00:45:57,268: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ConnectionOpenTest Connection Closed
2017-06-19 00:45:57,269: INFO: proc(54): run_and_log_output: Output: Test: ConnectionOpenTest Server Disposed
2017-06-19 00:45:57,269: INFO: proc(54): run_and_log_output: Output: Test: ConnectionOpenTest Listeners Disposed Successfully
2017-06-19 00:45:57,269: INFO: proc(54): run_and_log_output: Output: SqlClient.DiagnosticsTest.ConnectionOpenTest:: Done with Diagnostics collection
2017-06-19 00:45:57,395: INFO: proc(54): run_and_log_output: Output: Test: ExecuteReaderWithCommandBehaviorTest Enabled Listeners
2017-06-19 00:45:57,453: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteReaderWithCommandBehaviorTest Is Server Socket Bound: True Testing connectivity to the endpoint created for the server.
2017-06-19 00:45:57,492: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteReaderWithCommandBehaviorTest Endpoint test successful.
2017-06-19 00:45:57,493: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteReaderWithCommandBehaviorTest Listener Thread Started 
2017-06-19 00:45:57,529: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteReaderWithCommandBehaviorTest Connection Closed
2017-06-19 00:45:57,597: INFO: proc(54): run_and_log_output: Output: Test: ExecuteReaderWithCommandBehaviorTest Started Server
2017-06-19 00:45:57,733: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteReaderWithCommandBehaviorTest Connection Closed
2017-06-19 00:45:57,841: INFO: proc(54): run_and_log_output: Output: Exception from RemoteExecutorConsoleApp(System.Data.SqlClient.Tests, Version=4.3.0.0, Culture=neutral, PublicKeyToken=9d77cc7ad39b68eb, System.Data.SqlClient.Tests.DiagnosticTest+<>c, <ExecuteReaderWithCommandBehaviorTest>b__9_0):
2017-06-19 00:45:57,841: INFO: proc(54): run_and_log_output: Output: Assembly: System.Data.SqlClient.Tests, Version=4.3.0.0, Culture=neutral, PublicKeyToken=9d77cc7ad39b68eb
2017-06-19 00:45:57,841: INFO: proc(54): run_and_log_output: Output: Type: System.Data.SqlClient.Tests.DiagnosticTest+<>c
2017-06-19 00:45:57,841: INFO: proc(54): run_and_log_output: Output: Method: Int32 <ExecuteReaderWithCommandBehaviorTest>b__9_0()
2017-06-19 00:45:57,931: INFO: proc(54): run_and_log_output: Output: Exception: System.Reflection.TargetInvocationException: Exception has been thrown by the target of an invocation. ---> System.Data.SqlClient.SqlException: A connection was successfully established with the server, but then an error occurred during the pre-login handshake. (provider: TCP Provider, error: 35 - An internal exception was caught) ---> System.Security.Authentication.AuthenticationException: A call to SSPI failed, see inner exception. ---> Interop+OpenSsl+SslException: SSL Handshake failed with OpenSSL error - SSL_ERROR_SSL. ---> Interop+Crypto+OpenSslCryptographicException: error:14092072:SSL routines:SSL3_GET_SERVER_HELLO:bad message type
2017-06-19 00:45:57,931: INFO: proc(54): run_and_log_output: Output:    --- End of inner exception stack trace ---
2017-06-19 00:45:57,931: INFO: proc(54): run_and_log_output: Output:    at Interop.OpenSsl.DoSslHandshake(SafeSslHandle context, Byte[] recvBuf, Int32 recvOffset, Int32 recvCount, Byte[]& sendBuf, Int32& sendCount) in /root/corefx/src/Common/src/Interop/Unix/System.Security.Cryptography.Native/Interop.OpenSsl.cs:line 153
2017-06-19 00:45:57,931: INFO: proc(54): run_and_log_output: Output:    at System.Net.Security.SslStreamPal.HandshakeInternal(SafeFreeCredentials credential, SafeDeleteContext& context, SecurityBuffer inputBuffer, SecurityBuffer outputBuffer, Boolean isServer, Boolean remoteCertRequired) in /root/corefx/src/System.Net.Security/src/System/Net/Security/SslStreamPal.Unix.cs:line 128
2017-06-19 00:45:57,931: INFO: proc(54): run_and_log_output: Output:    --- End of inner exception stack trace ---
2017-06-19 00:45:57,931: INFO: proc(54): run_and_log_output: Output:    at System.Runtime.ExceptionServices.ExceptionDispatchInfo.Throw()
2017-06-19 00:45:57,931: INFO: proc(54): run_and_log_output: Output:    at System.Net.Security.SslState.StartSendAuthResetSignal(ProtocolToken message, AsyncProtocolRequest asyncRequest, ExceptionDispatchInfo exception) in /root/corefx/src/System.Net.Security/src/System/Net/Security/SslState.cs:line 991
2017-06-19 00:45:57,932: INFO: proc(54): run_and_log_output: Output:    at System.Net.Security.SslState.CheckCompletionBeforeNextReceive(ProtocolToken message, AsyncProtocolRequest asyncRequest) in /root/corefx/src/System.Net.Security/src/System/Net/Security/SslState.cs:line 818
2017-06-19 00:45:57,932: INFO: proc(54): run_and_log_output: Output:    at System.Net.Security.SslState.StartSendBlob(Byte[] incoming, Int32 count, AsyncProtocolRequest asyncRequest) in /root/corefx/src/System.Net.Security/src/System/Net/Security/SslState.cs:line 808
2017-06-19 00:45:57,932: INFO: proc(54): run_and_log_output: Output:    at System.Net.Security.SslState.ProcessReceivedBlob(Byte[] buffer, Int32 count, AsyncProtocolRequest asyncRequest) in /root/corefx/src/System.Net.Security/src/System/Net/Security/SslState.cs:line 977
2017-06-19 00:45:57,932: INFO: proc(54): run_and_log_output: Output:    at System.Net.Security.SslState.ReadFrameCallback(AsyncProtocolRequest asyncRequest) in /root/corefx/src/System.Net.Security/src/System/Net/Security/SslState.cs:line 1140
2017-06-19 00:45:57,932: INFO: proc(54): run_and_log_output: Output: --- End of stack trace from previous location where exception was thrown ---
2017-06-19 00:45:57,932: INFO: proc(54): run_and_log_output: Output:    at System.Runtime.ExceptionServices.ExceptionDispatchInfo.Throw()
2017-06-19 00:45:57,932: INFO: proc(54): run_and_log_output: Output:    at System.Net.Security.SslState.InternalEndProcessAuthentication(LazyAsyncResult lazyResult) in /root/corefx/src/System.Net.Security/src/System/Net/Security/SslState.cs:line 753
2017-06-19 00:45:57,932: INFO: proc(54): run_and_log_output: Output:    at System.Net.Security.SslState.EndProcessAuthentication(IAsyncResult result) in /root/corefx/src/System.Net.Security/src/System/Net/Security/SslState.cs:line 725
2017-06-19 00:45:57,932: INFO: proc(54): run_and_log_output: Output:    at System.Net.Security.SslStream.EndAuthenticateAsClient(IAsyncResult asyncResult) in /root/corefx/src/System.Net.Security/src/System/Net/Security/SslStream.cs:line 132
2017-06-19 00:45:57,932: INFO: proc(54): run_and_log_output: Output:    at System.Threading.Tasks.TaskFactory`1.FromAsyncCoreLogic(IAsyncResult iar, Func`2 endFunction, Action`1 endAction, Task`1 promise, Boolean requiresSynchronization)
2017-06-19 00:45:57,933: INFO: proc(54): run_and_log_output: Output: --- End of stack trace from previous location where exception was thrown ---
2017-06-19 00:45:57,933: INFO: proc(54): run_and_log_output: Output:    at System.Runtime.ExceptionServices.ExceptionDispatchInfo.Throw()
2017-06-19 00:45:57,933: INFO: proc(54): run_and_log_output: Output:    at System.Runtime.CompilerServices.TaskAwaiter.HandleNonSuccessAndDebuggerNotification(Task task)
2017-06-19 00:45:57,933: INFO: proc(54): run_and_log_output: Output:    at System.Data.SqlClient.SNI.SNITCPHandle.EnableSsl(UInt32 options) in /root/corefx/src/System.Data.SqlClient/src/System/Data/SqlClient/SNI/SNITcpHandle.cs:line 323
2017-06-19 00:45:57,933: INFO: proc(54): run_and_log_output: Output:    --- End of inner exception stack trace ---
2017-06-19 00:45:57,933: INFO: proc(54): run_and_log_output: Output:    at System.Data.SqlClient.SqlInternalConnectionTds..ctor(DbConnectionPoolIdentity identity, SqlConnectionString connectionOptions, Object providerInfo, Boolean redirectedUserInstance, SqlConnectionString userConnectionOptions, SessionData reconnectSessionData, Boolean applyTransientFaultHandling) in /root/corefx/src/System.Data.SqlClient/src/System/Data/SqlClient/SqlInternalConnectionTds.cs:line 372
2017-06-19 00:45:57,933: INFO: proc(54): run_and_log_output: Output:    at System.Data.SqlClient.SqlConnectionFactory.CreateConnection(DbConnectionOptions options, DbConnectionPoolKey poolKey, Object poolGroupProviderInfo, DbConnectionPool pool, DbConnection owningConnection, DbConnectionOptions userOptions) in /root/corefx/src/System.Data.SqlClient/src/System/Data/SqlClient/SqlConnectionFactory.cs:line 135
2017-06-19 00:45:57,933: INFO: proc(54): run_and_log_output: Output:    at System.Data.ProviderBase.DbConnectionFactory.CreatePooledConnection(DbConnectionPool pool, DbConnection owningObject, DbConnectionOptions options, DbConnectionPoolKey poolKey, DbConnectionOptions userOptions) in /root/corefx/src/System.Data.SqlClient/src/System/Data/ProviderBase/DbConnectionFactory.cs:line 114
2017-06-19 00:45:57,933: INFO: proc(54): run_and_log_output: Output:    at System.Data.ProviderBase.DbConnectionPool.CreateObject(DbConnection owningObject, DbConnectionOptions userOptions, DbConnectionInternal oldConnection) in /root/corefx/src/System.Data.SqlClient/src/System/Data/ProviderBase/DbConnectionPool.cs:line 476
2017-06-19 00:45:57,934: INFO: proc(54): run_and_log_output: Output:    at System.Data.ProviderBase.DbConnectionPool.UserCreateRequest(DbConnection owningObject, DbConnectionOptions userOptions, DbConnectionInternal oldConnection) in /root/corefx/src/System.Data.SqlClient/src/System/Data/ProviderBase/DbConnectionPool.cs:line 1187
2017-06-19 00:45:57,934: INFO: proc(54): run_and_log_output: Output:    at System.Data.ProviderBase.DbConnectionPool.TryGetConnection(DbConnection owningObject, UInt32 waitForMultipleObjectsTimeout, Boolean allowCreate, Boolean onlyOneCheckConnection, DbConnectionOptions userOptions, DbConnectionInternal& connection) in /root/corefx/src/System.Data.SqlClient/src/System/Data/ProviderBase/DbConnectionPool.cs:line 792
2017-06-19 00:45:57,934: INFO: proc(54): run_and_log_output: Output:    at System.Data.ProviderBase.DbConnectionPool.TryGetConnection(DbConnection owningObject, TaskCompletionSource`1 retry, DbConnectionOptions userOptions, DbConnectionInternal& connection) in /root/corefx/src/System.Data.SqlClient/src/System/Data/ProviderBase/DbConnectionPool.cs:line 712
2017-06-19 00:45:57,934: INFO: proc(54): run_and_log_output: Output:    at System.Data.ProviderBase.DbConnectionFactory.TryGetConnection(DbConnection owningConnection, TaskCompletionSource`1 retry, DbConnectionOptions userOptions, DbConnectionInternal oldConnection, DbConnectionInternal& connection) in /root/corefx/src/System.Data.SqlClient/src/System/Data/ProviderBase/DbConnectionFactory.cs:line 281
2017-06-19 00:45:57,934: INFO: proc(54): run_and_log_output: Output:    at System.Data.ProviderBase.DbConnectionInternal.TryOpenConnectionInternal(DbConnection outerConnection, DbConnectionFactory connectionFactory, TaskCompletionSource`1 retry, DbConnectionOptions userOptions) in /root/corefx/src/System.Data.SqlClient/src/System/Data/ProviderBase/DbConnectionInternal.cs:line 453
2017-06-19 00:45:57,934: INFO: proc(54): run_and_log_output: Output:    at System.Data.ProviderBase.DbConnectionClosed.TryOpenConnection(DbConnection outerConnection, DbConnectionFactory connectionFactory, TaskCompletionSource`1 retry, DbConnectionOptions userOptions) in /root/corefx/src/System.Data.SqlClient/src/System/Data/ProviderBase/DbConnectionClosed.cs:line 64
2017-06-19 00:45:57,934: INFO: proc(54): run_and_log_output: Output:    at System.Data.SqlClient.SqlConnection.TryOpen(TaskCompletionSource`1 retry) in /root/corefx/src/System.Data.SqlClient/src/System/Data/SqlClient/SqlConnection.cs:line 1032
2017-06-19 00:45:57,934: INFO: proc(54): run_and_log_output: Output:    at System.Data.SqlClient.SqlConnection.Open() in /root/corefx/src/System.Data.SqlClient/src/System/Data/SqlClient/SqlConnection.cs:line 598
2017-06-19 00:45:57,935: INFO: proc(54): run_and_log_output: Output:    at System.Data.SqlClient.Tests.DiagnosticTest.<>c.<ExecuteReaderWithCommandBehaviorTest>b__9_1(String connectionString) in /root/corefx/src/System.Data.SqlClient/tests/FunctionalTests/DiagnosticTest.cs:line 197
2017-06-19 00:45:57,935: INFO: proc(54): run_and_log_output: Output:    at System.Data.SqlClient.Tests.DiagnosticTest.CollectStatisticsDiagnostics(Action`1 sqlOperation, Boolean enableServerLogging, String methodName) in /root/corefx/src/System.Data.SqlClient/tests/FunctionalTests/DiagnosticTest.cs:line 703
2017-06-19 00:45:57,935: INFO: proc(54): run_and_log_output: Output:    at System.Data.SqlClient.Tests.DiagnosticTest.<>c.<ExecuteReaderWithCommandBehaviorTest>b__9_0() in /root/corefx/src/System.Data.SqlClient/tests/FunctionalTests/DiagnosticTest.cs:line 189
2017-06-19 00:45:57,935: INFO: proc(54): run_and_log_output: Output:    --- End of inner exception stack trace ---
2017-06-19 00:45:57,935: INFO: proc(54): run_and_log_output: Output:    at System.RuntimeMethodHandle.InvokeMethod(Object target, Object[] arguments, Signature sig, Boolean constructor)
2017-06-19 00:45:57,935: INFO: proc(54): run_and_log_output: Output:    at System.Reflection.RuntimeMethodInfo.UnsafeInvokeInternal(Object obj, Object[] parameters, Object[] arguments)
2017-06-19 00:45:57,935: INFO: proc(54): run_and_log_output: Output:    at RemoteExecutorConsoleApp.Program.Main(String[] args) in /root/corefx/src/Common/tests/System/Diagnostics/RemoteExecutorConsoleApp/RemoteExecutorConsoleApp.cs:line 55
2017-06-19 00:45:57,935: INFO: proc(54): run_and_log_output: Output: 
2017-06-19 00:45:57,935: INFO: proc(54): run_and_log_output: Output: Unhandled Exception: System.Reflection.TargetInvocationException: Exception has been thrown by the target of an invocation. ---> System.Data.SqlClient.SqlException: A connection was successfully established with the server, but then an error occurred during the pre-login handshake. (provider: TCP Provider, error: 35 - An internal exception was caught) ---> System.Security.Authentication.AuthenticationException: A call to SSPI failed, see inner exception. ---> Interop+OpenSsl+SslException: SSL Handshake failed with OpenSSL error - SSL_ERROR_SSL. ---> Interop+Crypto+OpenSslCryptographicException: error:14092072:SSL routines:SSL3_GET_SERVER_HELLO:bad message type
2017-06-19 00:45:57,936: INFO: proc(54): run_and_log_output: Output:    --- End of inner exception stack trace ---
2017-06-19 00:45:57,936: INFO: proc(54): run_and_log_output: Output:    at Interop.OpenSsl.DoSslHandshake(SafeSslHandle context, Byte[] recvBuf, Int32 recvOffset, Int32 recvCount, Byte[]& sendBuf, Int32& sendCount) in /root/corefx/src/Common/src/Interop/Unix/System.Security.Cryptography.Native/Interop.OpenSsl.cs:line 153
2017-06-19 00:45:57,936: INFO: proc(54): run_and_log_output: Output:    at System.Net.Security.SslStreamPal.HandshakeInternal(SafeFreeCredentials credential, SafeDeleteContext& context, SecurityBuffer inputBuffer, SecurityBuffer outputBuffer, Boolean isServer, Boolean remoteCertRequired) in /root/corefx/src/System.Net.Security/src/System/Net/Security/SslStreamPal.Unix.cs:line 128
2017-06-19 00:45:57,936: INFO: proc(54): run_and_log_output: Output:    --- End of inner exception stack trace ---
2017-06-19 00:45:57,936: INFO: proc(54): run_and_log_output: Output:    at System.Runtime.ExceptionServices.ExceptionDispatchInfo.Throw()
2017-06-19 00:45:57,936: INFO: proc(54): run_and_log_output: Output:    at System.Net.Security.SslState.StartSendAuthResetSignal(ProtocolToken message, AsyncProtocolRequest asyncRequest, ExceptionDispatchInfo exception) in /root/corefx/src/System.Net.Security/src/System/Net/Security/SslState.cs:line 991
2017-06-19 00:45:57,936: INFO: proc(54): run_and_log_output: Output:    at System.Net.Security.SslState.CheckCompletionBeforeNextReceive(ProtocolToken message, AsyncProtocolRequest asyncRequest) in /root/corefx/src/System.Net.Security/src/System/Net/Security/SslState.cs:line 818
2017-06-19 00:45:57,936: INFO: proc(54): run_and_log_output: Output:    at System.Net.Security.SslState.StartSendBlob(Byte[] incoming, Int32 count, AsyncProtocolRequest asyncRequest) in /root/corefx/src/System.Net.Security/src/System/Net/Security/SslState.cs:line 808
2017-06-19 00:45:57,936: INFO: proc(54): run_and_log_output: Output:    at System.Net.Security.SslState.ProcessReceivedBlob(Byte[] buffer, Int32 count, AsyncProtocolRequest asyncRequest) in /root/corefx/src/System.Net.Security/src/System/Net/Security/SslState.cs:line 977
2017-06-19 00:45:57,937: INFO: proc(54): run_and_log_output: Output:    at System.Net.Security.SslState.ReadFrameCallback(AsyncProtocolRequest asyncRequest) in /root/corefx/src/System.Net.Security/src/System/Net/Security/SslState.cs:line 1140
2017-06-19 00:45:57,937: INFO: proc(54): run_and_log_output: Output: --- End of stack trace from previous location where exception was thrown ---
2017-06-19 00:45:57,937: INFO: proc(54): run_and_log_output: Output:    at System.Runtime.ExceptionServices.ExceptionDispatchInfo.Throw()
2017-06-19 00:45:57,937: INFO: proc(54): run_and_log_output: Output:    at System.Net.Security.SslState.InternalEndProcessAuthentication(LazyAsyncResult lazyResult) in /root/corefx/src/System.Net.Security/src/System/Net/Security/SslState.cs:line 753
2017-06-19 00:45:57,937: INFO: proc(54): run_and_log_output: Output:    at System.Net.Security.SslState.EndProcessAuthentication(IAsyncResult result) in /root/corefx/src/System.Net.Security/src/System/Net/Security/SslState.cs:line 725
2017-06-19 00:45:57,937: INFO: proc(54): run_and_log_output: Output:    at System.Net.Security.SslStream.EndAuthenticateAsClient(IAsyncResult asyncResult) in /root/corefx/src/System.Net.Security/src/System/Net/Security/SslStream.cs:line 132
2017-06-19 00:45:57,937: INFO: proc(54): run_and_log_output: Output:    at System.Threading.Tasks.TaskFactory`1.FromAsyncCoreLogic(IAsyncResult iar, Func`2 endFunction, Action`1 endAction, Task`1 promise, Boolean requiresSynchronization)
2017-06-19 00:45:57,937: INFO: proc(54): run_and_log_output: Output: --- End of stack trace from previous location where exception was thrown ---
2017-06-19 00:45:57,937: INFO: proc(54): run_and_log_output: Output:    at System.Runtime.ExceptionServices.ExceptionDispatchInfo.Throw()
2017-06-19 00:45:57,938: INFO: proc(54): run_and_log_output: Output:    at System.Runtime.CompilerServices.TaskAwaiter.HandleNonSuccessAndDebuggerNotification(Task task)
2017-06-19 00:45:57,938: INFO: proc(54): run_and_log_output: Output:    at System.Data.SqlClient.SNI.SNITCPHandle.EnableSsl(UInt32 options) in /root/corefx/src/System.Data.SqlClient/src/System/Data/SqlClient/SNI/SNITcpHandle.cs:line 323
2017-06-19 00:45:57,938: INFO: proc(54): run_and_log_output: Output:    --- End of inner exception stack trace ---
2017-06-19 00:45:57,938: INFO: proc(54): run_and_log_output: Output:    at System.Data.SqlClient.SqlInternalConnectionTds..ctor(DbConnectionPoolIdentity identity, SqlConnectionString connectionOptions, Object providerInfo, Boolean redirectedUserInstance, SqlConnectionString userConnectionOptions, SessionData reconnectSessionData, Boolean applyTransientFaultHandling) in /root/corefx/src/System.Data.SqlClient/src/System/Data/SqlClient/SqlInternalConnectionTds.cs:line 372
2017-06-19 00:45:57,938: INFO: proc(54): run_and_log_output: Output:    at System.Data.SqlClient.SqlConnectionFactory.CreateConnection(DbConnectionOptions options, DbConnectionPoolKey poolKey, Object poolGroupProviderInfo, DbConnectionPool pool, DbConnection owningConnection, DbConnectionOptions userOptions) in /root/corefx/src/System.Data.SqlClient/src/System/Data/SqlClient/SqlConnectionFactory.cs:line 135
2017-06-19 00:45:57,938: INFO: proc(54): run_and_log_output: Output:    at System.Data.ProviderBase.DbConnectionFactory.CreatePooledConnection(DbConnectionPool pool, DbConnection owningObject, DbConnectionOptions options, DbConnectionPoolKey poolKey, DbConnectionOptions userOptions) in /root/corefx/src/System.Data.SqlClient/src/System/Data/ProviderBase/DbConnectionFactory.cs:line 114
2017-06-19 00:45:57,938: INFO: proc(54): run_and_log_output: Output:    at System.Data.ProviderBase.DbConnectionPool.CreateObject(DbConnection owningObject, DbConnectionOptions userOptions, DbConnectionInternal oldConnection) in /root/corefx/src/System.Data.SqlClient/src/System/Data/ProviderBase/DbConnectionPool.cs:line 476
2017-06-19 00:45:57,938: INFO: proc(54): run_and_log_output: Output:    at System.Data.ProviderBase.DbConnectionPool.UserCreateRequest(DbConnection owningObject, DbConnectionOptions userOptions, DbConnectionInternal oldConnection) in /root/corefx/src/System.Data.SqlClient/src/System/Data/ProviderBase/DbConnectionPool.cs:line 1187
2017-06-19 00:45:57,939: INFO: proc(54): run_and_log_output: Output:    at System.Data.ProviderBase.DbConnectionPool.TryGetConnection(DbConnection owningObject, UInt32 waitForMultipleObjectsTimeout, Boolean allowCreate, Boolean onlyOneCheckConnection, DbConnectionOptions userOptions, DbConnectionInternal& connection) in /root/corefx/src/System.Data.SqlClient/src/System/Data/ProviderBase/DbConnectionPool.cs:line 792
2017-06-19 00:45:57,939: INFO: proc(54): run_and_log_output: Output:    at System.Data.ProviderBase.DbConnectionPool.TryGetConnection(DbConnection owningObject, TaskCompletionSource`1 retry, DbConnectionOptions userOptions, DbConnectionInternal& connection) in /root/corefx/src/System.Data.SqlClient/src/System/Data/ProviderBase/DbConnectionPool.cs:line 712
2017-06-19 00:45:57,939: INFO: proc(54): run_and_log_output: Output:    at System.Data.ProviderBase.DbConnectionFactory.TryGetConnection(DbConnection owningConnection, TaskCompletionSource`1 retry, DbConnectionOptions userOptions, DbConnectionInternal oldConnection, DbConnectionInternal& connection) in /root/corefx/src/System.Data.SqlClient/src/System/Data/ProviderBase/DbConnectionFactory.cs:line 281
2017-06-19 00:45:57,939: INFO: proc(54): run_and_log_output: Output:    at System.Data.ProviderBase.DbConnectionInternal.TryOpenConnectionInternal(DbConnection outerConnection, DbConnectionFactory connectionFactory, TaskCompletionSource`1 retry, DbConnectionOptions userOptions) in /root/corefx/src/System.Data.SqlClient/src/System/Data/ProviderBase/DbConnectionInternal.cs:line 453
2017-06-19 00:45:57,939: INFO: proc(54): run_and_log_output: Output:    at System.Data.ProviderBase.DbConnectionClosed.TryOpenConnection(DbConnection outerConnection, DbConnectionFactory connectionFactory, TaskCompletionSource`1 retry, DbConnectionOptions userOptions) in /root/corefx/src/System.Data.SqlClient/src/System/Data/ProviderBase/DbConnectionClosed.cs:line 64
2017-06-19 00:45:57,939: INFO: proc(54): run_and_log_output: Output:    at System.Data.SqlClient.SqlConnection.TryOpen(TaskCompletionSource`1 retry) in /root/corefx/src/System.Data.SqlClient/src/System/Data/SqlClient/SqlConnection.cs:line 1032
2017-06-19 00:45:57,939: INFO: proc(54): run_and_log_output: Output:    at System.Data.SqlClient.SqlConnection.Open() in /root/corefx/src/System.Data.SqlClient/src/System/Data/SqlClient/SqlConnection.cs:line 598
2017-06-19 00:45:57,940: INFO: proc(54): run_and_log_output: Output:    at System.Data.SqlClient.Tests.DiagnosticTest.<>c.<ExecuteReaderWithCommandBehaviorTest>b__9_1(String connectionString) in /root/corefx/src/System.Data.SqlClient/tests/FunctionalTests/DiagnosticTest.cs:line 197
2017-06-19 00:45:57,940: INFO: proc(54): run_and_log_output: Output:    at System.Data.SqlClient.Tests.DiagnosticTest.CollectStatisticsDiagnostics(Action`1 sqlOperation, Boolean enableServerLogging, String methodName) in /root/corefx/src/System.Data.SqlClient/tests/FunctionalTests/DiagnosticTest.cs:line 703
2017-06-19 00:45:57,940: INFO: proc(54): run_and_log_output: Output:    at System.Data.SqlClient.Tests.DiagnosticTest.<>c.<ExecuteReaderWithCommandBehaviorTest>b__9_0() in /root/corefx/src/System.Data.SqlClient/tests/FunctionalTests/DiagnosticTest.cs:line 189
2017-06-19 00:45:57,940: INFO: proc(54): run_and_log_output: Output:    --- End of inner exception stack trace ---
2017-06-19 00:45:57,940: INFO: proc(54): run_and_log_output: Output:    at RemoteExecutorConsoleApp.Program.Main(String[] args) in /root/corefx/src/Common/tests/System/Diagnostics/RemoteExecutorConsoleApp/RemoteExecutorConsoleApp.cs:line 67
2017-06-19 00:45:58,130: INFO: proc(54): run_and_log_output: Output:    System.Data.SqlClient.Tests.DiagnosticTest.ExecuteReaderWithCommandBehaviorTest [FAIL]
2017-06-19 00:45:58,130: INFO: proc(54): run_and_log_output: Output:       Assert.Equal() Failure
2017-06-19 00:45:58,130: INFO: proc(54): run_and_log_output: Output:       Expected: 42
2017-06-19 00:45:58,130: INFO: proc(54): run_and_log_output: Output:       Actual:   134
2017-06-19 00:45:58,130: INFO: proc(54): run_and_log_output: Output:       Stack Trace:
2017-06-19 00:45:58,130: INFO: proc(54): run_and_log_output: Output:          /root/corefx/src/CoreFx.Private.TestUtilities/src/System/Diagnostics/RemoteExecutorTestBase.cs(173,0): at System.Diagnostics.RemoteExecutorTestBase.RemoteInvokeHandle.Dispose()
2017-06-19 00:45:58,132: INFO: proc(54): run_and_log_output: Output:          /root/corefx/src/System.Data.SqlClient/tests/FunctionalTests/DiagnosticTest.cs(187,0): at System.Data.SqlClient.Tests.DiagnosticTest.ExecuteReaderWithCommandBehaviorTest()
2017-06-19 00:45:58,209: INFO: proc(54): run_and_log_output: Output: Test: ExecuteXmlReaderErrorTest Enabled Listeners
2017-06-19 00:45:58,259: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteXmlReaderErrorTest Is Server Socket Bound: True Testing connectivity to the endpoint created for the server.
2017-06-19 00:45:58,291: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteXmlReaderErrorTest Endpoint test successful.
2017-06-19 00:45:58,294: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteXmlReaderErrorTest Listener Thread Started 
2017-06-19 00:45:58,319: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteXmlReaderErrorTest Connection Closed
2017-06-19 00:45:58,432: INFO: proc(54): run_and_log_output: Output: Test: ExecuteXmlReaderErrorTest Started Server
2017-06-19 00:45:58,502: INFO: proc(54): run_and_log_output: Output: Test: ExecuteXmlReaderErrorTest SqlOperation Successful
2017-06-19 00:45:58,503: INFO: proc(54): run_and_log_output: Output: Test: ExecuteXmlReaderErrorTest Listeners Disabled
2017-06-19 00:45:58,506: INFO: proc(54): run_and_log_output: Output: Test: ExecuteXmlReaderErrorTest Server Disposed
2017-06-19 00:45:58,506: INFO: proc(54): run_and_log_output: Output: Test: ExecuteXmlReaderErrorTest Listeners Disposed Successfully
2017-06-19 00:45:58,651: INFO: proc(54): run_and_log_output: Output: Test: ExecuteScalarTest Enabled Listeners
2017-06-19 00:45:58,702: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteScalarTest Is Server Socket Bound: True Testing connectivity to the endpoint created for the server.
2017-06-19 00:45:58,733: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteScalarTest Endpoint test successful.
2017-06-19 00:45:58,735: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteScalarTest Listener Thread Started 
2017-06-19 00:45:58,763: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteScalarTest Connection Closed
2017-06-19 00:45:58,867: INFO: proc(54): run_and_log_output: Output: Test: ExecuteScalarTest Started Server
2017-06-19 00:45:59,268: INFO: proc(54): run_and_log_output: Output: Test: ExecuteScalarTest SqlOperation Successful
2017-06-19 00:45:59,268: INFO: proc(54): run_and_log_output: Output: Test: ExecuteScalarTest Listeners Disabled
2017-06-19 00:45:59,272: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteScalarTest Connection Closed
2017-06-19 00:45:59,280: INFO: proc(54): run_and_log_output: Output: Test: ExecuteScalarTest Server Disposed
2017-06-19 00:45:59,280: INFO: proc(54): run_and_log_output: Output: Test: ExecuteScalarTest Listeners Disposed Successfully
2017-06-19 00:45:59,396: INFO: proc(54): run_and_log_output: Output: Test: ConnectionOpenErrorTest Enabled Listeners
2017-06-19 00:45:59,444: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ConnectionOpenErrorTest Is Server Socket Bound: True Testing connectivity to the endpoint created for the server.
2017-06-19 00:45:59,474: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ConnectionOpenErrorTest Endpoint test successful.
2017-06-19 00:45:59,479: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ConnectionOpenErrorTest Listener Thread Started 
2017-06-19 00:45:59,503: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ConnectionOpenErrorTest Connection Closed
2017-06-19 00:45:59,603: INFO: proc(54): run_and_log_output: Output: Test: ConnectionOpenErrorTest Started Server
2017-06-19 00:46:00,481: INFO: proc(54): run_and_log_output: Output: Test: ConnectionOpenErrorTest SqlOperation Successful
2017-06-19 00:46:00,481: INFO: proc(54): run_and_log_output: Output: Test: ConnectionOpenErrorTest Listeners Disabled
2017-06-19 00:46:00,488: INFO: proc(54): run_and_log_output: Output: Test: ConnectionOpenErrorTest Server Disposed
2017-06-19 00:46:00,489: INFO: proc(54): run_and_log_output: Output: Test: ConnectionOpenErrorTest Listeners Disposed Successfully
2017-06-19 00:46:00,644: INFO: proc(54): run_and_log_output: Output: Test: ExecuteNonQueryErrorTest Enabled Listeners
2017-06-19 00:46:00,694: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteNonQueryErrorTest Is Server Socket Bound: True Testing connectivity to the endpoint created for the server.
2017-06-19 00:46:00,727: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteNonQueryErrorTest Endpoint test successful.
2017-06-19 00:46:00,731: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteNonQueryErrorTest Listener Thread Started 
2017-06-19 00:46:00,758: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteNonQueryErrorTest Connection Closed
2017-06-19 00:46:00,857: INFO: proc(54): run_and_log_output: Output: Test: ExecuteNonQueryErrorTest Started Server
2017-06-19 00:46:01,174: INFO: proc(54): run_and_log_output: Output: SqlClient.DiagnosticTest.ExecuteNonQueryErrorTest Connection Open Successful
2017-06-19 00:46:01,211: INFO: proc(54): run_and_log_output: Output: SqlClient.DiagnosticTest.ExecuteNonQueryErrorTest Divide by zero error encountered.
2017-06-19 00:46:01,211: INFO: proc(54): run_and_log_output: Output: SqlClient.DiagnosticTest.ExecuteNonQueryErrorTest Command Executed
2017-06-19 00:46:01,211: INFO: proc(54): run_and_log_output: Output: SqlClient.DiagnosticTest.ExecuteNonQueryErrorTest Command Disposed
2017-06-19 00:46:01,215: INFO: proc(54): run_and_log_output: Output: SqlClient.DiagnosticTest.ExecuteNonQueryErrorTest Connection Disposed
2017-06-19 00:46:01,216: INFO: proc(54): run_and_log_output: Output: Test: ExecuteNonQueryErrorTest SqlOperation Successful
2017-06-19 00:46:01,216: INFO: proc(54): run_and_log_output: Output: Test: ExecuteNonQueryErrorTest Listeners Disabled
2017-06-19 00:46:01,216: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteNonQueryErrorTest Connection Closed
2017-06-19 00:46:01,218: INFO: proc(54): run_and_log_output: Output: Test: ExecuteNonQueryErrorTest Server Disposed
2017-06-19 00:46:01,218: INFO: proc(54): run_and_log_output: Output: Test: ExecuteNonQueryErrorTest Listeners Disposed Successfully
2017-06-19 00:46:01,374: INFO: proc(54): run_and_log_output: Output: Test: ExecuteNonQueryTest Enabled Listeners
2017-06-19 00:46:01,423: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteNonQueryTest Is Server Socket Bound: True Testing connectivity to the endpoint created for the server.
2017-06-19 00:46:01,456: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteNonQueryTest Endpoint test successful.
2017-06-19 00:46:01,457: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteNonQueryTest Listener Thread Started 
2017-06-19 00:46:01,496: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteNonQueryTest Connection Closed
2017-06-19 00:46:01,561: INFO: proc(54): run_and_log_output: Output: Test: ExecuteNonQueryTest Started Server
2017-06-19 00:46:01,936: INFO: proc(54): run_and_log_output: Output: Test: ExecuteNonQueryTest SqlOperation Successful
2017-06-19 00:46:01,937: INFO: proc(54): run_and_log_output: Output: Test: ExecuteNonQueryTest Listeners Disabled
2017-06-19 00:46:01,945: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteNonQueryTest Connection Closed
2017-06-19 00:46:01,946: INFO: proc(54): run_and_log_output: Output: Test: ExecuteNonQueryTest Server Disposed
2017-06-19 00:46:01,946: INFO: proc(54): run_and_log_output: Output: Test: ExecuteNonQueryTest Listeners Disposed Successfully
2017-06-19 00:46:02,136: INFO: proc(54): run_and_log_output: Output: Test: ExecuteNonQueryAsyncTest Enabled Listeners
2017-06-19 00:46:02,194: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteNonQueryAsyncTest Is Server Socket Bound: True Testing connectivity to the endpoint created for the server.
2017-06-19 00:46:02,229: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteNonQueryAsyncTest Endpoint test successful.
2017-06-19 00:46:02,230: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteNonQueryAsyncTest Listener Thread Started 
2017-06-19 00:46:02,263: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteNonQueryAsyncTest Connection Closed
2017-06-19 00:46:02,391: INFO: proc(54): run_and_log_output: Output: Test: ExecuteNonQueryAsyncTest Started Server
2017-06-19 00:46:02,778: INFO: proc(54): run_and_log_output: Output: Test: ExecuteNonQueryAsyncTest SqlOperation Successful
2017-06-19 00:46:02,779: INFO: proc(54): run_and_log_output: Output: Test: ExecuteNonQueryAsyncTest Listeners Disabled
2017-06-19 00:46:02,779: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteNonQueryAsyncTest Connection Closed
2017-06-19 00:46:02,780: INFO: proc(54): run_and_log_output: Output: Test: ExecuteNonQueryAsyncTest Server Disposed
2017-06-19 00:46:02,780: INFO: proc(54): run_and_log_output: Output: Test: ExecuteNonQueryAsyncTest Listeners Disposed Successfully
2017-06-19 00:46:02,959: INFO: proc(54): run_and_log_output: Output: Test: ConnectionOpenAsyncErrorTest Enabled Listeners
2017-06-19 00:46:03,008: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ConnectionOpenAsyncErrorTest Is Server Socket Bound: True Testing connectivity to the endpoint created for the server.
2017-06-19 00:46:03,040: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ConnectionOpenAsyncErrorTest Endpoint test successful.
2017-06-19 00:46:03,044: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ConnectionOpenAsyncErrorTest Listener Thread Started 
2017-06-19 00:46:03,069: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ConnectionOpenAsyncErrorTest Connection Closed
2017-06-19 00:46:03,150: INFO: proc(54): run_and_log_output: Output: Test: ConnectionOpenAsyncErrorTest Started Server
2017-06-19 00:46:04,002: INFO: proc(54): run_and_log_output: Output: Test: ConnectionOpenAsyncErrorTest SqlOperation Successful
2017-06-19 00:46:04,003: INFO: proc(54): run_and_log_output: Output: Test: ConnectionOpenAsyncErrorTest Listeners Disabled
2017-06-19 00:46:04,010: INFO: proc(54): run_and_log_output: Output: Test: ConnectionOpenAsyncErrorTest Server Disposed
2017-06-19 00:46:04,011: INFO: proc(54): run_and_log_output: Output: Test: ConnectionOpenAsyncErrorTest Listeners Disposed Successfully
2017-06-19 00:46:04,233: INFO: proc(54): run_and_log_output: Output: Test: ExecuteReaderAsyncTest Enabled Listeners
2017-06-19 00:46:04,292: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteReaderAsyncTest Is Server Socket Bound: True Testing connectivity to the endpoint created for the server.
2017-06-19 00:46:04,336: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteReaderAsyncTest Endpoint test successful.
2017-06-19 00:46:04,337: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteReaderAsyncTest Listener Thread Started 
2017-06-19 00:46:04,371: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteReaderAsyncTest Connection Closed
2017-06-19 00:46:04,467: INFO: proc(54): run_and_log_output: Output: Test: ExecuteReaderAsyncTest Started Server
2017-06-19 00:46:04,903: INFO: proc(54): run_and_log_output: Output: Test: ExecuteReaderAsyncTest SqlOperation Successful
2017-06-19 00:46:04,904: INFO: proc(54): run_and_log_output: Output: Test: ExecuteReaderAsyncTest Listeners Disabled
2017-06-19 00:46:04,914: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteReaderAsyncTest Connection Closed
2017-06-19 00:46:04,915: INFO: proc(54): run_and_log_output: Output: Test: ExecuteReaderAsyncTest Server Disposed
2017-06-19 00:46:04,915: INFO: proc(54): run_and_log_output: Output: Test: ExecuteReaderAsyncTest Listeners Disposed Successfully
2017-06-19 00:46:05,044: INFO: proc(54): run_and_log_output: Output: Test: ExecuteReaderTest Enabled Listeners
2017-06-19 00:46:05,089: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteReaderTest Is Server Socket Bound: True Testing connectivity to the endpoint created for the server.
2017-06-19 00:46:05,120: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteReaderTest Endpoint test successful.
2017-06-19 00:46:05,121: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteReaderTest Listener Thread Started 
2017-06-19 00:46:05,153: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteReaderTest Connection Closed
2017-06-19 00:46:05,223: INFO: proc(54): run_and_log_output: Output: Test: ExecuteReaderTest Started Server
2017-06-19 00:46:05,551: INFO: proc(54): run_and_log_output: Output: Test: ExecuteReaderTest SqlOperation Successful
2017-06-19 00:46:05,552: INFO: proc(54): run_and_log_output: Output: Test: ExecuteReaderTest Listeners Disabled
2017-06-19 00:46:05,561: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteReaderTest Connection Closed
2017-06-19 00:46:05,562: INFO: proc(54): run_and_log_output: Output: Test: ExecuteReaderTest Server Disposed
2017-06-19 00:46:05,562: INFO: proc(54): run_and_log_output: Output: Test: ExecuteReaderTest Listeners Disposed Successfully
2017-06-19 00:46:05,586: INFO: proc(54): run_and_log_output: Output:    System.Data.SqlClient.Tests.DiagnosticTest.ExecuteXmlReaderTest [SKIP]
2017-06-19 00:46:05,586: INFO: proc(54): run_and_log_output: Output:       Condition(s) not met: \"IsConnectionStringConfigured\"
2017-06-19 00:46:05,679: INFO: proc(54): run_and_log_output: Output: Test: ExecuteNonQueryAsyncErrorTest Enabled Listeners
2017-06-19 00:46:05,726: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteNonQueryAsyncErrorTest Is Server Socket Bound: True Testing connectivity to the endpoint created for the server.
2017-06-19 00:46:05,756: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteNonQueryAsyncErrorTest Endpoint test successful.
2017-06-19 00:46:05,759: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteNonQueryAsyncErrorTest Listener Thread Started 
2017-06-19 00:46:05,786: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteNonQueryAsyncErrorTest Connection Closed
2017-06-19 00:46:05,902: INFO: proc(54): run_and_log_output: Output: Test: ExecuteNonQueryAsyncErrorTest Started Server
2017-06-19 00:46:06,263: INFO: proc(54): run_and_log_output: Output: Test: ExecuteNonQueryAsyncErrorTest SqlOperation Successful
2017-06-19 00:46:06,263: INFO: proc(54): run_and_log_output: Output: Test: ExecuteNonQueryAsyncErrorTest Listeners Disabled
2017-06-19 00:46:06,267: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteNonQueryAsyncErrorTest Connection Closed
2017-06-19 00:46:06,268: INFO: proc(54): run_and_log_output: Output: Test: ExecuteNonQueryAsyncErrorTest Server Disposed
2017-06-19 00:46:06,268: INFO: proc(54): run_and_log_output: Output: Test: ExecuteNonQueryAsyncErrorTest Listeners Disposed Successfully
2017-06-19 00:46:06,327: INFO: proc(54): run_and_log_output: Output:    System.Data.SqlClient.Tests.DiagnosticTest.ExecuteXmlReaderAsyncTest [SKIP]
2017-06-19 00:46:06,327: INFO: proc(54): run_and_log_output: Output:       Condition(s) not met: \"IsConnectionStringConfigured\"
2017-06-19 00:46:06,431: INFO: proc(54): run_and_log_output: Output: Test: ExecuteReaderAsyncErrorTest Enabled Listeners
2017-06-19 00:46:06,479: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteReaderAsyncErrorTest Is Server Socket Bound: True Testing connectivity to the endpoint created for the server.
2017-06-19 00:46:06,509: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteReaderAsyncErrorTest Endpoint test successful.
2017-06-19 00:46:06,510: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteReaderAsyncErrorTest Listener Thread Started 
2017-06-19 00:46:06,538: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteReaderAsyncErrorTest Connection Closed
2017-06-19 00:46:06,615: INFO: proc(54): run_and_log_output: Output: Test: ExecuteReaderAsyncErrorTest Started Server
2017-06-19 00:46:06,676: INFO: proc(54): run_and_log_output: Output: Test: ExecuteReaderAsyncErrorTest SqlOperation Successful
2017-06-19 00:46:06,676: INFO: proc(54): run_and_log_output: Output: Test: ExecuteReaderAsyncErrorTest Listeners Disabled
2017-06-19 00:46:06,680: INFO: proc(54): run_and_log_output: Output: Test: ExecuteReaderAsyncErrorTest Server Disposed
2017-06-19 00:46:06,680: INFO: proc(54): run_and_log_output: Output: Test: ExecuteReaderAsyncErrorTest Listeners Disposed Successfully
2017-06-19 00:46:06,856: INFO: proc(54): run_and_log_output: Output: Test: ConnectionOpenAsyncTest Enabled Listeners
2017-06-19 00:46:06,903: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ConnectionOpenAsyncTest Is Server Socket Bound: True Testing connectivity to the endpoint created for the server.
2017-06-19 00:46:06,935: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ConnectionOpenAsyncTest Endpoint test successful.
2017-06-19 00:46:06,936: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ConnectionOpenAsyncTest Listener Thread Started 
2017-06-19 00:46:06,964: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ConnectionOpenAsyncTest Connection Closed
2017-06-19 00:46:07,058: INFO: proc(54): run_and_log_output: Output: Test: ConnectionOpenAsyncTest Started Server
2017-06-19 00:46:07,364: INFO: proc(54): run_and_log_output: Output: Test: ConnectionOpenAsyncTest SqlOperation Successful
2017-06-19 00:46:07,364: INFO: proc(54): run_and_log_output: Output: Test: ConnectionOpenAsyncTest Listeners Disabled
2017-06-19 00:46:07,369: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ConnectionOpenAsyncTest Connection Closed
2017-06-19 00:46:07,370: INFO: proc(54): run_and_log_output: Output: Test: ConnectionOpenAsyncTest Server Disposed
2017-06-19 00:46:07,370: INFO: proc(54): run_and_log_output: Output: Test: ConnectionOpenAsyncTest Listeners Disposed Successfully
2017-06-19 00:46:07,489: INFO: proc(54): run_and_log_output: Output: Test: ExecuteScalarAsyncTest Enabled Listeners
2017-06-19 00:46:07,536: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteScalarAsyncTest Is Server Socket Bound: True Testing connectivity to the endpoint created for the server.
2017-06-19 00:46:07,566: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteScalarAsyncTest Endpoint test successful.
2017-06-19 00:46:07,568: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteScalarAsyncTest Listener Thread Started 
2017-06-19 00:46:07,593: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteScalarAsyncTest Connection Closed
2017-06-19 00:46:07,673: INFO: proc(54): run_and_log_output: Output: Test: ExecuteScalarAsyncTest Started Server
2017-06-19 00:46:08,057: INFO: proc(54): run_and_log_output: Output: Test: ExecuteScalarAsyncTest SqlOperation Successful
2017-06-19 00:46:08,057: INFO: proc(54): run_and_log_output: Output: Test: ExecuteScalarAsyncTest Listeners Disabled
2017-06-19 00:46:08,058: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteScalarAsyncTest Connection Closed
2017-06-19 00:46:08,059: INFO: proc(54): run_and_log_output: Output: Test: ExecuteScalarAsyncTest Server Disposed
2017-06-19 00:46:08,059: INFO: proc(54): run_and_log_output: Output: Test: ExecuteScalarAsyncTest Listeners Disposed Successfully
2017-06-19 00:46:08,144: INFO: proc(54): run_and_log_output: Output: Finished:    System.Data.SqlClient.Tests

danmoseley · 2017-06-23T03:17:06Z

https://ci.dot.net/job/dotnet_corefx/job/release_2.0.0/job/ubuntu14.04_debug_prtest/326/

danmoseley · 2017-07-19T16:30:09Z

https://ci.dot.net/job/dotnet_corefx/job/master/job/outerloop_netcoreapp_ubuntu14.04_debug/lastCompletedBuild/consoleText

     System.Data.SqlClient.Tests.DiagnosticTest.ExecuteNonQueryTest [FAIL]
        Assert.Equal() Failure
        Expected: 42
        Actual:   -122
        Stack Trace:
           /mnt/j/workspace/dotnet_corefx/master/outerloop_netcoreapp_ubuntu14.04_debug/src/CoreFx.Private.TestUtilities/src/System/Diagnostics/RemoteExecutorTestBase.cs(175,0): at System.Diagnostics.RemoteExecutorTestBase.RemoteInvokeHandle.Dispose()
           /mnt/j/workspace/dotnet_corefx/master/outerloop_netcoreapp_ubuntu14.04_debug/src/System.Data.SqlClient/tests/FunctionalTests/DiagnosticTest.cs(78,0): at System.Data.SqlClient.Tests.DiagnosticTest.ExecuteNonQueryTest()
  Using as the test runtime folder.

  Starting:    System.Data.SqlClient.Tests
  TDSServerEndPoint ConnectionTest Is Server Socket Bound: True Testing connectivity to the endpoint created for the server.
  TDSServerEndPoint ConnectionTest Endpoint test successful.
  TDSServerEndPoint ConnectionTest Listener Thread Started 
  TDSServerEndPoint ConnectionTest Connection Closed
  TDSServerEndPoint ConnectionTest Connection Closed
  Test: ExecuteReaderErrorTest Enabled Listeners
  TDSServerEndPoint ExecuteReaderErrorTest Is Server Socket Bound: True Testing connectivity to the endpoint created for the server.
  TDSServerEndPoint ExecuteReaderErrorTest Endpoint test successful.
  TDSServerEndPoint ExecuteReaderErrorTest Listener Thread Started 
  TDSServerEndPoint ExecuteReaderErrorTest Connection Closed
  Test: ExecuteReaderErrorTest Started Server
  Test: ExecuteReaderErrorTest SqlOperation Successful
  Test: ExecuteReaderErrorTest Listeners Disabled
  Test: ExecuteReaderErrorTest Server Disposed
  Test: ExecuteReaderErrorTest Listeners Disposed Successfully
  Test: ExecuteScalarAsyncErrorTest Enabled Listeners
  TDSServerEndPoint ExecuteScalarAsyncErrorTest Is Server Socket Bound: True Testing connectivity to the endpoint created for the server.
  TDSServerEndPoint ExecuteScalarAsyncErrorTest Endpoint test successful.
  TDSServerEndPoint ExecuteScalarAsyncErrorTest Listener Thread Started 
  TDSServerEndPoint ExecuteScalarAsyncErrorTest Connection Closed
  Test: ExecuteScalarAsyncErrorTest Started Server
  Test: ExecuteScalarAsyncErrorTest SqlOperation Successful
  Test: ExecuteScalarAsyncErrorTest Listeners Disabled
  TDSServerEndPoint ExecuteScalarAsyncErrorTest Connection Closed
  Test: ExecuteScalarAsyncErrorTest Server Disposed
  Test: ExecuteScalarAsyncErrorTest Listeners Disposed Successfully
     System.Data.SqlClient.Tests.DiagnosticTest.ExecuteXmlReaderAsyncErrorTest [SKIP]
        Condition(s) not met: \"IsConnectionStringConfigured\"
  Test: ExecuteScalarErrorTest Enabled Listeners
  TDSServerEndPoint ExecuteScalarErrorTest Is Server Socket Bound: True Testing connectivity to the endpoint created for the server.
  TDSServerEndPoint ExecuteScalarErrorTest Endpoint test successful.
  TDSServerEndPoint ExecuteScalarErrorTest Listener Thread Started 
  TDSServerEndPoint ExecuteScalarErrorTest Connection Closed
  Test: ExecuteScalarErrorTest Started Server
  Test: ExecuteScalarErrorTest SqlOperation Successful
  Test: ExecuteScalarErrorTest Listeners Disabled
  TDSServerEndPoint ExecuteScalarErrorTest Connection Closed
  Test: ExecuteScalarErrorTest Server Disposed
  Test: ExecuteScalarErrorTest Listeners Disposed Successfully
  Test: ConnectionOpenTest Enabled Listeners
  TDSServerEndPoint ConnectionOpenTest Is Server Socket Bound: True Testing connectivity to the endpoint created for the server.
  TDSServerEndPoint ConnectionOpenTest Endpoint test successful.
  TDSServerEndPoint ConnectionOpenTest Listener Thread Started 
  TDSServerEndPoint ConnectionOpenTest Connection Closed
  Test: ConnectionOpenTest Started Server
  [7/18/17 11:03:54 PM] Request.TDSPreLoginToken.Version: 4.6.25518
  [7/18/17 11:03:54 PM] Request.TDSPreLoginToken.SubBuild: 0
  [7/18/17 11:03:54 PM] Request.TDSPreLoginToken.Encryption: Off
  [7/18/17 11:03:54 PM] Request.TDSPreLoginToken.ThreadID: 33554432
  [7/18/17 11:03:54 PM] Request.TDSPreLoginToken.IsMARS: False
  [7/18/17 11:03:54 PM] Request.TDSPreLoginToken.ClientTraceID: [29 1F F6 2 2C 6E 19 47 8E 2F F6 71 B3 46 97 66 ]
  [7/18/17 11:03:54 PM] Request.TDSPreLoginToken.ActivityID: [D CB 16 BF AA 80 1C 46 91 51 5B AB 86 F 74 14 1 0 0 0 ]
  [7/18/17 11:03:54 PM] Request.TDSPreLoginToken.Nonce: <null>
  [7/18/17 11:03:54 PM] Request.TDSPreLoginToken.FedAuthRequired: FedAuthNotRequired
  [7/18/17 11:03:54 PM] Response.TDSPreLoginToken.Version: 11.0.1083
  [7/18/17 11:03:54 PM] Response.TDSPreLoginToken.SubBuild: 0
  [7/18/17 11:03:54 PM] Response.TDSPreLoginToken.Encryption: NotSupported
  [7/18/17 11:03:54 PM] Response.TDSPreLoginToken.ThreadID: 0
  [7/18/17 11:03:54 PM] Response.TDSPreLoginToken.IsMARS: False
  [7/18/17 11:03:54 PM] Response.TDSPreLoginToken.ClientTraceID: <null>
  [7/18/17 11:03:54 PM] Response.TDSPreLoginToken.ActivityID: <null>
  [7/18/17 11:03:54 PM] Response.TDSPreLoginToken.Nonce: <null>
  [7/18/17 11:03:54 PM] Response.TDSPreLoginToken.FedAuthRequired: FedAuthNotRequired
  [7/18/17 11:03:54 PM] Request.TDSLogin7Token.TDSVersion: 7.4.0.4
  [7/18/17 11:03:54 PM] Request.TDSLogin7Token.PacketSize: 8000
  [7/18/17 11:03:54 PM] Request.TDSLogin7Token.ClientProgramVersion: 100663296
  [7/18/17 11:03:54 PM] Request.TDSLogin7Token.ClientPID: 102977896
  [7/18/17 11:03:54 PM] Request.TDSLogin7Token.ConnectionID: 0
  [7/18/17 11:03:54 PM] Request.TDSLogin7Token.OptionalFlags1.TDSLogin7TokenOptionalFlags1.Order: OrderX86
  [7/18/17 11:03:54 PM] Request.TDSLogin7Token.OptionalFlags1.TDSLogin7TokenOptionalFlags1.CharacterSet: Ascii
  [7/18/17 11:03:54 PM] Request.TDSLogin7Token.OptionalFlags1.TDSLogin7TokenOptionalFlags1.FloatingPoint: IEEE754
  [7/18/17 11:03:54 PM] Request.TDSLogin7Token.OptionalFlags1.TDSLogin7TokenOptionalFlags1.DumpLoad: On
  [7/18/17 11:03:54 PM] Request.TDSLogin7Token.OptionalFlags1.TDSLogin7TokenOptionalFlags1.UseDB: Off
  [7/18/17 11:03:54 PM] Request.TDSLogin7Token.OptionalFlags1.TDSLogin7TokenOptionalFlags1.Database: Fatal
  [7/18/17 11:03:54 PM] Request.TDSLogin7Token.OptionalFlags1.TDSLogin7TokenOptionalFlags1.Language: On
  [7/18/17 11:03:54 PM] Request.TDSLogin7Token.OptionalFlags2.TDSLogin7TokenOptionalFlags2.Language: Fatal
  [7/18/17 11:03:54 PM] Request.TDSLogin7Token.OptionalFlags2.TDSLogin7TokenOptionalFlags2.Odbc: On
  [7/18/17 11:03:54 PM] Request.TDSLogin7Token.OptionalFlags2.TDSLogin7TokenOptionalFlags2.UserType: Normal
  [7/18/17 11:03:54 PM] Request.TDSLogin7Token.OptionalFlags2.TDSLogin7TokenOptionalFlags2.IntegratedSecurity: Off
  [7/18/17 11:03:54 PM] Request.TDSLogin7Token.OptionalFlags3.TDSLogin7TokenOptionalFlags3.ChangePassword: No
  [7/18/17 11:03:54 PM] Request.TDSLogin7Token.OptionalFlags3.TDSLogin7TokenOptionalFlags3.IsUserInstance: False
  [7/18/17 11:03:54 PM] Request.TDSLogin7Token.OptionalFlags3.TDSLogin7TokenOptionalFlags3.SendYukonBinaryXML: False
  [7/18/17 11:03:54 PM] Request.TDSLogin7Token.OptionalFlags3.TDSLogin7TokenOptionalFlags3.UnknownCollation: Must
  [7/18/17 11:03:54 PM] Request.TDSLogin7Token.OptionalFlags3.TDSLogin7TokenOptionalFlags3.ExtensionFlag: True
  [7/18/17 11:03:54 PM] Request.TDSLogin7Token.TypeFlags.TDSLogin7TokenTypeFlags.SQL: Default
  [7/18/17 11:03:54 PM] Request.TDSLogin7Token.TypeFlags.TDSLogin7TokenTypeFlags.OleDb: Off
  [7/18/17 11:03:54 PM] Request.TDSLogin7Token.TypeFlags.TDSLogin7TokenTypeFlags.ReadOnlyIntent: ReadWrite
  [7/18/17 11:03:54 PM] Request.TDSLogin7Token.ClientTimeZone: 0
  [7/18/17 11:03:54 PM] Request.TDSLogin7Token.ClientLCID: 0
  [7/18/17 11:03:54 PM] Request.TDSLogin7Token.HostName: ubuntu1404-20160206-outerb9f9f0
  [7/18/17 11:03:54 PM] Request.TDSLogin7Token.UserID: <null>
  [7/18/17 11:03:54 PM] Request.TDSLogin7Token.Password: <null>
  [7/18/17 11:03:54 PM] Request.TDSLogin7Token.ApplicationName: Core .Net SqlClient Data Provider
  [7/18/17 11:03:54 PM] Request.TDSLogin7Token.ServerName: localhost,38431
  [7/18/17 11:03:54 PM] Request.TDSLogin7Token.LibraryName: Core .Net SqlClient Data Provider
  [7/18/17 11:03:54 PM] Request.TDSLogin7Token.Language: <null>
  [7/18/17 11:03:54 PM] Request.TDSLogin7Token.Database: <null>
  [7/18/17 11:03:54 PM] Request.TDSLogin7Token.ClientID: [31 36 6E BE 5 FC ]
  [7/18/17 11:03:54 PM] Request.TDSLogin7Token.AttachDatabaseFile: <null>
  [7/18/17 11:03:54 PM] Request.TDSLogin7Token.ChangePassword: <null>
  [7/18/17 11:03:54 PM] Request.TDSLogin7Token.SSPI: <null>
  [7/18/17 11:03:54 PM] Request.TDSLogin7Token.FeatureExt[0].TDSLogin7SessionRecoveryOptionToken.FeatureID: SessionRecovery
  [7/18/17 11:03:54 PM] Request.TDSLogin7Token.FeatureExt[0].TDSLogin7SessionRecoveryOptionToken.Initial: <null>
  [7/18/17 11:03:54 PM] Request.TDSLogin7Token.FeatureExt[0].TDSLogin7SessionRecoveryOptionToken.Current: <null>
  [7/18/17 11:03:54 PM] Request.TDSLogin7Token.FeatureExt[1].TDSLogin7GenericOptionToken.Data: []
  [7/18/17 11:03:54 PM] Request.TDSLogin7Token.FeatureExt[1].TDSLogin7GenericOptionToken.FeatureID: 5
  [7/18/17 11:03:54 PM] Response.TDSEnvChangeToken.Type: Database
  [7/18/17 11:03:54 PM] Response.TDSEnvChangeToken.OldValue: master
  [7/18/17 11:03:54 PM] Response.TDSEnvChangeToken.NewValue: master
  [7/18/17 11:03:54 PM] Response.TDSInfoToken.Number: 5701
  [7/18/17 11:03:54 PM] Response.TDSInfoToken.State: 2
  [7/18/17 11:03:54 PM] Response.TDSInfoToken.Class: 0
  [7/18/17 11:03:54 PM] Response.TDSInfoToken.Message: Changed database context to 'master'
  [7/18/17 11:03:54 PM] Response.TDSInfoToken.ServerName: ubuntu1404-20160206-outerb9f9f0
  [7/18/17 11:03:54 PM] Response.TDSInfoToken.ProcedureName: <null>
  [7/18/17 11:03:54 PM] Response.TDSInfoToken.Line: 0
  [7/18/17 11:03:54 PM] Response.TDSEnvChangeToken.Type: SQLCollation
  [7/18/17 11:03:54 PM] Response.TDSEnvChangeToken.OldValue: <null>
  [7/18/17 11:03:54 PM] Response.TDSEnvChangeToken.NewValue: [9 4 D0 0 34 ]
  [7/18/17 11:03:54 PM] Response.TDSEnvChangeToken.Type: Language
  [7/18/17 11:03:54 PM] Response.TDSEnvChangeToken.OldValue: <null>
  [7/18/17 11:03:54 PM] Response.TDSEnvChangeToken.NewValue: us_english
  [7/18/17 11:03:54 PM] Response.TDSInfoToken.Number: 5703
  [7/18/17 11:03:54 PM] Response.TDSInfoToken.State: 1
  [7/18/17 11:03:54 PM] Response.TDSInfoToken.Class: 0
  [7/18/17 11:03:54 PM] Response.TDSInfoToken.Message: Changed language setting to us_english
  [7/18/17 11:03:54 PM] Response.TDSInfoToken.ServerName: ubuntu1404-20160206-outerb9f9f0
  [7/18/17 11:03:54 PM] Response.TDSInfoToken.ProcedureName: <null>
  [7/18/17 11:03:54 PM] Response.TDSInfoToken.Line: 0
  [7/18/17 11:03:54 PM] Response.TDSEnvChangeToken.Type: PacketSize
  [7/18/17 11:03:54 PM] Response.TDSEnvChangeToken.OldValue: 4096
  [7/18/17 11:03:54 PM] Response.TDSEnvChangeToken.NewValue: 4096
  [7/18/17 11:03:54 PM] Response.TDSLoginAckToken.TDSVersion: 7.4.0.4
  [7/18/17 11:03:54 PM] Response.TDSLoginAckToken.Interface: SQL
  [7/18/17 11:03:54 PM] Response.TDSLoginAckToken.ServerName: Microsoft SQL Server
  [7/18/17 11:03:54 PM] Response.TDSLoginAckToken.ServerVersion: 11.0.1083
  [7/18/17 11:03:54 PM] Response.TDSFeatureExtAckToken.Options[0].TDSFeatureExtAckSessionStateOption.Options[0].TDSSessionStateUserOptionsOption.AnsiNullDefaultOn: False
  [7/18/17 11:03:54 PM] Response.TDSFeatureExtAckToken.Options[0].TDSFeatureExtAckSessionStateOption.Options[0].TDSSessionStateUserOptionsOption.AnsiNulls: False
  [7/18/17 11:03:54 PM] Response.TDSFeatureExtAckToken.Options[0].TDSFeatureExtAckSessionStateOption.Options[0].TDSSessionStateUserOptionsOption.AnsiPadding: False
  [7/18/17 11:03:54 PM] Response.TDSFeatureExtAckToken.Options[0].TDSFeatureExtAckSessionStateOption.Options[0].TDSSessionStateUserOptionsOption.AnsiWarnings: False
  [7/18/17 11:03:54 PM] Response.TDSFeatureExtAckToken.Options[0].TDSFeatureExtAckSessionStateOption.Options[0].TDSSessionStateUserOptionsOption.ArithAbort: False
  [7/18/17 11:03:54 PM] Response.TDSFeatureExtAckToken.Options[0].TDSFeatureExtAckSessionStateOption.Options[0].TDSSessionStateUserOptionsOption.ArithIgnore: False
  [7/18/17 11:03:54 PM] Response.TDSFeatureExtAckToken.Options[0].TDSFeatureExtAckSessionStateOption.Options[0].TDSSessionStateUserOptionsOption.ConcatNullYieldsNull: False
  [7/18/17 11:03:54 PM] Response.TDSFeatureExtAckToken.Options[0].TDSFeatureExtAckSessionStateOption.Options[0].TDSSessionStateUserOptionsOption.CursorCloseOnCommit: False
  [7/18/17 11:03:54 PM] Response.TDSFeatureExtAckToken.Options[0].TDSFeatureExtAckSessionStateOption.Options[0].TDSSessionStateUserOptionsOption.ImplicitTransactions: False
  [7/18/17 11:03:54 PM] Response.TDSFeatureExtAckToken.Options[0].TDSFeatureExtAckSessionStateOption.Options[0].TDSSessionStateUserOptionsOption.NoCount: False
  [7/18/17 11:03:54 PM] Response.TDSFeatureExtAckToken.Options[0].TDSFeatureExtAckSessionStateOption.Options[0].TDSSessionStateUserOptionsOption.NumericRoundAbort: False
  [7/18/17 11:03:54 PM] Response.TDSFeatureExtAckToken.Options[0].TDSFeatureExtAckSessionStateOption.Options[0].TDSSessionStateUserOptionsOption.QuotedIdentifier: False
  [7/18/17 11:03:54 PM] Response.TDSFeatureExtAckToken.Options[0].TDSFeatureExtAckSessionStateOption.Options[0].TDSSessionStateUserOptionsOption.TransactionAbortOnError: False
  [7/18/17 11:03:54 PM] Response.TDSFeatureExtAckToken.Options[0].TDSFeatureExtAckSessionStateOption.Options[0].TDSSessionStateUserOptionsOption.StateID: 0
  [7/18/17 11:03:54 PM] Response.TDSFeatureExtAckToken.Options[0].TDSFeatureExtAckSessionStateOption.Options[1].TDSSessionStateDateFirstDateFormatOption.DateFirst: 7
  [7/18/17 11:03:54 PM] Response.TDSFeatureExtAckToken.Options[0].TDSFeatureExtAckSessionStateOption.Options[1].TDSSessionStateDateFirstDateFormatOption.DateFormat: MonthDayYear
  [7/18/17 11:03:54 PM] Response.TDSFeatureExtAckToken.Options[0].TDSFeatureExtAckSessionStateOption.Options[1].TDSSessionStateDateFirstDateFormatOption.StateID: 2
  [7/18/17 11:03:54 PM] Response.TDSFeatureExtAckToken.Options[0].TDSFeatureExtAckSessionStateOption.Options[2].TDSSessionStateDeadlockPriorityOption.Value: 0
  [7/18/17 11:03:54 PM] Response.TDSFeatureExtAckToken.Options[0].TDSFeatureExtAckSessionStateOption.Options[2].TDSSessionStateDeadlockPriorityOption.StateID: 4
  [7/18/17 11:03:54 PM] Response.TDSFeatureExtAckToken.Options[0].TDSFeatureExtAckSessionStateOption.Options[3].TDSSessionStateLockTimeoutOption.Value: 0
  [7/18/17 11:03:54 PM] Response.TDSFeatureExtAckToken.Options[0].TDSFeatureExtAckSessionStateOption.Options[3].TDSSessionStateLockTimeoutOption.StateID: 5
  [7/18/17 11:03:54 PM] Response.TDSFeatureExtAckToken.Options[0].TDSFeatureExtAckSessionStateOption.Options[4].TDSSessionStateISOFipsOption.TransactionIsolationLevel: ReadCommited
  [7/18/17 11:03:54 PM] Response.TDSFeatureExtAckToken.Options[0].TDSFeatureExtAckSessionStateOption.Options[4].TDSSessionStateISOFipsOption.StateID: 7
  [7/18/17 11:03:54 PM] Response.TDSFeatureExtAckToken.Options[0].TDSFeatureExtAckSessionStateOption.Options[5].TDSSessionStateTextSizeOption.Value: -1
  [7/18/17 11:03:54 PM] Response.TDSFeatureExtAckToken.Options[0].TDSFeatureExtAckSessionStateOption.Options[5].TDSSessionStateTextSizeOption.StateID: 9
  [7/18/17 11:03:54 PM] Response.TDSFeatureExtAckToken.Options[0].TDSFeatureExtAckSessionStateOption.FeatureID: SessionRecovery
  [7/18/17 11:03:54 PM] Response.TDSDoneToken.Status: Final
  [7/18/17 11:03:54 PM] Response.TDSDoneToken.Command: 0
  [7/18/17 11:03:54 PM] Response.TDSDoneToken.RowCount: 0
  SqlClient.DiagnosticsTest.ConnectionOpenTest:: Connection Opened 
  SqlClient.DiagnosticsTest.ConnectionOpenTest:: Connection Should Be Disposed
  Test: ConnectionOpenTest SqlOperation Successful
  Test: ConnectionOpenTest Listeners Disabled
  TDSServerEndPoint ConnectionOpenTest Connection Closed
  Test: ConnectionOpenTest Server Disposed
  Test: ConnectionOpenTest Listeners Disposed Successfully
  SqlClient.DiagnosticsTest.ConnectionOpenTest:: Done with Diagnostics collection
  Test: ExecuteReaderWithCommandBehaviorTest Enabled Listeners
  TDSServerEndPoint ExecuteReaderWithCommandBehaviorTest Is Server Socket Bound: True Testing connectivity to the endpoint created for the server.
  TDSServerEndPoint ExecuteReaderWithCommandBehaviorTest Endpoint test successful.
  TDSServerEndPoint ExecuteReaderWithCommandBehaviorTest Listener Thread Started 
  TDSServerEndPoint ExecuteReaderWithCommandBehaviorTest Connection Closed
  Test: ExecuteReaderWithCommandBehaviorTest Started Server
  Test: ExecuteReaderWithCommandBehaviorTest SqlOperation Successful
  Test: ExecuteReaderWithCommandBehaviorTest Listeners Disabled
  TDSServerEndPoint ExecuteReaderWithCommandBehaviorTest Connection Closed
  Test: ExecuteReaderWithCommandBehaviorTest Server Disposed
  Test: ExecuteReaderWithCommandBehaviorTest Listeners Disposed Successfully
  Test: ExecuteXmlReaderErrorTest Enabled Listeners
  TDSServerEndPoint ExecuteXmlReaderErrorTest Is Server Socket Bound: True Testing connectivity to the endpoint created for the server.
  TDSServerEndPoint ExecuteXmlReaderErrorTest Endpoint test successful.
  TDSServerEndPoint ExecuteXmlReaderErrorTest Listener Thread Started 
  TDSServerEndPoint ExecuteXmlReaderErrorTest Connection Closed
  Test: ExecuteXmlReaderErrorTest Started Server
  Test: ExecuteXmlReaderErrorTest SqlOperation Successful
  Test: ExecuteXmlReaderErrorTest Listeners Disabled
  Test: ExecuteXmlReaderErrorTest Server Disposed
  Test: ExecuteXmlReaderErrorTest Listeners Disposed Successfully
  Test: ExecuteScalarTest Enabled Listeners
  TDSServerEndPoint ExecuteScalarTest Is Server Socket Bound: True Testing connectivity to the endpoint created for the server.
  TDSServerEndPoint ExecuteScalarTest Endpoint test successful.
  TDSServerEndPoint ExecuteScalarTest Listener Thread Started 
  TDSServerEndPoint ExecuteScalarTest Connection Closed
  Finished:    System.Data.Common.Tests
  
  === TEST EXECUTION SUMMARY ===
  Test: ExecuteScalarTest Started Server
     System.Data.Common.Tests  Total: 1703, Errors: 0, Failed: 0, Skipped: 0, Time: 6.523s
  /mnt/j/workspace/dotnet_corefx/master/outerloop_netcoreapp_ubuntu14.04_debug/src/System.Data.Common/tests
  Finished running tests. End time=23:03:56. Return value was 0
  Using as the test runtime folder.
  Running tests... Start time: 23:03:56
  Commands:
  python DumplingHelper.py install_dumpling
  __TIMESTAMP=`python DumplingHelper.py get_timestamp`
  export XUNIT_PERFORMANCE_MIN_ITERATION=1
  export XUNIT_PERFORMANCE_MAX_ITERATION=1
  chmod +x /mnt/j/workspace/dotnet_corefx/master/outerloop_netcoreapp_ubuntu14.04_debug/bin/testhost/netcoreapp-Linux-Debug-x64//dotnet
  /mnt/j/workspace/dotnet_corefx/master/outerloop_netcoreapp_ubuntu14.04_debug/bin/testhost/netcoreapp-Linux-Debug-x64//dotnet xunit.console.netcore.exe System.Diagnostics.DiagnosticSource.Tests.dll  -xml testResults.xml -notrait category=nonnetcoreapptests -notrait category=nonlinuxtests  -notrait category=IgnoreForCI -notrait category=failing
  python DumplingHelper.py collect_dump $\ `pwd`  System.Diagnostics.DiagnosticSource.Tests /mnt/j/workspace/dotnet_corefx/master/outerloop_netcoreapp_ubuntu14.04_debug/bin/runtime/netcoreapp-Linux-Debug-x64/,/mnt/j/workspace/dotnet_corefx/master/outerloop_netcoreapp_ubuntu14.04_debug/bin/AnyOS.AnyCPU.Debug/System.Diagnostics.DiagnosticSource.Tests/netstandard/
  /mnt/j/workspace/dotnet_corefx/master/outerloop_netcoreapp_ubuntu14.04_debug/bin/AnyOS.AnyCPU.Debug/System.Diagnostics.DiagnosticSource.Tests/netstandard /mnt/j/workspace/dotnet_corefx/master/outerloop_netcoreapp_ubuntu14.04_debug/src/System.Diagnostics.DiagnosticSource/tests
  Test: ExecuteScalarTest SqlOperation Successful
  Test: ExecuteScalarTest Listeners Disabled
  TDSServerEndPoint ExecuteScalarTest Connection Closed
  Test: ExecuteScalarTest Server Disposed
  Test: ExecuteScalarTest Listeners Disposed Successfully
  Test: ConnectionOpenErrorTest Enabled Listeners
  TDSServerEndPoint ConnectionOpenErrorTest Is Server Socket Bound: True Testing connectivity to the endpoint created for the server.
  TDSServerEndPoint ConnectionOpenErrorTest Endpoint test successful.
  TDSServerEndPoint ConnectionOpenErrorTest Listener Thread Started 
  downloaded dumpling.py
  total elapsed time 0:00:00.165449
  TDSServerEndPoint ConnectionOpenErrorTest Connection Closed
  Test: ConnectionOpenErrorTest Started Server
  xUnit.net console test runner (64-bit .NET Core)
  Copyright (C) 2014 Outercurve Foundation.
  
  Discovering: System.Diagnostics.DiagnosticSource.Tests
  Discovered:  System.Diagnostics.DiagnosticSource.Tests
  Starting:    System.Diagnostics.DiagnosticSource.Tests
  Test: ConnectionOpenErrorTest SqlOperation Successful
  Test: ConnectionOpenErrorTest Listeners Disabled
  Test: ConnectionOpenErrorTest Server Disposed
  Test: ConnectionOpenErrorTest Listeners Disposed Successfully
  Test: ExecuteNonQueryErrorTest Enabled Listeners
  TDSServerEndPoint ExecuteNonQueryErrorTest Is Server Socket Bound: True Testing connectivity to the endpoint created for the server.
  TDSServerEndPoint ExecuteNonQueryErrorTest Endpoint test successful.
  TDSServerEndPoint ExecuteNonQueryErrorTest Listener Thread Started 
  TDSServerEndPoint ExecuteNonQueryErrorTest Connection Closed
  Test: ExecuteNonQueryErrorTest Started Server
  SqlClient.DiagnosticTest.ExecuteNonQueryErrorTest Connection Open Successful
  SqlClient.DiagnosticTest.ExecuteNonQueryErrorTest Divide by zero error encountered.
  SqlClient.DiagnosticTest.ExecuteNonQueryErrorTest Command Executed
  SqlClient.DiagnosticTest.ExecuteNonQueryErrorTest Command Disposed
  SqlClient.DiagnosticTest.ExecuteNonQueryErrorTest Connection Disposed
  Test: ExecuteNonQueryErrorTest SqlOperation Successful
  Test: ExecuteNonQueryErrorTest Listeners Disabled
  TDSServerEndPoint ExecuteNonQueryErrorTest Connection Closed
  Test: ExecuteNonQueryErrorTest Server Disposed
  Test: ExecuteNonQueryErrorTest Listeners Disposed Successfully
  Finished:    System.Diagnostics.Process.Tests
  
  === TEST EXECUTION SUMMARY ===
     System.Diagnostics.Process.Tests  Total: 207, Errors: 0, Failed: 0, Skipped: 1, Time: 9.800s
  Test: ExecuteNonQueryTest Enabled Listeners
  TDSServerEndPoint ExecuteNonQueryTest Is Server Socket Bound: True Testing connectivity to the endpoint created for the server.
  /mnt/j/workspace/dotnet_corefx/master/outerloop_netcoreapp_ubuntu14.04_debug/src/System.Diagnostics.Process/tests
  Finished running tests. End time=23:03:59. Return value was 0
  TDSServerEndPoint ExecuteNonQueryTest Endpoint test successful.
  TDSServerEndPoint ExecuteNonQueryTest Listener Thread Started 
  TDSServerEndPoint ExecuteNonQueryTest Connection Closed
  Test: ExecuteNonQueryTest Started Server
  Using as the test runtime folder.
  Running tests... Start time: 23:04:00
  Commands:
  python DumplingHelper.py install_dumpling
  __TIMESTAMP=`python DumplingHelper.py get_timestamp`
  export XUNIT_PERFORMANCE_MIN_ITERATION=1
  export XUNIT_PERFORMANCE_MAX_ITERATION=1
  chmod +x /mnt/j/workspace/dotnet_corefx/master/outerloop_netcoreapp_ubuntu14.04_debug/bin/testhost/netcoreapp-Linux-Debug-x64//dotnet
  /mnt/j/workspace/dotnet_corefx/master/outerloop_netcoreapp_ubuntu14.04_debug/bin/testhost/netcoreapp-Linux-Debug-x64//dotnet xunit.console.netcore.exe System.Diagnostics.StackTrace.Tests.dll  -xml testResults.xml -notrait category=nonnetcoreapptests -notrait category=nonlinuxtests  -notrait category=IgnoreForCI -notrait category=failing
  python DumplingHelper.py collect_dump $\ `pwd`  System.Diagnostics.StackTrace.Tests /mnt/j/workspace/dotnet_corefx/master/outerloop_netcoreapp_ubuntu14.04_debug/bin/runtime/netcoreapp-Linux-Debug-x64/,/mnt/j/workspace/dotnet_corefx/master/outerloop_netcoreapp_ubuntu14.04_debug/bin/AnyOS.AnyCPU.Debug/System.Diagnostics.StackTrace.Tests/netstandard/
  /mnt/j/workspace/dotnet_corefx/master/outerloop_netcoreapp_ubuntu14.04_debug/bin/AnyOS.AnyCPU.Debug/System.Diagnostics.StackTrace.Tests/netstandard /mnt/j/workspace/dotnet_corefx/master/outerloop_netcoreapp_ubuntu14.04_debug/src/System.Diagnostics.StackTrace/tests
  Finished:    System.Diagnostics.DiagnosticSource.Tests
  
  === TEST EXECUTION SUMMARY ===
     System.Diagnostics.DiagnosticSource.Tests  Total: 49, Errors: 0, Failed: 0, Skipped: 0, Time: 6.658s
  /mnt/j/workspace/dotnet_corefx/master/outerloop_netcoreapp_ubuntu14.04_debug/src/System.Diagnostics.DiagnosticSource/tests
  Finished running tests. End time=23:04:03. Return value was 0
  Using as the test runtime folder.
  Running tests... Start time: 23:04:03
  Commands:
  python DumplingHelper.py install_dumpling
  __TIMESTAMP=`python DumplingHelper.py get_timestamp`
  export XUNIT_PERFORMANCE_MIN_ITERATION=1
  export XUNIT_PERFORMANCE_MAX_ITERATION=1
  chmod +x /mnt/j/workspace/dotnet_corefx/master/outerloop_netcoreapp_ubuntu14.04_debug/bin/testhost/netcoreapp-Linux-Debug-x64//dotnet
  /mnt/j/workspace/dotnet_corefx/master/outerloop_netcoreapp_ubuntu14.04_debug/bin/testhost/netcoreapp-Linux-Debug-x64//dotnet xunit.console.netcore.exe System.Diagnostics.FileVersionInfo.Tests.dll  -xml testResults.xml -notrait category=nonnetcoreapptests -notrait category=nonlinuxtests  -notrait category=IgnoreForCI -notrait category=failing
  python DumplingHelper.py collect_dump $\ `pwd`  System.Diagnostics.FileVersionInfo.Tests /mnt/j/workspace/dotnet_corefx/master/outerloop_netcoreapp_ubuntu14.04_debug/bin/runtime/netcoreapp-Linux-Debug-x64/,/mnt/j/workspace/dotnet_corefx/master/outerloop_netcoreapp_ubuntu14.04_debug/bin/Unix.AnyCPU.Debug/System.Diagnostics.FileVersionInfo.Tests/netstandard/
  /mnt/j/workspace/dotnet_corefx/master/outerloop_netcoreapp_ubuntu14.04_debug/bin/Unix.AnyCPU.Debug/System.Diagnostics.FileVersionInfo.Tests/netstandard /mnt/j/workspace/dotnet_corefx/master/outerloop_netcoreapp_ubuntu14.04_debug/src/System.Diagnostics.FileVersionInfo/tests/System.Diagnostics.FileVersionInfo.Tests
  Exception from RemoteExecutorConsoleApp(System.Data.SqlClient.Tests, Version=4.3.0.0, Culture=neutral, PublicKeyToken=9d77cc7ad39b68eb, System.Data.SqlClient.Tests.DiagnosticTest+<>c, <ExecuteNonQueryTest>b__5_0):
  Assembly: System.Data.SqlClient.Tests, Version=4.3.0.0, Culture=neutral, PublicKeyToken=9d77cc7ad39b68eb
  Type: System.Data.SqlClient.Tests.DiagnosticTest+<>c
  Method: Int32 <ExecuteNonQueryTest>b__5_0()
  Exception: System.Reflection.TargetInvocationException: Exception has been thrown by the target of an invocation. ---> System.Data.SqlClient.SqlException: A network-related or instance-specific error occurred while establishing a connection to SQL Server. The server was not found or was not accessible. Verify that the instance name is correct and that SQL Server is configured to allow remote connections. (provider: TCP Provider, error: 40 - Could not open a connection to SQL Server)
     at System.Data.SqlClient.SqlInternalConnectionTds..ctor(DbConnectionPoolIdentity identity, SqlConnectionString connectionOptions, Object providerInfo, Boolean redirectedUserInstance, SqlConnectionString userConnectionOptions, SessionData reconnectSessionData, Boolean applyTransientFaultHandling) in /mnt/j/workspace/dotnet_corefx/master/outerloop_netcoreapp_ubuntu14.04_debug/src/System.Data.SqlClient/src/System/Data/SqlClient/SqlInternalConnectionTds.cs:line 373
     at System.Data.SqlClient.SqlConnectionFactory.CreateConnection(DbConnectionOptions options, DbConnectionPoolKey poolKey, Object poolGroupProviderInfo, DbConnectionPool pool, DbConnection owningConnection, DbConnectionOptions userOptions) in /mnt/j/workspace/dotnet_corefx/master/outerloop_netcoreapp_ubuntu14.04_debug/src/System.Data.SqlClient/src/System/Data/SqlClient/SqlConnectionFactory.cs:line 135
     at System.Data.ProviderBase.DbConnectionFactory.CreatePooledConnection(DbConnectionPool pool, DbConnection owningObject, DbConnectionOptions options, DbConnectionPoolKey poolKey, DbConnectionOptions userOptions) in /mnt/j/workspace/dotnet_corefx/master/outerloop_netcoreapp_ubuntu14.04_debug/src/System.Data.SqlClient/src/System/Data/ProviderBase/DbConnectionFactory.cs:line 113
     at System.Data.ProviderBase.DbConnectionPool.CreateObject(DbConnection owningObject, DbConnectionOptions userOptions, DbConnectionInternal oldConnection) in /mnt/j/workspace/dotnet_corefx/master/outerloop_netcoreapp_ubuntu14.04_debug/src/System.Data.SqlClient/src/System/Data/ProviderBase/DbConnectionPool.cs:line 755
     at System.Data.ProviderBase.DbConnectionPool.UserCreateRequest(DbConnection owningObject, DbConnectionOptions userOptions, DbConnectionInternal oldConnection) in /mnt/j/workspace/dotnet_corefx/master/outerloop_netcoreapp_ubuntu14.04_debug/src/System.Data.SqlClient/src/System/Data/ProviderBase/DbConnectionPool.cs:line 1606
     at System.Data.ProviderBase.DbConnectionPool.TryGetConnection(DbConnection owningObject, UInt32 waitForMultipleObjectsTimeout, Boolean allowCreate, Boolean onlyOneCheckConnection, DbConnectionOptions userOptions, DbConnectionInternal& connection) in /mnt/j/workspace/dotnet_corefx/master/outerloop_netcoreapp_ubuntu14.04_debug/src/System.Data.SqlClient/src/System/Data/ProviderBase/DbConnectionPool.cs:line 1132
     at System.Data.ProviderBase.DbConnectionPool.TryGetConnection(DbConnection owningObject, TaskCompletionSource`1 retry, DbConnectionOptions userOptions, DbConnectionInternal& connection) in /mnt/j/workspace/dotnet_corefx/master/outerloop_netcoreapp_ubuntu14.04_debug/src/System.Data.SqlClient/src/System/Data/ProviderBase/DbConnectionPool.cs:line 1044
     at System.Data.ProviderBase.DbConnectionFactory.TryGetConnection(DbConnection owningConnection, TaskCompletionSource`1 retry, DbConnectionOptions userOptions, DbConnectionInternal oldConnection, DbConnectionInternal& connection) in /mnt/j/workspace/dotnet_corefx/master/outerloop_netcoreapp_ubuntu14.04_debug/src/System.Data.SqlClient/src/System/Data/ProviderBase/DbConnectionFactory.cs:line 289
     at System.Data.ProviderBase.DbConnectionInternal.TryOpenConnectionInternal(DbConnection outerConnection, DbConnectionFactory connectionFactory, TaskCompletionSource`1 retry, DbConnectionOptions userOptions) in /mnt/j/workspace/dotnet_corefx/master/outerloop_netcoreapp_ubuntu14.04_debug/src/System.Data.SqlClient/src/System/Data/ProviderBase/DbConnectionInternal.cs:line 700
     at System.Data.ProviderBase.DbConnectionClosed.TryOpenConnection(DbConnection outerConnection, DbConnectionFactory connectionFactory, TaskCompletionSource`1 retry, DbConnectionOptions userOptions) in /mnt/j/workspace/dotnet_corefx/master/outerloop_netcoreapp_ubuntu14.04_debug/src/System.Data.SqlClient/src/System/Data/ProviderBase/DbConnectionClosed.cs:line 69
     at System.Data.SqlClient.SqlConnection.TryOpen(TaskCompletionSource`1 retry) in /mnt/j/workspace/dotnet_corefx/master/outerloop_netcoreapp_ubuntu14.04_debug/src/System.Data.SqlClient/src/System/Data/SqlClient/SqlConnection.cs:line 1031
     at System.Data.SqlClient.SqlConnection.Open() in /mnt/j/workspace/dotnet_corefx/master/outerloop_netcoreapp_ubuntu14.04_debug/src/System.Data.SqlClient/src/System/Data/SqlClient/SqlConnection.cs:line 606
     at System.Data.SqlClient.Tests.DiagnosticTest.<>c.<ExecuteNonQueryTest>b__5_1(String connectionString) in /mnt/j/workspace/dotnet_corefx/master/outerloop_netcoreapp_ubuntu14.04_debug/src/System.Data.SqlClient/tests/FunctionalTests/DiagnosticTest.cs:line 88
     at System.Data.SqlClient.Tests.DiagnosticTest.CollectStatisticsDiagnostics(Action`1 sqlOperation, Boolean enableServerLogging, String methodName) in /mnt/j/workspace/dotnet_corefx/master/outerloop_netcoreapp_ubuntu14.04_debug/src/System.Data.SqlClient/tests/FunctionalTests/DiagnosticTest.cs:line 703
     at System.Data.SqlClient.Tests.DiagnosticTest.<>c.<ExecuteNonQueryTest>b__5_0() in /mnt/j/workspace/dotnet_corefx/master/outerloop_netcoreapp_ubuntu14.04_debug/src/System.Data.SqlClient/tests/FunctionalTests/DiagnosticTest.cs:line 80
     --- End of inner exception stack trace ---
     at System.RuntimeMethodHandle.InvokeMethod(Object target, Object[] arguments, Signature sig, Boolean constructor)
     at System.Reflection.RuntimeMethodInfo.UnsafeInvokeInternal(Object obj, Object[] parameters, Object[] arguments)
     at RemoteExecutorConsoleApp.Program.Main(String[] args) in /mnt/j/workspace/dotnet_corefx/master/outerloop_netcoreapp_ubuntu14.04_debug/src/Common/tests/System/Diagnostics/RemoteExecutorConsoleApp/RemoteExecutorConsoleApp.cs:line 55
  
  Unhandled Exception: System.Reflection.TargetInvocationException: Exception has been thrown by the target of an invocation. ---> System.Data.SqlClient.SqlException: A network-related or instance-specific error occurred while establishing a connection to SQL Server. The server was not found or was not accessible. Verify that the instance name is correct and that SQL Server is configured to allow remote connections. (provider: TCP Provider, error: 40 - Could not open a connection to SQL Server)
     at System.Data.SqlClient.SqlInternalConnectionTds..ctor(DbConnectionPoolIdentity identity, SqlConnectionString connectionOptions, Object providerInfo, Boolean redirectedUserInstance, SqlConnectionString userConnectionOptions, SessionData reconnectSessionData, Boolean applyTransientFaultHandling) in /mnt/j/workspace/dotnet_corefx/master/outerloop_netcoreapp_ubuntu14.04_debug/src/System.Data.SqlClient/src/System/Data/SqlClient/SqlInternalConnectionTds.cs:line 373
     at System.Data.SqlClient.SqlConnectionFactory.CreateConnection(DbConnectionOptions options, DbConnectionPoolKey poolKey, Object poolGroupProviderInfo, DbConnectionPool pool, DbConnection owningConnection, DbConnectionOptions userOptions) in /mnt/j/workspace/dotnet_corefx/master/outerloop_netcoreapp_ubuntu14.04_debug/src/System.Data.SqlClient/src/System/Data/SqlClient/SqlConnectionFactory.cs:line 135
     at System.Data.ProviderBase.DbConnectionFactory.CreatePooledConnection(DbConnectionPool pool, DbConnection owningObject, DbConnectionOptions options, DbConnectionPoolKey poolKey, DbConnectionOptions userOptions) in /mnt/j/workspace/dotnet_corefx/master/outerloop_netcoreapp_ubuntu14.04_debug/src/System.Data.SqlClient/src/System/Data/ProviderBase/DbConnectionFactory.cs:line 113
     at System.Data.ProviderBase.DbConnectionPool.CreateObject(DbConnection owningObject, DbConnectionOptions userOptions, DbConnectionInternal oldConnection) in /mnt/j/workspace/dotnet_corefx/master/outerloop_netcoreapp_ubuntu14.04_debug/src/System.Data.SqlClient/src/System/Data/ProviderBase/DbConnectionPool.cs:line 755
     at System.Data.ProviderBase.DbConnectionPool.UserCreateRequest(DbConnection owningObject, DbConnectionOptions userOptions, DbConnectionInternal oldConnection) in /mnt/j/workspace/dotnet_corefx/master/outerloop_netcoreapp_ubuntu14.04_debug/src/System.Data.SqlClient/src/System/Data/ProviderBase/DbConnectionPool.cs:line 1606
     at System.Data.ProviderBase.DbConnectionPool.TryGetConnection(DbConnection owningObject, UInt32 waitForMultipleObjectsTimeout, Boolean allowCreate, Boolean onlyOneCheckConnection, DbConnectionOptions userOptions, DbConnectionInternal& connection) in /mnt/j/workspace/dotnet_corefx/master/outerloop_netcoreapp_ubuntu14.04_debug/src/System.Data.SqlClient/src/System/Data/ProviderBase/DbConnectionPool.cs:line 1132
     at System.Data.ProviderBase.DbConnectionPool.TryGetConnection(DbConnection owningObject, TaskCompletionSource`1 retry, DbConnectionOptions userOptions, DbConnectionInternal& connection) in /mnt/j/workspace/dotnet_corefx/master/outerloop_netcoreapp_ubuntu14.04_debug/src/System.Data.SqlClient/src/System/Data/ProviderBase/DbConnectionPool.cs:line 1044
     at System.Data.ProviderBase.DbConnectionFactory.TryGetConnection(DbConnection owningConnection, TaskCompletionSource`1 retry, DbConnectionOptions userOptions, DbConnectionInternal oldConnection, DbConnectionInternal& connection) in /mnt/j/workspace/dotnet_corefx/master/outerloop_netcoreapp_ubuntu14.04_debug/src/System.Data.SqlClient/src/System/Data/ProviderBase/DbConnectionFactory.cs:line 289
     at System.Data.ProviderBase.DbConnectionInternal.TryOpenConnectionInternal(DbConnection outerConnection, DbConnectionFactory connectionFactory, TaskCompletionSource`1 retry, DbConnectionOptions userOptions) in /mnt/j/workspace/dotnet_corefx/master/outerloop_netcoreapp_ubuntu14.04_debug/src/System.Data.SqlClient/src/System/Data/ProviderBase/DbConnectionInternal.cs:line 700
     at System.Data.ProviderBase.DbConnectionClosed.TryOpenConnection(DbConnection outerConnection, DbConnectionFactory connectionFactory, TaskCompletionSource`1 retry, DbConnectionOptions userOptions) in /mnt/j/workspace/dotnet_corefx/master/outerloop_netcoreapp_ubuntu14.04_debug/src/System.Data.SqlClient/src/System/Data/ProviderBase/DbConnectionClosed.cs:line 69
     at System.Data.SqlClient.SqlConnection.TryOpen(TaskCompletionSource`1 retry) in /mnt/j/workspace/dotnet_corefx/master/outerloop_netcoreapp_ubuntu14.04_debug/src/System.Data.SqlClient/src/System/Data/SqlClient/SqlConnection.cs:line 1031
     at System.Data.SqlClient.SqlConnection.Open() in /mnt/j/workspace/dotnet_corefx/master/outerloop_netcoreapp_ubuntu14.04_debug/src/System.Data.SqlClient/src/System/Data/SqlClient/SqlConnection.cs:line 606
     at System.Data.SqlClient.Tests.DiagnosticTest.<>c.<ExecuteNonQueryTest>b__5_1(String connectionString) in /mnt/j/workspace/dotnet_corefx/master/outerloop_netcoreapp_ubuntu14.04_debug/src/System.Data.SqlClient/tests/FunctionalTests/DiagnosticTest.cs:line 88
     at System.Data.SqlClient.Tests.DiagnosticTest.CollectStatisticsDiagnostics(Action`1 sqlOperation, Boolean enableServerLogging, String methodName) in /mnt/j/workspace/dotnet_corefx/master/outerloop_netcoreapp_ubuntu14.04_debug/src/System.Data.SqlClient/tests/FunctionalTests/DiagnosticTest.cs:line 703
     at System.Data.SqlClient.Tests.DiagnosticTest.<>c.<ExecuteNonQueryTest>b__5_0() in /mnt/j/workspace/dotnet_corefx/master/outerloop_netcoreapp_ubuntu14.04_debug/src/System.Data.SqlClient/tests/FunctionalTests/DiagnosticTest.cs:line 80
     --- End of inner exception stack trace ---
     at RemoteExecutorConsoleApp.Program.Main(String[] args) in /mnt/j/workspace/dotnet_corefx/master/outerloop_netcoreapp_ubuntu14.04_debug/src/Common/tests/System/Diagnostics/RemoteExecutorConsoleApp/RemoteExecutorConsoleApp.cs:line 67
  downloaded dumpling.py

danmoseley · 2017-07-19T16:30:50Z

@corivera this continues to fail sporadically. Do the logs help?

saurabh500 · 2018-02-03T01:56:36Z

This is fixed with https://github.com/dotnet/corefx/issues/25620 and the tests have been re-enabled for sometime. The tests are passing

saurabh500 self-assigned this Jun 6, 2017

danmoseley closed this as completed Jun 14, 2017

danmoseley reopened this Jun 14, 2017

danmoseley changed the title ~~ExecuteScalarAsyncTest test failed in CI~~ System.Data.SqlClient.Tests (eg ExecuteScalarAsyncTest) test break CI with "The server was not found or was not accessible" Jun 19, 2017

saurabh500 closed this as completed Feb 3, 2018

msftgits transferred this issue from dotnet/corefx Jan 31, 2020

msftgits added this to the 2.1.0 milestone Jan 31, 2020

ghost locked as resolved and limited conversation to collaborators Dec 22, 2020

Provide feedback

Saved searches

Use saved searches to filter your results more quickly

System.Data.SqlClient.Tests (eg ExecuteScalarAsyncTest) test break CI with "The server was not found or was not accessible" #22028

System.Data.SqlClient.Tests (eg ExecuteScalarAsyncTest) test break CI with "The server was not found or was not accessible" #22028

stephentoub commented May 30, 2017

danmoseley commented Jun 6, 2017

saurabh500 commented Jun 6, 2017

danmoseley commented Jun 7, 2017

danmoseley commented Jun 7, 2017

danmoseley commented Jun 8, 2017

danmoseley commented Jun 8, 2017

saurabh500 commented Jun 8, 2017

danmoseley commented Jun 8, 2017

danmoseley commented Jun 8, 2017

saurabh500 commented Jun 8, 2017

danmoseley commented Jun 14, 2017

danmoseley commented Jun 17, 2017

danmoseley commented Jun 19, 2017

danmoseley commented Jun 23, 2017

danmoseley commented Jul 19, 2017

danmoseley commented Jul 19, 2017

saurabh500 commented Feb 3, 2018

System.Data.SqlClient.Tests (eg ExecuteScalarAsyncTest) test break CI with "The server was not found or was not accessible" #22028

System.Data.SqlClient.Tests (eg ExecuteScalarAsyncTest) test break CI with "The server was not found or was not accessible" #22028

Comments

stephentoub commented May 30, 2017

danmoseley commented Jun 6, 2017

saurabh500 commented Jun 6, 2017

danmoseley commented Jun 7, 2017

danmoseley commented Jun 7, 2017

danmoseley commented Jun 8, 2017

danmoseley commented Jun 8, 2017

saurabh500 commented Jun 8, 2017

danmoseley commented Jun 8, 2017

danmoseley commented Jun 8, 2017

saurabh500 commented Jun 8, 2017

danmoseley commented Jun 14, 2017

danmoseley commented Jun 17, 2017

danmoseley commented Jun 19, 2017

danmoseley commented Jun 23, 2017

danmoseley commented Jul 19, 2017

danmoseley commented Jul 19, 2017

saurabh500 commented Feb 3, 2018