[09/04 15:55:12,Telerik.TestStudio.Desktop.exe(13480:1),ProjectModel] StandaloneHost.EditControlledFiles() : ------- Function Start -------
[09/04 15:55:12,Telerik.TestStudio.Desktop.exe(13480:1),ProjectModel] StandaloneHost.EditControlledFiles() : Project is offline or uncontrolled; making files writeable
[09/04 15:55:12,Telerik.TestStudio.Desktop.exe(13480:1),ProjectModel] StandaloneHost.EditControlledFiles() : ------- Function End -------
[09/04 15:55:44,Telerik.TestStudio.ExecutionManagerService.exe(10252:18)] First trace message from pool unnamed thread (managed ID = 18, native ID = 12524).
[09/04 15:55:44,Telerik.TestStudio.ExecutionManagerService.exe(10252:18),Execution] JobBroker.ScheduleJobAsync() : Job satisfied all preconditions, id = 434b1023-5c2b-424f-8d53-b12632a09b3a
[09/04 15:55:44,Telerik.TestStudio.ExecutionManagerService.exe(10252:18),Execution] JobBroker.ScheduleJobAsync() : Job sent to scheduler, id = 434b1023-5c2b-424f-8d53-b12632a09b3a
[09/04 15:55:44,Telerik.TestStudio.ExecutionManagerService.exe(10252:18),Execution] JobsController.CreateNewJob() : Accepted new job, Id = 434b1023-5c2b-424f-8d53-b12632a09b3a
[09/04 15:55:49,Telerik.TestStudio.ExecutionManagerService.exe(10252:4),Execution] JobRunner.RunJobAsync() : TestList loaded for job ID = 434b1023-5c2b-424f-8d53-b12632a09b3a
[09/04 15:55:49,Telerik.TestStudio.ExecutionManagerService.exe(10252:4),Execution] JobRunner.RunJobAsync() : Job started, ID = 434b1023-5c2b-424f-8d53-b12632a09b3a
[09/04 15:55:50,Telerik.TestStudio.RemoteExecutor.exe(4820:14)] First trace message from pool unnamed thread (managed ID = 14, native ID = 8772).
[09/04 15:55:50,Telerik.TestStudio.RemoteExecutor.exe(4820:14),Execution] RunningTestsController.StartTest() : Test run requested for Cloud path=C:\Users\acouzin\Documents\Test Studio Projects\Swedish
[09/04 15:55:51,ArtOfTest.Runner.exe(9428:3)] First trace message from process 9428: "C:\Program Files (x86)\Telerik\Test Studio\Bin\ArtOfTest.Runner.exe"  /controller /parentprocesshandle=2448
[09/04 15:55:51,ArtOfTest.Runner.exe(9428:3)] First trace message from foreground thread "Remote.ArtOfTest.Runner.Listener" (managed ID = 3, native ID = 11992).
[09/04 15:55:51,ArtOfTest.Runner.exe(9428:3),TestStudio] Loader.LoadTranslatorGroups() : ------- Function Start -------
[09/04 15:55:52,ArtOfTest.Runner.exe(9428:3),TestStudio] Loader.LoadTranslatorGroups() : ------- Function End -------
[09/04 15:55:52,ArtOfTest.Runner.exe(9428:5)] First trace message from pool unnamed thread (managed ID = 5, native ID = 11532).
[09/04 15:55:52,ArtOfTest.Runner.exe(9428:5),Framework] InternetExplorerActions.LaunchNewBrowserInstance() : Process launched (ID=10760, Path="C:\Program Files\Internet Explorer\iexplore.exe", Arguments="-nomerge about:blank").
[09/04 15:55:52,ArtOfTest.Runner.exe(9428:5),Framework] InternetExplorerActions.LaunchNewBrowserInstance() : Attempting to attach on IE frame (HWND=2820182)...
[09/04 15:55:53,ArtOfTest.Runner.exe(9428:5),Framework] Connector.Start() : Process launched (ID=8432, Path="C:\Windows\SysWOW64\rundll32.exe", Arguments=""C:\Program Files (x86)\Telerik\Test Studio\Bin\ArtOfTest.Connector.dll",AttachToWindowEntryPoint 36571336").
[09/04 15:55:53,ArtOfTest.Runner.exe(9428:5),Framework] Connector.Start() : Process exited (ID=8432, ExitCode=0, Path="C:\Windows\SysWOW64\rundll32.exe", Arguments=""C:\Program Files (x86)\Telerik\Test Studio\Bin\ArtOfTest.Connector.dll",AttachToWindowEntryPoint 36571336").
[09/04 15:55:53,ArtOfTest.Runner.exe(9428:5),Framework] InternetExplorerActions.LaunchNewBrowserInstance() : Process "Telerik.TestStudio.RemoteExecutor" (ID=4820) is assumed to host the controller.
[09/04 15:55:53,ArtOfTest.Runner.exe(9428:5),Framework] Connector.InjectCode() : ------- Function Start -------
[09/04 15:55:53,ArtOfTest.Runner.exe(9428: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?4820
[09/04 15:55:53,ArtOfTest.Runner.exe(9428:5),Framework] InternetExplorerActions.WaitForDocument() : Attempting to get IHTMLDocument2 from Internet Explorer HWND 36571336 on another thread...
[09/04 15:55:53,ArtOfTest.Runner.exe(9428:6)] First trace message from pool thread "WaitAsync polling wait worker" (managed ID = 6, native ID = 13160).
[09/04 15:55:53,ArtOfTest.Runner.exe(9428:6),Framework] <>c__DisplayClassf.<WaitForDocument>b__8() : Attempting to get document from window handle
[09/04 15:55:53,ArtOfTest.Runner.exe(9428:5),Framework] InternetExplorerActions.WaitForDocument() : IHTMLDocument2 successfully retrieved.
[09/04 15:55:53,ArtOfTest.Runner.exe(9428:5),Framework] Connector.InjectCode() : Sending WM_COPYDATA to HWND 36571336 (lpData = "C:\windows\Microsoft.Net\assembly\GAC_MSIL\ArtOfTest.InternetExplorer\v4.0_2015.2.723.0__5339893a7cefe4d6\ArtOfTest.InternetExplorer.dll?ArtOfTest.InternetExplorer.ArtOfTestPluginEntryPoint?4820")...
[09/04 15:55:53,IEXPLORE.EXE(12188:1)] First trace message from process 12188: "C:\Program Files (x86)\Internet Explorer\IEXPLORE.EXE" SCODEF:10760 CREDAT:275457 /prefetch:2
[09/04 15:55:53,IEXPLORE.EXE(12188:1)] First trace message from background unnamed thread (managed ID = 1, native ID = 10940).
[09/04 15:55:53,IEXPLORE.EXE(12188:1),Framework] ArtOfTestPluginEntryPoint.Connect() : The .NET Framework is now active inside the hooked process (HWND = 36571336, PipeName = "Pipe.ArtOfTest.WebAii.BrowserProvisioner_-153972303", PID = 4820).
[09/04 15:55:53,IEXPLORE.EXE(12188:1),Framework] InternetExplorerActions.WaitForDocument() : Attempting to get IHTMLDocument2 from Internet Explorer HWND 36571336 on same thread...
[09/04 15:55:53,IEXPLORE.EXE(12188:1),Framework] InternetExplorerActions.WaitForDocument() : IHTMLDocument2 successfully retrieved.
[09/04 15:55:54,IEXPLORE.EXE(12188:1),Framework] BrowserRemoteClient constructor : The client ID for this hooked process will be "Client_33d9819a-dd59-4680-aea7-71da34de0e78".
[09/04 15:55:54,ArtOfTest.Runner.exe(9428:7)] First trace message from background thread "Pipe.ArtOfTest.WebAii.BrowserProvisioner" (managed ID = 7, native ID = 8248).
[09/04 15:55:54,ArtOfTest.Runner.exe(9428:7),Framework] BrowserProvisioner.ServiceThread() : A new client has connected to the provisioning pipe.
[09/04 15:55:54,IEXPLORE.EXE(12188:1),Framework] BrowserRemoteClient.CreateNamedPipes() : Named pipes created for communication with the server: {Command = "d6527bfd-bfb9-4b84-b945-378c598eb3cc.CommandPipe", Async = "d6527bfd-bfb9-4b84-b945-378c598eb3cc.AsyncPipe"}
[09/04 15:55:54,ArtOfTest.Runner.exe(9428:13)] First trace message from background thread "Remoted async command listener" (managed ID = 13, native ID = 10628).
[09/04 15:55:54,ArtOfTest.Runner.exe(9428:13),Framework] BrowserRemoted.AsyncListenerThreadEntry() : Not connected; this will now wait indefinitely for a pipe client connection...
[09/04 15:55:54,ArtOfTest.Runner.exe(9428:13),Framework] BrowserRemoted.AsyncListenerThreadEntry() : A new client has connected to the pipe.
[09/04 15:55:54,ArtOfTest.Runner.exe(9428:13),Framework] BrowserRemoted.AsyncListenerThreadEntry() : Now waiting indefinitely for a command from the pipe client...
[09/04 15:55:54,ArtOfTest.Runner.exe(9428:7),Framework] Manager.AddNewBrowser() : Added client ID "Client_33d9819a-dd59-4680-aea7-71da34de0e78" (now there is/are 1 total).
[09/04 15:55:54,ArtOfTest.Runner.exe(9428:5),Framework] Connector.InjectCode() : Returned from sending WM_COPYDATA (LRESULT=0, GetLastError()=ERROR_ALREADY_EXISTS).
[09/04 15:55:54,ArtOfTest.Runner.exe(9428:5),Framework] Connector.InjectCode() : ------- Function End -------
[09/04 15:55:54,ArtOfTest.Runner.exe(9428:5),Execution] ExecutionEngine.InternalExecuteTest() : Executing test: 'FAD- Viewing page 1 of', path: 'FAD- Viewing page 1 of.tstest.'
[09/04 15:55:54,ArtOfTest.Runner.exe(9428:5),Execution] ExtensionsManager.LoadExtensions() : ------- Function Start -------
[09/04 15:55:54,ArtOfTest.Runner.exe(9428:5),Uncategorized] ExtensionsManager.LoadExtensions() : Got 16 plugin(s) in folder "C:\Program Files (x86)\Telerik\Test Studio\Bin\Plugins".
[09/04 15:55:54,ArtOfTest.Runner.exe(9428:5),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Autofac.dll" from plugins folder.
[09/04 15:55:54,ArtOfTest.Runner.exe(9428:5),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Microsoft.CompilerServices.AsyncTargetingPack.Net4.dll" from plugins folder.
[09/04 15:55:54,ArtOfTest.Runner.exe(9428:5),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Newtonsoft.Json.dll" from plugins folder.
[09/04 15:55:54,ArtOfTest.Runner.exe(9428:5),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "System.Net.Http.dll" from plugins folder.
[09/04 15:55:54,ArtOfTest.Runner.exe(9428:5),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "System.Net.Http.Formatting.dll" from plugins folder.
[09/04 15:55:54,ArtOfTest.Runner.exe(9428:5),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "System.Web.Http.dll" from plugins folder.
[09/04 15:55:54,ArtOfTest.Runner.exe(9428:5),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.Jira.BugTracking.dll" from plugins folder.
[09/04 15:55:54,ArtOfTest.Runner.exe(9428:5),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.Load.dll" from plugins folder.
[09/04 15:55:54,ArtOfTest.Runner.exe(9428:5),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.Manual.dll" from plugins folder.
[09/04 15:55:54,ArtOfTest.Runner.exe(9428:5),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.OTA.Interop.dll" from plugins folder.
[09/04 15:55:54,ArtOfTest.Runner.exe(9428:5),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.Plugins.dll" from plugins folder.
[09/04 15:55:54,ArtOfTest.Runner.exe(9428:5),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.Shared.dll" from plugins folder.
[09/04 15:55:54,ArtOfTest.Runner.exe(9428:5),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.TeamPulse.dll" from plugins folder.
[09/04 15:55:54,ArtOfTest.Runner.exe(9428:5),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.Theme.dll" from plugins folder.
[09/04 15:55:54,ArtOfTest.Runner.exe(9428:5),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.Web.dll" from plugins folder.
[09/04 15:55:54,ArtOfTest.Runner.exe(9428:5),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.Wpf.dll" from plugins folder.
[09/04 15:55:54,ArtOfTest.Runner.exe(9428:5),Execution] ExtensionsManager.LoadExtensions() : ------- Function End -------
[09/04 15:55:54,ArtOfTest.Runner.exe(9428:5),TestStudio] VisualDebugger.SetCurrentResult() : VisualDebugger::SetCurrentResult
[09/04 15:55:54,ArtOfTest.Runner.exe(9428:5),DialogPlayback] DialogMonitor.Start() : Beginning monitoring for dialogs from the set {}.
[09/04 15:55:54,ArtOfTest.Runner.exe(9428:5),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[09/04 15:55:54,ArtOfTest.Runner.exe(9428:5),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 1 "Navigate to : 'http://www.swedish.org/'"...
[09/04 15:55:54,ArtOfTest.Runner.exe(9428:9)] First trace message from background thread "UAI Dispatcher Thread" (managed ID = 9, native ID = 7796).
[09/04 15:55:54,ArtOfTest.Runner.exe(9428:9),UIAutomation] AutomationExtensions.AddStructureChangedEventHandler() : Added a structure change handler on element System.Windows.Automation.AutomationElement scope Subtree.
[09/04 15:55:55,ArtOfTest.Runner.exe(9428:5),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 1 "Navigate to : 'http://www.swedish.org/'".
[09/04 15:55:55,ArtOfTest.Runner.exe(9428:5),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[09/04 15:55:55,ArtOfTest.Runner.exe(9428:5),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 2 "Click 'FindALink'"...
[09/04 15:55:56,ArtOfTest.Runner.exe(9428:5),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 2 "Click 'FindALink'".
[09/04 15:55:56,ArtOfTest.Runner.exe(9428:5),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[09/04 15:55:56,ArtOfTest.Runner.exe(9428:5),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 3 "Enter text 'Cardiac Surgery' in 'Main0Contentpanel2TxtHealthConditionAutoCompleteText'"...
[09/04 15:55:57,ArtOfTest.Runner.exe(9428:5),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 3 "Enter text 'Cardiac Surgery' in 'Main0Contentpanel2TxtHealthConditionAutoCompleteText'".
[09/04 15:55:57,ArtOfTest.Runner.exe(9428:5),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[09/04 15:55:57,ArtOfTest.Runner.exe(9428:5),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 4 "Click 'Main0Contentpanel2BtnSubmitSubmit'"...
[09/04 15:55:59,ArtOfTest.Runner.exe(9428:5),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 4 "Click 'Main0Contentpanel2BtnSubmitSubmit'".
[09/04 15:55:59,ArtOfTest.Runner.exe(9428:5),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[09/04 15:55:59,ArtOfTest.Runner.exe(9428:5),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 5 "Wait for Exists 'HtmlTag'"...
[09/04 15:55:59,ArtOfTest.Runner.exe(9428:5),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 5 "Wait for Exists 'HtmlTag'".
[09/04 15:55:59,ArtOfTest.Runner.exe(9428:5),TestStudio] VisualDebugger.ShutDown() : VisualDebbuger::ShutDown::Start
[09/04 15:55:59,ArtOfTest.Runner.exe(9428:5),TestStudio] VisualDebugger.ShutDown() : VisualDebbuger::ShutDown::End
[09/04 15:55:59,ArtOfTest.Runner.exe(9428:5),Framework] BrowserProvisioner.EndService() : Connecting to the provisioning pipe "Pipe.ArtOfTest.WebAii.BrowserProvisioner_-153972303" in order to unblock and eventually terminate it...
[09/04 15:55:59,ArtOfTest.Runner.exe(9428:7),Framework] BrowserProvisioner.ServiceThread() : A new client has connected to the provisioning pipe but this will be treated as a termination cue.
[09/04 15:55:59,ArtOfTest.Runner.exe(9428:5),Framework] BrowserProvisioner.EndService() : Provisioning pipe connected.
[09/04 15:55:59,ArtOfTest.Runner.exe(9428:5),Framework] BrowserProvisioner.EndService() : Provisioning thread terminated.
[09/04 15:55:59,ArtOfTest.Runner.exe(9428:5),DialogPlayback] DialogMonitor.AddDialog() : Added {OnBeforeUnloadDialog(Dismiss=OK)} for monitoring.
[09/04 15:55:59,ArtOfTest.Runner.exe(9428:5),Framework] Manager.RemoveBrowser() : Removed client ID "Client_33d9819a-dd59-4680-aea7-71da34de0e78" (0 remaining).
[09/04 15:55:59,ArtOfTest.Runner.exe(9428:5),Framework] Manager.SetActiveBrowser() : Active browser is now null.
[09/04 15:55:59,ArtOfTest.Runner.exe(9428:5),Framework] BrowserRemoted.End() : Now aborting the listener thread (TID = 13).
[09/04 15:55:59,IEXPLORE.EXE(12188:1),Framework] BrowserRemoteClient.Disconnect() : Sending ClientDisconnectRequest(ClientId="Client_33d9819a-dd59-4680-aea7-71da34de0e78") to pipe server...
[09/04 15:55:59,IEXPLORE.EXE(12188:1),Framework] BrowserRemoteClient.Disconnect() : Command pipe is already disconnected.
[09/04 15:55:59,ArtOfTest.Runner.exe(9428:13),Warning] PipeCommunication.SyncPipeRead() : Pipe read broken by thread-abort.
[09/04 15:55:59,ArtOfTest.Runner.exe(9428:5),DialogPlayback] DialogMonitor.Stop() : Dialog monitoring is being halted.
[09/04 15:55:59,ArtOfTest.Runner.exe(9428:9),UIAutomation] AutomationExtensions.RemoveStructureChangedEventHandler() : Removed the structure change handler on element System.Windows.Automation.AutomationElement scope Subtree.
[09/04 15:55:59,ArtOfTest.Runner.exe(9428:5),UIAutomation] AutomationExtensions.AddAutomationEventHandler() : Added a(n) WindowPatternIdentifiers.WindowOpenedProperty handler on element System.Windows.Automation.AutomationElement scope Descendants.
[09/04 15:56:00,Telerik.TestStudio.RemoteExecutor.exe(4820:10)] First trace message from background thread "Callback Listener Thread" (managed ID = 10, native ID = 13660).
[09/04 15:56:00,Telerik.TestStudio.RemoteExecutor.exe(4820:10),Execution] TestPlatform.AfterRunFinished() : Finished running test platform- attempting publish
[09/04 15:56:05,Telerik.TestStudio.RemoteExecutor.exe(4820:12)] First trace message from pool unnamed thread (managed ID = 12, native ID = 11016).
[09/04 15:56:05,Telerik.TestStudio.RemoteExecutor.exe(4820:12),Execution] RunningTestsController.StartTest() : Test run requested for Cloud path=C:\Users\acouzin\Documents\Test Studio Projects\Swedish
[09/04 15:56:05,ArtOfTest.Runner.exe(9428:6)] First trace message from pool unnamed thread (managed ID = 6, native ID = 13160).
[09/04 15:56:05,ArtOfTest.Runner.exe(9428:6),Framework] InternetExplorerActions.LaunchNewBrowserInstance() : Process launched (ID=9072, Path="C:\Program Files\Internet Explorer\iexplore.exe", Arguments="-nomerge about:blank").
[09/04 15:56:05,ArtOfTest.Runner.exe(9428:6),Framework] InternetExplorerActions.LaunchNewBrowserInstance() : Attempting to attach on IE frame (HWND=2296816)...
[09/04 15:56:05,ArtOfTest.Runner.exe(9428:6),Framework] Connector.Start() : Process launched (ID=12096, Path="C:\Windows\SysWOW64\rundll32.exe", Arguments=""C:\Program Files (x86)\Telerik\Test Studio\Bin\ArtOfTest.Connector.dll",AttachToWindowEntryPoint 396376").
[09/04 15:56:05,ArtOfTest.Runner.exe(9428:6),Framework] Connector.Start() : Process exited (ID=12096, ExitCode=0, Path="C:\Windows\SysWOW64\rundll32.exe", Arguments=""C:\Program Files (x86)\Telerik\Test Studio\Bin\ArtOfTest.Connector.dll",AttachToWindowEntryPoint 396376").
[09/04 15:56:05,ArtOfTest.Runner.exe(9428:6),Framework] InternetExplorerActions.LaunchNewBrowserInstance() : Process "Telerik.TestStudio.RemoteExecutor" (ID=4820) is assumed to host the controller.
[09/04 15:56:05,ArtOfTest.Runner.exe(9428:6),Framework] Connector.InjectCode() : ------- Function Start -------
[09/04 15:56:05,ArtOfTest.Runner.exe(9428: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?4820
[09/04 15:56:05,ArtOfTest.Runner.exe(9428:6),Framework] InternetExplorerActions.WaitForDocument() : Attempting to get IHTMLDocument2 from Internet Explorer HWND 396376 on another thread...
[09/04 15:56:05,ArtOfTest.Runner.exe(9428:10)] First trace message from pool thread "WaitAsync polling wait worker" (managed ID = 10, native ID = 10532).
[09/04 15:56:05,ArtOfTest.Runner.exe(9428:10),Framework] <>c__DisplayClassf.<WaitForDocument>b__8() : Attempting to get document from window handle
[09/04 15:56:06,ArtOfTest.Runner.exe(9428:6),Framework] InternetExplorerActions.WaitForDocument() : IHTMLDocument2 successfully retrieved.
[09/04 15:56:06,ArtOfTest.Runner.exe(9428:6),Framework] Connector.InjectCode() : Sending WM_COPYDATA to HWND 396376 (lpData = "C:\windows\Microsoft.Net\assembly\GAC_MSIL\ArtOfTest.InternetExplorer\v4.0_2015.2.723.0__5339893a7cefe4d6\ArtOfTest.InternetExplorer.dll?ArtOfTest.InternetExplorer.ArtOfTestPluginEntryPoint?4820")...
[09/04 15:56:06,IEXPLORE.EXE(13484:1)] First trace message from process 13484: "C:\Program Files (x86)\Internet Explorer\IEXPLORE.EXE" SCODEF:9072 CREDAT:275457 /prefetch:2
[09/04 15:56:06,IEXPLORE.EXE(13484:1)] First trace message from background unnamed thread (managed ID = 1, native ID = 4052).
[09/04 15:56:06,IEXPLORE.EXE(13484:1),Framework] ArtOfTestPluginEntryPoint.Connect() : The .NET Framework is now active inside the hooked process (HWND = 396376, PipeName = "Pipe.ArtOfTest.WebAii.BrowserProvisioner_1199974673", PID = 4820).
[09/04 15:56:06,IEXPLORE.EXE(13484:1),Framework] InternetExplorerActions.WaitForDocument() : Attempting to get IHTMLDocument2 from Internet Explorer HWND 396376 on same thread...
[09/04 15:56:06,IEXPLORE.EXE(13484:1),Framework] InternetExplorerActions.WaitForDocument() : IHTMLDocument2 successfully retrieved.
[09/04 15:56:06,IEXPLORE.EXE(13484:1),Framework] BrowserRemoteClient constructor : The client ID for this hooked process will be "Client_3b51a1bc-8f49-4214-9992-9fb075baa6ee".
[09/04 15:56:06,ArtOfTest.Runner.exe(9428:19)] First trace message from background thread "Pipe.ArtOfTest.WebAii.BrowserProvisioner" (managed ID = 19, native ID = 11636).
[09/04 15:56:06,ArtOfTest.Runner.exe(9428:19),Framework] BrowserProvisioner.ServiceThread() : A new client has connected to the provisioning pipe.
[09/04 15:56:06,ArtOfTest.Runner.exe(9428:18)] First trace message from background thread "Remoted async command listener" (managed ID = 18, native ID = 760).
[09/04 15:56:06,ArtOfTest.Runner.exe(9428:18),Framework] BrowserRemoted.AsyncListenerThreadEntry() : Not connected; this will now wait indefinitely for a pipe client connection...
[09/04 15:56:06,IEXPLORE.EXE(13484:1),Framework] BrowserRemoteClient.CreateNamedPipes() : Named pipes created for communication with the server: {Command = "74c73c4d-44f5-4268-a337-ffef0ce0b506.CommandPipe", Async = "74c73c4d-44f5-4268-a337-ffef0ce0b506.AsyncPipe"}
[09/04 15:56:06,ArtOfTest.Runner.exe(9428:18),Framework] BrowserRemoted.AsyncListenerThreadEntry() : A new client has connected to the pipe.
[09/04 15:56:06,ArtOfTest.Runner.exe(9428:18),Framework] BrowserRemoted.AsyncListenerThreadEntry() : Now waiting indefinitely for a command from the pipe client...
[09/04 15:56:06,ArtOfTest.Runner.exe(9428:19),Framework] Manager.AddNewBrowser() : Added client ID "Client_3b51a1bc-8f49-4214-9992-9fb075baa6ee" (now there is/are 1 total).
[09/04 15:56:06,ArtOfTest.Runner.exe(9428:6),Framework] Connector.InjectCode() : Returned from sending WM_COPYDATA (LRESULT=0, GetLastError()=ERROR_ALREADY_EXISTS).
[09/04 15:56:06,ArtOfTest.Runner.exe(9428:6),Framework] Connector.InjectCode() : ------- Function End -------
[09/04 15:56:06,ArtOfTest.Runner.exe(9428:6),Execution] ExecutionEngine.InternalExecuteTest() : Executing test: 'Schedule Appt - Exisitng Patients', path: 'Schedule Appt - Exisitng Patients.tstest.'
[09/04 15:56:06,ArtOfTest.Runner.exe(9428:6),TestStudio] VisualDebugger.SetCurrentResult() : VisualDebugger::SetCurrentResult
[09/04 15:56:06,ArtOfTest.Runner.exe(9428:6),DialogPlayback] DialogMonitor.Start() : Beginning monitoring for dialogs from the set {}.
[09/04 15:56:06,ArtOfTest.Runner.exe(9428:6),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[09/04 15:56:06,ArtOfTest.Runner.exe(9428:6),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 1 "Navigate to : 'http://www.swedish.org/'"...
[09/04 15:56:06,ArtOfTest.Runner.exe(9428:13)] First trace message from background thread "Remoted async command listener" (managed ID = 13, native ID = 8724).
[09/04 15:56:06,ArtOfTest.Runner.exe(9428:13),UIAutomation] AutomationExtensions.AddStructureChangedEventHandler() : Added a structure change handler on element System.Windows.Automation.AutomationElement scope Subtree.
[09/04 15:56:07,ArtOfTest.Runner.exe(9428:6),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 1 "Navigate to : 'http://www.swedish.org/'".
[09/04 15:56:07,ArtOfTest.Runner.exe(9428:6),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[09/04 15:56:07,ArtOfTest.Runner.exe(9428:6),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 2 "Click 'FindALink'"...
[09/04 15:56:08,ArtOfTest.Runner.exe(9428:6),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 2 "Click 'FindALink'".
[09/04 15:56:08,ArtOfTest.Runner.exe(9428:6),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[09/04 15:56:09,ArtOfTest.Runner.exe(9428:6),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 3 "Enter text 'Meena Mital' in 'Main0Contentpanel2TxtNameAutoCompleteText'"...
[09/04 15:56:09,ArtOfTest.Runner.exe(9428:6),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 3 "Enter text 'Meena Mital' in 'Main0Contentpanel2TxtNameAutoCompleteText'".
[09/04 15:56:09,ArtOfTest.Runner.exe(9428:6),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[09/04 15:56:09,ArtOfTest.Runner.exe(9428:6),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 4 "Click 'Main0Contentpanel2BtnNameSubmitSubmit'"...
[09/04 15:56:10,ArtOfTest.Runner.exe(9428:6),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 4 "Click 'Main0Contentpanel2BtnNameSubmitSubmit'".
[09/04 15:56:10,ArtOfTest.Runner.exe(9428:6),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[09/04 15:56:10,ArtOfTest.Runner.exe(9428:6),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 5 "Click 'Main0Contentpanel2LvSearchResultsHlMoreLink0Link'"...
[09/04 15:56:12,ArtOfTest.Runner.exe(9428:6),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 5 "Click 'Main0Contentpanel2LvSearchResultsHlMoreLink0Link'".
[09/04 15:56:12,ArtOfTest.Runner.exe(9428:6),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[09/04 15:56:12,ArtOfTest.Runner.exe(9428:6),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 6 "Click 'ScheduleDiv'"...
[09/04 15:56:14,ArtOfTest.Runner.exe(9428:6),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 6 "Click 'ScheduleDiv'".
[09/04 15:56:14,ArtOfTest.Runner.exe(9428:6),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[09/04 15:56:14,ArtOfTest.Runner.exe(9428:6),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 7 "Click 'YesDiv'"...
[09/04 15:56:15,ArtOfTest.Runner.exe(9428:6),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 7 "Click 'YesDiv'".
[09/04 15:56:15,ArtOfTest.Runner.exe(9428:6),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[09/04 15:56:15,ArtOfTest.Runner.exe(9428:6),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 8 "Verify element 'ScheduleH3Tag' 'is' visible."...
[09/04 15:56:16,ArtOfTest.Runner.exe(9428:6),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 8 "Verify element 'ScheduleH3Tag' 'is' visible.".
[09/04 15:56:16,ArtOfTest.Runner.exe(9428:6),TestStudio] VisualDebugger.ShutDown() : VisualDebbuger::ShutDown::Start
[09/04 15:56:16,ArtOfTest.Runner.exe(9428:6),TestStudio] VisualDebugger.ShutDown() : VisualDebbuger::ShutDown::End
[09/04 15:56:16,ArtOfTest.Runner.exe(9428:6),Framework] BrowserProvisioner.EndService() : Connecting to the provisioning pipe "Pipe.ArtOfTest.WebAii.BrowserProvisioner_1199974673" in order to unblock and eventually terminate it...
[09/04 15:56:16,ArtOfTest.Runner.exe(9428:6),Framework] BrowserProvisioner.EndService() : Provisioning pipe connected.
[09/04 15:56:16,ArtOfTest.Runner.exe(9428:19),Framework] BrowserProvisioner.ServiceThread() : A new client has connected to the provisioning pipe but this will be treated as a termination cue.
[09/04 15:56:16,ArtOfTest.Runner.exe(9428:6),Framework] BrowserProvisioner.EndService() : Provisioning thread terminated.
[09/04 15:56:16,ArtOfTest.Runner.exe(9428:6),DialogPlayback] DialogMonitor.AddDialog() : Added {OnBeforeUnloadDialog(Dismiss=OK)} for monitoring.
[09/04 15:56:16,ArtOfTest.Runner.exe(9428:6),Framework] Manager.RemoveBrowser() : Removed client ID "Client_3b51a1bc-8f49-4214-9992-9fb075baa6ee" (0 remaining).
[09/04 15:56:16,ArtOfTest.Runner.exe(9428:6),Framework] Manager.SetActiveBrowser() : Active browser is now null.
[09/04 15:56:16,ArtOfTest.Runner.exe(9428:6),Framework] BrowserRemoted.End() : Now aborting the listener thread (TID = 18).
[09/04 15:56:16,IEXPLORE.EXE(13484:1),Framework] BrowserRemoteClient.Disconnect() : Sending ClientDisconnectRequest(ClientId="Client_3b51a1bc-8f49-4214-9992-9fb075baa6ee") to pipe server...
[09/04 15:56:16,IEXPLORE.EXE(13484:1),Framework] BrowserRemoteClient.Disconnect() : Command pipe is already disconnected.
[09/04 15:56:16,ArtOfTest.Runner.exe(9428:18),Warning] PipeCommunication.SyncPipeRead() : Pipe read broken by thread-abort.
[09/04 15:56:16,ArtOfTest.Runner.exe(9428:13),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[09/04 15:56:16,ArtOfTest.Runner.exe(9428:6),DialogPlayback] DialogMonitor.Stop() : Dialog monitoring is being halted.
[09/04 15:56:16,ArtOfTest.Runner.exe(9428:6),UIAutomation] AutomationExtensions.AddAutomationEventHandler() : Added a(n) WindowPatternIdentifiers.WindowOpenedProperty handler on element System.Windows.Automation.AutomationElement scope Descendants.
[09/04 15:56:16,ArtOfTest.Runner.exe(9428:13),UIAutomation] AutomationExtensions.RemoveStructureChangedEventHandler() : Removed the structure change handler on element System.Windows.Automation.AutomationElement scope Subtree.
[09/04 15:56:16,Telerik.TestStudio.RemoteExecutor.exe(4820:10),Execution] TestPlatform.AfterRunFinished() : Finished running test platform- attempting publish
[09/04 15:56:20,Telerik.TestStudio.RemoteExecutor.exe(4820:19)] First trace message from pool unnamed thread (managed ID = 19, native ID = 9360).
[09/04 15:56:20,Telerik.TestStudio.RemoteExecutor.exe(4820:19),Execution] RunningTestsController.StartTest() : Test run requested for Cloud path=C:\Users\acouzin\Documents\Test Studio Projects\Swedish
[09/04 15:56:20,ArtOfTest.Runner.exe(9428:5)] First trace message from pool unnamed thread (managed ID = 5, native ID = 11532).
[09/04 15:56:20,ArtOfTest.Runner.exe(9428:5),Framework] InternetExplorerActions.LaunchNewBrowserInstance() : Process launched (ID=9404, Path="C:\Program Files\Internet Explorer\iexplore.exe", Arguments="-nomerge about:blank").
[09/04 15:56:20,ArtOfTest.Runner.exe(9428:5),Framework] InternetExplorerActions.LaunchNewBrowserInstance() : Attempting to attach on IE frame (HWND=2624504)...
[09/04 15:56:20,ArtOfTest.Runner.exe(9428:5),Framework] Connector.Start() : Process launched (ID=9176, Path="C:\Windows\SysWOW64\rundll32.exe", Arguments=""C:\Program Files (x86)\Telerik\Test Studio\Bin\ArtOfTest.Connector.dll",AttachToWindowEntryPoint 1706820").
[09/04 15:56:20,ArtOfTest.Runner.exe(9428:5),Framework] Connector.Start() : Process exited (ID=9176, ExitCode=0, Path="C:\Windows\SysWOW64\rundll32.exe", Arguments=""C:\Program Files (x86)\Telerik\Test Studio\Bin\ArtOfTest.Connector.dll",AttachToWindowEntryPoint 1706820").
[09/04 15:56:20,ArtOfTest.Runner.exe(9428:5),Framework] InternetExplorerActions.LaunchNewBrowserInstance() : Process "Telerik.TestStudio.RemoteExecutor" (ID=4820) is assumed to host the controller.
[09/04 15:56:20,ArtOfTest.Runner.exe(9428:5),Framework] Connector.InjectCode() : ------- Function Start -------
[09/04 15:56:20,ArtOfTest.Runner.exe(9428: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?4820
[09/04 15:56:20,ArtOfTest.Runner.exe(9428:5),Framework] InternetExplorerActions.WaitForDocument() : Attempting to get IHTMLDocument2 from Internet Explorer HWND 1706820 on another thread...
[09/04 15:56:20,ArtOfTest.Runner.exe(9428:6)] First trace message from pool thread "WaitAsync polling wait worker" (managed ID = 6, native ID = 13160).
[09/04 15:56:20,ArtOfTest.Runner.exe(9428:6),Framework] <>c__DisplayClassf.<WaitForDocument>b__8() : Attempting to get document from window handle
[09/04 15:56:21,ArtOfTest.Runner.exe(9428:5),Framework] InternetExplorerActions.WaitForDocument() : IHTMLDocument2 successfully retrieved.
[09/04 15:56:21,ArtOfTest.Runner.exe(9428:5),Framework] Connector.InjectCode() : Sending WM_COPYDATA to HWND 1706820 (lpData = "C:\windows\Microsoft.Net\assembly\GAC_MSIL\ArtOfTest.InternetExplorer\v4.0_2015.2.723.0__5339893a7cefe4d6\ArtOfTest.InternetExplorer.dll?ArtOfTest.InternetExplorer.ArtOfTestPluginEntryPoint?4820")...
[09/04 15:56:21,IEXPLORE.EXE(11032:1)] First trace message from process 11032: "C:\Program Files (x86)\Internet Explorer\IEXPLORE.EXE" SCODEF:9404 CREDAT:275457 /prefetch:2
[09/04 15:56:21,IEXPLORE.EXE(11032:1)] First trace message from background unnamed thread (managed ID = 1, native ID = 7204).
[09/04 15:56:21,IEXPLORE.EXE(11032:1),Framework] ArtOfTestPluginEntryPoint.Connect() : The .NET Framework is now active inside the hooked process (HWND = 1706820, PipeName = "Pipe.ArtOfTest.WebAii.BrowserProvisioner_787175265", PID = 4820).
[09/04 15:56:21,IEXPLORE.EXE(11032:1),Framework] InternetExplorerActions.WaitForDocument() : Attempting to get IHTMLDocument2 from Internet Explorer HWND 1706820 on same thread...
[09/04 15:56:21,IEXPLORE.EXE(11032:1),Framework] InternetExplorerActions.WaitForDocument() : IHTMLDocument2 successfully retrieved.
[09/04 15:56:21,IEXPLORE.EXE(11032:1),Framework] BrowserRemoteClient constructor : The client ID for this hooked process will be "Client_8d5a2e3d-8d53-4e7e-80c4-0126f388fa5d".
[09/04 15:56:21,ArtOfTest.Runner.exe(9428:7)] First trace message from background thread "Pipe.ArtOfTest.WebAii.BrowserProvisioner" (managed ID = 7, native ID = 14028).
[09/04 15:56:21,ArtOfTest.Runner.exe(9428:7),Framework] BrowserProvisioner.ServiceThread() : A new client has connected to the provisioning pipe.
[09/04 15:56:21,ArtOfTest.Runner.exe(9428:21)] First trace message from background thread "Remoted async command listener" (managed ID = 21, native ID = 7656).
[09/04 15:56:21,ArtOfTest.Runner.exe(9428:21),Framework] BrowserRemoted.AsyncListenerThreadEntry() : Not connected; this will now wait indefinitely for a pipe client connection...
[09/04 15:56:21,IEXPLORE.EXE(11032:1),Framework] BrowserRemoteClient.CreateNamedPipes() : Named pipes created for communication with the server: {Command = "70c51ea4-1f7c-465d-99f7-220fb897ba04.CommandPipe", Async = "70c51ea4-1f7c-465d-99f7-220fb897ba04.AsyncPipe"}
[09/04 15:56:21,ArtOfTest.Runner.exe(9428:21),Framework] BrowserRemoted.AsyncListenerThreadEntry() : A new client has connected to the pipe.
[09/04 15:56:21,ArtOfTest.Runner.exe(9428:21),Framework] BrowserRemoted.AsyncListenerThreadEntry() : Now waiting indefinitely for a command from the pipe client...
[09/04 15:56:21,ArtOfTest.Runner.exe(9428:7),Framework] Manager.AddNewBrowser() : Added client ID "Client_8d5a2e3d-8d53-4e7e-80c4-0126f388fa5d" (now there is/are 1 total).
[09/04 15:56:21,ArtOfTest.Runner.exe(9428:5),Framework] Connector.InjectCode() : Returned from sending WM_COPYDATA (LRESULT=0, GetLastError()=ERROR_ALREADY_EXISTS).
[09/04 15:56:21,ArtOfTest.Runner.exe(9428:5),Framework] Connector.InjectCode() : ------- Function End -------
[09/04 15:56:21,ArtOfTest.Runner.exe(9428:5),Execution] ExecutionEngine.InternalExecuteTest() : Executing test: 'Schedule Appt - New Patients', path: 'Schedule Appt - New Patients.tstest.'
[09/04 15:56:21,ArtOfTest.Runner.exe(9428:5),TestStudio] VisualDebugger.SetCurrentResult() : VisualDebugger::SetCurrentResult
[09/04 15:56:21,ArtOfTest.Runner.exe(9428:5),DialogPlayback] DialogMonitor.Start() : Beginning monitoring for dialogs from the set {}.
[09/04 15:56:21,ArtOfTest.Runner.exe(9428:5),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[09/04 15:56:21,ArtOfTest.Runner.exe(9428:5),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 1 "Navigate to : 'http://www.swedish.org/'"...
[09/04 15:56:21,ArtOfTest.Runner.exe(9428:22)] First trace message from background thread "UAI Dispatcher Thread" (managed ID = 22, native ID = 3248).
[09/04 15:56:21,ArtOfTest.Runner.exe(9428:22),UIAutomation] AutomationExtensions.AddStructureChangedEventHandler() : Added a structure change handler on element System.Windows.Automation.AutomationElement scope Subtree.
[09/04 15:56:22,ArtOfTest.Runner.exe(9428:5),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 1 "Navigate to : 'http://www.swedish.org/'".
[09/04 15:56:22,ArtOfTest.Runner.exe(9428:5),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[09/04 15:56:22,ArtOfTest.Runner.exe(9428:5),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 2 "Click 'FindALink'"...
[09/04 15:56:23,ArtOfTest.Runner.exe(9428:5),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 2 "Click 'FindALink'".
[09/04 15:56:23,ArtOfTest.Runner.exe(9428:5),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[09/04 15:56:23,ArtOfTest.Runner.exe(9428:5),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 3 "Enter text 'Meena Mital' in 'Main0Contentpanel2TxtNameAutoCompleteText'"...
[09/04 15:56:23,ArtOfTest.Runner.exe(9428:5),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 3 "Enter text 'Meena Mital' in 'Main0Contentpanel2TxtNameAutoCompleteText'".
[09/04 15:56:23,ArtOfTest.Runner.exe(9428:5),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[09/04 15:56:24,ArtOfTest.Runner.exe(9428:5),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 4 "Click 'Main0Contentpanel2BtnNameSubmitSubmit'"...
[09/04 15:56:25,ArtOfTest.Runner.exe(9428:5),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 4 "Click 'Main0Contentpanel2BtnNameSubmitSubmit'".
[09/04 15:56:25,ArtOfTest.Runner.exe(9428:5),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[09/04 15:56:25,ArtOfTest.Runner.exe(9428:5),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 5 "Click 'Main0Contentpanel2LvSearchResultsHlMoreLink0Link'"...
[09/04 15:56:26,ArtOfTest.Runner.exe(9428:5),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 5 "Click 'Main0Contentpanel2LvSearchResultsHlMoreLink0Link'".
[09/04 15:56:26,ArtOfTest.Runner.exe(9428:5),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[09/04 15:56:26,ArtOfTest.Runner.exe(9428:5),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 6 "Click 'ScheduleDiv'"...
[09/04 15:56:28,ArtOfTest.Runner.exe(9428:5),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 6 "Click 'ScheduleDiv'".
[09/04 15:56:28,ArtOfTest.Runner.exe(9428:5),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[09/04 15:56:28,ArtOfTest.Runner.exe(9428:5),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 7 "Click 'NoDiv'"...
[09/04 15:56:29,ArtOfTest.Runner.exe(9428:5),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 7 "Click 'NoDiv'".
[09/04 15:56:29,ArtOfTest.Runner.exe(9428:5),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[09/04 15:56:29,ArtOfTest.Runner.exe(9428:5),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 8 "Verify element 'ScheduleH3Tag' 'is' visible."...
[09/04 15:56:29,ArtOfTest.Runner.exe(9428:5),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 8 "Verify element 'ScheduleH3Tag' 'is' visible.".
[09/04 15:56:29,ArtOfTest.Runner.exe(9428:5),TestStudio] VisualDebugger.ShutDown() : VisualDebbuger::ShutDown::Start
[09/04 15:56:29,ArtOfTest.Runner.exe(9428:5),TestStudio] VisualDebugger.ShutDown() : VisualDebbuger::ShutDown::End
[09/04 15:56:29,ArtOfTest.Runner.exe(9428:5),Framework] BrowserProvisioner.EndService() : Connecting to the provisioning pipe "Pipe.ArtOfTest.WebAii.BrowserProvisioner_787175265" in order to unblock and eventually terminate it...
[09/04 15:56:29,ArtOfTest.Runner.exe(9428:7),Framework] BrowserProvisioner.ServiceThread() : A new client has connected to the provisioning pipe but this will be treated as a termination cue.
[09/04 15:56:29,ArtOfTest.Runner.exe(9428:5),Framework] BrowserProvisioner.EndService() : Provisioning pipe connected.
[09/04 15:56:29,ArtOfTest.Runner.exe(9428:5),Framework] BrowserProvisioner.EndService() : Provisioning thread terminated.
[09/04 15:56:29,ArtOfTest.Runner.exe(9428:5),DialogPlayback] DialogMonitor.AddDialog() : Added {OnBeforeUnloadDialog(Dismiss=OK)} for monitoring.
[09/04 15:56:29,ArtOfTest.Runner.exe(9428:5),Framework] Manager.RemoveBrowser() : Removed client ID "Client_8d5a2e3d-8d53-4e7e-80c4-0126f388fa5d" (0 remaining).
[09/04 15:56:29,ArtOfTest.Runner.exe(9428:5),Framework] Manager.SetActiveBrowser() : Active browser is now null.
[09/04 15:56:29,ArtOfTest.Runner.exe(9428:5),Framework] BrowserRemoted.End() : Now aborting the listener thread (TID = 21).
[09/04 15:56:29,IEXPLORE.EXE(11032:1),Framework] BrowserRemoteClient.Disconnect() : Sending ClientDisconnectRequest(ClientId="Client_8d5a2e3d-8d53-4e7e-80c4-0126f388fa5d") to pipe server...
[09/04 15:56:29,IEXPLORE.EXE(11032:1),Framework] BrowserRemoteClient.Disconnect() : Command pipe is already disconnected.
[09/04 15:56:29,ArtOfTest.Runner.exe(9428:21),Warning] PipeCommunication.SyncPipeRead() : Pipe read broken by thread-abort.
[09/04 15:56:29,ArtOfTest.Runner.exe(9428:5),DialogPlayback] DialogMonitor.Stop() : Dialog monitoring is being halted.
[09/04 15:56:29,ArtOfTest.Runner.exe(9428:5),UIAutomation] AutomationExtensions.AddAutomationEventHandler() : Added a(n) WindowPatternIdentifiers.WindowOpenedProperty handler on element System.Windows.Automation.AutomationElement scope Descendants.
[09/04 15:56:29,ArtOfTest.Runner.exe(9428:22),UIAutomation] AutomationExtensions.RemoveStructureChangedEventHandler() : Removed the structure change handler on element System.Windows.Automation.AutomationElement scope Subtree.
[09/04 15:56:30,Telerik.TestStudio.RemoteExecutor.exe(4820:10),Execution] TestPlatform.AfterRunFinished() : Finished running test platform- attempting publish
[09/04 15:56:35,Telerik.TestStudio.ExecutionManagerService.exe(10252:11)] First trace message from pool unnamed thread (managed ID = 11, native ID = 11952).
[09/04 15:56:35,Telerik.TestStudio.ExecutionManagerService.exe(10252:11),Execution] JobRunner.<CleanupTestRunsStatus>b__12() : TestList finished; updating dispatch group header job Id = 434b1023-5c2b-424f-8d53-b12632a09b3a
[09/04 15:56:35,Telerik.TestStudio.ExecutionManagerService.exe(10252:11),Execution] JobRunner.<CleanupTestRunsStatus>b__12() : TestList finished; sending notifications
[09/04 15:56:35,Telerik.TestStudio.ExecutionManagerService.exe(10252:11),Execution] PostListExecutionEmailer.OnAfterTestListCompleted() : Attempting to send email for job 434b1023-5c2b-424f-8d53-b12632a09b3a
[09/04 15:56:36,Telerik.TestStudio.ExecutionManagerService.exe(10252:19),Execution] ResultMailSender.client_SendCompleted() : Smtp sending email failed: The SMTP server requires a secure connection or the client was not authenticated. The server response was: 5.5.1 Authentication Required. Learn more at
[09/04 15:57:06,Telerik.TestStudio.ExecutionManagerService.exe(10252:11),Execution] JobRunner.<CleanupTestRunsStatus>b__12() : TestList execution status expired- removing from list job id=d03128aa-0fd5-4dec-833d-79c04c65f9ab
