[09/04 14:55:35,Telerik.TestStudio.Desktop.exe(13708:1),Framework] BrowserProvisioner.EndService() : Connecting to the provisioning pipe "Pipe.ArtOfTest.WebAii.BrowserProvisioner_-1367098573" in order to unblock and eventually terminate it...
[09/04 14:55:35,Telerik.TestStudio.Desktop.exe(13708:1),Framework] BrowserProvisioner.EndService() : Provisioning pipe connected.
[09/04 14:55:35,Telerik.TestStudio.Desktop.exe(13708:8)] First trace message from background thread "Pipe.ArtOfTest.WebAii.BrowserProvisioner" (managed ID = 8, native ID = 8248).
[09/04 14:55:35,Telerik.TestStudio.Desktop.exe(13708:8),Framework] BrowserProvisioner.ServiceThread() : A new client has connected to the provisioning pipe but this will be treated as a termination cue.
[09/04 14:55:35,Telerik.TestStudio.Desktop.exe(13708:1),Framework] BrowserProvisioner.EndService() : Provisioning thread terminated.
[09/04 14:55:35,Telerik.TestStudio.Desktop.exe(13708:1),DialogPlayback] DialogMonitor.Stop() : Dialog monitoring is being halted.
[09/04 14:55:35,Telerik.TestStudio.Desktop.exe(13708:1),UIAutomation] AutomationExtensions.AddAutomationEventHandler() : Added a(n) WindowPatternIdentifiers.WindowOpenedProperty handler on element System.Windows.Automation.AutomationElement scope Descendants.
[09/04 14:55:35,Telerik.TestStudio.Desktop.exe(13708:1),Framework] BrowserProvisioner.EndService() : Connecting to the provisioning pipe "Pipe.ArtOfTest.WebAii.BrowserProvisioner_-2123429581" in order to unblock and eventually terminate it...
[09/04 14:55:35,Telerik.TestStudio.Desktop.exe(13708:10)] First trace message from background thread "Pipe.ArtOfTest.WebAii.BrowserProvisioner" (managed ID = 10, native ID = 9632).
[09/04 14:55:35,Telerik.TestStudio.Desktop.exe(13708:10),Framework] BrowserProvisioner.ServiceThread() : A new client has connected to the provisioning pipe but this will be treated as a termination cue.
[09/04 14:55:35,Telerik.TestStudio.Desktop.exe(13708:1),Framework] BrowserProvisioner.EndService() : Provisioning pipe connected.
[09/04 14:55:35,Telerik.TestStudio.Desktop.exe(13708:1),Framework] BrowserProvisioner.EndService() : Provisioning thread terminated.
[09/04 14:55:35,Telerik.TestStudio.Desktop.exe(13708:1),DialogPlayback] DialogMonitor.Stop() : Dialog monitoring is being halted.
[09/04 14:55:35,Telerik.TestStudio.Desktop.exe(13708:1),UIAutomation] AutomationExtensions.AddAutomationEventHandler() : Added a(n) WindowPatternIdentifiers.WindowOpenedProperty handler on element System.Windows.Automation.AutomationElement scope Descendants.
[09/04 14:55:45,Telerik.TestStudio.exe(1164:1)] First trace message from process 1164: "C:\Program Files (x86)\Telerik\Test Studio\Bin\Telerik.TestStudio.exe" 
[09/04 14:55:45,Telerik.TestStudio.exe(1164:1)] First trace message from foreground unnamed thread (managed ID = 1, native ID = 7648).
[09/04 14:55:45,Telerik.TestStudio.exe(1164:1),Error] RegistryHelper.PerformActionOnCurrentKey() : Cannot locate HKEY_LOCAL_MACHINE\SOFTWARE\Microsoft\DevDiv\vs\Servicing\10.0\VSTSCore
[09/04 14:55:45,Telerik.TestStudio.exe(1164:1),Error] RegistryHelper.PerformActionOnCurrentKey() : Cannot locate HKEY_LOCAL_MACHINE\SOFTWARE\Microsoft\DevDiv\vs\Servicing\10.0\VSTDCore
[09/04 14:55:45,Telerik.TestStudio.exe(1164:1),Error] RegistryHelper.PerformActionOnCurrentKey() : Cannot locate HKEY_LOCAL_MACHINE\SOFTWARE\Microsoft\DevDiv\vs\Servicing\10.0\PROCore
[09/04 14:55:46,Telerik.TestStudio.exe(1164:1),Analytics] ActivityLog.LogError() : Feature track failed!
[09/04 14:55:53,Telerik.TestStudio.Desktop.exe(9972:1)] First trace message from process 9972: "C:\Program Files (x86)\Telerik\Test Studio\Bin\Telerik.TestStudio.Desktop.exe" False "C:\Users\acouzin\Documents\Test Studio Projects\Swedish" /trial
[09/04 14:55:53,Telerik.TestStudio.Desktop.exe(9972:1)] First trace message from foreground thread "Application start thread" (managed ID = 1, native ID = 12292).
[09/04 14:55:53,Telerik.TestStudio.Desktop.exe(9972:1),Uncategorized] App.OnStartup() : 
     Using: Telerik.TestStudio.Core, Version=2015.2.723.0, Culture=neutral, PublicKeyToken=4fd5f65be123776c
     OS: Microsoft Windows 7 Professional 64-bit 6.1.7601 Service Pack 1
     .NET version: 4.0.30319.34209
[09/04 14:55:53,Telerik.TestStudio.Scheduling.Client.exe(12212:1)] First trace message from process 12212: "C:\Program Files (x86)\Telerik\Test Studio\Bin\Telerik.TestStudio.Scheduling.Client.exe" /EmbedExecutionManager /EmbedProcessManager
[09/04 14:55:53,Telerik.TestStudio.Scheduling.Client.exe(12212:1)] First trace message from foreground unnamed thread (managed ID = 1, native ID = 13180).
[09/04 14:55:53,Telerik.TestStudio.Scheduling.Client.exe(12212:1),TestStudio] Loader.LoadTranslatorGroups() : ------- Function Start -------
[09/04 14:55:54,Telerik.TestStudio.Scheduling.Client.exe(12212:1),TestStudio] Loader.LoadTranslatorGroups() : ------- Function End -------
[09/04 14:55:56,Telerik.TestStudio.Desktop.exe(9972:1),TestStudio] Utils.EnumPluginTypes() : Duplicate assemblies encountered while enumerating plugin types: Autofac, Version=2.6.3.862, Culture=neutral, PublicKeyToken=17863af14b0044da
[09/04 14:55:56,Telerik.TestStudio.Desktop.exe(9972:1),TestStudio] Utils.EnumPluginTypes() : Duplicate assemblies encountered while enumerating plugin types: Microsoft.CompilerServices.AsyncTargetingPack.Net4, Version=1.0.0.0, Culture=neutral, PublicKeyToken=31bf3856ad364e35
[09/04 14:55:56,Telerik.TestStudio.Desktop.exe(9972:1),TestStudio] Utils.EnumPluginTypes() : Duplicate assemblies encountered while enumerating plugin types: Telerik.TestStudio.Theme, Version=2015.2.723.0, Culture=neutral, PublicKeyToken=308294b5ce7f3552
[09/04 14:55:57,Telerik.TestStudio.Desktop.exe(9972:1),Uncategorized] Orchestrator.LoadPackages() : Telerik.TestStudio.Load.LoadPkg implements ITestStudioPkg and was added as a package.
[09/04 14:55:57,Telerik.TestStudio.Desktop.exe(9972:1),Uncategorized] Orchestrator.LoadPackages() : Telerik.TestStudio.Manual.ManualPkg implements ITestStudioPkg and was added as a package.
[09/04 14:55:57,Telerik.TestStudio.Desktop.exe(9972:1),Uncategorized] Orchestrator.LoadPackages() : Telerik.TestStudio.Web.WebPkg implements ITestStudioPkg and was added as a package.
[09/04 14:55:57,Telerik.TestStudio.Desktop.exe(9972:1),Uncategorized] Orchestrator.LoadPackages() : Telerik.TestStudio.Wpf.WpfPkg implements ITestStudioPkg and was added as a package.
[09/04 14:55:57,Telerik.TestStudio.Desktop.exe(9972:5)] First trace message from pool unnamed thread (managed ID = 5, native ID = 10924).
[09/04 14:55:57,Telerik.TestStudio.Desktop.exe(9972:5),TestStudio] Loader.LoadTranslatorGroups() : ------- Function Start -------
[09/04 14:55:57,Telerik.TestStudio.Desktop.exe(9972:1),Performance] PerformanceProfileViewModel.set_ViewingTimeRange() : Viewing time range overwritten: 9/4/2015 5:55:57 PM to 9/4/2015 5:55:57 PM.
[09/04 14:55:57,Telerik.TestStudio.Desktop.exe(9972:1),Performance] PerformanceProfileViewModel.set_ViewingTimeRange() : Viewing time range overwritten: 9/4/2015 5:55:57 PM to 9/4/2015 5:55:57 PM.
[09/04 14:55:58,Telerik.TestStudio.Desktop.exe(9972:5),TestStudio] Loader.LoadTranslatorGroups() : ------- Function End -------
[09/04 14:55:58,Telerik.TestStudio.Desktop.exe(9972:6)] First trace message from pool unnamed thread (managed ID = 6, native ID = 13016).
[09/04 14:55:58,Telerik.TestStudio.Desktop.exe(9972:6),Recording] RecorderHostService constructor : Host endpoint: net.pipe://localhost/Instance/61193/DesignCanvas/9972
[09/04 14:55:59,Telerik.TestStudio.Desktop.exe(9972:1),TestStudio] MainWindow.MainWindow_Loaded() : Test Studio (version:2015.2.723.0) started.
[09/04 14:55:59,Telerik.TestStudio.Scheduling.Client.exe(12212:1),Execution] TabControlViewModel constructor : Attempted to start a new scheduling server, but detected an already operational one.... using existing
[09/04 14:56:00,Telerik.TestStudio.Scheduling.Client.exe(12212:1),Execution] ProcessManager.StartProcess() : Starting profiler
[09/04 14:56:00,Telerik.TestStudio.Scheduling.Client.exe(12212:1),Execution] ProcessManager.CreateNativeProcess() : Started profiler:11796 url= http://lw7bos01074.medtouch.lcl:8031/
[09/04 14:56:00,Telerik.TestStudio.Scheduling.Client.exe(12212:1),Execution] ProcessManager.StartProcess() : Starting executor
[09/04 14:56:00,Telerik.TestStudio.Scheduling.Client.exe(12212:1),Execution] ProcessManager.CreateNativeProcess() : Started executor:13608 url= http://lw7bos01074.medtouch.lcl:8032/
[09/04 14:56:00,Telerik.TestStudio.Profiler.exe(11796:1)] First trace message from process 11796: "Telerik.TestStudio.Profiler.exe" "http://lw7bos01074.medtouch.lcl:8031/" ""
[09/04 14:56:00,Telerik.TestStudio.Profiler.exe(11796:1)] First trace message from foreground unnamed thread (managed ID = 1, native ID = 13636).
[09/04 14:56:00,Telerik.TestStudio.Profiler.exe(11796:1),Execution] WcfHttpRawCommunicationLayer.Start() : Starting WCF Service with RawHTTP Bindings at http://lw7bos01074.medtouch.lcl:8031/
[09/04 14:56:01,Telerik.TestStudio.Desktop.exe(9972:1),Analytics] ActivityLog.LogError() : Feature track failed!
[09/04 14:56:01,Telerik.TestStudio.Desktop.exe(9972:1),Analytics] ActivityLog.LogError() : Feature track failed!
[09/04 14:56:01,Telerik.TestStudio.Desktop.exe(9972:1),Analytics] ActivityLog.LogError() : LogMessage failed
[09/04 14:56:01,Telerik.TestStudio.Desktop.exe(9972:1),Analytics] ActivityLog.LogError() : Track feature start failed!
[09/04 14:56:01,Telerik.TestStudio.RemoteExecutor.exe(13608:1)] First trace message from process 13608: "Telerik.TestStudio.RemoteExecutor.exe" "http://lw7bos01074.medtouch.lcl:8032/" ""
[09/04 14:56:01,Telerik.TestStudio.RemoteExecutor.exe(13608:1)] First trace message from foreground unnamed thread (managed ID = 1, native ID = 13548).
[09/04 14:56:01,Telerik.TestStudio.RemoteExecutor.exe(13608:1),TestStudio] Loader.LoadTranslatorGroups() : ------- Function Start -------
[09/04 14:56:02,Telerik.TestStudio.RemoteExecutor.exe(13608:1),TestStudio] Loader.LoadTranslatorGroups() : ------- Function End -------
[09/04 14:56:03,Telerik.TestStudio.Scheduling.Client.exe(12212:4)] First trace message from pool unnamed thread (managed ID = 4, native ID = 3964).
[09/04 14:56:03,Telerik.TestStudio.Scheduling.Client.exe(12212:4),Error] WcfHttpRawRequest.Send() : EXCEPTION! (see below)
     Outer Exception Type: System.Net.WebException
     Message: Unable to connect to the remote server
     HRESULT: 0x80131509 (Official ID (if app.) = COR_E_INVALIDOPERATION, Error Bit = FAILED, Facility = FACILITY_URT, Code = 5385)
     Call Stack:
          at System.Net.HttpWebRequest.GetResponse()
          at ArtOfTest.WebAii.Messaging.Http.WcfHttpRawRequest.Send()

     Inner Exception Type: System.Net.Sockets.SocketException
     Message: No connection could be made because the target machine actively refused it 10.1.0.203:8032
     HRESULT: 0x80004005 (Official ID (if app.) = E_FAIL, Error Bit = FAILED, Facility = FACILITY_NULL, Code = 16389)
     Call Stack:
          at System.Net.Sockets.Socket.DoConnect(EndPoint endPointSnapshot, SocketAddress socketAddress)
          at System.Net.ServicePoint.ConnectSocketInternal(Boolean connectFailure, Socket s4, Socket s6, Socket& socket, IPAddress& address, ConnectSocketState state, IAsyncResult asyncResult, Exception& exception)

[09/04 14:56:03,Telerik.TestStudio.Scheduling.Client.exe(12212:4),Execution] <>c__DisplayClass10.<BeginPingingProcesses>b__b() : 13608 failed ping 1 times. Not dropping.
[09/04 14:56:05,Telerik.TestStudio.Desktop.exe(9972:4)] First trace message from pool unnamed thread (managed ID = 4, native ID = 14232).
[09/04 14:56:05,Telerik.TestStudio.Desktop.exe(9972:4),Uncategorized] BugTrackersLoader.LoadSystems() : ------- Function Start -------
[09/04 14:56:05,Telerik.TestStudio.Desktop.exe(9972:1),Uncategorized] Orchestrator.set_SelectedProject() : Active Project is : 'Swedish'
[09/04 14:56:05,Telerik.TestStudio.Desktop.exe(9972:1),Uncategorized] Orchestrator._iUIProject_ProjectOpened() : Project 'Swedish' Ready.
[09/04 14:56:06,Telerik.TestStudio.Desktop.exe(9972:11)] First trace message from pool unnamed thread (managed ID = 11, native ID = 3156).
[09/04 14:56:06,Telerik.TestStudio.Desktop.exe(9972:11),TestStudio] <>c__DisplayClasse.<LoadInternal>b__9() : Extract elements/build elements tree time: 00:00:00.2560804
[09/04 14:56:06,Telerik.TestStudio.Desktop.exe(9972:11),TestStudio] <>c__DisplayClasse.<LoadInternal>b__9() : Total Elements count: 28
[09/04 14:56:07,Telerik.TestStudio.Desktop.exe(9972:21)] First trace message from pool unnamed thread (managed ID = 21, native ID = 10252).
[09/04 14:56:07,Telerik.TestStudio.Desktop.exe(9972:21),Uncategorized] <>c__DisplayClassd.<Refresh>b__5() : Enumerating test list files in "C:\Users\acouzin\Documents\Test Studio Projects\Swedish\TestLists"...
[09/04 14:56:07,Telerik.TestStudio.Desktop.exe(9972:21),Uncategorized] <>c__DisplayClassd.<Refresh>b__5() : 1 test lists found.
[09/04 14:56:07,Telerik.TestStudio.Desktop.exe(9972:5),Uncategorized] <>c__DisplayClassd.<Refresh>b__9() : All files parsed and 1 viewmodels added.
[09/04 14:56:55,Telerik.TestStudio.Desktop.exe(9972:1),Error] RegistryHelper.PerformActionOnCurrentKey() : Cannot locate HKEY_LOCAL_MACHINE\SOFTWARE\Microsoft\DevDiv\vs\Servicing\10.0\VSTSCore
[09/04 14:56:55,Telerik.TestStudio.Desktop.exe(9972:1),Error] RegistryHelper.PerformActionOnCurrentKey() : Cannot locate HKEY_LOCAL_MACHINE\SOFTWARE\Microsoft\DevDiv\vs\Servicing\10.0\VSTDCore
[09/04 14:56:55,Telerik.TestStudio.Desktop.exe(9972:1),Error] RegistryHelper.PerformActionOnCurrentKey() : Cannot locate HKEY_LOCAL_MACHINE\SOFTWARE\Microsoft\DevDiv\vs\Servicing\10.0\PROCore
[09/04 14:56:55,Telerik.TestStudio.Desktop.exe(9972:1),Error] RegistryHelper.PerformActionOnCurrentKey() : Cannot locate HKEY_LOCAL_MACHINE\SOFTWARE\Microsoft\DevDiv\vs\Servicing\11.0\ultimate
[09/04 14:57:21,Telerik.TestStudio.RemoteExecutor.exe(13608:8)] First trace message from pool unnamed thread (managed ID = 8, native ID = 13832).
[09/04 14:57:21,Telerik.TestStudio.RemoteExecutor.exe(13608:8),Execution] RunningTestsController.StartTest() : Test run requested for Local path=C:\Users\acouzin\Documents\Test Studio Projects\Swedish
[09/04 14:57:21,Telerik.TestStudio.RemoteExecutor.exe(13608:12)] First trace message from pool unnamed thread (managed ID = 12, native ID = 2460).
[09/04 14:57:21,Telerik.TestStudio.RemoteExecutor.exe(13608:12),Uncategorized] BugTrackersLoader.LoadSystems() : ------- Function Start -------
[09/04 14:57:22,ArtOfTest.Runner.exe(4220:3)] First trace message from process 4220: "C:\Program Files (x86)\Telerik\Test Studio\Bin\ArtOfTest.Runner.exe"  /controller /parentprocesshandle=1804
[09/04 14:57:22,ArtOfTest.Runner.exe(4220:3)] First trace message from foreground thread "Remote.ArtOfTest.Runner.Listener" (managed ID = 3, native ID = 13380).
[09/04 14:57:22,ArtOfTest.Runner.exe(4220:3),TestStudio] Loader.LoadTranslatorGroups() : ------- Function Start -------
[09/04 14:57:23,ArtOfTest.Runner.exe(4220:3),TestStudio] Loader.LoadTranslatorGroups() : ------- Function End -------
[09/04 14:57:23,ArtOfTest.Runner.exe(4220:5)] First trace message from pool unnamed thread (managed ID = 5, native ID = 12784).
[09/04 14:57:23,ArtOfTest.Runner.exe(4220:5),Framework] InternetExplorerActions.LaunchNewBrowserInstance() : Process launched (ID=3768, Path="C:\Program Files\Internet Explorer\iexplore.exe", Arguments="-nomerge about:blank").
[09/04 14:57:23,ArtOfTest.Runner.exe(4220:5),Framework] InternetExplorerActions.LaunchNewBrowserInstance() : Attempting to attach on IE frame (HWND=723948)...
[09/04 14:57:24,ArtOfTest.Runner.exe(4220:5),Framework] Connector.Start() : Process launched (ID=8892, Path="C:\Windows\SysWOW64\rundll32.exe", Arguments=""C:\Program Files (x86)\Telerik\Test Studio\Bin\ArtOfTest.Connector.dll",AttachToWindowEntryPoint 788604").
[09/04 14:57:24,ArtOfTest.Runner.exe(4220:5),Framework] Connector.Start() : Process exited (ID=8892, ExitCode=0, Path="C:\Windows\SysWOW64\rundll32.exe", Arguments=""C:\Program Files (x86)\Telerik\Test Studio\Bin\ArtOfTest.Connector.dll",AttachToWindowEntryPoint 788604").
[09/04 14:57:24,ArtOfTest.Runner.exe(4220:5),Framework] InternetExplorerActions.LaunchNewBrowserInstance() : Process "Telerik.TestStudio.RemoteExecutor" (ID=13608) is assumed to host the controller.
[09/04 14:57:24,ArtOfTest.Runner.exe(4220:5),Framework] Connector.InjectCode() : ------- Function Start -------
[09/04 14:57:24,ArtOfTest.Runner.exe(4220:5),Framework] Connector.InjectCode() : Connection string: C:\windows\Microsoft.Net\assembly\GAC_MSIL\ArtOfTest.InternetExplorer\v4.0_2015.2.723.0__5339893a7cefe4d6\ArtOfTest.InternetExplorer.dll?ArtOfTest.InternetExplorer.ArtOfTestPluginEntryPoint?13608
[09/04 14:57:24,ArtOfTest.Runner.exe(4220:5),Framework] InternetExplorerActions.WaitForDocument() : Attempting to get IHTMLDocument2 from Internet Explorer HWND 788604 on another thread...
[09/04 14:57:24,ArtOfTest.Runner.exe(4220:6)] First trace message from pool thread "WaitAsync polling wait worker" (managed ID = 6, native ID = 6764).
[09/04 14:57:24,ArtOfTest.Runner.exe(4220:6),Framework] <>c__DisplayClassf.<WaitForDocument>b__8() : Attempting to get document from window handle
[09/04 14:57:24,ArtOfTest.Runner.exe(4220:5),Framework] InternetExplorerActions.WaitForDocument() : IHTMLDocument2 successfully retrieved.
[09/04 14:57:24,ArtOfTest.Runner.exe(4220:5),Framework] Connector.InjectCode() : Sending WM_COPYDATA to HWND 788604 (lpData = "C:\windows\Microsoft.Net\assembly\GAC_MSIL\ArtOfTest.InternetExplorer\v4.0_2015.2.723.0__5339893a7cefe4d6\ArtOfTest.InternetExplorer.dll?ArtOfTest.InternetExplorer.ArtOfTestPluginEntryPoint?13608")...
[09/04 14:57:24,IEXPLORE.EXE(10716:1)] First trace message from process 10716: "C:\Program Files (x86)\Internet Explorer\IEXPLORE.EXE" SCODEF:3768 CREDAT:275457 /prefetch:2
[09/04 14:57:24,IEXPLORE.EXE(10716:1)] First trace message from background unnamed thread (managed ID = 1, native ID = 13240).
[09/04 14:57:24,IEXPLORE.EXE(10716:1),Framework] ArtOfTestPluginEntryPoint.Connect() : The .NET Framework is now active inside the hooked process (HWND = 788604, PipeName = "Pipe.ArtOfTest.WebAii.BrowserProvisioner_60632509", PID = 13608).
[09/04 14:57:24,IEXPLORE.EXE(10716:1),Framework] InternetExplorerActions.WaitForDocument() : Attempting to get IHTMLDocument2 from Internet Explorer HWND 788604 on same thread...
[09/04 14:57:24,IEXPLORE.EXE(10716:1),Framework] InternetExplorerActions.WaitForDocument() : IHTMLDocument2 successfully retrieved.
[09/04 14:57:24,IEXPLORE.EXE(10716:1),Framework] BrowserRemoteClient constructor : The client ID for this hooked process will be "Client_23d55bb1-7605-403f-97cb-e86b57b77441".
[09/04 14:57:24,ArtOfTest.Runner.exe(4220:7)] First trace message from background thread "Pipe.ArtOfTest.WebAii.BrowserProvisioner" (managed ID = 7, native ID = 7664).
[09/04 14:57:24,ArtOfTest.Runner.exe(4220:7),Framework] BrowserProvisioner.ServiceThread() : A new client has connected to the provisioning pipe.
[09/04 14:57:24,IEXPLORE.EXE(10716:1),Framework] BrowserRemoteClient.CreateNamedPipes() : Named pipes created for communication with the server: {Command = "1e050355-00a3-408a-b920-841960a2bdb7.CommandPipe", Async = "1e050355-00a3-408a-b920-841960a2bdb7.AsyncPipe"}
[09/04 14:57:24,ArtOfTest.Runner.exe(4220:13)] First trace message from background thread "Remoted async command listener" (managed ID = 13, native ID = 11928).
[09/04 14:57:24,ArtOfTest.Runner.exe(4220:13),Framework] BrowserRemoted.AsyncListenerThreadEntry() : Not connected; this will now wait indefinitely for a pipe client connection...
[09/04 14:57:24,ArtOfTest.Runner.exe(4220:13),Framework] BrowserRemoted.AsyncListenerThreadEntry() : A new client has connected to the pipe.
[09/04 14:57:24,ArtOfTest.Runner.exe(4220:13),Framework] BrowserRemoted.AsyncListenerThreadEntry() : Now waiting indefinitely for a command from the pipe client...
[09/04 14:57:24,ArtOfTest.Runner.exe(4220:7),Framework] Manager.AddNewBrowser() : Added client ID "Client_23d55bb1-7605-403f-97cb-e86b57b77441" (now there is/are 1 total).
[09/04 14:57:24,ArtOfTest.Runner.exe(4220:5),Framework] Connector.InjectCode() : Returned from sending WM_COPYDATA (LRESULT=0, GetLastError()=ERROR_ALREADY_EXISTS).
[09/04 14:57:24,ArtOfTest.Runner.exe(4220:5),Framework] Connector.InjectCode() : ------- Function End -------
[09/04 14:57:24,ArtOfTest.Runner.exe(4220:5),Execution] ExecutionEngine.InternalExecuteTest() : Executing test: 'FAD- Viewing page 1 of', path: 'FAD- Viewing page 1 of.tstest.'
[09/04 14:57:24,ArtOfTest.Runner.exe(4220:5),Execution] ExtensionsManager.LoadExtensions() : ------- Function Start -------
[09/04 14:57:24,ArtOfTest.Runner.exe(4220:5),Uncategorized] ExtensionsManager.LoadExtensions() : Got 16 plugin(s) in folder "C:\Program Files (x86)\Telerik\Test Studio\Bin\Plugins".
[09/04 14:57:24,ArtOfTest.Runner.exe(4220:5),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Autofac.dll" from plugins folder.
[09/04 14:57:24,ArtOfTest.Runner.exe(4220:5),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Microsoft.CompilerServices.AsyncTargetingPack.Net4.dll" from plugins folder.
[09/04 14:57:24,ArtOfTest.Runner.exe(4220:5),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Newtonsoft.Json.dll" from plugins folder.
[09/04 14:57:24,ArtOfTest.Runner.exe(4220:5),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "System.Net.Http.dll" from plugins folder.
[09/04 14:57:24,ArtOfTest.Runner.exe(4220:5),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "System.Net.Http.Formatting.dll" from plugins folder.
[09/04 14:57:24,ArtOfTest.Runner.exe(4220:5),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "System.Web.Http.dll" from plugins folder.
[09/04 14:57:24,ArtOfTest.Runner.exe(4220:5),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.Jira.BugTracking.dll" from plugins folder.
[09/04 14:57:24,ArtOfTest.Runner.exe(4220:5),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.Load.dll" from plugins folder.
[09/04 14:57:24,ArtOfTest.Runner.exe(4220:5),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.Manual.dll" from plugins folder.
[09/04 14:57:25,ArtOfTest.Runner.exe(4220:5),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.OTA.Interop.dll" from plugins folder.
[09/04 14:57:25,ArtOfTest.Runner.exe(4220:5),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.Plugins.dll" from plugins folder.
[09/04 14:57:25,ArtOfTest.Runner.exe(4220:5),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.Shared.dll" from plugins folder.
[09/04 14:57:25,ArtOfTest.Runner.exe(4220:5),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.TeamPulse.dll" from plugins folder.
[09/04 14:57:25,ArtOfTest.Runner.exe(4220:5),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.Theme.dll" from plugins folder.
[09/04 14:57:25,ArtOfTest.Runner.exe(4220:5),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.Web.dll" from plugins folder.
[09/04 14:57:25,ArtOfTest.Runner.exe(4220:5),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.Wpf.dll" from plugins folder.
[09/04 14:57:25,ArtOfTest.Runner.exe(4220:5),Execution] ExtensionsManager.LoadExtensions() : ------- Function End -------
[09/04 14:57:25,ArtOfTest.Runner.exe(4220:5),TestStudio] VisualDebugger.SetCurrentResult() : VisualDebugger::SetCurrentResult
[09/04 14:57:25,ArtOfTest.Runner.exe(4220:5),DialogPlayback] DialogMonitor.Start() : Beginning monitoring for dialogs from the set {}.
[09/04 14:57:25,ArtOfTest.Runner.exe(4220:5),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[09/04 14:57:25,ArtOfTest.Runner.exe(4220:5),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 1 "Navigate to : 'http://www.swedish.org/'"...
[09/04 14:57:25,ArtOfTest.Runner.exe(4220:9)] First trace message from background thread "UAI Dispatcher Thread" (managed ID = 9, native ID = 13112).
[09/04 14:57:25,ArtOfTest.Runner.exe(4220:9),UIAutomation] AutomationExtensions.AddStructureChangedEventHandler() : Added a structure change handler on element System.Windows.Automation.AutomationElement scope Subtree.
[09/04 14:57:26,ArtOfTest.Runner.exe(4220:5),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 1 "Navigate to : 'http://www.swedish.org/'".
[09/04 14:57:26,ArtOfTest.Runner.exe(4220:5),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[09/04 14:57:26,ArtOfTest.Runner.exe(4220:5),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 2 "Click 'FindALink'"...
[09/04 14:57:27,ArtOfTest.Runner.exe(4220:5),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 2 "Click 'FindALink'".
[09/04 14:57:27,ArtOfTest.Runner.exe(4220:5),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[09/04 14:57:27,ArtOfTest.Runner.exe(4220:5),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 3 "Enter text 'Cardiac Surgery' in 'Main0Contentpanel2TxtHealthConditionAutoCompleteText'"...
[09/04 14:57:27,ArtOfTest.Runner.exe(4220:5),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 3 "Enter text 'Cardiac Surgery' in 'Main0Contentpanel2TxtHealthConditionAutoCompleteText'".
[09/04 14:57:27,ArtOfTest.Runner.exe(4220:5),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[09/04 14:57:27,ArtOfTest.Runner.exe(4220:5),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 4 "Click 'Main0Contentpanel2BtnSubmitSubmit'"...
[09/04 14:57:29,ArtOfTest.Runner.exe(4220:5),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 4 "Click 'Main0Contentpanel2BtnSubmitSubmit'".
[09/04 14:57:29,ArtOfTest.Runner.exe(4220:5),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[09/04 14:57:29,ArtOfTest.Runner.exe(4220:5),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 5 "Wait for Exists 'HtmlTag'"...
[09/04 14:57:29,ArtOfTest.Runner.exe(4220:5),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 5 "Wait for Exists 'HtmlTag'".
[09/04 14:57:29,ArtOfTest.Runner.exe(4220:5),TestStudio] VisualDebugger.ShutDown() : VisualDebbuger::ShutDown::Start
[09/04 14:57:29,ArtOfTest.Runner.exe(4220:5),TestStudio] VisualDebugger.ShutDown() : VisualDebbuger::ShutDown::End
[09/04 14:57:29,ArtOfTest.Runner.exe(4220:5),Framework] BrowserProvisioner.EndService() : Connecting to the provisioning pipe "Pipe.ArtOfTest.WebAii.BrowserProvisioner_60632509" in order to unblock and eventually terminate it...
[09/04 14:57:29,ArtOfTest.Runner.exe(4220:7),Framework] BrowserProvisioner.ServiceThread() : A new client has connected to the provisioning pipe but this will be treated as a termination cue.
[09/04 14:57:29,ArtOfTest.Runner.exe(4220:5),Framework] BrowserProvisioner.EndService() : Provisioning pipe connected.
[09/04 14:57:29,ArtOfTest.Runner.exe(4220:5),Framework] BrowserProvisioner.EndService() : Provisioning thread terminated.
[09/04 14:57:29,ArtOfTest.Runner.exe(4220:5),DialogPlayback] DialogMonitor.AddDialog() : Added {OnBeforeUnloadDialog(Dismiss=OK)} for monitoring.
[09/04 14:57:29,ArtOfTest.Runner.exe(4220:5),Framework] Manager.RemoveBrowser() : Removed client ID "Client_23d55bb1-7605-403f-97cb-e86b57b77441" (0 remaining).
[09/04 14:57:29,ArtOfTest.Runner.exe(4220:5),Framework] Manager.SetActiveBrowser() : Active browser is now null.
[09/04 14:57:29,ArtOfTest.Runner.exe(4220:5),Framework] BrowserRemoted.End() : Now aborting the listener thread (TID = 13).
[09/04 14:57:29,IEXPLORE.EXE(10716:1),Framework] BrowserRemoteClient.Disconnect() : Sending ClientDisconnectRequest(ClientId="Client_23d55bb1-7605-403f-97cb-e86b57b77441") to pipe server...
[09/04 14:57:29,IEXPLORE.EXE(10716:1),Framework] BrowserRemoteClient.Disconnect() : Command pipe is already disconnected.
[09/04 14:57:29,ArtOfTest.Runner.exe(4220:13),Warning] PipeCommunication.SyncPipeRead() : Pipe read broken by thread-abort.
[09/04 14:57:29,ArtOfTest.Runner.exe(4220:9),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[09/04 14:57:29,ArtOfTest.Runner.exe(4220:5),DialogPlayback] DialogMonitor.Stop() : Dialog monitoring is being halted.
[09/04 14:57:29,ArtOfTest.Runner.exe(4220:9),UIAutomation] AutomationExtensions.RemoveStructureChangedEventHandler() : Removed the structure change handler on element System.Windows.Automation.AutomationElement scope Subtree.
[09/04 14:57:29,ArtOfTest.Runner.exe(4220:5),UIAutomation] AutomationExtensions.AddAutomationEventHandler() : Added a(n) WindowPatternIdentifiers.WindowOpenedProperty handler on element System.Windows.Automation.AutomationElement scope Descendants.
[09/04 14:57:30,Telerik.TestStudio.RemoteExecutor.exe(13608:15)] First trace message from background thread "Callback Listener Thread" (managed ID = 15, native ID = 8704).
[09/04 14:57:30,Telerik.TestStudio.RemoteExecutor.exe(13608:15),Execution] TestPlatform.AfterRunFinished() : Finished running test platform- attempting publish
[09/04 14:57:31,Telerik.TestStudio.RemoteExecutor.exe(13608:13)] First trace message from pool unnamed thread (managed ID = 13, native ID = 13316).
[09/04 14:57:31,Telerik.TestStudio.RemoteExecutor.exe(13608:13),Execution] RunningTestsController.StartTest() : Test run requested for Local path=C:\Users\acouzin\Documents\Test Studio Projects\Swedish
[09/04 14:57:31,ArtOfTest.Runner.exe(4220:6)] First trace message from pool unnamed thread (managed ID = 6, native ID = 6764).
[09/04 14:57:31,ArtOfTest.Runner.exe(4220:6),Framework] InternetExplorerActions.LaunchNewBrowserInstance() : Process launched (ID=9924, Path="C:\Program Files\Internet Explorer\iexplore.exe", Arguments="-nomerge about:blank").
[09/04 14:57:32,ArtOfTest.Runner.exe(4220:6),Framework] InternetExplorerActions.LaunchNewBrowserInstance() : Attempting to attach on IE frame (HWND=1117184)...
[09/04 14:57:32,ArtOfTest.Runner.exe(4220:6),Framework] Connector.Start() : Process launched (ID=13092, Path="C:\Windows\SysWOW64\rundll32.exe", Arguments=""C:\Program Files (x86)\Telerik\Test Studio\Bin\ArtOfTest.Connector.dll",AttachToWindowEntryPoint 1968794").
[09/04 14:57:32,ArtOfTest.Runner.exe(4220:6),Framework] Connector.Start() : Process exited (ID=13092, ExitCode=0, Path="C:\Windows\SysWOW64\rundll32.exe", Arguments=""C:\Program Files (x86)\Telerik\Test Studio\Bin\ArtOfTest.Connector.dll",AttachToWindowEntryPoint 1968794").
[09/04 14:57:32,ArtOfTest.Runner.exe(4220:6),Framework] InternetExplorerActions.LaunchNewBrowserInstance() : Process "Telerik.TestStudio.RemoteExecutor" (ID=13608) is assumed to host the controller.
[09/04 14:57:32,ArtOfTest.Runner.exe(4220:6),Framework] Connector.InjectCode() : ------- Function Start -------
[09/04 14:57:32,ArtOfTest.Runner.exe(4220:6),Framework] Connector.InjectCode() : Connection string: C:\windows\Microsoft.Net\assembly\GAC_MSIL\ArtOfTest.InternetExplorer\v4.0_2015.2.723.0__5339893a7cefe4d6\ArtOfTest.InternetExplorer.dll?ArtOfTest.InternetExplorer.ArtOfTestPluginEntryPoint?13608
[09/04 14:57:32,ArtOfTest.Runner.exe(4220:6),Framework] InternetExplorerActions.WaitForDocument() : Attempting to get IHTMLDocument2 from Internet Explorer HWND 1968794 on another thread...
[09/04 14:57:32,ArtOfTest.Runner.exe(4220:10)] First trace message from pool thread "WaitAsync polling wait worker" (managed ID = 10, native ID = 14308).
[09/04 14:57:32,ArtOfTest.Runner.exe(4220:10),Framework] <>c__DisplayClassf.<WaitForDocument>b__8() : Attempting to get document from window handle
[09/04 14:57:32,ArtOfTest.Runner.exe(4220:6),Framework] InternetExplorerActions.WaitForDocument() : IHTMLDocument2 successfully retrieved.
[09/04 14:57:32,ArtOfTest.Runner.exe(4220:6),Framework] Connector.InjectCode() : Sending WM_COPYDATA to HWND 1968794 (lpData = "C:\windows\Microsoft.Net\assembly\GAC_MSIL\ArtOfTest.InternetExplorer\v4.0_2015.2.723.0__5339893a7cefe4d6\ArtOfTest.InternetExplorer.dll?ArtOfTest.InternetExplorer.ArtOfTestPluginEntryPoint?13608")...
[09/04 14:57:32,IEXPLORE.EXE(4028:1)] First trace message from process 4028: "C:\Program Files (x86)\Internet Explorer\IEXPLORE.EXE" SCODEF:9924 CREDAT:275457 /prefetch:2
[09/04 14:57:32,IEXPLORE.EXE(4028:1)] First trace message from background unnamed thread (managed ID = 1, native ID = 13840).
[09/04 14:57:32,IEXPLORE.EXE(4028:1),Framework] ArtOfTestPluginEntryPoint.Connect() : The .NET Framework is now active inside the hooked process (HWND = 1968794, PipeName = "Pipe.ArtOfTest.WebAii.BrowserProvisioner_1648285046", PID = 13608).
[09/04 14:57:32,IEXPLORE.EXE(4028:1),Framework] InternetExplorerActions.WaitForDocument() : Attempting to get IHTMLDocument2 from Internet Explorer HWND 1968794 on same thread...
[09/04 14:57:32,IEXPLORE.EXE(4028:1),Framework] InternetExplorerActions.WaitForDocument() : IHTMLDocument2 successfully retrieved.
[09/04 14:57:32,IEXPLORE.EXE(4028:1),Framework] BrowserRemoteClient constructor : The client ID for this hooked process will be "Client_21684d31-4a5c-4f6c-b8c2-924642e67548".
[09/04 14:57:32,ArtOfTest.Runner.exe(4220:19)] First trace message from background thread "Pipe.ArtOfTest.WebAii.BrowserProvisioner" (managed ID = 19, native ID = 5724).
[09/04 14:57:32,ArtOfTest.Runner.exe(4220:19),Framework] BrowserProvisioner.ServiceThread() : A new client has connected to the provisioning pipe.
[09/04 14:57:32,ArtOfTest.Runner.exe(4220:20)] First trace message from background thread "Remoted async command listener" (managed ID = 20, native ID = 12460).
[09/04 14:57:32,ArtOfTest.Runner.exe(4220:20),Framework] BrowserRemoted.AsyncListenerThreadEntry() : Not connected; this will now wait indefinitely for a pipe client connection...
[09/04 14:57:32,IEXPLORE.EXE(4028:1),Framework] BrowserRemoteClient.CreateNamedPipes() : Named pipes created for communication with the server: {Command = "9f0fa252-4599-4b27-aba5-bd5f15ba0e2b.CommandPipe", Async = "9f0fa252-4599-4b27-aba5-bd5f15ba0e2b.AsyncPipe"}
[09/04 14:57:32,ArtOfTest.Runner.exe(4220:20),Framework] BrowserRemoted.AsyncListenerThreadEntry() : A new client has connected to the pipe.
[09/04 14:57:32,ArtOfTest.Runner.exe(4220:20),Framework] BrowserRemoted.AsyncListenerThreadEntry() : Now waiting indefinitely for a command from the pipe client...
[09/04 14:57:32,ArtOfTest.Runner.exe(4220:19),Framework] Manager.AddNewBrowser() : Added client ID "Client_21684d31-4a5c-4f6c-b8c2-924642e67548" (now there is/are 1 total).
[09/04 14:57:32,ArtOfTest.Runner.exe(4220:6),Framework] Connector.InjectCode() : Returned from sending WM_COPYDATA (LRESULT=0, GetLastError()=ERROR_ALREADY_EXISTS).
[09/04 14:57:32,ArtOfTest.Runner.exe(4220:6),Framework] Connector.InjectCode() : ------- Function End -------
[09/04 14:57:32,ArtOfTest.Runner.exe(4220:6),Execution] ExecutionEngine.InternalExecuteTest() : Executing test: 'Schedule Appt - Exisitng Patients', path: 'Schedule Appt - Exisitng Patients.tstest.'
[09/04 14:57:32,ArtOfTest.Runner.exe(4220:6),TestStudio] VisualDebugger.SetCurrentResult() : VisualDebugger::SetCurrentResult
[09/04 14:57:32,ArtOfTest.Runner.exe(4220:6),DialogPlayback] DialogMonitor.Start() : Beginning monitoring for dialogs from the set {}.
[09/04 14:57:32,ArtOfTest.Runner.exe(4220:6),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[09/04 14:57:32,ArtOfTest.Runner.exe(4220:6),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 1 "Navigate to : 'http://www.swedish.org/'"...
[09/04 14:57:33,ArtOfTest.Runner.exe(4220:21)] First trace message from background thread "UAI Dispatcher Thread" (managed ID = 21, native ID = 8696).
[09/04 14:57:33,ArtOfTest.Runner.exe(4220:21),UIAutomation] AutomationExtensions.AddStructureChangedEventHandler() : Added a structure change handler on element System.Windows.Automation.AutomationElement scope Subtree.
[09/04 14:57:33,ArtOfTest.Runner.exe(4220:6),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 1 "Navigate to : 'http://www.swedish.org/'".
[09/04 14:57:33,ArtOfTest.Runner.exe(4220:6),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[09/04 14:57:33,ArtOfTest.Runner.exe(4220:6),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 2 "Click 'FindALink'"...
[09/04 14:57:35,ArtOfTest.Runner.exe(4220:6),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 2 "Click 'FindALink'".
[09/04 14:57:35,ArtOfTest.Runner.exe(4220:6),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[09/04 14:57:35,ArtOfTest.Runner.exe(4220:6),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 3 "Enter text 'Meena Mital' in 'Main0Contentpanel2TxtNameAutoCompleteText'"...
[09/04 14:57:35,ArtOfTest.Runner.exe(4220:6),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 3 "Enter text 'Meena Mital' in 'Main0Contentpanel2TxtNameAutoCompleteText'".
[09/04 14:57:35,ArtOfTest.Runner.exe(4220:6),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[09/04 14:57:35,ArtOfTest.Runner.exe(4220:6),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 4 "Click 'Main0Contentpanel2BtnNameSubmitSubmit'"...
[09/04 14:57:36,ArtOfTest.Runner.exe(4220:6),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 4 "Click 'Main0Contentpanel2BtnNameSubmitSubmit'".
[09/04 14:57:36,ArtOfTest.Runner.exe(4220:6),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[09/04 14:57:36,ArtOfTest.Runner.exe(4220:6),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 5 "Click 'Main0Contentpanel2LvSearchResultsHlMoreLink0Link'"...
[09/04 14:57:37,ArtOfTest.Runner.exe(4220:6),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 5 "Click 'Main0Contentpanel2LvSearchResultsHlMoreLink0Link'".
[09/04 14:57:37,ArtOfTest.Runner.exe(4220:6),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[09/04 14:57:38,ArtOfTest.Runner.exe(4220:6),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 6 "Click 'ScheduleDiv'"...
[09/04 14:57:39,ArtOfTest.Runner.exe(4220:6),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 6 "Click 'ScheduleDiv'".
[09/04 14:57:39,ArtOfTest.Runner.exe(4220:6),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[09/04 14:57:39,ArtOfTest.Runner.exe(4220:6),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 7 "Click 'YesDiv'"...
[09/04 14:57:40,ArtOfTest.Runner.exe(4220:6),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 7 "Click 'YesDiv'".
[09/04 14:57:40,ArtOfTest.Runner.exe(4220:6),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[09/04 14:57:40,ArtOfTest.Runner.exe(4220:6),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 8 "Verify element 'ScheduleH3Tag' 'is' visible."...
[09/04 14:57:40,ArtOfTest.Runner.exe(4220:6),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 8 "Verify element 'ScheduleH3Tag' 'is' visible.".
[09/04 14:57:40,ArtOfTest.Runner.exe(4220:6),TestStudio] VisualDebugger.ShutDown() : VisualDebbuger::ShutDown::Start
[09/04 14:57:40,ArtOfTest.Runner.exe(4220:6),TestStudio] VisualDebugger.ShutDown() : VisualDebbuger::ShutDown::End
[09/04 14:57:40,ArtOfTest.Runner.exe(4220:6),Framework] BrowserProvisioner.EndService() : Connecting to the provisioning pipe "Pipe.ArtOfTest.WebAii.BrowserProvisioner_1648285046" in order to unblock and eventually terminate it...
[09/04 14:57:40,ArtOfTest.Runner.exe(4220:6),Framework] BrowserProvisioner.EndService() : Provisioning pipe connected.
[09/04 14:57:40,ArtOfTest.Runner.exe(4220:19),Framework] BrowserProvisioner.ServiceThread() : A new client has connected to the provisioning pipe but this will be treated as a termination cue.
[09/04 14:57:40,ArtOfTest.Runner.exe(4220:6),Framework] BrowserProvisioner.EndService() : Provisioning thread terminated.
[09/04 14:57:40,ArtOfTest.Runner.exe(4220:6),DialogPlayback] DialogMonitor.AddDialog() : Added {OnBeforeUnloadDialog(Dismiss=OK)} for monitoring.
[09/04 14:57:40,ArtOfTest.Runner.exe(4220:6),Framework] Manager.RemoveBrowser() : Removed client ID "Client_21684d31-4a5c-4f6c-b8c2-924642e67548" (0 remaining).
[09/04 14:57:40,ArtOfTest.Runner.exe(4220:6),Framework] Manager.SetActiveBrowser() : Active browser is now null.
[09/04 14:57:40,ArtOfTest.Runner.exe(4220:6),Framework] BrowserRemoted.End() : Now aborting the listener thread (TID = 20).
[09/04 14:57:40,IEXPLORE.EXE(4028:1),Framework] BrowserRemoteClient.Disconnect() : Sending ClientDisconnectRequest(ClientId="Client_21684d31-4a5c-4f6c-b8c2-924642e67548") to pipe server...
[09/04 14:57:40,IEXPLORE.EXE(4028:1),Framework] BrowserRemoteClient.Disconnect() : Command pipe is already disconnected.
[09/04 14:57:40,ArtOfTest.Runner.exe(4220:20),Warning] PipeCommunication.SyncPipeRead() : Pipe read broken by thread-abort.
[09/04 14:57:41,ArtOfTest.Runner.exe(4220:6),DialogPlayback] DialogMonitor.Stop() : Dialog monitoring is being halted.
[09/04 14:57:41,ArtOfTest.Runner.exe(4220:6),UIAutomation] AutomationExtensions.AddAutomationEventHandler() : Added a(n) WindowPatternIdentifiers.WindowOpenedProperty handler on element System.Windows.Automation.AutomationElement scope Descendants.
[09/04 14:57:41,ArtOfTest.Runner.exe(4220:21),UIAutomation] AutomationExtensions.RemoveStructureChangedEventHandler() : Removed the structure change handler on element System.Windows.Automation.AutomationElement scope Subtree.
[09/04 14:57:41,Telerik.TestStudio.RemoteExecutor.exe(13608:15),Execution] TestPlatform.AfterRunFinished() : Finished running test platform- attempting publish
[09/04 14:57:46,Telerik.TestStudio.RemoteExecutor.exe(13608:8),Execution] RunningTestsController.StartTest() : Test run requested for Local path=C:\Users\acouzin\Documents\Test Studio Projects\Swedish
[09/04 14:57:46,ArtOfTest.Runner.exe(4220:10)] First trace message from pool unnamed thread (managed ID = 10, native ID = 14308).
[09/04 14:57:46,ArtOfTest.Runner.exe(4220:10),Framework] InternetExplorerActions.LaunchNewBrowserInstance() : Process launched (ID=10428, Path="C:\Program Files\Internet Explorer\iexplore.exe", Arguments="-nomerge about:blank").
[09/04 14:57:47,ArtOfTest.Runner.exe(4220:10),Framework] InternetExplorerActions.LaunchNewBrowserInstance() : Attempting to attach on IE frame (HWND=2034234)...
[09/04 14:57:47,ArtOfTest.Runner.exe(4220:10),Framework] Connector.Start() : Process launched (ID=8896, Path="C:\Windows\SysWOW64\rundll32.exe", Arguments=""C:\Program Files (x86)\Telerik\Test Studio\Bin\ArtOfTest.Connector.dll",AttachToWindowEntryPoint 1706070").
[09/04 14:57:47,ArtOfTest.Runner.exe(4220:10),Framework] Connector.Start() : Process exited (ID=8896, ExitCode=0, Path="C:\Windows\SysWOW64\rundll32.exe", Arguments=""C:\Program Files (x86)\Telerik\Test Studio\Bin\ArtOfTest.Connector.dll",AttachToWindowEntryPoint 1706070").
[09/04 14:57:47,ArtOfTest.Runner.exe(4220:10),Framework] InternetExplorerActions.LaunchNewBrowserInstance() : Process "Telerik.TestStudio.RemoteExecutor" (ID=13608) is assumed to host the controller.
[09/04 14:57:47,ArtOfTest.Runner.exe(4220:10),Framework] Connector.InjectCode() : ------- Function Start -------
[09/04 14:57:47,ArtOfTest.Runner.exe(4220:10),Framework] Connector.InjectCode() : Connection string: C:\windows\Microsoft.Net\assembly\GAC_MSIL\ArtOfTest.InternetExplorer\v4.0_2015.2.723.0__5339893a7cefe4d6\ArtOfTest.InternetExplorer.dll?ArtOfTest.InternetExplorer.ArtOfTestPluginEntryPoint?13608
[09/04 14:57:47,ArtOfTest.Runner.exe(4220:10),Framework] InternetExplorerActions.WaitForDocument() : Attempting to get IHTMLDocument2 from Internet Explorer HWND 1706070 on another thread...
[09/04 14:57:47,ArtOfTest.Runner.exe(4220:6)] First trace message from pool thread "WaitAsync polling wait worker" (managed ID = 6, native ID = 6764).
[09/04 14:57:47,ArtOfTest.Runner.exe(4220:6),Framework] <>c__DisplayClassf.<WaitForDocument>b__8() : Attempting to get document from window handle
[09/04 14:57:47,ArtOfTest.Runner.exe(4220:10),Framework] InternetExplorerActions.WaitForDocument() : IHTMLDocument2 successfully retrieved.
[09/04 14:57:47,ArtOfTest.Runner.exe(4220:10),Framework] Connector.InjectCode() : Sending WM_COPYDATA to HWND 1706070 (lpData = "C:\windows\Microsoft.Net\assembly\GAC_MSIL\ArtOfTest.InternetExplorer\v4.0_2015.2.723.0__5339893a7cefe4d6\ArtOfTest.InternetExplorer.dll?ArtOfTest.InternetExplorer.ArtOfTestPluginEntryPoint?13608")...
[09/04 14:57:47,IEXPLORE.EXE(13260:1)] First trace message from process 13260: "C:\Program Files (x86)\Internet Explorer\IEXPLORE.EXE" SCODEF:10428 CREDAT:275457 /prefetch:2
[09/04 14:57:47,IEXPLORE.EXE(13260:1)] First trace message from background unnamed thread (managed ID = 1, native ID = 14060).
[09/04 14:57:47,IEXPLORE.EXE(13260:1),Framework] ArtOfTestPluginEntryPoint.Connect() : The .NET Framework is now active inside the hooked process (HWND = 1706070, PipeName = "Pipe.ArtOfTest.WebAii.BrowserProvisioner_1305761118", PID = 13608).
[09/04 14:57:47,IEXPLORE.EXE(13260:1),Framework] InternetExplorerActions.WaitForDocument() : Attempting to get IHTMLDocument2 from Internet Explorer HWND 1706070 on same thread...
[09/04 14:57:47,IEXPLORE.EXE(13260:1),Framework] InternetExplorerActions.WaitForDocument() : IHTMLDocument2 successfully retrieved.
[09/04 14:57:47,IEXPLORE.EXE(13260:1),Framework] BrowserRemoteClient constructor : The client ID for this hooked process will be "Client_76dd2354-48d6-444c-a705-76c2cbe77f25".
[09/04 14:57:47,ArtOfTest.Runner.exe(4220:11)] First trace message from background thread "Pipe.ArtOfTest.WebAii.BrowserProvisioner" (managed ID = 11, native ID = 8248).
[09/04 14:57:47,ArtOfTest.Runner.exe(4220:11),Framework] BrowserProvisioner.ServiceThread() : A new client has connected to the provisioning pipe.
[09/04 14:57:47,ArtOfTest.Runner.exe(4220:13)] First trace message from background thread "Remoted async command listener" (managed ID = 13, native ID = 11408).
[09/04 14:57:47,ArtOfTest.Runner.exe(4220:13),Framework] BrowserRemoted.AsyncListenerThreadEntry() : Not connected; this will now wait indefinitely for a pipe client connection...
[09/04 14:57:47,IEXPLORE.EXE(13260:1),Framework] BrowserRemoteClient.CreateNamedPipes() : Named pipes created for communication with the server: {Command = "ff9c09e5-9db6-48c9-a10f-9105a19ccbf4.CommandPipe", Async = "ff9c09e5-9db6-48c9-a10f-9105a19ccbf4.AsyncPipe"}
[09/04 14:57:47,ArtOfTest.Runner.exe(4220:13),Framework] BrowserRemoted.AsyncListenerThreadEntry() : A new client has connected to the pipe.
[09/04 14:57:47,ArtOfTest.Runner.exe(4220:13),Framework] BrowserRemoted.AsyncListenerThreadEntry() : Now waiting indefinitely for a command from the pipe client...
[09/04 14:57:47,ArtOfTest.Runner.exe(4220:11),Framework] Manager.AddNewBrowser() : Added client ID "Client_76dd2354-48d6-444c-a705-76c2cbe77f25" (now there is/are 1 total).
[09/04 14:57:47,ArtOfTest.Runner.exe(4220:10),Framework] Connector.InjectCode() : Returned from sending WM_COPYDATA (LRESULT=0, GetLastError()=ERROR_ALREADY_EXISTS).
[09/04 14:57:47,ArtOfTest.Runner.exe(4220:10),Framework] Connector.InjectCode() : ------- Function End -------
[09/04 14:57:47,ArtOfTest.Runner.exe(4220:10),Execution] ExecutionEngine.InternalExecuteTest() : Executing test: 'Schedule Appt - New Patients', path: 'Schedule Appt - New Patients.tstest.'
[09/04 14:57:47,ArtOfTest.Runner.exe(4220:10),TestStudio] VisualDebugger.SetCurrentResult() : VisualDebugger::SetCurrentResult
[09/04 14:57:47,ArtOfTest.Runner.exe(4220:10),DialogPlayback] DialogMonitor.Start() : Beginning monitoring for dialogs from the set {}.
[09/04 14:57:47,ArtOfTest.Runner.exe(4220:10),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[09/04 14:57:47,ArtOfTest.Runner.exe(4220:10),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 1 "Navigate to : 'http://www.swedish.org/'"...
[09/04 14:57:48,ArtOfTest.Runner.exe(4220:7)] First trace message from background thread "UAI Dispatcher Thread" (managed ID = 7, native ID = 11328).
[09/04 14:57:48,ArtOfTest.Runner.exe(4220:7),UIAutomation] AutomationExtensions.AddStructureChangedEventHandler() : Added a structure change handler on element System.Windows.Automation.AutomationElement scope Subtree.
[09/04 14:57:49,ArtOfTest.Runner.exe(4220:10),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 1 "Navigate to : 'http://www.swedish.org/'".
[09/04 14:57:49,ArtOfTest.Runner.exe(4220:10),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[09/04 14:57:49,ArtOfTest.Runner.exe(4220:10),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 2 "Click 'FindALink'"...
[09/04 14:57:50,ArtOfTest.Runner.exe(4220:10),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 2 "Click 'FindALink'".
[09/04 14:57:50,ArtOfTest.Runner.exe(4220:10),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[09/04 14:57:50,ArtOfTest.Runner.exe(4220:10),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 3 "Enter text 'Meena Mital' in 'Main0Contentpanel2TxtNameAutoCompleteText'"...
[09/04 14:57:50,ArtOfTest.Runner.exe(4220:10),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 3 "Enter text 'Meena Mital' in 'Main0Contentpanel2TxtNameAutoCompleteText'".
[09/04 14:57:50,ArtOfTest.Runner.exe(4220:10),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[09/04 14:57:50,ArtOfTest.Runner.exe(4220:10),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 4 "Click 'Main0Contentpanel2BtnNameSubmitSubmit'"...
[09/04 14:57:51,ArtOfTest.Runner.exe(4220:10),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 4 "Click 'Main0Contentpanel2BtnNameSubmitSubmit'".
[09/04 14:57:51,ArtOfTest.Runner.exe(4220:10),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[09/04 14:57:51,ArtOfTest.Runner.exe(4220:10),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 5 "Click 'Main0Contentpanel2LvSearchResultsHlMoreLink0Link'"...
[09/04 14:57:52,ArtOfTest.Runner.exe(4220:10),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 5 "Click 'Main0Contentpanel2LvSearchResultsHlMoreLink0Link'".
[09/04 14:57:52,ArtOfTest.Runner.exe(4220:10),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[09/04 14:57:52,ArtOfTest.Runner.exe(4220:10),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 6 "Click 'ScheduleDiv'"...
[09/04 14:57:54,ArtOfTest.Runner.exe(4220:10),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 6 "Click 'ScheduleDiv'".
[09/04 14:57:54,ArtOfTest.Runner.exe(4220:10),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[09/04 14:57:54,ArtOfTest.Runner.exe(4220:10),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 7 "Click 'NoDiv'"...
[09/04 14:57:54,ArtOfTest.Runner.exe(4220:10),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 7 "Click 'NoDiv'".
[09/04 14:57:54,ArtOfTest.Runner.exe(4220:10),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[09/04 14:57:55,ArtOfTest.Runner.exe(4220:10),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 8 "Verify element 'ScheduleH3Tag' 'is' visible."...
[09/04 14:57:55,ArtOfTest.Runner.exe(4220:10),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 8 "Verify element 'ScheduleH3Tag' 'is' visible.".
[09/04 14:57:55,ArtOfTest.Runner.exe(4220:10),TestStudio] VisualDebugger.ShutDown() : VisualDebbuger::ShutDown::Start
[09/04 14:57:55,ArtOfTest.Runner.exe(4220:10),TestStudio] VisualDebugger.ShutDown() : VisualDebbuger::ShutDown::End
[09/04 14:57:55,ArtOfTest.Runner.exe(4220:10),Framework] BrowserProvisioner.EndService() : Connecting to the provisioning pipe "Pipe.ArtOfTest.WebAii.BrowserProvisioner_1305761118" in order to unblock and eventually terminate it...
[09/04 14:57:55,ArtOfTest.Runner.exe(4220:10),Framework] BrowserProvisioner.EndService() : Provisioning pipe connected.
[09/04 14:57:55,ArtOfTest.Runner.exe(4220:11),Framework] BrowserProvisioner.ServiceThread() : A new client has connected to the provisioning pipe but this will be treated as a termination cue.
[09/04 14:57:55,ArtOfTest.Runner.exe(4220:10),Framework] BrowserProvisioner.EndService() : Provisioning thread terminated.
[09/04 14:57:55,ArtOfTest.Runner.exe(4220:10),DialogPlayback] DialogMonitor.AddDialog() : Added {OnBeforeUnloadDialog(Dismiss=OK)} for monitoring.
[09/04 14:57:55,ArtOfTest.Runner.exe(4220:10),Framework] Manager.RemoveBrowser() : Removed client ID "Client_76dd2354-48d6-444c-a705-76c2cbe77f25" (0 remaining).
[09/04 14:57:55,ArtOfTest.Runner.exe(4220:10),Framework] Manager.SetActiveBrowser() : Active browser is now null.
[09/04 14:57:55,ArtOfTest.Runner.exe(4220:10),Framework] BrowserRemoted.End() : Now aborting the listener thread (TID = 13).
[09/04 14:57:55,IEXPLORE.EXE(13260:1),Framework] BrowserRemoteClient.Disconnect() : Sending ClientDisconnectRequest(ClientId="Client_76dd2354-48d6-444c-a705-76c2cbe77f25") to pipe server...
[09/04 14:57:55,IEXPLORE.EXE(13260:1),Framework] BrowserRemoteClient.Disconnect() : Command pipe is already disconnected.
[09/04 14:57:55,ArtOfTest.Runner.exe(4220:13),Warning] PipeCommunication.SyncPipeRead() : Pipe read broken by thread-abort.
[09/04 14:57:55,ArtOfTest.Runner.exe(4220:10),DialogPlayback] DialogMonitor.Stop() : Dialog monitoring is being halted.
[09/04 14:57:55,ArtOfTest.Runner.exe(4220:10),UIAutomation] AutomationExtensions.AddAutomationEventHandler() : Added a(n) WindowPatternIdentifiers.WindowOpenedProperty handler on element System.Windows.Automation.AutomationElement scope Descendants.
[09/04 14:57:55,ArtOfTest.Runner.exe(4220:7),UIAutomation] AutomationExtensions.RemoveStructureChangedEventHandler() : Removed the structure change handler on element System.Windows.Automation.AutomationElement scope Subtree.
[09/04 14:57:56,Telerik.TestStudio.RemoteExecutor.exe(13608:15),Execution] TestPlatform.AfterRunFinished() : Finished running test platform- attempting publish
