2017-06-13 23:39:45,629: INFO: scriptrunner(28): _main: BuildTools Helix Script Runner v0.1 starting
2017-06-13 23:39:45,629: INFO: helix_test_execution(24): __init__: Copying execution payload files from /home/helixbot/dotnetbuild/work/4c28ff69-e956-4fc2-be5b-8c44231c3ab3/Work/0d9b1125-df3e-4a79-b1d5-e9faad2d8ae5/Unzip to /home/helixbot/dotnetbuild/work/4c28ff69-e956-4fc2-be5b-8c44231c3ab3/Work/0d9b1125-df3e-4a79-b1d5-e9faad2d8ae5/Exec/execution
2017-06-13 23:39:45,629: INFO: io(27): copy_tree_to: Copying '/home/helixbot/dotnetbuild/work/4c28ff69-e956-4fc2-be5b-8c44231c3ab3/Work/0d9b1125-df3e-4a79-b1d5-e9faad2d8ae5/Unzip/System.Data.SqlClient.Tests.pdb' to '/home/helixbot/dotnetbuild/work/4c28ff69-e956-4fc2-be5b-8c44231c3ab3/Work/0d9b1125-df3e-4a79-b1d5-e9faad2d8ae5/Exec/execution/System.Data.SqlClient.Tests.pdb'
2017-06-13 23:39:45,629: INFO: io(27): copy_tree_to: Copying '/home/helixbot/dotnetbuild/work/4c28ff69-e956-4fc2-be5b-8c44231c3ab3/Work/0d9b1125-df3e-4a79-b1d5-e9faad2d8ae5/Unzip/Microsoft.SqlServer.TDS.dll' to '/home/helixbot/dotnetbuild/work/4c28ff69-e956-4fc2-be5b-8c44231c3ab3/Work/0d9b1125-df3e-4a79-b1d5-e9faad2d8ae5/Exec/execution/Microsoft.SqlServer.TDS.dll'
2017-06-13 23:39:45,630: INFO: io(27): copy_tree_to: Copying '/home/helixbot/dotnetbuild/work/4c28ff69-e956-4fc2-be5b-8c44231c3ab3/Work/0d9b1125-df3e-4a79-b1d5-e9faad2d8ae5/Unzip/TdsServerCertificate.pfx' to '/home/helixbot/dotnetbuild/work/4c28ff69-e956-4fc2-be5b-8c44231c3ab3/Work/0d9b1125-df3e-4a79-b1d5-e9faad2d8ae5/Exec/execution/TdsServerCertificate.pfx'
2017-06-13 23:39:45,630: INFO: io(27): copy_tree_to: Copying '/home/helixbot/dotnetbuild/work/4c28ff69-e956-4fc2-be5b-8c44231c3ab3/Work/0d9b1125-df3e-4a79-b1d5-e9faad2d8ae5/Unzip/Microsoft.SqlServer.TDS.EndPoint.pdb' to '/home/helixbot/dotnetbuild/work/4c28ff69-e956-4fc2-be5b-8c44231c3ab3/Work/0d9b1125-df3e-4a79-b1d5-e9faad2d8ae5/Exec/execution/Microsoft.SqlServer.TDS.EndPoint.pdb'
2017-06-13 23:39:45,630: INFO: io(27): copy_tree_to: Copying '/home/helixbot/dotnetbuild/work/4c28ff69-e956-4fc2-be5b-8c44231c3ab3/Work/0d9b1125-df3e-4a79-b1d5-e9faad2d8ae5/Unzip/RemoteExecutorConsoleApp.pdb' to '/home/helixbot/dotnetbuild/work/4c28ff69-e956-4fc2-be5b-8c44231c3ab3/Work/0d9b1125-df3e-4a79-b1d5-e9faad2d8ae5/Exec/execution/RemoteExecutorConsoleApp.pdb'
2017-06-13 23:39:45,630: INFO: io(27): copy_tree_to: Copying '/home/helixbot/dotnetbuild/work/4c28ff69-e956-4fc2-be5b-8c44231c3ab3/Work/0d9b1125-df3e-4a79-b1d5-e9faad2d8ae5/Unzip/Microsoft.SqlServer.TDS.Servers.dll' to '/home/helixbot/dotnetbuild/work/4c28ff69-e956-4fc2-be5b-8c44231c3ab3/Work/0d9b1125-df3e-4a79-b1d5-e9faad2d8ae5/Exec/execution/Microsoft.SqlServer.TDS.Servers.dll'
2017-06-13 23:39:45,630: INFO: io(27): copy_tree_to: Copying '/home/helixbot/dotnetbuild/work/4c28ff69-e956-4fc2-be5b-8c44231c3ab3/Work/0d9b1125-df3e-4a79-b1d5-e9faad2d8ae5/Unzip/xunit.console.netcore.runtimeconfig.json' to '/home/helixbot/dotnetbuild/work/4c28ff69-e956-4fc2-be5b-8c44231c3ab3/Work/0d9b1125-df3e-4a79-b1d5-e9faad2d8ae5/Exec/execution/xunit.console.netcore.runtimeconfig.json'
2017-06-13 23:39:45,631: INFO: io(27): copy_tree_to: Copying '/home/helixbot/dotnetbuild/work/4c28ff69-e956-4fc2-be5b-8c44231c3ab3/Work/0d9b1125-df3e-4a79-b1d5-e9faad2d8ae5/Unzip/RunTests.sh' to '/home/helixbot/dotnetbuild/work/4c28ff69-e956-4fc2-be5b-8c44231c3ab3/Work/0d9b1125-df3e-4a79-b1d5-e9faad2d8ae5/Exec/execution/RunTests.sh'
2017-06-13 23:39:45,631: INFO: io(27): copy_tree_to: Copying '/home/helixbot/dotnetbuild/work/4c28ff69-e956-4fc2-be5b-8c44231c3ab3/Work/0d9b1125-df3e-4a79-b1d5-e9faad2d8ae5/Unzip/RemoteExecutorConsoleApp.exe' to '/home/helixbot/dotnetbuild/work/4c28ff69-e956-4fc2-be5b-8c44231c3ab3/Work/0d9b1125-df3e-4a79-b1d5-e9faad2d8ae5/Exec/execution/RemoteExecutorConsoleApp.exe'
2017-06-13 23:39:45,631: INFO: io(27): copy_tree_to: Copying '/home/helixbot/dotnetbuild/work/4c28ff69-e956-4fc2-be5b-8c44231c3ab3/Work/0d9b1125-df3e-4a79-b1d5-e9faad2d8ae5/Unzip/xunit.console.netcore.exe' to '/home/helixbot/dotnetbuild/work/4c28ff69-e956-4fc2-be5b-8c44231c3ab3/Work/0d9b1125-df3e-4a79-b1d5-e9faad2d8ae5/Exec/execution/xunit.console.netcore.exe'
2017-06-13 23:39:45,631: INFO: io(27): copy_tree_to: Copying '/home/helixbot/dotnetbuild/work/4c28ff69-e956-4fc2-be5b-8c44231c3ab3/Work/0d9b1125-df3e-4a79-b1d5-e9faad2d8ae5/Unzip/Microsoft.SqlServer.TDS.pdb' to '/home/helixbot/dotnetbuild/work/4c28ff69-e956-4fc2-be5b-8c44231c3ab3/Work/0d9b1125-df3e-4a79-b1d5-e9faad2d8ae5/Exec/execution/Microsoft.SqlServer.TDS.pdb'
2017-06-13 23:39:45,631: INFO: io(27): copy_tree_to: Copying '/home/helixbot/dotnetbuild/work/4c28ff69-e956-4fc2-be5b-8c44231c3ab3/Work/0d9b1125-df3e-4a79-b1d5-e9faad2d8ae5/Unzip/System.Data.SqlClient.Tests.dll' to '/home/helixbot/dotnetbuild/work/4c28ff69-e956-4fc2-be5b-8c44231c3ab3/Work/0d9b1125-df3e-4a79-b1d5-e9faad2d8ae5/Exec/execution/System.Data.SqlClient.Tests.dll'
2017-06-13 23:39:45,632: INFO: io(27): copy_tree_to: Copying '/home/helixbot/dotnetbuild/work/4c28ff69-e956-4fc2-be5b-8c44231c3ab3/Work/0d9b1125-df3e-4a79-b1d5-e9faad2d8ae5/Unzip/RemoteExecutorConsoleApp.runtimeconfig.json' to '/home/helixbot/dotnetbuild/work/4c28ff69-e956-4fc2-be5b-8c44231c3ab3/Work/0d9b1125-df3e-4a79-b1d5-e9faad2d8ae5/Exec/execution/RemoteExecutorConsoleApp.runtimeconfig.json'
2017-06-13 23:39:45,632: INFO: io(27): copy_tree_to: Copying '/home/helixbot/dotnetbuild/work/4c28ff69-e956-4fc2-be5b-8c44231c3ab3/Work/0d9b1125-df3e-4a79-b1d5-e9faad2d8ae5/Unzip/Microsoft.SqlServer.TDS.Servers.pdb' to '/home/helixbot/dotnetbuild/work/4c28ff69-e956-4fc2-be5b-8c44231c3ab3/Work/0d9b1125-df3e-4a79-b1d5-e9faad2d8ae5/Exec/execution/Microsoft.SqlServer.TDS.Servers.pdb'
2017-06-13 23:39:45,632: INFO: io(27): copy_tree_to: Copying '/home/helixbot/dotnetbuild/work/4c28ff69-e956-4fc2-be5b-8c44231c3ab3/Work/0d9b1125-df3e-4a79-b1d5-e9faad2d8ae5/Unzip/Microsoft.SqlServer.TDS.EndPoint.dll' to '/home/helixbot/dotnetbuild/work/4c28ff69-e956-4fc2-be5b-8c44231c3ab3/Work/0d9b1125-df3e-4a79-b1d5-e9faad2d8ae5/Exec/execution/Microsoft.SqlServer.TDS.EndPoint.dll'
2017-06-13 23:39:45,632: INFO: io(27): copy_tree_to: Copying '/home/helixbot/dotnetbuild/work/4c28ff69-e956-4fc2-be5b-8c44231c3ab3/Work/0d9b1125-df3e-4a79-b1d5-e9faad2d8ae5/Unzip/DumplingHelper.py' to '/home/helixbot/dotnetbuild/work/4c28ff69-e956-4fc2-be5b-8c44231c3ab3/Work/0d9b1125-df3e-4a79-b1d5-e9faad2d8ae5/Exec/execution/DumplingHelper.py'
2017-06-13 23:39:45,632: INFO: proc(23): run_and_log_output: Running: /home/helixbot/dotnetbuild/work/4c28ff69-e956-4fc2-be5b-8c44231c3ab3/Work/0d9b1125-df3e-4a79-b1d5-e9faad2d8ae5/Unzip/RunTests.sh /home/helixbot/dotnetbuild/work/4c28ff69-e956-4fc2-be5b-8c44231c3ab3/Payload
2017-06-13 23:39:45,632: INFO: proc(26): run_and_log_output: CWD: /home/helixbot/dotnetbuild/work/4c28ff69-e956-4fc2-be5b-8c44231c3ab3/Work/0d9b1125-df3e-4a79-b1d5-e9faad2d8ae5/Unzip
2017-06-13 23:39:45,635: INFO: proc(54): run_and_log_output: Output: Using as the test runtime folder.
2017-06-13 23:39:45,637: INFO: proc(54): run_and_log_output: Output: Running tests... Start time: 23:39:45
2017-06-13 23:39:45,637: INFO: proc(54): run_and_log_output: Output: Commands:
2017-06-13 23:39:45,637: INFO: proc(54): run_and_log_output: Output: python DumplingHelper.py install_dumpling
2017-06-13 23:39:45,637: INFO: proc(54): run_and_log_output: Output: __TIMESTAMP=`python DumplingHelper.py get_timestamp`
2017-06-13 23:39:45,637: INFO: proc(54): run_and_log_output: Output: chmod +x /home/helixbot/dotnetbuild/work/4c28ff69-e956-4fc2-be5b-8c44231c3ab3/Payload/dotnet
2017-06-13 23:39:45,638: INFO: proc(54): run_and_log_output: Output: /home/helixbot/dotnetbuild/work/4c28ff69-e956-4fc2-be5b-8c44231c3ab3/Payload/dotnet xunit.console.netcore.exe System.Data.SqlClient.Tests.dll  -xml testResults.xml -notrait Benchmark=true -notrait category=nonnetcoreapptests -notrait category=nonlinuxtests  -notrait category=OuterLoop -notrait category=failing
2017-06-13 23:39:45,638: INFO: proc(54): run_and_log_output: Output: python DumplingHelper.py collect_dump $\ `pwd`  System.Data.SqlClient.Tests /mnt/j/workspace/dotnet_corefx/master/portable-linux-Config_Release+OuterLoop_false_prtest/bin/runtime/netcoreapp-Linux-Release-x64/,/mnt/j/workspace/dotnet_corefx/master/portable-linux-Config_Release+OuterLoop_false_prtest/bin/Unix.AnyCPU.Release/System.Data.SqlClient.Tests/netstandard/
2017-06-13 23:39:45,638: INFO: proc(54): run_and_log_output: Output: ~/dotnetbuild/work/4c28ff69-e956-4fc2-be5b-8c44231c3ab3/Work/0d9b1125-df3e-4a79-b1d5-e9faad2d8ae5/Unzip ~/dotnetbuild/work/4c28ff69-e956-4fc2-be5b-8c44231c3ab3/Work/0d9b1125-df3e-4a79-b1d5-e9faad2d8ae5/Unzip
2017-06-13 23:39:45,899: INFO: proc(54): run_and_log_output: Output: downloaded dumpling.py
2017-06-13 23:39:45,899: INFO: proc(54): run_and_log_output: Output: total elapsed time 0:00:00.159654
2017-06-13 23:39:46,001: INFO: proc(54): run_and_log_output: Output: xUnit.net console test runner (64-bit .NET Core)
2017-06-13 23:39:46,001: INFO: proc(54): run_and_log_output: Output: Copyright (C) 2014 Outercurve Foundation.
2017-06-13 23:39:46,002: INFO: proc(54): run_and_log_output: Output: 
2017-06-13 23:39:46,061: INFO: proc(54): run_and_log_output: Output: Discovering: System.Data.SqlClient.Tests
2017-06-13 23:39:46,231: INFO: proc(54): run_and_log_output: Output: Discovered:  System.Data.SqlClient.Tests
2017-06-13 23:39:46,316: INFO: proc(54): run_and_log_output: Output: Starting:    System.Data.SqlClient.Tests
2017-06-13 23:39:46,951: 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-13 23:39:46,989: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ConnectionTest Endpoint test successful.
2017-06-13 23:39:46,991: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ConnectionTest Listener Thread Started 
2017-06-13 23:39:47,035: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ConnectionTest Connection Closed
2017-06-13 23:39:47,175: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ConnectionTest Connection Closed
2017-06-13 23:39:47,856: INFO: proc(54): run_and_log_output: Output: Test: ExecuteReaderErrorTest Enabled Listeners
2017-06-13 23:39:47,901: 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-13 23:39:47,933: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteReaderErrorTest Endpoint test successful.
2017-06-13 23:39:47,935: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteReaderErrorTest Listener Thread Started 
2017-06-13 23:39:47,963: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteReaderErrorTest Connection Closed
2017-06-13 23:39:48,062: INFO: proc(54): run_and_log_output: Output: Test: ExecuteReaderErrorTest Started Server
2017-06-13 23:39:48,112: INFO: proc(54): run_and_log_output: Output: Test: ExecuteReaderErrorTest SqlOperation Successful
2017-06-13 23:39:48,113: INFO: proc(54): run_and_log_output: Output: Test: ExecuteReaderErrorTest Listeners Disabled
2017-06-13 23:39:48,118: INFO: proc(54): run_and_log_output: Output: Test: ExecuteReaderErrorTest Server Disposed
2017-06-13 23:39:48,118: INFO: proc(54): run_and_log_output: Output: Test: ExecuteReaderErrorTest Listeners Disposed Successfully
2017-06-13 23:39:48,314: INFO: proc(54): run_and_log_output: Output: Test: ExecuteScalarAsyncErrorTest Enabled Listeners
2017-06-13 23:39:48,359: 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-13 23:39:48,393: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteScalarAsyncErrorTest Endpoint test successful.
2017-06-13 23:39:48,393: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteScalarAsyncErrorTest Listener Thread Started 
2017-06-13 23:39:48,425: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteScalarAsyncErrorTest Connection Closed
2017-06-13 23:39:48,530: INFO: proc(54): run_and_log_output: Output: Test: ExecuteScalarAsyncErrorTest Started Server
2017-06-13 23:39:48,896: INFO: proc(54): run_and_log_output: Output: Test: ExecuteScalarAsyncErrorTest SqlOperation Successful
2017-06-13 23:39:48,896: INFO: proc(54): run_and_log_output: Output: Test: ExecuteScalarAsyncErrorTest Listeners Disabled
2017-06-13 23:39:48,907: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteScalarAsyncErrorTest Connection Closed
2017-06-13 23:39:48,907: INFO: proc(54): run_and_log_output: Output: Test: ExecuteScalarAsyncErrorTest Server Disposed
2017-06-13 23:39:48,908: INFO: proc(54): run_and_log_output: Output: Test: ExecuteScalarAsyncErrorTest Listeners Disposed Successfully
2017-06-13 23:39:49,824: INFO: proc(54): run_and_log_output: Output:    System.Data.SqlClient.Tests.DiagnosticTest.ExecuteXmlReaderAsyncErrorTest [SKIP]
2017-06-13 23:39:49,824: INFO: proc(54): run_and_log_output: Output:       Condition(s) not met: \"IsConnectionStringConfigured\"
2017-06-13 23:39:49,900: INFO: proc(54): run_and_log_output: Output: Test: ExecuteScalarErrorTest Enabled Listeners
2017-06-13 23:39:49,947: 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-13 23:39:49,980: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteScalarErrorTest Endpoint test successful.
2017-06-13 23:39:49,987: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteScalarErrorTest Listener Thread Started 
2017-06-13 23:39:50,020: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteScalarErrorTest Connection Closed
2017-06-13 23:39:50,119: INFO: proc(54): run_and_log_output: Output: Test: ExecuteScalarErrorTest Started Server
2017-06-13 23:39:50,450: INFO: proc(54): run_and_log_output: Output: Test: ExecuteScalarErrorTest SqlOperation Successful
2017-06-13 23:39:50,451: INFO: proc(54): run_and_log_output: Output: Test: ExecuteScalarErrorTest Listeners Disabled
2017-06-13 23:39:50,463: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteScalarErrorTest Connection Closed
2017-06-13 23:39:50,463: INFO: proc(54): run_and_log_output: Output: Test: ExecuteScalarErrorTest Server Disposed
2017-06-13 23:39:50,464: INFO: proc(54): run_and_log_output: Output: Test: ExecuteScalarErrorTest Listeners Disposed Successfully
2017-06-13 23:39:50,902: INFO: proc(54): run_and_log_output: Output: Test: ConnectionOpenTest Enabled Listeners
2017-06-13 23:39:50,949: 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-13 23:39:50,982: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ConnectionOpenTest Endpoint test successful.
2017-06-13 23:39:50,987: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ConnectionOpenTest Listener Thread Started 
2017-06-13 23:39:51,018: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ConnectionOpenTest Connection Closed
2017-06-13 23:39:51,116: INFO: proc(54): run_and_log_output: Output: Test: ConnectionOpenTest Started Server
2017-06-13 23:39:51,302: INFO: proc(54): run_and_log_output: Output: [6/13/17 11:39:51 PM] Request.TDSPreLoginToken.Version: 4.6.25413
2017-06-13 23:39:51,302: INFO: proc(54): run_and_log_output: Output: [6/13/17 11:39:51 PM] Request.TDSPreLoginToken.SubBuild: 0
2017-06-13 23:39:51,302: INFO: proc(54): run_and_log_output: Output: [6/13/17 11:39:51 PM] Request.TDSPreLoginToken.Encryption: Off
2017-06-13 23:39:51,302: INFO: proc(54): run_and_log_output: Output: [6/13/17 11:39:51 PM] Request.TDSPreLoginToken.ThreadID: 33554432
2017-06-13 23:39:51,303: INFO: proc(54): run_and_log_output: Output: [6/13/17 11:39:51 PM] Request.TDSPreLoginToken.IsMARS: False
2017-06-13 23:39:51,303: INFO: proc(54): run_and_log_output: Output: [6/13/17 11:39:51 PM] Request.TDSPreLoginToken.ClientTraceID: [9F 91 34 92 16 9E 5A 45 91 E9 F2 E1 19 18 33 FC ]
2017-06-13 23:39:51,304: INFO: proc(54): run_and_log_output: Output: [6/13/17 11:39:51 PM] Request.TDSPreLoginToken.ActivityID: [4E 48 9 82 A6 1C 5B 42 B7 59 B4 AF 58 34 2 52 1 0 0 0 ]
2017-06-13 23:39:51,304: INFO: proc(54): run_and_log_output: Output: [6/13/17 11:39:51 PM] Request.TDSPreLoginToken.Nonce: <null>
2017-06-13 23:39:51,304: INFO: proc(54): run_and_log_output: Output: [6/13/17 11:39:51 PM] Request.TDSPreLoginToken.FedAuthRequired: FedAuthNotRequired
2017-06-13 23:39:51,305: INFO: proc(54): run_and_log_output: Output: [6/13/17 11:39:51 PM] Response.TDSPreLoginToken.Version: 11.0.1083
2017-06-13 23:39:51,305: INFO: proc(54): run_and_log_output: Output: [6/13/17 11:39:51 PM] Response.TDSPreLoginToken.SubBuild: 0
2017-06-13 23:39:51,305: INFO: proc(54): run_and_log_output: Output: [6/13/17 11:39:51 PM] Response.TDSPreLoginToken.Encryption: NotSupported
2017-06-13 23:39:51,305: INFO: proc(54): run_and_log_output: Output: [6/13/17 11:39:51 PM] Response.TDSPreLoginToken.ThreadID: 0
2017-06-13 23:39:51,305: INFO: proc(54): run_and_log_output: Output: [6/13/17 11:39:51 PM] Response.TDSPreLoginToken.IsMARS: False
2017-06-13 23:39:51,305: INFO: proc(54): run_and_log_output: Output: [6/13/17 11:39:51 PM] Response.TDSPreLoginToken.ClientTraceID: <null>
2017-06-13 23:39:51,306: INFO: proc(54): run_and_log_output: Output: [6/13/17 11:39:51 PM] Response.TDSPreLoginToken.ActivityID: <null>
2017-06-13 23:39:51,306: INFO: proc(54): run_and_log_output: Output: [6/13/17 11:39:51 PM] Response.TDSPreLoginToken.Nonce: <null>
2017-06-13 23:39:51,306: INFO: proc(54): run_and_log_output: Output: [6/13/17 11:39:51 PM] Response.TDSPreLoginToken.FedAuthRequired: FedAuthNotRequired
2017-06-13 23:39:51,346: INFO: proc(54): run_and_log_output: Output: [6/13/17 11:39:51 PM] Request.TDSLogin7Token.TDSVersion: 7.4.0.4
2017-06-13 23:39:51,346: INFO: proc(54): run_and_log_output: Output: [6/13/17 11:39:51 PM] Request.TDSLogin7Token.PacketSize: 8000
2017-06-13 23:39:51,346: INFO: proc(54): run_and_log_output: Output: [6/13/17 11:39:51 PM] Request.TDSLogin7Token.ClientProgramVersion: 100663296
2017-06-13 23:39:51,346: INFO: proc(54): run_and_log_output: Output: [6/13/17 11:39:51 PM] Request.TDSLogin7Token.ClientPID: 838641194
2017-06-13 23:39:51,346: INFO: proc(54): run_and_log_output: Output: [6/13/17 11:39:51 PM] Request.TDSLogin7Token.ConnectionID: 0
2017-06-13 23:39:51,347: INFO: proc(54): run_and_log_output: Output: [6/13/17 11:39:51 PM] Request.TDSLogin7Token.OptionalFlags1.TDSLogin7TokenOptionalFlags1.Order: OrderX86
2017-06-13 23:39:51,347: INFO: proc(54): run_and_log_output: Output: [6/13/17 11:39:51 PM] Request.TDSLogin7Token.OptionalFlags1.TDSLogin7TokenOptionalFlags1.CharacterSet: Ascii
2017-06-13 23:39:51,347: INFO: proc(54): run_and_log_output: Output: [6/13/17 11:39:51 PM] Request.TDSLogin7Token.OptionalFlags1.TDSLogin7TokenOptionalFlags1.FloatingPoint: IEEE754
2017-06-13 23:39:51,348: INFO: proc(54): run_and_log_output: Output: [6/13/17 11:39:51 PM] Request.TDSLogin7Token.OptionalFlags1.TDSLogin7TokenOptionalFlags1.DumpLoad: On
2017-06-13 23:39:51,348: INFO: proc(54): run_and_log_output: Output: [6/13/17 11:39:51 PM] Request.TDSLogin7Token.OptionalFlags1.TDSLogin7TokenOptionalFlags1.UseDB: Off
2017-06-13 23:39:51,348: INFO: proc(54): run_and_log_output: Output: [6/13/17 11:39:51 PM] Request.TDSLogin7Token.OptionalFlags1.TDSLogin7TokenOptionalFlags1.Database: Fatal
2017-06-13 23:39:51,348: INFO: proc(54): run_and_log_output: Output: [6/13/17 11:39:51 PM] Request.TDSLogin7Token.OptionalFlags1.TDSLogin7TokenOptionalFlags1.Language: On
2017-06-13 23:39:51,349: INFO: proc(54): run_and_log_output: Output: [6/13/17 11:39:51 PM] Request.TDSLogin7Token.OptionalFlags2.TDSLogin7TokenOptionalFlags2.Language: Fatal
2017-06-13 23:39:51,349: INFO: proc(54): run_and_log_output: Output: [6/13/17 11:39:51 PM] Request.TDSLogin7Token.OptionalFlags2.TDSLogin7TokenOptionalFlags2.Odbc: On
2017-06-13 23:39:51,349: INFO: proc(54): run_and_log_output: Output: [6/13/17 11:39:51 PM] Request.TDSLogin7Token.OptionalFlags2.TDSLogin7TokenOptionalFlags2.UserType: Normal
2017-06-13 23:39:51,349: INFO: proc(54): run_and_log_output: Output: [6/13/17 11:39:51 PM] Request.TDSLogin7Token.OptionalFlags2.TDSLogin7TokenOptionalFlags2.IntegratedSecurity: Off
2017-06-13 23:39:51,349: INFO: proc(54): run_and_log_output: Output: [6/13/17 11:39:51 PM] Request.TDSLogin7Token.OptionalFlags3.TDSLogin7TokenOptionalFlags3.ChangePassword: No
2017-06-13 23:39:51,350: INFO: proc(54): run_and_log_output: Output: [6/13/17 11:39:51 PM] Request.TDSLogin7Token.OptionalFlags3.TDSLogin7TokenOptionalFlags3.IsUserInstance: False
2017-06-13 23:39:51,350: INFO: proc(54): run_and_log_output: Output: [6/13/17 11:39:51 PM] Request.TDSLogin7Token.OptionalFlags3.TDSLogin7TokenOptionalFlags3.SendYukonBinaryXML: False
2017-06-13 23:39:51,350: INFO: proc(54): run_and_log_output: Output: [6/13/17 11:39:51 PM] Request.TDSLogin7Token.OptionalFlags3.TDSLogin7TokenOptionalFlags3.UnknownCollation: Must
2017-06-13 23:39:51,350: INFO: proc(54): run_and_log_output: Output: [6/13/17 11:39:51 PM] Request.TDSLogin7Token.OptionalFlags3.TDSLogin7TokenOptionalFlags3.ExtensionFlag: True
2017-06-13 23:39:51,351: INFO: proc(54): run_and_log_output: Output: [6/13/17 11:39:51 PM] Request.TDSLogin7Token.TypeFlags.TDSLogin7TokenTypeFlags.SQL: Default
2017-06-13 23:39:51,351: INFO: proc(54): run_and_log_output: Output: [6/13/17 11:39:51 PM] Request.TDSLogin7Token.TypeFlags.TDSLogin7TokenTypeFlags.OleDb: Off
2017-06-13 23:39:51,351: INFO: proc(54): run_and_log_output: Output: [6/13/17 11:39:51 PM] Request.TDSLogin7Token.TypeFlags.TDSLogin7TokenTypeFlags.ReadOnlyIntent: ReadWrite
2017-06-13 23:39:51,351: INFO: proc(54): run_and_log_output: Output: [6/13/17 11:39:51 PM] Request.TDSLogin7Token.ClientTimeZone: 0
2017-06-13 23:39:51,351: INFO: proc(54): run_and_log_output: Output: [6/13/17 11:39:51 PM] Request.TDSLogin7Token.ClientLCID: 0
2017-06-13 23:39:51,352: INFO: proc(54): run_and_log_output: Output: [6/13/17 11:39:51 PM] Request.TDSLogin7Token.HostName: dnblup1400009AM
2017-06-13 23:39:51,352: INFO: proc(54): run_and_log_output: Output: [6/13/17 11:39:51 PM] Request.TDSLogin7Token.UserID: <null>
2017-06-13 23:39:51,352: INFO: proc(54): run_and_log_output: Output: [6/13/17 11:39:51 PM] Request.TDSLogin7Token.Password: <null>
2017-06-13 23:39:51,352: INFO: proc(54): run_and_log_output: Output: [6/13/17 11:39:51 PM] Request.TDSLogin7Token.ApplicationName: Core .Net SqlClient Data Provider
2017-06-13 23:39:51,352: INFO: proc(54): run_and_log_output: Output: [6/13/17 11:39:51 PM] Request.TDSLogin7Token.ServerName: localhost,50409
2017-06-13 23:39:51,353: INFO: proc(54): run_and_log_output: Output: [6/13/17 11:39:51 PM] Request.TDSLogin7Token.LibraryName: Core .Net SqlClient Data Provider
2017-06-13 23:39:51,353: INFO: proc(54): run_and_log_output: Output: [6/13/17 11:39:51 PM] Request.TDSLogin7Token.Language: <null>
2017-06-13 23:39:51,353: INFO: proc(54): run_and_log_output: Output: [6/13/17 11:39:51 PM] Request.TDSLogin7Token.Database: <null>
2017-06-13 23:39:51,353: INFO: proc(54): run_and_log_output: Output: [6/13/17 11:39:51 PM] Request.TDSLogin7Token.ClientID: [14 6E 71 F 27 9D ]
2017-06-13 23:39:51,353: INFO: proc(54): run_and_log_output: Output: [6/13/17 11:39:51 PM] Request.TDSLogin7Token.AttachDatabaseFile: <null>
2017-06-13 23:39:51,354: INFO: proc(54): run_and_log_output: Output: [6/13/17 11:39:51 PM] Request.TDSLogin7Token.ChangePassword: <null>
2017-06-13 23:39:51,354: INFO: proc(54): run_and_log_output: Output: [6/13/17 11:39:51 PM] Request.TDSLogin7Token.SSPI: <null>
2017-06-13 23:39:51,354: INFO: proc(54): run_and_log_output: Output: [6/13/17 11:39:51 PM] Request.TDSLogin7Token.FeatureExt[0].TDSLogin7SessionRecoveryOptionToken.FeatureID: SessionRecovery
2017-06-13 23:39:51,354: INFO: proc(54): run_and_log_output: Output: [6/13/17 11:39:51 PM] Request.TDSLogin7Token.FeatureExt[0].TDSLogin7SessionRecoveryOptionToken.Initial: <null>
2017-06-13 23:39:51,355: INFO: proc(54): run_and_log_output: Output: [6/13/17 11:39:51 PM] Request.TDSLogin7Token.FeatureExt[0].TDSLogin7SessionRecoveryOptionToken.Current: <null>
2017-06-13 23:39:51,361: INFO: proc(54): run_and_log_output: Output: [6/13/17 11:39:51 PM] Response.TDSEnvChangeToken.Type: Database
2017-06-13 23:39:51,361: INFO: proc(54): run_and_log_output: Output: [6/13/17 11:39:51 PM] Response.TDSEnvChangeToken.OldValue: master
2017-06-13 23:39:51,361: INFO: proc(54): run_and_log_output: Output: [6/13/17 11:39:51 PM] Response.TDSEnvChangeToken.NewValue: master
2017-06-13 23:39:51,361: INFO: proc(54): run_and_log_output: Output: [6/13/17 11:39:51 PM] Response.TDSInfoToken.Number: 5701
2017-06-13 23:39:51,362: INFO: proc(54): run_and_log_output: Output: [6/13/17 11:39:51 PM] Response.TDSInfoToken.State: 2
2017-06-13 23:39:51,362: INFO: proc(54): run_and_log_output: Output: [6/13/17 11:39:51 PM] Response.TDSInfoToken.Class: 0
2017-06-13 23:39:51,362: INFO: proc(54): run_and_log_output: Output: [6/13/17 11:39:51 PM] Response.TDSInfoToken.Message: Changed database context to 'master'
2017-06-13 23:39:51,362: INFO: proc(54): run_and_log_output: Output: [6/13/17 11:39:51 PM] Response.TDSInfoToken.ServerName: dnblup1400009AM
2017-06-13 23:39:51,362: INFO: proc(54): run_and_log_output: Output: [6/13/17 11:39:51 PM] Response.TDSInfoToken.ProcedureName: <null>
2017-06-13 23:39:51,363: INFO: proc(54): run_and_log_output: Output: [6/13/17 11:39:51 PM] Response.TDSInfoToken.Line: 0
2017-06-13 23:39:51,363: INFO: proc(54): run_and_log_output: Output: [6/13/17 11:39:51 PM] Response.TDSEnvChangeToken.Type: SQLCollation
2017-06-13 23:39:51,363: INFO: proc(54): run_and_log_output: Output: [6/13/17 11:39:51 PM] Response.TDSEnvChangeToken.OldValue: <null>
2017-06-13 23:39:51,363: INFO: proc(54): run_and_log_output: Output: [6/13/17 11:39:51 PM] Response.TDSEnvChangeToken.NewValue: [9 4 D0 0 34 ]
2017-06-13 23:39:51,364: INFO: proc(54): run_and_log_output: Output: [6/13/17 11:39:51 PM] Response.TDSEnvChangeToken.Type: Language
2017-06-13 23:39:51,364: INFO: proc(54): run_and_log_output: Output: [6/13/17 11:39:51 PM] Response.TDSEnvChangeToken.OldValue: <null>
2017-06-13 23:39:51,364: INFO: proc(54): run_and_log_output: Output: [6/13/17 11:39:51 PM] Response.TDSEnvChangeToken.NewValue: us_english
2017-06-13 23:39:51,364: INFO: proc(54): run_and_log_output: Output: [6/13/17 11:39:51 PM] Response.TDSInfoToken.Number: 5703
2017-06-13 23:39:51,364: INFO: proc(54): run_and_log_output: Output: [6/13/17 11:39:51 PM] Response.TDSInfoToken.State: 1
2017-06-13 23:39:51,364: INFO: proc(54): run_and_log_output: Output: [6/13/17 11:39:51 PM] Response.TDSInfoToken.Class: 0
2017-06-13 23:39:51,364: INFO: proc(54): run_and_log_output: Output: [6/13/17 11:39:51 PM] Response.TDSInfoToken.Message: Changed language setting to us_english
2017-06-13 23:39:51,364: INFO: proc(54): run_and_log_output: Output: [6/13/17 11:39:51 PM] Response.TDSInfoToken.ServerName: dnblup1400009AM
2017-06-13 23:39:51,365: INFO: proc(54): run_and_log_output: Output: [6/13/17 11:39:51 PM] Response.TDSInfoToken.ProcedureName: <null>
2017-06-13 23:39:51,365: INFO: proc(54): run_and_log_output: Output: [6/13/17 11:39:51 PM] Response.TDSInfoToken.Line: 0
2017-06-13 23:39:51,365: INFO: proc(54): run_and_log_output: Output: [6/13/17 11:39:51 PM] Response.TDSEnvChangeToken.Type: PacketSize
2017-06-13 23:39:51,365: INFO: proc(54): run_and_log_output: Output: [6/13/17 11:39:51 PM] Response.TDSEnvChangeToken.OldValue: 4096
2017-06-13 23:39:51,365: INFO: proc(54): run_and_log_output: Output: [6/13/17 11:39:51 PM] Response.TDSEnvChangeToken.NewValue: 4096
2017-06-13 23:39:51,365: INFO: proc(54): run_and_log_output: Output: [6/13/17 11:39:51 PM] Response.TDSLoginAckToken.TDSVersion: 7.4.0.4
2017-06-13 23:39:51,366: INFO: proc(54): run_and_log_output: Output: [6/13/17 11:39:51 PM] Response.TDSLoginAckToken.Interface: SQL
2017-06-13 23:39:51,366: INFO: proc(54): run_and_log_output: Output: [6/13/17 11:39:51 PM] Response.TDSLoginAckToken.ServerName: Microsoft SQL Server
2017-06-13 23:39:51,366: INFO: proc(54): run_and_log_output: Output: [6/13/17 11:39:51 PM] Response.TDSLoginAckToken.ServerVersion: 11.0.1083
2017-06-13 23:39:51,368: INFO: proc(54): run_and_log_output: Output: [6/13/17 11:39:51 PM] Response.TDSFeatureExtAckToken.Options[0].TDSFeatureExtAckSessionStateOption.Options[0].TDSSessionStateUserOptionsOption.AnsiNullDefaultOn: False
2017-06-13 23:39:51,369: INFO: proc(54): run_and_log_output: Output: [6/13/17 11:39:51 PM] Response.TDSFeatureExtAckToken.Options[0].TDSFeatureExtAckSessionStateOption.Options[0].TDSSessionStateUserOptionsOption.AnsiNulls: False
2017-06-13 23:39:51,369: INFO: proc(54): run_and_log_output: Output: [6/13/17 11:39:51 PM] Response.TDSFeatureExtAckToken.Options[0].TDSFeatureExtAckSessionStateOption.Options[0].TDSSessionStateUserOptionsOption.AnsiPadding: False
2017-06-13 23:39:51,369: INFO: proc(54): run_and_log_output: Output: [6/13/17 11:39:51 PM] Response.TDSFeatureExtAckToken.Options[0].TDSFeatureExtAckSessionStateOption.Options[0].TDSSessionStateUserOptionsOption.AnsiWarnings: False
2017-06-13 23:39:51,369: INFO: proc(54): run_and_log_output: Output: [6/13/17 11:39:51 PM] Response.TDSFeatureExtAckToken.Options[0].TDSFeatureExtAckSessionStateOption.Options[0].TDSSessionStateUserOptionsOption.ArithAbort: False
2017-06-13 23:39:51,370: INFO: proc(54): run_and_log_output: Output: [6/13/17 11:39:51 PM] Response.TDSFeatureExtAckToken.Options[0].TDSFeatureExtAckSessionStateOption.Options[0].TDSSessionStateUserOptionsOption.ArithIgnore: False
2017-06-13 23:39:51,370: INFO: proc(54): run_and_log_output: Output: [6/13/17 11:39:51 PM] Response.TDSFeatureExtAckToken.Options[0].TDSFeatureExtAckSessionStateOption.Options[0].TDSSessionStateUserOptionsOption.ConcatNullYieldsNull: False
2017-06-13 23:39:51,370: INFO: proc(54): run_and_log_output: Output: [6/13/17 11:39:51 PM] Response.TDSFeatureExtAckToken.Options[0].TDSFeatureExtAckSessionStateOption.Options[0].TDSSessionStateUserOptionsOption.CursorCloseOnCommit: False
2017-06-13 23:39:51,370: INFO: proc(54): run_and_log_output: Output: [6/13/17 11:39:51 PM] Response.TDSFeatureExtAckToken.Options[0].TDSFeatureExtAckSessionStateOption.Options[0].TDSSessionStateUserOptionsOption.ImplicitTransactions: False
2017-06-13 23:39:51,370: INFO: proc(54): run_and_log_output: Output: [6/13/17 11:39:51 PM] Response.TDSFeatureExtAckToken.Options[0].TDSFeatureExtAckSessionStateOption.Options[0].TDSSessionStateUserOptionsOption.NoCount: False
2017-06-13 23:39:51,371: INFO: proc(54): run_and_log_output: Output: [6/13/17 11:39:51 PM] Response.TDSFeatureExtAckToken.Options[0].TDSFeatureExtAckSessionStateOption.Options[0].TDSSessionStateUserOptionsOption.NumericRoundAbort: False
2017-06-13 23:39:51,371: INFO: proc(54): run_and_log_output: Output: [6/13/17 11:39:51 PM] Response.TDSFeatureExtAckToken.Options[0].TDSFeatureExtAckSessionStateOption.Options[0].TDSSessionStateUserOptionsOption.QuotedIdentifier: False
2017-06-13 23:39:51,371: INFO: proc(54): run_and_log_output: Output: [6/13/17 11:39:51 PM] Response.TDSFeatureExtAckToken.Options[0].TDSFeatureExtAckSessionStateOption.Options[0].TDSSessionStateUserOptionsOption.TransactionAbortOnError: False
2017-06-13 23:39:51,371: INFO: proc(54): run_and_log_output: Output: [6/13/17 11:39:51 PM] Response.TDSFeatureExtAckToken.Options[0].TDSFeatureExtAckSessionStateOption.Options[0].TDSSessionStateUserOptionsOption.StateID: 0
2017-06-13 23:39:51,372: INFO: proc(54): run_and_log_output: Output: [6/13/17 11:39:51 PM] Response.TDSFeatureExtAckToken.Options[0].TDSFeatureExtAckSessionStateOption.Options[1].TDSSessionStateDateFirstDateFormatOption.DateFirst: 7
2017-06-13 23:39:51,372: INFO: proc(54): run_and_log_output: Output: [6/13/17 11:39:51 PM] Response.TDSFeatureExtAckToken.Options[0].TDSFeatureExtAckSessionStateOption.Options[1].TDSSessionStateDateFirstDateFormatOption.DateFormat: MonthDayYear
2017-06-13 23:39:51,372: INFO: proc(54): run_and_log_output: Output: [6/13/17 11:39:51 PM] Response.TDSFeatureExtAckToken.Options[0].TDSFeatureExtAckSessionStateOption.Options[1].TDSSessionStateDateFirstDateFormatOption.StateID: 2
2017-06-13 23:39:51,372: INFO: proc(54): run_and_log_output: Output: [6/13/17 11:39:51 PM] Response.TDSFeatureExtAckToken.Options[0].TDSFeatureExtAckSessionStateOption.Options[2].TDSSessionStateDeadlockPriorityOption.Value: 0
2017-06-13 23:39:51,373: INFO: proc(54): run_and_log_output: Output: [6/13/17 11:39:51 PM] Response.TDSFeatureExtAckToken.Options[0].TDSFeatureExtAckSessionStateOption.Options[2].TDSSessionStateDeadlockPriorityOption.StateID: 4
2017-06-13 23:39:51,373: INFO: proc(54): run_and_log_output: Output: [6/13/17 11:39:51 PM] Response.TDSFeatureExtAckToken.Options[0].TDSFeatureExtAckSessionStateOption.Options[3].TDSSessionStateLockTimeoutOption.Value: 0
2017-06-13 23:39:51,373: INFO: proc(54): run_and_log_output: Output: [6/13/17 11:39:51 PM] Response.TDSFeatureExtAckToken.Options[0].TDSFeatureExtAckSessionStateOption.Options[3].TDSSessionStateLockTimeoutOption.StateID: 5
2017-06-13 23:39:51,373: INFO: proc(54): run_and_log_output: Output: [6/13/17 11:39:51 PM] Response.TDSFeatureExtAckToken.Options[0].TDSFeatureExtAckSessionStateOption.Options[4].TDSSessionStateISOFipsOption.TransactionIsolationLevel: ReadCommited
2017-06-13 23:39:51,373: INFO: proc(54): run_and_log_output: Output: [6/13/17 11:39:51 PM] Response.TDSFeatureExtAckToken.Options[0].TDSFeatureExtAckSessionStateOption.Options[4].TDSSessionStateISOFipsOption.StateID: 7
2017-06-13 23:39:51,374: INFO: proc(54): run_and_log_output: Output: [6/13/17 11:39:51 PM] Response.TDSFeatureExtAckToken.Options[0].TDSFeatureExtAckSessionStateOption.Options[5].TDSSessionStateTextSizeOption.Value: -1
2017-06-13 23:39:51,374: INFO: proc(54): run_and_log_output: Output: [6/13/17 11:39:51 PM] Response.TDSFeatureExtAckToken.Options[0].TDSFeatureExtAckSessionStateOption.Options[5].TDSSessionStateTextSizeOption.StateID: 9
2017-06-13 23:39:51,374: INFO: proc(54): run_and_log_output: Output: [6/13/17 11:39:51 PM] Response.TDSFeatureExtAckToken.Options[0].TDSFeatureExtAckSessionStateOption.FeatureID: SessionRecovery
2017-06-13 23:39:51,374: INFO: proc(54): run_and_log_output: Output: [6/13/17 11:39:51 PM] Response.TDSDoneToken.Status: Final
2017-06-13 23:39:51,375: INFO: proc(54): run_and_log_output: Output: [6/13/17 11:39:51 PM] Response.TDSDoneToken.Command: 0
2017-06-13 23:39:51,375: INFO: proc(54): run_and_log_output: Output: [6/13/17 11:39:51 PM] Response.TDSDoneToken.RowCount: 0
2017-06-13 23:39:51,418: INFO: proc(54): run_and_log_output: Output: SqlClient.DiagnosticsTest.ConnectionOpenTest:: Connection Opened 
2017-06-13 23:39:51,426: INFO: proc(54): run_and_log_output: Output: SqlClient.DiagnosticsTest.ConnectionOpenTest:: Connection Should Be Disposed
2017-06-13 23:39:51,426: INFO: proc(54): run_and_log_output: Output: Test: ConnectionOpenTest SqlOperation Successful
2017-06-13 23:39:51,427: INFO: proc(54): run_and_log_output: Output: Test: ConnectionOpenTest Listeners Disabled
2017-06-13 23:39:51,433: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ConnectionOpenTest Connection Closed
2017-06-13 23:39:51,439: INFO: proc(54): run_and_log_output: Output: Test: ConnectionOpenTest Server Disposed
2017-06-13 23:39:51,439: INFO: proc(54): run_and_log_output: Output: Test: ConnectionOpenTest Listeners Disposed Successfully
2017-06-13 23:39:51,439: INFO: proc(54): run_and_log_output: Output: SqlClient.DiagnosticsTest.ConnectionOpenTest:: Done with Diagnostics collection
2017-06-13 23:39:51,901: INFO: proc(54): run_and_log_output: Output: Test: ExecuteReaderWithCommandBehaviorTest Enabled Listeners
2017-06-13 23:39:51,948: 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-13 23:39:51,981: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteReaderWithCommandBehaviorTest Endpoint test successful.
2017-06-13 23:39:51,983: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteReaderWithCommandBehaviorTest Listener Thread Started 
2017-06-13 23:39:52,015: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteReaderWithCommandBehaviorTest Connection Closed
2017-06-13 23:39:52,110: INFO: proc(54): run_and_log_output: Output: Test: ExecuteReaderWithCommandBehaviorTest Started Server
2017-06-13 23:39:52,462: INFO: proc(54): run_and_log_output: Output: Test: ExecuteReaderWithCommandBehaviorTest SqlOperation Successful
2017-06-13 23:39:52,462: INFO: proc(54): run_and_log_output: Output: Test: ExecuteReaderWithCommandBehaviorTest Listeners Disabled
2017-06-13 23:39:52,472: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteReaderWithCommandBehaviorTest Connection Closed
2017-06-13 23:39:52,472: INFO: proc(54): run_and_log_output: Output: Test: ExecuteReaderWithCommandBehaviorTest Server Disposed
2017-06-13 23:39:52,473: INFO: proc(54): run_and_log_output: Output: Test: ExecuteReaderWithCommandBehaviorTest Listeners Disposed Successfully
2017-06-13 23:39:52,915: INFO: proc(54): run_and_log_output: Output: Test: ExecuteXmlReaderErrorTest Enabled Listeners
2017-06-13 23:39:52,960: 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-13 23:39:52,992: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteXmlReaderErrorTest Endpoint test successful.
2017-06-13 23:39:52,995: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteXmlReaderErrorTest Listener Thread Started 
2017-06-13 23:39:53,024: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteXmlReaderErrorTest Connection Closed
2017-06-13 23:39:53,126: INFO: proc(54): run_and_log_output: Output: Test: ExecuteXmlReaderErrorTest Started Server
2017-06-13 23:39:53,179: INFO: proc(54): run_and_log_output: Output: Test: ExecuteXmlReaderErrorTest SqlOperation Successful
2017-06-13 23:39:53,179: INFO: proc(54): run_and_log_output: Output: Test: ExecuteXmlReaderErrorTest Listeners Disabled
2017-06-13 23:39:53,185: INFO: proc(54): run_and_log_output: Output: Test: ExecuteXmlReaderErrorTest Server Disposed
2017-06-13 23:39:53,186: INFO: proc(54): run_and_log_output: Output: Test: ExecuteXmlReaderErrorTest Listeners Disposed Successfully
2017-06-13 23:39:53,720: INFO: proc(54): run_and_log_output: Output: Test: ExecuteScalarTest Enabled Listeners
2017-06-13 23:39:53,765: 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-13 23:39:53,798: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteScalarTest Endpoint test successful.
2017-06-13 23:39:53,798: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteScalarTest Listener Thread Started 
2017-06-13 23:39:53,828: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteScalarTest Connection Closed
2017-06-13 23:39:53,929: INFO: proc(54): run_and_log_output: Output: Test: ExecuteScalarTest Started Server
2017-06-13 23:39:59,045: 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-13 23:39:59,045: INFO: proc(54): run_and_log_output: Output: Assembly: System.Data.SqlClient.Tests, Version=4.3.0.0, Culture=neutral, PublicKeyToken=9d77cc7ad39b68eb
2017-06-13 23:39:59,045: INFO: proc(54): run_and_log_output: Output: Type: System.Data.SqlClient.Tests.DiagnosticTest+<>c
2017-06-13 23:39:59,045: INFO: proc(54): run_and_log_output: Output: Method: Int32 <ExecuteScalarTest>b__3_0()
2017-06-13 23:39:59,167: 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-13 23:39:59,168: 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-13 23:39:59,168: 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-13 23:39:59,168: 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-13 23:39:59,168: 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-13 23:39:59,168: 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-13 23:39:59,168: 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-13 23:39:59,168: 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-13 23:39:59,169: 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-13 23:39:59,169: 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-13 23:39:59,169: 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-13 23:39:59,169: 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-13 23:39:59,169: 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-13 23:39:59,169: 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-13 23:39:59,169: 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-13 23:39:59,169: INFO: proc(54): run_and_log_output: Output:    --- End of inner exception stack trace ---
2017-06-13 23:39:59,169: INFO: proc(54): run_and_log_output: Output:    at System.RuntimeMethodHandle.InvokeMethod(Object target, Object[] arguments, Signature sig, Boolean constructor)
2017-06-13 23:39:59,169: INFO: proc(54): run_and_log_output: Output:    at System.Reflection.RuntimeMethodInfo.UnsafeInvokeInternal(Object obj, Object[] parameters, Object[] arguments)
2017-06-13 23:39:59,169: 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-13 23:39:59,169: INFO: proc(54): run_and_log_output: Output: 
2017-06-13 23:39:59,170: 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-13 23:39:59,170: 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-13 23:39:59,170: 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-13 23:39:59,170: 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-13 23:39:59,170: 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-13 23:39:59,170: 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-13 23:39:59,170: 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-13 23:39:59,170: 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-13 23:39:59,170: 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-13 23:39:59,171: 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-13 23:39:59,171: 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-13 23:39:59,171: 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-13 23:39:59,171: 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-13 23:39:59,171: 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-13 23:39:59,171: 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-13 23:39:59,171: INFO: proc(54): run_and_log_output: Output:    --- End of inner exception stack trace ---
2017-06-13 23:39:59,171: 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-13 23:40:00,215: INFO: proc(54): run_and_log_output: Output:    System.Data.SqlClient.Tests.DiagnosticTest.ExecuteScalarTest [FAIL]
2017-06-13 23:40:00,216: INFO: proc(54): run_and_log_output: Output:       Assert.Equal() Failure
2017-06-13 23:40:00,216: INFO: proc(54): run_and_log_output: Output:       Expected: 42
2017-06-13 23:40:00,216: INFO: proc(54): run_and_log_output: Output:       Actual:   134
2017-06-13 23:40:00,220: INFO: proc(54): run_and_log_output: Output:       Stack Trace:
2017-06-13 23:40:00,231: 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(295,0): at System.Diagnostics.RemoteExecutorTestBase.RemoteInvokeHandle.Dispose()
2017-06-13 23:40:00,307: INFO: proc(54): run_and_log_output: Output: Test: ConnectionOpenErrorTest Enabled Listeners
2017-06-13 23:40:00,354: 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-13 23:40:00,386: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ConnectionOpenErrorTest Endpoint test successful.
2017-06-13 23:40:00,391: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ConnectionOpenErrorTest Listener Thread Started 
2017-06-13 23:40:00,428: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ConnectionOpenErrorTest Connection Closed
2017-06-13 23:40:00,530: INFO: proc(54): run_and_log_output: Output: Test: ConnectionOpenErrorTest Started Server
2017-06-13 23:40:01,408: INFO: proc(54): run_and_log_output: Output: Test: ConnectionOpenErrorTest SqlOperation Successful
2017-06-13 23:40:01,408: INFO: proc(54): run_and_log_output: Output: Test: ConnectionOpenErrorTest Listeners Disabled
2017-06-13 23:40:01,412: INFO: proc(54): run_and_log_output: Output: Test: ConnectionOpenErrorTest Server Disposed
2017-06-13 23:40:01,413: INFO: proc(54): run_and_log_output: Output: Test: ConnectionOpenErrorTest Listeners Disposed Successfully
2017-06-13 23:40:01,559: INFO: proc(54): run_and_log_output: Output: Test: ExecuteNonQueryErrorTest Enabled Listeners
2017-06-13 23:40:01,605: 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-13 23:40:01,637: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteNonQueryErrorTest Endpoint test successful.
2017-06-13 23:40:01,639: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteNonQueryErrorTest Listener Thread Started 
2017-06-13 23:40:01,668: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteNonQueryErrorTest Connection Closed
2017-06-13 23:40:01,767: INFO: proc(54): run_and_log_output: Output: Test: ExecuteNonQueryErrorTest Started Server
2017-06-13 23:40:02,045: INFO: proc(54): run_and_log_output: Output: SqlClient.DiagnosticTest.ExecuteNonQueryErrorTest Connection Open Successful
2017-06-13 23:40:02,080: INFO: proc(54): run_and_log_output: Output: SqlClient.DiagnosticTest.ExecuteNonQueryErrorTest Divide by zero error encountered.
2017-06-13 23:40:02,080: INFO: proc(54): run_and_log_output: Output: SqlClient.DiagnosticTest.ExecuteNonQueryErrorTest Command Executed
2017-06-13 23:40:02,081: INFO: proc(54): run_and_log_output: Output: SqlClient.DiagnosticTest.ExecuteNonQueryErrorTest Command Disposed
2017-06-13 23:40:02,088: INFO: proc(54): run_and_log_output: Output: SqlClient.DiagnosticTest.ExecuteNonQueryErrorTest Connection Disposed
2017-06-13 23:40:02,089: INFO: proc(54): run_and_log_output: Output: Test: ExecuteNonQueryErrorTest SqlOperation Successful
2017-06-13 23:40:02,089: INFO: proc(54): run_and_log_output: Output: Test: ExecuteNonQueryErrorTest Listeners Disabled
2017-06-13 23:40:02,097: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteNonQueryErrorTest Connection Closed
2017-06-13 23:40:02,098: INFO: proc(54): run_and_log_output: Output: Test: ExecuteNonQueryErrorTest Server Disposed
2017-06-13 23:40:02,099: INFO: proc(54): run_and_log_output: Output: Test: ExecuteNonQueryErrorTest Listeners Disposed Successfully
2017-06-13 23:40:02,191: INFO: proc(54): run_and_log_output: Output: Test: ExecuteNonQueryTest Enabled Listeners
2017-06-13 23:40:02,235: 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-13 23:40:02,267: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteNonQueryTest Endpoint test successful.
2017-06-13 23:40:02,271: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteNonQueryTest Listener Thread Started 
2017-06-13 23:40:02,301: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteNonQueryTest Connection Closed
2017-06-13 23:40:02,406: INFO: proc(54): run_and_log_output: Output: Test: ExecuteNonQueryTest Started Server
2017-06-13 23:40:02,757: INFO: proc(54): run_and_log_output: Output: Test: ExecuteNonQueryTest SqlOperation Successful
2017-06-13 23:40:02,757: INFO: proc(54): run_and_log_output: Output: Test: ExecuteNonQueryTest Listeners Disabled
2017-06-13 23:40:02,763: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteNonQueryTest Connection Closed
2017-06-13 23:40:02,764: INFO: proc(54): run_and_log_output: Output: Test: ExecuteNonQueryTest Server Disposed
2017-06-13 23:40:02,765: INFO: proc(54): run_and_log_output: Output: Test: ExecuteNonQueryTest Listeners Disposed Successfully
2017-06-13 23:40:02,929: INFO: proc(54): run_and_log_output: Output: Test: ExecuteNonQueryAsyncTest Enabled Listeners
2017-06-13 23:40:02,975: 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-13 23:40:03,008: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteNonQueryAsyncTest Endpoint test successful.
2017-06-13 23:40:03,011: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteNonQueryAsyncTest Listener Thread Started 
2017-06-13 23:40:03,039: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteNonQueryAsyncTest Connection Closed
2017-06-13 23:40:03,138: INFO: proc(54): run_and_log_output: Output: Test: ExecuteNonQueryAsyncTest Started Server
2017-06-13 23:40:03,499: INFO: proc(54): run_and_log_output: Output: Test: ExecuteNonQueryAsyncTest SqlOperation Successful
2017-06-13 23:40:03,500: INFO: proc(54): run_and_log_output: Output: Test: ExecuteNonQueryAsyncTest Listeners Disabled
2017-06-13 23:40:03,511: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteNonQueryAsyncTest Connection Closed
2017-06-13 23:40:03,511: INFO: proc(54): run_and_log_output: Output: Test: ExecuteNonQueryAsyncTest Server Disposed
2017-06-13 23:40:03,512: INFO: proc(54): run_and_log_output: Output: Test: ExecuteNonQueryAsyncTest Listeners Disposed Successfully
2017-06-13 23:40:03,666: INFO: proc(54): run_and_log_output: Output: Test: ConnectionOpenAsyncErrorTest Enabled Listeners
2017-06-13 23:40:03,710: 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-13 23:40:03,742: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ConnectionOpenAsyncErrorTest Endpoint test successful.
2017-06-13 23:40:03,747: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ConnectionOpenAsyncErrorTest Listener Thread Started 
2017-06-13 23:40:03,772: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ConnectionOpenAsyncErrorTest Connection Closed
2017-06-13 23:40:03,874: INFO: proc(54): run_and_log_output: Output: Test: ConnectionOpenAsyncErrorTest Started Server
2017-06-13 23:40:04,725: INFO: proc(54): run_and_log_output: Output: Test: ConnectionOpenAsyncErrorTest SqlOperation Successful
2017-06-13 23:40:04,726: INFO: proc(54): run_and_log_output: Output: Test: ConnectionOpenAsyncErrorTest Listeners Disabled
2017-06-13 23:40:04,735: INFO: proc(54): run_and_log_output: Output: Test: ConnectionOpenAsyncErrorTest Server Disposed
2017-06-13 23:40:04,736: INFO: proc(54): run_and_log_output: Output: Test: ConnectionOpenAsyncErrorTest Listeners Disposed Successfully
2017-06-13 23:40:04,908: INFO: proc(54): run_and_log_output: Output: Test: ExecuteReaderAsyncTest Enabled Listeners
2017-06-13 23:40:04,953: 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-13 23:40:04,985: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteReaderAsyncTest Endpoint test successful.
2017-06-13 23:40:04,987: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteReaderAsyncTest Listener Thread Started 
2017-06-13 23:40:05,020: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteReaderAsyncTest Connection Closed
2017-06-13 23:40:05,116: INFO: proc(54): run_and_log_output: Output: Test: ExecuteReaderAsyncTest Started Server
2017-06-13 23:40:05,493: INFO: proc(54): run_and_log_output: Output: Test: ExecuteReaderAsyncTest SqlOperation Successful
2017-06-13 23:40:05,493: INFO: proc(54): run_and_log_output: Output: Test: ExecuteReaderAsyncTest Listeners Disabled
2017-06-13 23:40:05,498: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteReaderAsyncTest Connection Closed
2017-06-13 23:40:05,501: INFO: proc(54): run_and_log_output: Output: Test: ExecuteReaderAsyncTest Server Disposed
2017-06-13 23:40:05,502: INFO: proc(54): run_and_log_output: Output: Test: ExecuteReaderAsyncTest Listeners Disposed Successfully
2017-06-13 23:40:05,639: INFO: proc(54): run_and_log_output: Output: Test: ExecuteReaderTest Enabled Listeners
2017-06-13 23:40:05,684: 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-13 23:40:05,716: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteReaderTest Endpoint test successful.
2017-06-13 23:40:05,719: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteReaderTest Listener Thread Started 
2017-06-13 23:40:05,746: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteReaderTest Connection Closed
2017-06-13 23:40:05,846: INFO: proc(54): run_and_log_output: Output: Test: ExecuteReaderTest Started Server
2017-06-13 23:40:06,193: INFO: proc(54): run_and_log_output: Output: Test: ExecuteReaderTest SqlOperation Successful
2017-06-13 23:40:06,193: INFO: proc(54): run_and_log_output: Output: Test: ExecuteReaderTest Listeners Disabled
2017-06-13 23:40:06,202: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteReaderTest Connection Closed
2017-06-13 23:40:06,203: INFO: proc(54): run_and_log_output: Output: Test: ExecuteReaderTest Server Disposed
2017-06-13 23:40:06,204: INFO: proc(54): run_and_log_output: Output: Test: ExecuteReaderTest Listeners Disposed Successfully
2017-06-13 23:40:06,295: INFO: proc(54): run_and_log_output: Output:    System.Data.SqlClient.Tests.DiagnosticTest.ExecuteXmlReaderTest [SKIP]
2017-06-13 23:40:06,295: INFO: proc(54): run_and_log_output: Output:       Condition(s) not met: \"IsConnectionStringConfigured\"
2017-06-13 23:40:06,378: INFO: proc(54): run_and_log_output: Output: Test: ExecuteNonQueryAsyncErrorTest Enabled Listeners
2017-06-13 23:40:06,423: 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-13 23:40:06,455: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteNonQueryAsyncErrorTest Endpoint test successful.
2017-06-13 23:40:06,459: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteNonQueryAsyncErrorTest Listener Thread Started 
2017-06-13 23:40:06,485: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteNonQueryAsyncErrorTest Connection Closed
2017-06-13 23:40:06,587: INFO: proc(54): run_and_log_output: Output: Test: ExecuteNonQueryAsyncErrorTest Started Server
2017-06-13 23:40:06,928: INFO: proc(54): run_and_log_output: Output: Test: ExecuteNonQueryAsyncErrorTest SqlOperation Successful
2017-06-13 23:40:06,928: INFO: proc(54): run_and_log_output: Output: Test: ExecuteNonQueryAsyncErrorTest Listeners Disabled
2017-06-13 23:40:06,939: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteNonQueryAsyncErrorTest Connection Closed
2017-06-13 23:40:06,940: INFO: proc(54): run_and_log_output: Output: Test: ExecuteNonQueryAsyncErrorTest Server Disposed
2017-06-13 23:40:06,940: INFO: proc(54): run_and_log_output: Output: Test: ExecuteNonQueryAsyncErrorTest Listeners Disposed Successfully
2017-06-13 23:40:07,031: INFO: proc(54): run_and_log_output: Output:    System.Data.SqlClient.Tests.DiagnosticTest.ExecuteXmlReaderAsyncTest [SKIP]
2017-06-13 23:40:07,031: INFO: proc(54): run_and_log_output: Output:       Condition(s) not met: \"IsConnectionStringConfigured\"
2017-06-13 23:40:07,113: INFO: proc(54): run_and_log_output: Output: Test: ExecuteReaderAsyncErrorTest Enabled Listeners
2017-06-13 23:40:07,158: 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-13 23:40:07,190: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteReaderAsyncErrorTest Endpoint test successful.
2017-06-13 23:40:07,195: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteReaderAsyncErrorTest Listener Thread Started 
2017-06-13 23:40:07,224: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteReaderAsyncErrorTest Connection Closed
2017-06-13 23:40:07,326: INFO: proc(54): run_and_log_output: Output: Test: ExecuteReaderAsyncErrorTest Started Server
2017-06-13 23:40:07,385: INFO: proc(54): run_and_log_output: Output: Test: ExecuteReaderAsyncErrorTest SqlOperation Successful
2017-06-13 23:40:07,386: INFO: proc(54): run_and_log_output: Output: Test: ExecuteReaderAsyncErrorTest Listeners Disabled
2017-06-13 23:40:07,396: INFO: proc(54): run_and_log_output: Output: Test: ExecuteReaderAsyncErrorTest Server Disposed
2017-06-13 23:40:07,397: INFO: proc(54): run_and_log_output: Output: Test: ExecuteReaderAsyncErrorTest Listeners Disposed Successfully
2017-06-13 23:40:07,551: INFO: proc(54): run_and_log_output: Output: Test: ConnectionOpenAsyncTest Enabled Listeners
2017-06-13 23:40:07,595: 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-13 23:40:07,627: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ConnectionOpenAsyncTest Endpoint test successful.
2017-06-13 23:40:07,631: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ConnectionOpenAsyncTest Listener Thread Started 
2017-06-13 23:40:07,657: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ConnectionOpenAsyncTest Connection Closed
2017-06-13 23:40:07,758: INFO: proc(54): run_and_log_output: Output: Test: ConnectionOpenAsyncTest Started Server
2017-06-13 23:40:08,048: INFO: proc(54): run_and_log_output: Output: Test: ConnectionOpenAsyncTest SqlOperation Successful
2017-06-13 23:40:08,049: INFO: proc(54): run_and_log_output: Output: Test: ConnectionOpenAsyncTest Listeners Disabled
2017-06-13 23:40:08,064: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ConnectionOpenAsyncTest Connection Closed
2017-06-13 23:40:08,064: INFO: proc(54): run_and_log_output: Output: Test: ConnectionOpenAsyncTest Server Disposed
2017-06-13 23:40:08,064: INFO: proc(54): run_and_log_output: Output: Test: ConnectionOpenAsyncTest Listeners Disposed Successfully
2017-06-13 23:40:08,187: INFO: proc(54): run_and_log_output: Output: Test: ExecuteScalarAsyncTest Enabled Listeners
2017-06-13 23:40:08,232: 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-13 23:40:08,264: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteScalarAsyncTest Endpoint test successful.
2017-06-13 23:40:08,267: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteScalarAsyncTest Listener Thread Started 
2017-06-13 23:40:08,305: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteScalarAsyncTest Connection Closed
2017-06-13 23:40:08,406: INFO: proc(54): run_and_log_output: Output: Test: ExecuteScalarAsyncTest Started Server
2017-06-13 23:40:08,809: INFO: proc(54): run_and_log_output: Output: Test: ExecuteScalarAsyncTest SqlOperation Successful
2017-06-13 23:40:08,809: INFO: proc(54): run_and_log_output: Output: Test: ExecuteScalarAsyncTest Listeners Disabled
2017-06-13 23:40:08,814: INFO: proc(54): run_and_log_output: Output: TDSServerEndPoint ExecuteScalarAsyncTest Connection Closed
2017-06-13 23:40:08,821: INFO: proc(54): run_and_log_output: Output: Test: ExecuteScalarAsyncTest Server Disposed
2017-06-13 23:40:08,822: INFO: proc(54): run_and_log_output: Output: Test: ExecuteScalarAsyncTest Listeners Disposed Successfully
2017-06-13 23:40:08,844: INFO: proc(54): run_and_log_output: Output: Finished:    System.Data.SqlClient.Tests
2017-06-13 23:40:08,849: INFO: proc(54): run_and_log_output: Output: 
2017-06-13 23:40:08,849: INFO: proc(54): run_and_log_output: Output: === TEST EXECUTION SUMMARY ===
2017-06-13 23:40:08,861: INFO: proc(54): run_and_log_output: Output:    System.Data.SqlClient.Tests  Total: 123, Errors: 0, Failed: 1, Skipped: 3, Time: 22.518s
2017-06-13 23:40:32,692: INFO: proc(54): run_and_log_output: Output: processing dump file /home/helixbot/dotnetbuild/work/4c28ff69-e956-4fc2-be5b-8c44231c3ab3/Work/0d9b1125-df3e-4a79-b1d5-e9faad2d8ae5/Unzip/core
2017-06-13 23:40:32,692: INFO: proc(54): run_and_log_output: Output: creating dumpling dump ee2338be512deeb6f1cd021af9ac27574dc6afb4
2017-06-13 23:40:32,692: INFO: proc(54): run_and_log_output: Output: uploading artifact ee2338be512deeb6f1cd021af9ac27574dc6afb4 core
2017-06-13 23:40:32,692: INFO: proc(54): run_and_log_output: Output: Traceback (most recent call last):
2017-06-13 23:40:32,692: INFO: proc(54): run_and_log_output: Output:   File "/home/helixbot/.dumpling/dumpling.py", line 1128, in <module>
2017-06-13 23:40:32,692: INFO: proc(54): run_and_log_output: Output:     main(sys.argv)
2017-06-13 23:40:32,693: INFO: proc(54): run_and_log_output: Output:   File "/home/helixbot/.dumpling/dumpling.py", line 1123, in main
2017-06-13 23:40:32,693: INFO: proc(54): run_and_log_output: Output:     cmdProc.Process(config)
2017-06-13 23:40:32,693: INFO: proc(54): run_and_log_output: Output:   File "/home/helixbot/.dumpling/dumpling.py", line 529, in Process
2017-06-13 23:40:32,693: INFO: proc(54): run_and_log_output: Output:     self.Upload(config)
2017-06-13 23:40:32,693: INFO: proc(54): run_and_log_output: Output:   File "/home/helixbot/.dumpling/dumpling.py", line 624, in Upload
2017-06-13 23:40:32,693: INFO: proc(54): run_and_log_output: Output:     self.UploadDump(config)
2017-06-13 23:40:32,694: INFO: proc(54): run_and_log_output: Output:   File "/home/helixbot/.dumpling/dumpling.py", line 644, in UploadDump
2017-06-13 23:40:32,694: INFO: proc(54): run_and_log_output: Output:     self._triage_dump(dumpid, config.dumppath, config)
2017-06-13 23:40:32,694: INFO: proc(54): run_and_log_output: Output:   File "/home/helixbot/.dumpling/dumpling.py", line 812, in _triage_dump
2017-06-13 23:40:32,694: INFO: proc(54): run_and_log_output: Output:     if config.dbgpath is None:
2017-06-13 23:40:32,694: INFO: proc(54): run_and_log_output: Output: AttributeError: DumplingConfig instance has no attribute 'dbgpath'
2017-06-13 23:40:32,707: INFO: proc(54): run_and_log_output: Output: Trying to find crash dumps for project: System.Data.SqlClient.Tests
2017-06-13 23:40:32,707: INFO: proc(54): run_and_log_output: Output: Uploading dump file: /home/helixbot/dotnetbuild/work/4c28ff69-e956-4fc2-be5b-8c44231c3ab3/Work/0d9b1125-df3e-4a79-b1d5-e9faad2d8ae5/Unzip/core
2017-06-13 23:40:32,710: INFO: proc(54): run_and_log_output: Output: ~/dotnetbuild/work/4c28ff69-e956-4fc2-be5b-8c44231c3ab3/Work/0d9b1125-df3e-4a79-b1d5-e9faad2d8ae5/Unzip
2017-06-13 23:40:32,711: INFO: proc(54): run_and_log_output: Output: Finished running tests. End time=23:40:32. Return value was 1
2017-06-13 23:40:32,713: INFO: proc(54): run_and_log_output: Output: Unable to find executable corerun
2017-06-13 23:40:32,715: INFO: proc(54): run_and_log_output: Output: Copying core file core to /tmp/coredumps
2017-06-13 23:40:32,866: INFO: proc(58): run_and_log_output: Exit Code: 1
2017-06-13 23:40:32,867: INFO: scriptrunner(57): _main: Uploading results from /home/helixbot/dotnetbuild/work/4c28ff69-e956-4fc2-be5b-8c44231c3ab3/Work/0d9b1125-df3e-4a79-b1d5-e9faad2d8ae5/Unzip/testResults.xml
2017-06-13 23:40:32,867: INFO: azure_storage(205): _upload: Uploading single blob: 'testResults.xml'
2017-06-13 23:40:33,028: INFO: saferequests(90): request_with_retry: Response complete with status code '201'
2017-06-13 23:40:33,028: INFO: scriptrunner(70): _main: Sending completion event
2017-06-13 23:40:33,028: INFO: event(38): send: Sending event type XUnitTestResult
2017-06-13 23:40:33,156: INFO: saferequests(90): request_with_retry: Response complete with status code '201'
2017-06-13 23:40:33,157: INFO: azure_storage(205): _upload: Uploading single blob: '92efbd519c5747c2b780f5066b9f70f8.log'
2017-06-13 23:40:33,312: INFO: saferequests(90): request_with_retry: Response complete with status code '201'
2017-06-13 23:40:33,312: INFO: event(38): send: Sending event type Logs
2017-06-13 23:40:33,486: INFO: saferequests(90): request_with_retry: Response complete with status code '201'