[09/04 14:28:47,Telerik.TestStudio.Desktop.exe(11452:5),Uncategorized] <>c__DisplayClassd.<Refresh>b__5() : Enumerating test list files in "C:\Users\acouzin\Documents\Test Studio Projects\Swedish\TestLists"...
[09/04 14:28:47,Telerik.TestStudio.Desktop.exe(11452:5),Uncategorized] <>c__DisplayClassd.<Refresh>b__5() : 1 test lists found.
[09/04 14:28:47,Telerik.TestStudio.Desktop.exe(11452:21)] First trace message from pool unnamed thread (managed ID = 21, native ID = 12964).
[09/04 14:28:47,Telerik.TestStudio.Desktop.exe(11452:21),Uncategorized] <>c__DisplayClassd.<Refresh>b__9() : All files parsed and 1 viewmodels added.
[09/04 14:29:30,Telerik.TestStudio.Desktop.exe(11452:1),Error] RegistryHelper.PerformActionOnCurrentKey() : Cannot locate HKEY_LOCAL_MACHINE\SOFTWARE\Microsoft\DevDiv\vs\Servicing\10.0\VSTSCore
[09/04 14:29:30,Telerik.TestStudio.Desktop.exe(11452:1),Error] RegistryHelper.PerformActionOnCurrentKey() : Cannot locate HKEY_LOCAL_MACHINE\SOFTWARE\Microsoft\DevDiv\vs\Servicing\10.0\VSTDCore
[09/04 14:29:30,Telerik.TestStudio.Desktop.exe(11452:1),Error] RegistryHelper.PerformActionOnCurrentKey() : Cannot locate HKEY_LOCAL_MACHINE\SOFTWARE\Microsoft\DevDiv\vs\Servicing\10.0\PROCore
[09/04 14:29:30,Telerik.TestStudio.Desktop.exe(11452:1),Error] RegistryHelper.PerformActionOnCurrentKey() : Cannot locate HKEY_LOCAL_MACHINE\SOFTWARE\Microsoft\DevDiv\vs\Servicing\11.0\ultimate
[09/04 14:29:50,Telerik.TestStudio.RemoteExecutor.exe(13036:8)] First trace message from pool unnamed thread (managed ID = 8, native ID = 11884).
[09/04 14:29:50,Telerik.TestStudio.RemoteExecutor.exe(13036:8),Execution] RunningTestsController.StartTest() : Test run requested for Local path=C:\Users\acouzin\Documents\Test Studio Projects\Swedish
[09/04 14:29:51,Telerik.TestStudio.RemoteExecutor.exe(13036:10)] First trace message from pool unnamed thread (managed ID = 10, native ID = 11280).
[09/04 14:29:51,Telerik.TestStudio.RemoteExecutor.exe(13036:10),Uncategorized] BugTrackersLoader.LoadSystems() : ------- Function Start -------
[09/04 14:29:52,ArtOfTest.Runner.exe(12428:3)] First trace message from process 12428: "C:\Program Files (x86)\Telerik\Test Studio\Bin\ArtOfTest.Runner.exe"  /controller /parentprocesshandle=1824
[09/04 14:29:52,ArtOfTest.Runner.exe(12428:3)] First trace message from foreground thread "Remote.ArtOfTest.Runner.Listener" (managed ID = 3, native ID = 10088).
[09/04 14:29:52,ArtOfTest.Runner.exe(12428:3),TestStudio] Loader.LoadTranslatorGroups() : ------- Function Start -------
[09/04 14:29:52,ArtOfTest.Runner.exe(12428:3),TestStudio] Loader.LoadTranslatorGroups() : ------- Function End -------
[09/04 14:29:53,ArtOfTest.Runner.exe(12428:5)] First trace message from pool unnamed thread (managed ID = 5, native ID = 2864).
[09/04 14:29:53,ArtOfTest.Runner.exe(12428:5),Framework] InternetExplorerActions.LaunchNewBrowserInstance() : Process launched (ID=13616, Path="C:\Program Files\Internet Explorer\iexplore.exe", Arguments="-nomerge about:blank").
[09/04 14:29:53,ArtOfTest.Runner.exe(12428:5),Framework] InternetExplorerActions.LaunchNewBrowserInstance() : Attempting to attach on IE frame (HWND=2295822)...
[09/04 14:29:53,ArtOfTest.Runner.exe(12428:5),Framework] Connector.Start() : Process launched (ID=5984, Path="C:\Windows\SysWOW64\rundll32.exe", Arguments=""C:\Program Files (x86)\Telerik\Test Studio\Bin\ArtOfTest.Connector.dll",AttachToWindowEntryPoint 1313182").
[09/04 14:29:53,ArtOfTest.Runner.exe(12428:5),Framework] Connector.Start() : Process exited (ID=5984, ExitCode=0, Path="C:\Windows\SysWOW64\rundll32.exe", Arguments=""C:\Program Files (x86)\Telerik\Test Studio\Bin\ArtOfTest.Connector.dll",AttachToWindowEntryPoint 1313182").
[09/04 14:29:53,ArtOfTest.Runner.exe(12428:5),Framework] InternetExplorerActions.LaunchNewBrowserInstance() : Process "Telerik.TestStudio.RemoteExecutor" (ID=13036) is assumed to host the controller.
[09/04 14:29:53,ArtOfTest.Runner.exe(12428:5),Framework] Connector.InjectCode() : ------- Function Start -------
[09/04 14:29:53,ArtOfTest.Runner.exe(12428: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?13036
[09/04 14:29:53,ArtOfTest.Runner.exe(12428:5),Framework] InternetExplorerActions.WaitForDocument() : Attempting to get IHTMLDocument2 from Internet Explorer HWND 1313182 on another thread...
[09/04 14:29:53,ArtOfTest.Runner.exe(12428:6)] First trace message from pool thread "WaitAsync polling wait worker" (managed ID = 6, native ID = 11984).
[09/04 14:29:53,ArtOfTest.Runner.exe(12428:6),Framework] <>c__DisplayClassf.<WaitForDocument>b__8() : Attempting to get document from window handle
[09/04 14:29:54,ArtOfTest.Runner.exe(12428:5),Framework] InternetExplorerActions.WaitForDocument() : IHTMLDocument2 successfully retrieved.
[09/04 14:29:54,ArtOfTest.Runner.exe(12428:5),Framework] Connector.InjectCode() : Sending WM_COPYDATA to HWND 1313182 (lpData = "C:\windows\Microsoft.Net\assembly\GAC_MSIL\ArtOfTest.InternetExplorer\v4.0_2015.2.723.0__5339893a7cefe4d6\ArtOfTest.InternetExplorer.dll?ArtOfTest.InternetExplorer.ArtOfTestPluginEntryPoint?13036")...
[09/04 14:29:54,IEXPLORE.EXE(13656:1)] First trace message from process 13656: "C:\Program Files (x86)\Internet Explorer\IEXPLORE.EXE" SCODEF:13616 CREDAT:275457 /prefetch:2
[09/04 14:29:54,IEXPLORE.EXE(13656:1)] First trace message from background unnamed thread (managed ID = 1, native ID = 8832).
[09/04 14:29:54,IEXPLORE.EXE(13656:1),Framework] ArtOfTestPluginEntryPoint.Connect() : The .NET Framework is now active inside the hooked process (HWND = 1313182, PipeName = "Pipe.ArtOfTest.WebAii.BrowserProvisioner_-1408813338", PID = 13036).
[09/04 14:29:54,IEXPLORE.EXE(13656:1),Framework] InternetExplorerActions.WaitForDocument() : Attempting to get IHTMLDocument2 from Internet Explorer HWND 1313182 on same thread...
[09/04 14:29:54,IEXPLORE.EXE(13656:1),Framework] InternetExplorerActions.WaitForDocument() : IHTMLDocument2 successfully retrieved.
[09/04 14:29:54,IEXPLORE.EXE(13656:1),Framework] BrowserRemoteClient constructor : The client ID for this hooked process will be "Client_a59c5c8d-5b60-4316-b080-0330dc70e9bc".
[09/04 14:29:54,ArtOfTest.Runner.exe(12428:7)] First trace message from background thread "Pipe.ArtOfTest.WebAii.BrowserProvisioner" (managed ID = 7, native ID = 7456).
[09/04 14:29:54,ArtOfTest.Runner.exe(12428:7),Framework] BrowserProvisioner.ServiceThread() : A new client has connected to the provisioning pipe.
[09/04 14:29:54,ArtOfTest.Runner.exe(12428:13)] First trace message from background thread "Remoted async command listener" (managed ID = 13, native ID = 14332).
[09/04 14:29:54,ArtOfTest.Runner.exe(12428:13),Framework] BrowserRemoted.AsyncListenerThreadEntry() : Not connected; this will now wait indefinitely for a pipe client connection...
[09/04 14:29:54,IEXPLORE.EXE(13656:1),Framework] BrowserRemoteClient.CreateNamedPipes() : Named pipes created for communication with the server: {Command = "7e538028-d3d0-482b-a6f0-2b8a8082c005.CommandPipe", Async = "7e538028-d3d0-482b-a6f0-2b8a8082c005.AsyncPipe"}
[09/04 14:29:54,ArtOfTest.Runner.exe(12428:13),Framework] BrowserRemoted.AsyncListenerThreadEntry() : A new client has connected to the pipe.
[09/04 14:29:54,ArtOfTest.Runner.exe(12428:13),Framework] BrowserRemoted.AsyncListenerThreadEntry() : Now waiting indefinitely for a command from the pipe client...
[09/04 14:29:54,ArtOfTest.Runner.exe(12428:7),Framework] Manager.AddNewBrowser() : Added client ID "Client_a59c5c8d-5b60-4316-b080-0330dc70e9bc" (now there is/are 1 total).
[09/04 14:29:54,ArtOfTest.Runner.exe(12428:5),Framework] Connector.InjectCode() : Returned from sending WM_COPYDATA (LRESULT=0, GetLastError()=ERROR_ALREADY_EXISTS).
[09/04 14:29:54,ArtOfTest.Runner.exe(12428:5),Framework] Connector.InjectCode() : ------- Function End -------
[09/04 14:29:54,ArtOfTest.Runner.exe(12428:5),Execution] ExecutionEngine.InternalExecuteTest() : Executing test: 'FAD- Viewing page 1 of', path: 'FAD- Viewing page 1 of.tstest.'
[09/04 14:29:54,ArtOfTest.Runner.exe(12428:5),Execution] ExtensionsManager.LoadExtensions() : ------- Function Start -------
[09/04 14:29:54,ArtOfTest.Runner.exe(12428:5),Uncategorized] ExtensionsManager.LoadExtensions() : Got 16 plugin(s) in folder "C:\Program Files (x86)\Telerik\Test Studio\Bin\Plugins".
[09/04 14:29:54,ArtOfTest.Runner.exe(12428:5),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Autofac.dll" from plugins folder.
[09/04 14:29:54,ArtOfTest.Runner.exe(12428:5),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Microsoft.CompilerServices.AsyncTargetingPack.Net4.dll" from plugins folder.
[09/04 14:29:54,ArtOfTest.Runner.exe(12428:5),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Newtonsoft.Json.dll" from plugins folder.
[09/04 14:29:54,ArtOfTest.Runner.exe(12428:5),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "System.Net.Http.dll" from plugins folder.
[09/04 14:29:54,ArtOfTest.Runner.exe(12428:5),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "System.Net.Http.Formatting.dll" from plugins folder.
[09/04 14:29:54,ArtOfTest.Runner.exe(12428:5),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "System.Web.Http.dll" from plugins folder.
[09/04 14:29:54,ArtOfTest.Runner.exe(12428:5),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.Jira.BugTracking.dll" from plugins folder.
[09/04 14:29:54,ArtOfTest.Runner.exe(12428:5),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.Load.dll" from plugins folder.
[09/04 14:29:54,ArtOfTest.Runner.exe(12428:5),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.Manual.dll" from plugins folder.
[09/04 14:29:55,ArtOfTest.Runner.exe(12428:5),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.OTA.Interop.dll" from plugins folder.
[09/04 14:29:55,ArtOfTest.Runner.exe(12428:5),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.Plugins.dll" from plugins folder.
[09/04 14:29:55,ArtOfTest.Runner.exe(12428:5),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.Shared.dll" from plugins folder.
[09/04 14:29:55,ArtOfTest.Runner.exe(12428:5),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.TeamPulse.dll" from plugins folder.
[09/04 14:29:55,ArtOfTest.Runner.exe(12428:5),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.Theme.dll" from plugins folder.
[09/04 14:29:55,ArtOfTest.Runner.exe(12428:5),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.Web.dll" from plugins folder.
[09/04 14:29:55,ArtOfTest.Runner.exe(12428:5),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.Wpf.dll" from plugins folder.
[09/04 14:29:55,ArtOfTest.Runner.exe(12428:5),Execution] ExtensionsManager.LoadExtensions() : ------- Function End -------
[09/04 14:29:55,ArtOfTest.Runner.exe(12428:5),TestStudio] VisualDebugger.SetCurrentResult() : VisualDebugger::SetCurrentResult
[09/04 14:29:55,ArtOfTest.Runner.exe(12428:5),DialogPlayback] DialogMonitor.Start() : Beginning monitoring for dialogs from the set {}.
[09/04 14:29:55,ArtOfTest.Runner.exe(12428:5),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[09/04 14:29:55,ArtOfTest.Runner.exe(12428:5),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 1 "Navigate to : 'http://www.swedish.org/'"...
[09/04 14:29:55,ArtOfTest.Runner.exe(12428:9)] First trace message from background thread "UAI Dispatcher Thread" (managed ID = 9, native ID = 11128).
[09/04 14:29:55,ArtOfTest.Runner.exe(12428:9),UIAutomation] AutomationExtensions.AddStructureChangedEventHandler() : Added a structure change handler on element System.Windows.Automation.AutomationElement scope Subtree.
[09/04 14:29:55,ArtOfTest.Runner.exe(12428:5),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 1 "Navigate to : 'http://www.swedish.org/'".
[09/04 14:29:55,ArtOfTest.Runner.exe(12428:5),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[09/04 14:29:56,ArtOfTest.Runner.exe(12428:5),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 2 "Click 'FindALink'"...
[09/04 14:29:57,ArtOfTest.Runner.exe(12428:5),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 2 "Click 'FindALink'".
[09/04 14:29:57,ArtOfTest.Runner.exe(12428:5),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[09/04 14:29:57,ArtOfTest.Runner.exe(12428:5),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 3 "Enter text 'Cardiac Surgery' in 'Main0Contentpanel2TxtHealthConditionAutoCompleteText'"...
[09/04 14:29:58,ArtOfTest.Runner.exe(12428:5),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 3 "Enter text 'Cardiac Surgery' in 'Main0Contentpanel2TxtHealthConditionAutoCompleteText'".
[09/04 14:29:58,ArtOfTest.Runner.exe(12428:5),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[09/04 14:29:58,ArtOfTest.Runner.exe(12428:5),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 4 "Click 'Main0Contentpanel2BtnSubmitSubmit'"...
[09/04 14:29:59,ArtOfTest.Runner.exe(12428:5),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 4 "Click 'Main0Contentpanel2BtnSubmitSubmit'".
[09/04 14:29:59,ArtOfTest.Runner.exe(12428:5),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[09/04 14:29:59,ArtOfTest.Runner.exe(12428:5),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 5 "Wait for Exists 'HtmlTag'"...
[09/04 14:29:59,ArtOfTest.Runner.exe(12428:5),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 5 "Wait for Exists 'HtmlTag'".
[09/04 14:30:00,ArtOfTest.Runner.exe(12428:5),TestStudio] VisualDebugger.ShutDown() : VisualDebbuger::ShutDown::Start
[09/04 14:30:00,ArtOfTest.Runner.exe(12428:5),TestStudio] VisualDebugger.ShutDown() : VisualDebbuger::ShutDown::End
[09/04 14:30:00,ArtOfTest.Runner.exe(12428:5),Framework] BrowserProvisioner.EndService() : Connecting to the provisioning pipe "Pipe.ArtOfTest.WebAii.BrowserProvisioner_-1408813338" in order to unblock and eventually terminate it...
[09/04 14:30:00,ArtOfTest.Runner.exe(12428: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:30:00,ArtOfTest.Runner.exe(12428:5),Framework] BrowserProvisioner.EndService() : Provisioning pipe connected.
[09/04 14:30:00,ArtOfTest.Runner.exe(12428:5),Framework] BrowserProvisioner.EndService() : Provisioning thread terminated.
[09/04 14:30:00,ArtOfTest.Runner.exe(12428:5),DialogPlayback] DialogMonitor.AddDialog() : Added {OnBeforeUnloadDialog(Dismiss=OK)} for monitoring.
[09/04 14:30:00,ArtOfTest.Runner.exe(12428:5),Framework] Manager.RemoveBrowser() : Removed client ID "Client_a59c5c8d-5b60-4316-b080-0330dc70e9bc" (0 remaining).
[09/04 14:30:00,ArtOfTest.Runner.exe(12428:5),Framework] Manager.SetActiveBrowser() : Active browser is now null.
[09/04 14:30:00,ArtOfTest.Runner.exe(12428:5),Framework] BrowserRemoted.End() : Now aborting the listener thread (TID = 13).
[09/04 14:30:00,IEXPLORE.EXE(13656:1),Framework] BrowserRemoteClient.Disconnect() : Sending ClientDisconnectRequest(ClientId="Client_a59c5c8d-5b60-4316-b080-0330dc70e9bc") to pipe server...
[09/04 14:30:00,IEXPLORE.EXE(13656:1),Framework] BrowserRemoteClient.Disconnect() : Command pipe is already disconnected.
[09/04 14:30:00,ArtOfTest.Runner.exe(12428:13),Warning] PipeCommunication.SyncPipeRead() : Pipe read broken by thread-abort.
[09/04 14:30:00,ArtOfTest.Runner.exe(12428:9),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[09/04 14:30:00,ArtOfTest.Runner.exe(12428:9),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[09/04 14:30:00,ArtOfTest.Runner.exe(12428:5),DialogPlayback] DialogMonitor.Stop() : Dialog monitoring is being halted.
[09/04 14:30:00,ArtOfTest.Runner.exe(12428:5),UIAutomation] AutomationExtensions.AddAutomationEventHandler() : Added a(n) WindowPatternIdentifiers.WindowOpenedProperty handler on element System.Windows.Automation.AutomationElement scope Descendants.
[09/04 14:30:00,ArtOfTest.Runner.exe(12428:9),UIAutomation] AutomationExtensions.RemoveStructureChangedEventHandler() : Removed the structure change handler on element System.Windows.Automation.AutomationElement scope Subtree.
[09/04 14:30:01,Telerik.TestStudio.RemoteExecutor.exe(13036:14)] First trace message from background thread "Callback Listener Thread" (managed ID = 14, native ID = 11772).
[09/04 14:30:01,Telerik.TestStudio.RemoteExecutor.exe(13036:14),Execution] TestPlatform.AfterRunFinished() : Finished running test platform- attempting publish
[09/04 14:30:06,Telerik.TestStudio.RemoteExecutor.exe(13036:9)] First trace message from pool unnamed thread (managed ID = 9, native ID = 13248).
[09/04 14:30:06,Telerik.TestStudio.RemoteExecutor.exe(13036:9),Execution] RunningTestsController.StartTest() : Test run requested for Local path=C:\Users\acouzin\Documents\Test Studio Projects\Swedish
[09/04 14:30:06,ArtOfTest.Runner.exe(12428:10)] First trace message from pool unnamed thread (managed ID = 10, native ID = 12232).
[09/04 14:30:06,ArtOfTest.Runner.exe(12428:10),Framework] InternetExplorerActions.LaunchNewBrowserInstance() : Process launched (ID=7080, Path="C:\Program Files\Internet Explorer\iexplore.exe", Arguments="-nomerge about:blank").
[09/04 14:30:06,ArtOfTest.Runner.exe(12428:10),Framework] InternetExplorerActions.LaunchNewBrowserInstance() : Attempting to attach on IE frame (HWND=1247014)...
[09/04 14:30:06,ArtOfTest.Runner.exe(12428:10),Framework] Connector.Start() : Process launched (ID=14124, Path="C:\Windows\SysWOW64\rundll32.exe", Arguments=""C:\Program Files (x86)\Telerik\Test Studio\Bin\ArtOfTest.Connector.dll",AttachToWindowEntryPoint 985762").
[09/04 14:30:06,ArtOfTest.Runner.exe(12428:10),Framework] Connector.Start() : Process exited (ID=14124, ExitCode=0, Path="C:\Windows\SysWOW64\rundll32.exe", Arguments=""C:\Program Files (x86)\Telerik\Test Studio\Bin\ArtOfTest.Connector.dll",AttachToWindowEntryPoint 985762").
[09/04 14:30:06,ArtOfTest.Runner.exe(12428:10),Framework] InternetExplorerActions.LaunchNewBrowserInstance() : Process "Telerik.TestStudio.RemoteExecutor" (ID=13036) is assumed to host the controller.
[09/04 14:30:06,ArtOfTest.Runner.exe(12428:10),Framework] Connector.InjectCode() : ------- Function Start -------
[09/04 14:30:06,ArtOfTest.Runner.exe(12428: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?13036
[09/04 14:30:06,ArtOfTest.Runner.exe(12428:10),Framework] InternetExplorerActions.WaitForDocument() : Attempting to get IHTMLDocument2 from Internet Explorer HWND 985762 on another thread...
[09/04 14:30:06,ArtOfTest.Runner.exe(12428:5)] First trace message from pool thread "WaitAsync polling wait worker" (managed ID = 5, native ID = 2864).
[09/04 14:30:06,ArtOfTest.Runner.exe(12428:5),Framework] <>c__DisplayClassf.<WaitForDocument>b__8() : Attempting to get document from window handle
[09/04 14:30:07,ArtOfTest.Runner.exe(12428:10),Framework] InternetExplorerActions.WaitForDocument() : IHTMLDocument2 successfully retrieved.
[09/04 14:30:07,ArtOfTest.Runner.exe(12428:10),Framework] Connector.InjectCode() : Sending WM_COPYDATA to HWND 985762 (lpData = "C:\windows\Microsoft.Net\assembly\GAC_MSIL\ArtOfTest.InternetExplorer\v4.0_2015.2.723.0__5339893a7cefe4d6\ArtOfTest.InternetExplorer.dll?ArtOfTest.InternetExplorer.ArtOfTestPluginEntryPoint?13036")...
[09/04 14:30:07,IEXPLORE.EXE(13456:1)] First trace message from process 13456: "C:\Program Files (x86)\Internet Explorer\IEXPLORE.EXE" SCODEF:7080 CREDAT:275457 /prefetch:2
[09/04 14:30:07,IEXPLORE.EXE(13456:1)] First trace message from background unnamed thread (managed ID = 1, native ID = 11184).
[09/04 14:30:07,IEXPLORE.EXE(13456:1),Framework] ArtOfTestPluginEntryPoint.Connect() : The .NET Framework is now active inside the hooked process (HWND = 985762, PipeName = "Pipe.ArtOfTest.WebAii.BrowserProvisioner_888408982", PID = 13036).
[09/04 14:30:07,IEXPLORE.EXE(13456:1),Framework] InternetExplorerActions.WaitForDocument() : Attempting to get IHTMLDocument2 from Internet Explorer HWND 985762 on same thread...
[09/04 14:30:07,IEXPLORE.EXE(13456:1),Framework] InternetExplorerActions.WaitForDocument() : IHTMLDocument2 successfully retrieved.
[09/04 14:30:07,IEXPLORE.EXE(13456:1),Framework] BrowserRemoteClient constructor : The client ID for this hooked process will be "Client_34fd0b5a-7765-4c5c-a995-8714e7521262".
[09/04 14:30:07,ArtOfTest.Runner.exe(12428:19)] First trace message from background thread "Pipe.ArtOfTest.WebAii.BrowserProvisioner" (managed ID = 19, native ID = 13532).
[09/04 14:30:07,ArtOfTest.Runner.exe(12428:19),Framework] BrowserProvisioner.ServiceThread() : A new client has connected to the provisioning pipe.
[09/04 14:30:07,ArtOfTest.Runner.exe(12428:20)] First trace message from background thread "Remoted async command listener" (managed ID = 20, native ID = 1568).
[09/04 14:30:07,ArtOfTest.Runner.exe(12428:20),Framework] BrowserRemoted.AsyncListenerThreadEntry() : Not connected; this will now wait indefinitely for a pipe client connection...
[09/04 14:30:07,IEXPLORE.EXE(13456:1),Framework] BrowserRemoteClient.CreateNamedPipes() : Named pipes created for communication with the server: {Command = "586c7d6f-9376-418c-b5a2-ce0124e692d9.CommandPipe", Async = "586c7d6f-9376-418c-b5a2-ce0124e692d9.AsyncPipe"}
[09/04 14:30:07,ArtOfTest.Runner.exe(12428:20),Framework] BrowserRemoted.AsyncListenerThreadEntry() : A new client has connected to the pipe.
[09/04 14:30:07,ArtOfTest.Runner.exe(12428:20),Framework] BrowserRemoted.AsyncListenerThreadEntry() : Now waiting indefinitely for a command from the pipe client...
[09/04 14:30:07,ArtOfTest.Runner.exe(12428:19),Framework] Manager.AddNewBrowser() : Added client ID "Client_34fd0b5a-7765-4c5c-a995-8714e7521262" (now there is/are 1 total).
[09/04 14:30:07,ArtOfTest.Runner.exe(12428:10),Framework] Connector.InjectCode() : Returned from sending WM_COPYDATA (LRESULT=0, GetLastError()=ERROR_ALREADY_EXISTS).
[09/04 14:30:07,ArtOfTest.Runner.exe(12428:10),Framework] Connector.InjectCode() : ------- Function End -------
[09/04 14:30:07,ArtOfTest.Runner.exe(12428:10),Execution] ExecutionEngine.InternalExecuteTest() : Executing test: 'Schedule Appt - Exisitng Patients', path: 'Schedule Appt - Exisitng Patients.tstest.'
[09/04 14:30:07,ArtOfTest.Runner.exe(12428:10),TestStudio] VisualDebugger.SetCurrentResult() : VisualDebugger::SetCurrentResult
[09/04 14:30:07,ArtOfTest.Runner.exe(12428:10),DialogPlayback] DialogMonitor.Start() : Beginning monitoring for dialogs from the set {}.
[09/04 14:30:07,ArtOfTest.Runner.exe(12428:10),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[09/04 14:30:07,ArtOfTest.Runner.exe(12428:10),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 1 "Navigate to : 'http://www.swedish.org/'"...
[09/04 14:30:07,ArtOfTest.Runner.exe(12428:21)] First trace message from background thread "UAI Dispatcher Thread" (managed ID = 21, native ID = 14140).
[09/04 14:30:07,ArtOfTest.Runner.exe(12428:21),UIAutomation] AutomationExtensions.AddStructureChangedEventHandler() : Added a structure change handler on element System.Windows.Automation.AutomationElement scope Subtree.
[09/04 14:30:09,ArtOfTest.Runner.exe(12428:10),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 1 "Navigate to : 'http://www.swedish.org/'".
[09/04 14:30:09,ArtOfTest.Runner.exe(12428:10),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[09/04 14:30:09,ArtOfTest.Runner.exe(12428:10),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 2 "Click 'FindALink'"...
[09/04 14:30:10,ArtOfTest.Runner.exe(12428:10),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 2 "Click 'FindALink'".
[09/04 14:30:10,ArtOfTest.Runner.exe(12428:10),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[09/04 14:30:10,ArtOfTest.Runner.exe(12428:10),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 3 "Enter text 'Meena Mital' in 'Main0Contentpanel2TxtNameAutoCompleteText'"...
[09/04 14:30:10,ArtOfTest.Runner.exe(12428:10),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 3 "Enter text 'Meena Mital' in 'Main0Contentpanel2TxtNameAutoCompleteText'".
[09/04 14:30:10,ArtOfTest.Runner.exe(12428:10),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[09/04 14:30:10,ArtOfTest.Runner.exe(12428:10),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 4 "Click 'Main0Contentpanel2BtnNameSubmitSubmit'"...
[09/04 14:30:12,ArtOfTest.Runner.exe(12428:10),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 4 "Click 'Main0Contentpanel2BtnNameSubmitSubmit'".
[09/04 14:30:12,ArtOfTest.Runner.exe(12428:10),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[09/04 14:30:12,ArtOfTest.Runner.exe(12428:10),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 5 "Click 'Main0Contentpanel2LvSearchResultsHlMoreLink0Link'"...
[09/04 14:30:13,ArtOfTest.Runner.exe(12428:10),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 5 "Click 'Main0Contentpanel2LvSearchResultsHlMoreLink0Link'".
[09/04 14:30:13,ArtOfTest.Runner.exe(12428:10),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[09/04 14:30:14,ArtOfTest.Runner.exe(12428:10),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 6 "Click 'ScheduleDiv'"...
[09/04 14:30:15,ArtOfTest.Runner.exe(12428:10),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 6 "Click 'ScheduleDiv'".
[09/04 14:30:15,ArtOfTest.Runner.exe(12428:10),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[09/04 14:30:15,ArtOfTest.Runner.exe(12428:10),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 7 "Click 'YesDiv'"...
[09/04 14:30:16,ArtOfTest.Runner.exe(12428:10),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 7 "Click 'YesDiv'".
[09/04 14:30:16,ArtOfTest.Runner.exe(12428:10),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[09/04 14:30:17,ArtOfTest.Runner.exe(12428:10),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 8 "Verify element 'ScheduleH3Tag' 'is' visible."...
[09/04 14:30:17,ArtOfTest.Runner.exe(12428:10),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 8 "Verify element 'ScheduleH3Tag' 'is' visible.".
[09/04 14:30:17,ArtOfTest.Runner.exe(12428:10),TestStudio] VisualDebugger.ShutDown() : VisualDebbuger::ShutDown::Start
[09/04 14:30:17,ArtOfTest.Runner.exe(12428:10),TestStudio] VisualDebugger.ShutDown() : VisualDebbuger::ShutDown::End
[09/04 14:30:17,ArtOfTest.Runner.exe(12428:10),Framework] BrowserProvisioner.EndService() : Connecting to the provisioning pipe "Pipe.ArtOfTest.WebAii.BrowserProvisioner_888408982" in order to unblock and eventually terminate it...
[09/04 14:30:17,ArtOfTest.Runner.exe(12428:10),Framework] BrowserProvisioner.EndService() : Provisioning pipe connected.
[09/04 14:30:17,ArtOfTest.Runner.exe(12428: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:30:17,ArtOfTest.Runner.exe(12428:10),Framework] BrowserProvisioner.EndService() : Provisioning thread terminated.
[09/04 14:30:17,ArtOfTest.Runner.exe(12428:10),DialogPlayback] DialogMonitor.AddDialog() : Added {OnBeforeUnloadDialog(Dismiss=OK)} for monitoring.
[09/04 14:30:17,ArtOfTest.Runner.exe(12428:10),Framework] Manager.RemoveBrowser() : Removed client ID "Client_34fd0b5a-7765-4c5c-a995-8714e7521262" (0 remaining).
[09/04 14:30:17,ArtOfTest.Runner.exe(12428:10),Framework] Manager.SetActiveBrowser() : Active browser is now null.
[09/04 14:30:17,ArtOfTest.Runner.exe(12428:10),Framework] BrowserRemoted.End() : Now aborting the listener thread (TID = 20).
[09/04 14:30:17,IEXPLORE.EXE(13456:1),Framework] BrowserRemoteClient.Disconnect() : Sending ClientDisconnectRequest(ClientId="Client_34fd0b5a-7765-4c5c-a995-8714e7521262") to pipe server...
[09/04 14:30:17,IEXPLORE.EXE(13456:1),Framework] BrowserRemoteClient.Disconnect() : Command pipe is already disconnected.
[09/04 14:30:17,ArtOfTest.Runner.exe(12428:20),Warning] PipeCommunication.SyncPipeRead() : Pipe read broken by thread-abort.
[09/04 14:30:18,ArtOfTest.Runner.exe(12428:10),DialogPlayback] DialogMonitor.Stop() : Dialog monitoring is being halted.
[09/04 14:30:18,ArtOfTest.Runner.exe(12428:10),UIAutomation] AutomationExtensions.AddAutomationEventHandler() : Added a(n) WindowPatternIdentifiers.WindowOpenedProperty handler on element System.Windows.Automation.AutomationElement scope Descendants.
[09/04 14:30:18,ArtOfTest.Runner.exe(12428:21),UIAutomation] AutomationExtensions.RemoveStructureChangedEventHandler() : Removed the structure change handler on element System.Windows.Automation.AutomationElement scope Subtree.
[09/04 14:30:18,Telerik.TestStudio.RemoteExecutor.exe(13036:14),Execution] TestPlatform.AfterRunFinished() : Finished running test platform- attempting publish
[09/04 14:30:21,Telerik.TestStudio.RemoteExecutor.exe(13036:10),Execution] RunningTestsController.StartTest() : Test run requested for Local path=C:\Users\acouzin\Documents\Test Studio Projects\Swedish
[09/04 14:30:21,ArtOfTest.Runner.exe(12428:6)] First trace message from pool unnamed thread (managed ID = 6, native ID = 11984).
[09/04 14:30:21,ArtOfTest.Runner.exe(12428:6),Framework] InternetExplorerActions.LaunchNewBrowserInstance() : Process launched (ID=14180, Path="C:\Program Files\Internet Explorer\iexplore.exe", Arguments="-nomerge about:blank").
[09/04 14:30:22,ArtOfTest.Runner.exe(12428:6),Framework] InternetExplorerActions.LaunchNewBrowserInstance() : Attempting to attach on IE frame (HWND=1377336)...
[09/04 14:30:22,ArtOfTest.Runner.exe(12428:6),Framework] Connector.Start() : Process launched (ID=13844, Path="C:\Windows\SysWOW64\rundll32.exe", Arguments=""C:\Program Files (x86)\Telerik\Test Studio\Bin\ArtOfTest.Connector.dll",AttachToWindowEntryPoint 2099606").
[09/04 14:30:22,ArtOfTest.Runner.exe(12428:6),Framework] Connector.Start() : Process exited (ID=13844, ExitCode=0, Path="C:\Windows\SysWOW64\rundll32.exe", Arguments=""C:\Program Files (x86)\Telerik\Test Studio\Bin\ArtOfTest.Connector.dll",AttachToWindowEntryPoint 2099606").
[09/04 14:30:22,ArtOfTest.Runner.exe(12428:6),Framework] InternetExplorerActions.LaunchNewBrowserInstance() : Process "Telerik.TestStudio.RemoteExecutor" (ID=13036) is assumed to host the controller.
[09/04 14:30:22,ArtOfTest.Runner.exe(12428:6),Framework] Connector.InjectCode() : ------- Function Start -------
[09/04 14:30:22,ArtOfTest.Runner.exe(12428: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?13036
[09/04 14:30:22,ArtOfTest.Runner.exe(12428:6),Framework] InternetExplorerActions.WaitForDocument() : Attempting to get IHTMLDocument2 from Internet Explorer HWND 2099606 on another thread...
[09/04 14:30:22,ArtOfTest.Runner.exe(12428:10)] First trace message from pool thread "WaitAsync polling wait worker" (managed ID = 10, native ID = 12232).
[09/04 14:30:22,ArtOfTest.Runner.exe(12428:10),Framework] <>c__DisplayClassf.<WaitForDocument>b__8() : Attempting to get document from window handle
[09/04 14:30:22,ArtOfTest.Runner.exe(12428:6),Framework] InternetExplorerActions.WaitForDocument() : IHTMLDocument2 successfully retrieved.
[09/04 14:30:22,ArtOfTest.Runner.exe(12428:6),Framework] Connector.InjectCode() : Sending WM_COPYDATA to HWND 2099606 (lpData = "C:\windows\Microsoft.Net\assembly\GAC_MSIL\ArtOfTest.InternetExplorer\v4.0_2015.2.723.0__5339893a7cefe4d6\ArtOfTest.InternetExplorer.dll?ArtOfTest.InternetExplorer.ArtOfTestPluginEntryPoint?13036")...
[09/04 14:30:22,IEXPLORE.EXE(13416:1)] First trace message from process 13416: "C:\Program Files (x86)\Internet Explorer\IEXPLORE.EXE" SCODEF:14180 CREDAT:275457 /prefetch:2
[09/04 14:30:22,IEXPLORE.EXE(13416:1)] First trace message from background unnamed thread (managed ID = 1, native ID = 10892).
[09/04 14:30:22,IEXPLORE.EXE(13416:1),Framework] ArtOfTestPluginEntryPoint.Connect() : The .NET Framework is now active inside the hooked process (HWND = 2099606, PipeName = "Pipe.ArtOfTest.WebAii.BrowserProvisioner_-153552816", PID = 13036).
[09/04 14:30:22,IEXPLORE.EXE(13416:1),Framework] InternetExplorerActions.WaitForDocument() : Attempting to get IHTMLDocument2 from Internet Explorer HWND 2099606 on same thread...
[09/04 14:30:22,IEXPLORE.EXE(13416:1),Framework] InternetExplorerActions.WaitForDocument() : IHTMLDocument2 successfully retrieved.
[09/04 14:30:22,IEXPLORE.EXE(13416:1),Framework] BrowserRemoteClient constructor : The client ID for this hooked process will be "Client_58fc7ed7-932a-4b7d-82aa-e5278e979de1".
[09/04 14:30:22,ArtOfTest.Runner.exe(12428:11)] First trace message from background thread "Pipe.ArtOfTest.WebAii.BrowserProvisioner" (managed ID = 11, native ID = 1540).
[09/04 14:30:22,ArtOfTest.Runner.exe(12428:11),Framework] BrowserProvisioner.ServiceThread() : A new client has connected to the provisioning pipe.
[09/04 14:30:22,ArtOfTest.Runner.exe(12428:13)] First trace message from background thread "Remoted async command listener" (managed ID = 13, native ID = 11704).
[09/04 14:30:22,ArtOfTest.Runner.exe(12428:13),Framework] BrowserRemoted.AsyncListenerThreadEntry() : Not connected; this will now wait indefinitely for a pipe client connection...
[09/04 14:30:22,IEXPLORE.EXE(13416:1),Framework] BrowserRemoteClient.CreateNamedPipes() : Named pipes created for communication with the server: {Command = "0d28c5ee-8922-46eb-9337-bb4a97388354.CommandPipe", Async = "0d28c5ee-8922-46eb-9337-bb4a97388354.AsyncPipe"}
[09/04 14:30:22,ArtOfTest.Runner.exe(12428:13),Framework] BrowserRemoted.AsyncListenerThreadEntry() : A new client has connected to the pipe.
[09/04 14:30:22,ArtOfTest.Runner.exe(12428:13),Framework] BrowserRemoted.AsyncListenerThreadEntry() : Now waiting indefinitely for a command from the pipe client...
[09/04 14:30:22,ArtOfTest.Runner.exe(12428:11),Framework] Manager.AddNewBrowser() : Added client ID "Client_58fc7ed7-932a-4b7d-82aa-e5278e979de1" (now there is/are 1 total).
[09/04 14:30:22,ArtOfTest.Runner.exe(12428:6),Framework] Connector.InjectCode() : Returned from sending WM_COPYDATA (LRESULT=0, GetLastError()=ERROR_ALREADY_EXISTS).
[09/04 14:30:22,ArtOfTest.Runner.exe(12428:6),Framework] Connector.InjectCode() : ------- Function End -------
[09/04 14:30:22,ArtOfTest.Runner.exe(12428:6),Execution] ExecutionEngine.InternalExecuteTest() : Executing test: 'Schedule Appt - New Patients', path: 'Schedule Appt - New Patients.tstest.'
[09/04 14:30:22,ArtOfTest.Runner.exe(12428:6),TestStudio] VisualDebugger.SetCurrentResult() : VisualDebugger::SetCurrentResult
[09/04 14:30:22,ArtOfTest.Runner.exe(12428:6),DialogPlayback] DialogMonitor.Start() : Beginning monitoring for dialogs from the set {}.
[09/04 14:30:22,ArtOfTest.Runner.exe(12428:6),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[09/04 14:30:22,ArtOfTest.Runner.exe(12428:6),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 1 "Navigate to : 'http://www.swedish.org/'"...
[09/04 14:30:22,ArtOfTest.Runner.exe(12428:7)] First trace message from background thread "UAI Dispatcher Thread" (managed ID = 7, native ID = 10152).
[09/04 14:30:22,ArtOfTest.Runner.exe(12428:7),UIAutomation] AutomationExtensions.AddStructureChangedEventHandler() : Added a structure change handler on element System.Windows.Automation.AutomationElement scope Subtree.
[09/04 14:30:24,ArtOfTest.Runner.exe(12428:6),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 1 "Navigate to : 'http://www.swedish.org/'".
[09/04 14:30:24,ArtOfTest.Runner.exe(12428:6),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[09/04 14:30:24,ArtOfTest.Runner.exe(12428:6),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 2 "Click 'FindALink'"...
[09/04 14:30:26,ArtOfTest.Runner.exe(12428:6),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 2 "Click 'FindALink'".
[09/04 14:30:26,ArtOfTest.Runner.exe(12428:6),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[09/04 14:30:26,ArtOfTest.Runner.exe(12428:6),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 3 "Enter text 'Meena Mital' in 'Main0Contentpanel2TxtNameAutoCompleteText'"...
[09/04 14:30:26,ArtOfTest.Runner.exe(12428:6),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 3 "Enter text 'Meena Mital' in 'Main0Contentpanel2TxtNameAutoCompleteText'".
[09/04 14:30:26,ArtOfTest.Runner.exe(12428:6),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[09/04 14:30:26,ArtOfTest.Runner.exe(12428:6),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 4 "Click 'Main0Contentpanel2BtnNameSubmitSubmit'"...
[09/04 14:30:28,ArtOfTest.Runner.exe(12428:6),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 4 "Click 'Main0Contentpanel2BtnNameSubmitSubmit'".
[09/04 14:30:28,ArtOfTest.Runner.exe(12428:6),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[09/04 14:30:28,ArtOfTest.Runner.exe(12428:6),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 5 "Click 'Main0Contentpanel2LvSearchResultsHlMoreLink0Link'"...
[09/04 14:30:30,ArtOfTest.Runner.exe(12428:6),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 5 "Click 'Main0Contentpanel2LvSearchResultsHlMoreLink0Link'".
[09/04 14:30:30,ArtOfTest.Runner.exe(12428:6),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[09/04 14:30:30,ArtOfTest.Runner.exe(12428:6),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 6 "Click 'ScheduleDiv'"...
[09/04 14:30:32,ArtOfTest.Runner.exe(12428:6),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 6 "Click 'ScheduleDiv'".
[09/04 14:30:32,ArtOfTest.Runner.exe(12428:6),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[09/04 14:30:32,ArtOfTest.Runner.exe(12428:6),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 7 "Click 'NoDiv'"...
[09/04 14:30:33,ArtOfTest.Runner.exe(12428:6),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 7 "Click 'NoDiv'".
[09/04 14:30:33,ArtOfTest.Runner.exe(12428:6),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[09/04 14:30:33,ArtOfTest.Runner.exe(12428:6),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 8 "Verify element 'ScheduleH3Tag' 'is' visible."...
[09/04 14:30:33,ArtOfTest.Runner.exe(12428:6),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 8 "Verify element 'ScheduleH3Tag' 'is' visible.".
[09/04 14:30:34,ArtOfTest.Runner.exe(12428:6),TestStudio] VisualDebugger.ShutDown() : VisualDebbuger::ShutDown::Start
[09/04 14:30:34,ArtOfTest.Runner.exe(12428:6),TestStudio] VisualDebugger.ShutDown() : VisualDebbuger::ShutDown::End
[09/04 14:30:34,ArtOfTest.Runner.exe(12428:6),Framework] BrowserProvisioner.EndService() : Connecting to the provisioning pipe "Pipe.ArtOfTest.WebAii.BrowserProvisioner_-153552816" in order to unblock and eventually terminate it...
[09/04 14:30:34,ArtOfTest.Runner.exe(12428:6),Framework] BrowserProvisioner.EndService() : Provisioning pipe connected.
[09/04 14:30:34,ArtOfTest.Runner.exe(12428: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:30:34,ArtOfTest.Runner.exe(12428:6),Framework] BrowserProvisioner.EndService() : Provisioning thread terminated.
[09/04 14:30:34,ArtOfTest.Runner.exe(12428:6),DialogPlayback] DialogMonitor.AddDialog() : Added {OnBeforeUnloadDialog(Dismiss=OK)} for monitoring.
[09/04 14:30:34,ArtOfTest.Runner.exe(12428:6),Framework] Manager.RemoveBrowser() : Removed client ID "Client_58fc7ed7-932a-4b7d-82aa-e5278e979de1" (0 remaining).
[09/04 14:30:34,ArtOfTest.Runner.exe(12428:6),Framework] Manager.SetActiveBrowser() : Active browser is now null.
[09/04 14:30:34,ArtOfTest.Runner.exe(12428:6),Framework] BrowserRemoted.End() : Now aborting the listener thread (TID = 13).
[09/04 14:30:34,IEXPLORE.EXE(13416:1),Framework] BrowserRemoteClient.Disconnect() : Sending ClientDisconnectRequest(ClientId="Client_58fc7ed7-932a-4b7d-82aa-e5278e979de1") to pipe server...
[09/04 14:30:34,IEXPLORE.EXE(13416:1),Framework] BrowserRemoteClient.Disconnect() : Command pipe is already disconnected.
[09/04 14:30:34,ArtOfTest.Runner.exe(12428:13),Warning] PipeCommunication.SyncPipeRead() : Pipe read broken by thread-abort.
[09/04 14:30:34,ArtOfTest.Runner.exe(12428:6),DialogPlayback] DialogMonitor.Stop() : Dialog monitoring is being halted.
[09/04 14:30:34,ArtOfTest.Runner.exe(12428:6),UIAutomation] AutomationExtensions.AddAutomationEventHandler() : Added a(n) WindowPatternIdentifiers.WindowOpenedProperty handler on element System.Windows.Automation.AutomationElement scope Descendants.
[09/04 14:30:34,ArtOfTest.Runner.exe(12428:7),UIAutomation] AutomationExtensions.RemoveStructureChangedEventHandler() : Removed the structure change handler on element System.Windows.Automation.AutomationElement scope Subtree.
[09/04 14:30:35,Telerik.TestStudio.RemoteExecutor.exe(13036:14),Execution] TestPlatform.AfterRunFinished() : Finished running test platform- attempting publish
