[09/28 09:02:43,Telerik.TestStudio.Desktop.exe(7716:1),Uncategorized] Orchestrator._iUIProject_TestSelected() : Test 'Pivit_Reg_MAT_IPAdmit' selected.
[09/28 09:02:43,Telerik.TestStudio.Desktop.exe(7716:1),Uncategorized] Orchestrator._iUIProject_TestSelected() : Test 'Pivit_Reg_MAT_IPAdmit' selected.
[09/28 09:02:44,ArtOfTest.Runner.exe(9616:3)] First trace message from process 9616: "C:\Program Files (x86)\Progress\Test Studio\Bin\ArtOfTest.Runner.exe"  /controller /parentprocesshandle=5148
[09/28 09:02:44,ArtOfTest.Runner.exe(9616:3)] First trace message from foreground thread "Remote.ArtOfTest.Runner.Listener" (managed ID = 3, native ID = 6080).
[09/28 09:02:44,ArtOfTest.Runner.exe(9616:3),TestStudio] Loader.LoadTranslatorGroups() : ------- Function Start -------
[09/28 09:02:44,ArtOfTest.Runner.exe(9616:3),TestStudio] Loader.LoadTranslatorGroups() : ------- Function End -------
[09/28 09:02:45,ArtOfTest.Runner.exe(9616:4)] First trace message from pool unnamed thread (managed ID = 4, native ID = 6640).
[09/28 09:02:45,ArtOfTest.Runner.exe(9616:4),Framework] InternetExplorerActions.LaunchNewBrowserInstance() : Process launched (ID=7272, Path="C:\Program Files\internet explorer\iexplore.exe", Arguments="-nomerge about:blank").
[09/28 09:02:45,ArtOfTest.Runner.exe(9616:4),Framework] InternetExplorerActions.LaunchNewBrowserInstance() : Attempting to attach on IE frame (HWND=199236)...
[09/28 09:02:46,ArtOfTest.Runner.exe(9616:4),Framework] Connector.Start() : Process launched (ID=4416, Path="C:\Windows\SysWOW64\rundll32.exe", Arguments=""C:\Program Files (x86)\Progress\Test Studio\Bin\ArtOfTest.Connector.dll",AttachToWindowEntryPoint 133834").
[09/28 09:02:46,ArtOfTest.Runner.exe(9616:4),Framework] Connector.Start() : Process exited (ID=4416, ExitCode=0, Path="C:\Windows\SysWOW64\rundll32.exe", Arguments=""C:\Program Files (x86)\Progress\Test Studio\Bin\ArtOfTest.Connector.dll",AttachToWindowEntryPoint 133834").
[09/28 09:02:46,ArtOfTest.Runner.exe(9616:4),Framework] InternetExplorerActions.LaunchNewBrowserInstance() : Process "Telerik.TestStudio.Desktop" (ID=7716) is assumed to host the controller.
[09/28 09:02:46,ArtOfTest.Runner.exe(9616:4),Framework] Connector.InjectCode() : ------- Function Start -------
[09/28 09:02:46,ArtOfTest.Runner.exe(9616:4),Framework] Connector.InjectCode() : Connection string: C:\WINDOWS\Microsoft.Net\assembly\GAC_MSIL\ArtOfTest.InternetExplorer\v4.0_2018.2.606.0__5339893a7cefe4d6\ArtOfTest.InternetExplorer.dll?ArtOfTest.InternetExplorer.ArtOfTestPluginEntryPoint?7716
[09/28 09:02:46,ArtOfTest.Runner.exe(9616:4),Framework] InternetExplorerActions.WaitForDocument() : Attempting to get IHTMLDocument2 from Internet Explorer HWND 133834 on another thread...
[09/28 09:02:46,ArtOfTest.Runner.exe(9616:9)] First trace message from pool thread "WaitAsync polling wait worker" (managed ID = 9, native ID = 7936).
[09/28 09:02:46,ArtOfTest.Runner.exe(9616:9),Framework] <>c__DisplayClass25_3.<WaitForDocument>b__1() : Attempting to get document from window handle
[09/28 09:02:46,ArtOfTest.Runner.exe(9616:4),Framework] InternetExplorerActions.WaitForDocument() : IHTMLDocument2 successfully retrieved.
[09/28 09:02:46,ArtOfTest.Runner.exe(9616:4),Framework] Connector.InjectCode() : Sending WM_COPYDATA to HWND 133834 (lpData = "C:\WINDOWS\Microsoft.Net\assembly\GAC_MSIL\ArtOfTest.InternetExplorer\v4.0_2018.2.606.0__5339893a7cefe4d6\ArtOfTest.InternetExplorer.dll?ArtOfTest.InternetExplorer.ArtOfTestPluginEntryPoint?7716")...
[09/28 09:02:47,IEXPLORE.EXE(1044:1)] First trace message from process 1044: "C:\Program Files (x86)\Internet Explorer\IEXPLORE.EXE" SCODEF:7272 CREDAT:17410 /prefetch:2
[09/28 09:02:47,IEXPLORE.EXE(1044:1)] First trace message from background unnamed thread (managed ID = 1, native ID = 9532).
[09/28 09:02:47,IEXPLORE.EXE(1044:1),Framework] ArtOfTestPluginEntryPoint.Connect() : The .NET Framework is now active inside the hooked process (HWND = 133834, PipeName = "Pipe.ArtOfTest.WebAii.BrowserProvisioner_-997853167", PID = 7716).
[09/28 09:02:47,IEXPLORE.EXE(1044:1),Framework] InternetExplorerActions.WaitForDocument() : Attempting to get IHTMLDocument2 from Internet Explorer HWND 133834 on same thread...
[09/28 09:02:47,IEXPLORE.EXE(1044:1),Framework] InternetExplorerActions.WaitForDocument() : IHTMLDocument2 successfully retrieved.
[09/28 09:02:47,IEXPLORE.EXE(1044:1),Framework] BrowserRemoteClient constructor : The client ID for this hooked process will be "Client_1ecb1b14-2119-4221-9559-09bbf49b89b0".
[09/28 09:02:47,ArtOfTest.Runner.exe(9616:6)] First trace message from background thread "Pipe.ArtOfTest.WebAii.BrowserProvisioner" (managed ID = 6, native ID = 7864).
[09/28 09:02:47,ArtOfTest.Runner.exe(9616:6),Framework] BrowserProvisioner.ServiceThread() : A new client has connected to the provisioning pipe.
[09/28 09:02:47,IEXPLORE.EXE(1044:1),Framework] BrowserRemoteClient.CreateNamedPipes() : Named pipes created for communication with the server: {Command = "2ed4bb63-1c8a-4c51-9044-bc6061b994ee.CommandPipe", Async = "2ed4bb63-1c8a-4c51-9044-bc6061b994ee.AsyncPipe"}
[09/28 09:02:47,ArtOfTest.Runner.exe(9616:13)] First trace message from background thread "Remoted async command listener" (managed ID = 13, native ID = 3412).
[09/28 09:02:47,ArtOfTest.Runner.exe(9616:13),Framework] BrowserRemoted.AsyncListenerThreadEntry() : Not connected; this will now wait indefinitely for a pipe client connection...
[09/28 09:02:47,ArtOfTest.Runner.exe(9616:13),Framework] BrowserRemoted.AsyncListenerThreadEntry() : A new client has connected to the pipe.
[09/28 09:02:47,ArtOfTest.Runner.exe(9616:13),Framework] BrowserRemoted.AsyncListenerThreadEntry() : Now waiting indefinitely for a command from the pipe client...
[09/28 09:02:47,ArtOfTest.Runner.exe(9616:6),Framework] Manager.AddNewBrowser() : Added client ID "Client_1ecb1b14-2119-4221-9559-09bbf49b89b0" (now there is/are 1 total).
[09/28 09:02:47,ArtOfTest.Runner.exe(9616:4),Framework] Connector.InjectCode() : Returned from sending WM_COPYDATA (LRESULT=0, GetLastError()=ERROR_ALREADY_EXISTS).
[09/28 09:02:47,ArtOfTest.Runner.exe(9616:4),Framework] Connector.InjectCode() : ------- Function End -------
[09/28 09:02:47,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.InternalExecuteTest() : Executing test: 'Pivit_Reg_MAT_IPAdmit', path: 'Pivit incl. claims\MATTests\Pivit_Reg_MAT_IPAdmit.tstest.'
[09/28 09:02:47,ArtOfTest.Runner.exe(9616:4),Execution] ExtensionsManager.LoadExtensions() : ------- Function Start -------
[09/28 09:02:47,ArtOfTest.Runner.exe(9616:4),Uncategorized] ExtensionsManager.LoadExtensions() : Got 14 plugin(s) in folder "C:\Program Files (x86)\Progress\Test Studio\Bin\Plugins".
[09/28 09:02:48,ArtOfTest.Runner.exe(9616:4),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "LibGit2Sharp.dll" from plugins folder.
[09/28 09:02:48,ArtOfTest.Runner.exe(9616:4),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.Functional.dll" from plugins folder.
[09/28 09:02:48,ArtOfTest.Runner.exe(9616:4),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.Jira.BugTracking.dll" from plugins folder.
[09/28 09:02:48,ArtOfTest.Runner.exe(9616:4),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.Load.dll" from plugins folder.
[09/28 09:02:48,ArtOfTest.Runner.exe(9616:4),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.Manual.dll" from plugins folder.
[09/28 09:02:48,ArtOfTest.Runner.exe(9616:4),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.OTA.Interop.dll" from plugins folder.
[09/28 09:02:48,ArtOfTest.Runner.exe(9616:4),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.Plugins.dll" from plugins folder.
[09/28 09:02:48,ArtOfTest.Runner.exe(9616:4),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.Shared.dll" from plugins folder.
[09/28 09:02:48,ArtOfTest.Runner.exe(9616:4),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.SourceControl.dll" from plugins folder.
[09/28 09:02:48,ArtOfTest.Runner.exe(9616:4),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.SourceControl.Git.dll" from plugins folder.
[09/28 09:02:48,ArtOfTest.Runner.exe(9616:4),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.SourceControl.Git.TS.dll" from plugins folder.
[09/28 09:02:48,ArtOfTest.Runner.exe(9616:4),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.TeamPulse.dll" from plugins folder.
[09/28 09:02:48,ArtOfTest.Runner.exe(9616:4),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.Web.dll" from plugins folder.
[09/28 09:02:48,ArtOfTest.Runner.exe(9616:4),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.Wpf.dll" from plugins folder.
[09/28 09:02:48,ArtOfTest.Runner.exe(9616:4),Execution] ExtensionsManager.LoadExtensions() : ------- Function End -------
[09/28 09:02:48,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.SetCurrentResult() : VisualDebugger::SetCurrentResult
[09/28 09:02:48,ArtOfTest.Runner.exe(9616:4),DialogPlayback] DialogMonitor.Start() : Beginning monitoring for dialogs from the set {}.
[09/28 09:02:48,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[09/28 09:02:48,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:02:48,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:02:48,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 1 "Execute test 'OpenIPWizard'"...
[09/28 09:02:48,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.InternalExecuteTest() : Executing test: 'OpenIPWizard', path: 'Pivit incl. claims\Steps\OpenIPWizard.tstest.'
[09/28 09:02:48,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.SetCurrentResult() : VisualDebugger::SetCurrentResult
[09/28 09:02:48,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[09/28 09:02:48,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:02:48,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:02:48,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 1 "Execute test 'SearchByQHID'"...
[09/28 09:02:48,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.InternalExecuteTest() : Executing test: 'SearchByQHID', path: 'Pivit incl. claims\Steps\SearchByQHID.tstest.'
[09/28 09:02:48,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.SetCurrentResult() : VisualDebugger::SetCurrentResult
[09/28 09:02:48,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[09/28 09:02:48,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:02:48,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:02:48,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 1 "Execute test 'NavigateToPivit.tstest'"...
[09/28 09:02:48,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.InternalExecuteTest() : Executing test: 'NavigateToPivit', path: 'Pivit incl. claims\Steps\NavigateToPivit.tstest.'
[09/28 09:02:48,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.SetCurrentResult() : VisualDebugger::SetCurrentResult
[09/28 09:02:48,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[09/28 09:02:48,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:02:48,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:02:48,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 1 "Navigate to : '/nextgen/' - DataDriven: [$(PivitBaseURL)]"...
[09/28 09:02:49,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 1 "Navigate to : '/nextgen/' - DataDriven: [$(PivitBaseURL)]".
[09/28 09:02:49,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:02:49,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:02:49,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 1 "Execute test 'NavigateToPivit.tstest'".
[09/28 09:02:49,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:02:49,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:02:49,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[09/28 09:02:49,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:02:49,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:02:49,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 2 "Enter text '' in 'QHID'"...
[09/28 09:02:49,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 2 "Enter text '' in 'QHID'".
[09/28 09:02:49,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:02:49,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:02:49,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[09/28 09:02:49,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:02:49,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:02:49,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 3 "Click 'SearchButton'"...
[09/28 09:02:49,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 3 "Click 'SearchButton'".
[09/28 09:02:49,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:02:50,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:02:50,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[09/28 09:02:50,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:02:50,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:02:50,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 4 "Wait '30000' msec for url:'' - DataDriven: [$(SearchByQHID)]"...
[09/28 09:02:56,ArtOfTest.Runner.exe(9616:8)] First trace message from background thread "UAI Dispatcher Thread" (managed ID = 8, native ID = 11852).
[09/28 09:02:56,ArtOfTest.Runner.exe(9616:8),DialogPlayback] DialogMonitor.OnWindowCreated() : New dialog detected (ClassName = "#32770", Handle = "1444560").
[09/28 09:02:56,ArtOfTest.Runner.exe(9616:8),Uncategorized] Log.WriteLineInternal() : Unexpected dialog encountered. Taking no action.
[09/28 09:02:56,ArtOfTest.Runner.exe(9616:8),DialogPlayback] DialogMonitor.HandleUnexpectedDialog() : Unexpected dialog encountered. Taking no action.
[09/28 09:02:57,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 4 "Wait '30000' msec for url:'' - DataDriven: [$(SearchByQHID)]".
[09/28 09:02:57,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:02:57,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:02:57,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 1 "Execute test 'SearchByQHID'".
[09/28 09:02:57,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:02:57,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:02:57,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[09/28 09:02:57,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:02:57,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:02:58,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 2 "Click 'IP_OBS_ERAdmitLink'"...
[09/28 09:02:58,IEXPLORE.EXE(1044:1),Framework] InternetExplorerActions.BeginAsyncInjection() : Spawned Internet Explorer injection thread (HWND=199176, AssemblyAndClass="C:\WINDOWS\Microsoft.Net\assembly\GAC_MSIL\ArtOfTest.InternetExplorer\v4.0_2018.2.606.0__5339893a7cefe4d6\ArtOfTest.InternetExplorer.dll?ArtOfTest.InternetExplorer.ArtOfTestPluginEntryPoint", PipeName="Pipe.ArtOfTest.WebAii.BrowserProvisioner_-997853167", ProcessId="7716").
[09/28 09:02:59,IEXPLORE.EXE(1044:7)] First trace message from pool thread "Internet Explorer injection thread" (managed ID = 7, native ID = 1748).
[09/28 09:02:59,IEXPLORE.EXE(1044:7),Framework] Connector.Start() : Process launched (ID=2212, Path="C:\Windows\SysWOW64\rundll32.exe", Arguments=""C:\Program Files (x86)\Progress\Test Studio\Bin\ArtOfTest.Connector.dll",AttachToWindowEntryPoint 133548").
[09/28 09:02:59,IEXPLORE.EXE(1044:7),Framework] Connector.Start() : Process exited (ID=2212, ExitCode=0, Path="C:\Windows\SysWOW64\rundll32.exe", Arguments=""C:\Program Files (x86)\Progress\Test Studio\Bin\ArtOfTest.Connector.dll",AttachToWindowEntryPoint 133548").
[09/28 09:02:59,IEXPLORE.EXE(1044:7),Framework] Connector.InjectCode() : ------- Function Start -------
[09/28 09:02:59,IEXPLORE.EXE(1044:7),Framework] Connector.InjectCode() : Connection string: C:\WINDOWS\Microsoft.Net\assembly\GAC_MSIL\ArtOfTest.InternetExplorer\v4.0_2018.2.606.0__5339893a7cefe4d6\ArtOfTest.InternetExplorer.dll?ArtOfTest.InternetExplorer.ArtOfTestPluginEntryPoint?7716
[09/28 09:02:59,IEXPLORE.EXE(1044:7),Framework] InternetExplorerActions.WaitForDocument() : Attempting to get IHTMLDocument2 from Internet Explorer HWND 133548 on another thread...
[09/28 09:02:59,IEXPLORE.EXE(1044:9)] First trace message from pool thread "WaitAsync polling wait worker" (managed ID = 9, native ID = 13112).
[09/28 09:02:59,IEXPLORE.EXE(1044:9),Framework] <>c__DisplayClass25_3.<WaitForDocument>b__1() : Attempting to get document from window handle
[09/28 09:02:59,IEXPLORE.EXE(1044:7),Framework] InternetExplorerActions.WaitForDocument() : IHTMLDocument2 successfully retrieved.
[09/28 09:02:59,IEXPLORE.EXE(1044:7),Framework] Connector.InjectCode() : Sending WM_COPYDATA to HWND 133548 (lpData = "C:\WINDOWS\Microsoft.Net\assembly\GAC_MSIL\ArtOfTest.InternetExplorer\v4.0_2018.2.606.0__5339893a7cefe4d6\ArtOfTest.InternetExplorer.dll?ArtOfTest.InternetExplorer.ArtOfTestPluginEntryPoint?7716")...
[09/28 09:02:59,IEXPLORE.EXE(1044:14)] First trace message from background unnamed thread (managed ID = 14, native ID = 7916).
[09/28 09:02:59,IEXPLORE.EXE(1044:14),Framework] ArtOfTestPluginEntryPoint.Connect() : The .NET Framework is now active inside the hooked process (HWND = 133548, PipeName = "Pipe.ArtOfTest.WebAii.BrowserProvisioner_-997853167", PID = 7716).
[09/28 09:02:59,IEXPLORE.EXE(1044:14),Framework] InternetExplorerActions.WaitForDocument() : Attempting to get IHTMLDocument2 from Internet Explorer HWND 133548 on same thread...
[09/28 09:02:59,IEXPLORE.EXE(1044:14),Framework] InternetExplorerActions.WaitForDocument() : IHTMLDocument2 successfully retrieved.
[09/28 09:02:59,IEXPLORE.EXE(1044:14),Framework] BrowserRemoteClient constructor : The client ID for this hooked process will be "Client_7e060604-61ca-461c-a0b6-c1c0bc24f198".
[09/28 09:02:59,ArtOfTest.Runner.exe(9616:6),Framework] BrowserProvisioner.ServiceThread() : A new client has connected to the provisioning pipe.
[09/28 09:02:59,ArtOfTest.Runner.exe(9616:19)] First trace message from background thread "Remoted async command listener" (managed ID = 19, native ID = 8188).
[09/28 09:02:59,ArtOfTest.Runner.exe(9616:19),Framework] BrowserRemoted.AsyncListenerThreadEntry() : Not connected; this will now wait indefinitely for a pipe client connection...
[09/28 09:02:59,IEXPLORE.EXE(1044:14),Framework] BrowserRemoteClient.CreateNamedPipes() : Named pipes created for communication with the server: {Command = "4ba4ea8e-8d41-48e5-93d2-1eddc03dd028.CommandPipe", Async = "4ba4ea8e-8d41-48e5-93d2-1eddc03dd028.AsyncPipe"}
[09/28 09:02:59,ArtOfTest.Runner.exe(9616:19),Framework] BrowserRemoted.AsyncListenerThreadEntry() : A new client has connected to the pipe.
[09/28 09:02:59,IEXPLORE.EXE(1044:7),Framework] Connector.InjectCode() : Returned from sending WM_COPYDATA (LRESULT=0, GetLastError()=DS_S_SUCCESS).
[09/28 09:02:59,ArtOfTest.Runner.exe(9616:19),Framework] BrowserRemoted.AsyncListenerThreadEntry() : Now waiting indefinitely for a command from the pipe client...
[09/28 09:02:59,IEXPLORE.EXE(1044:7),Framework] Connector.InjectCode() : ------- Function End -------
[09/28 09:02:59,ArtOfTest.Runner.exe(9616:6),Framework] Manager.AddNewBrowser() : Added client ID "Client_7e060604-61ca-461c-a0b6-c1c0bc24f198" (now there is/are 2 total).
[09/28 09:03:02,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 2 "Click 'IP_OBS_ERAdmitLink'".
[09/28 09:03:02,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:03:02,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:03:02,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[09/28 09:03:02,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:03:02,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:03:02,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 3 "Connect to pop-up window : 'inpatient.aspx'"...
[09/28 09:03:02,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 3 "Connect to pop-up window : 'inpatient.aspx'".
[09/28 09:03:02,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:03:02,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:03:02,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 1 "Execute test 'OpenIPWizard'".
[09/28 09:03:02,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:03:02,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:03:02,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[09/28 09:03:02,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:03:02,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:03:02,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 2 "Click 'AdmittingPhysicianLink'"...
[09/28 09:03:03,IEXPLORE.EXE(1044:14),Framework] InternetExplorerActions.BeginAsyncInjection() : Spawned Internet Explorer injection thread (HWND=199232, AssemblyAndClass="C:\WINDOWS\Microsoft.Net\assembly\GAC_MSIL\ArtOfTest.InternetExplorer\v4.0_2018.2.606.0__5339893a7cefe4d6\ArtOfTest.InternetExplorer.dll?ArtOfTest.InternetExplorer.ArtOfTestPluginEntryPoint", PipeName="Pipe.ArtOfTest.WebAii.BrowserProvisioner_-997853167", ProcessId="7716").
[09/28 09:03:03,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 2 "Click 'AdmittingPhysicianLink'".
[09/28 09:03:03,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:03:03,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:03:03,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[09/28 09:03:03,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:03:03,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:03:03,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 3 "Connect to pop-up window : '' - DataDriven: [$(InternalProvidersBaseURL)]"...
[09/28 09:03:04,IEXPLORE.EXE(1044:8)] First trace message from pool thread "Internet Explorer injection thread" (managed ID = 8, native ID = 7852).
[09/28 09:03:04,IEXPLORE.EXE(1044:8),Framework] Connector.Start() : Process launched (ID=15356, Path="C:\Windows\SysWOW64\rundll32.exe", Arguments=""C:\Program Files (x86)\Progress\Test Studio\Bin\ArtOfTest.Connector.dll",AttachToWindowEntryPoint 723584").
[09/28 09:03:04,IEXPLORE.EXE(1044:8),Framework] Connector.Start() : Process exited (ID=15356, ExitCode=0, Path="C:\Windows\SysWOW64\rundll32.exe", Arguments=""C:\Program Files (x86)\Progress\Test Studio\Bin\ArtOfTest.Connector.dll",AttachToWindowEntryPoint 723584").
[09/28 09:03:04,IEXPLORE.EXE(1044:8),Framework] Connector.InjectCode() : ------- Function Start -------
[09/28 09:03:04,IEXPLORE.EXE(1044:8),Framework] Connector.InjectCode() : Connection string: C:\WINDOWS\Microsoft.Net\assembly\GAC_MSIL\ArtOfTest.InternetExplorer\v4.0_2018.2.606.0__5339893a7cefe4d6\ArtOfTest.InternetExplorer.dll?ArtOfTest.InternetExplorer.ArtOfTestPluginEntryPoint?7716
[09/28 09:03:04,IEXPLORE.EXE(1044:8),Framework] InternetExplorerActions.WaitForDocument() : Attempting to get IHTMLDocument2 from Internet Explorer HWND 723584 on another thread...
[09/28 09:03:04,IEXPLORE.EXE(1044:9)] First trace message from pool thread "WaitAsync polling wait worker" (managed ID = 9, native ID = 13112).
[09/28 09:03:04,IEXPLORE.EXE(1044:9),Framework] <>c__DisplayClass25_3.<WaitForDocument>b__1() : Attempting to get document from window handle
[09/28 09:03:05,IEXPLORE.EXE(1044:8),Framework] InternetExplorerActions.WaitForDocument() : IHTMLDocument2 successfully retrieved.
[09/28 09:03:05,IEXPLORE.EXE(1044:8),Framework] Connector.InjectCode() : Sending WM_COPYDATA to HWND 723584 (lpData = "C:\WINDOWS\Microsoft.Net\assembly\GAC_MSIL\ArtOfTest.InternetExplorer\v4.0_2018.2.606.0__5339893a7cefe4d6\ArtOfTest.InternetExplorer.dll?ArtOfTest.InternetExplorer.ArtOfTestPluginEntryPoint?7716")...
[09/28 09:03:05,IEXPLORE.EXE(10748:1)] First trace message from process 10748: "C:\Program Files (x86)\Internet Explorer\IEXPLORE.EXE" SCODEF:7272 CREDAT:17428 /prefetch:2
[09/28 09:03:05,IEXPLORE.EXE(10748:1)] First trace message from background unnamed thread (managed ID = 1, native ID = 14912).
[09/28 09:03:05,IEXPLORE.EXE(10748:1),Framework] ArtOfTestPluginEntryPoint.Connect() : The .NET Framework is now active inside the hooked process (HWND = 723584, PipeName = "Pipe.ArtOfTest.WebAii.BrowserProvisioner_-997853167", PID = 7716).
[09/28 09:03:05,IEXPLORE.EXE(10748:1),Framework] InternetExplorerActions.WaitForDocument() : Attempting to get IHTMLDocument2 from Internet Explorer HWND 723584 on same thread...
[09/28 09:03:05,IEXPLORE.EXE(10748:1),Framework] InternetExplorerActions.WaitForDocument() : IHTMLDocument2 successfully retrieved.
[09/28 09:03:05,IEXPLORE.EXE(10748:1),Framework] BrowserRemoteClient constructor : The client ID for this hooked process will be "Client_5111ebb3-27bb-48f3-83aa-f83330b52e5b".
[09/28 09:03:05,ArtOfTest.Runner.exe(9616:6),Framework] BrowserProvisioner.ServiceThread() : A new client has connected to the provisioning pipe.
[09/28 09:03:05,ArtOfTest.Runner.exe(9616:12)] First trace message from background thread "Remoted async command listener" (managed ID = 12, native ID = 15168).
[09/28 09:03:05,ArtOfTest.Runner.exe(9616:12),Framework] BrowserRemoted.AsyncListenerThreadEntry() : Not connected; this will now wait indefinitely for a pipe client connection...
[09/28 09:03:05,IEXPLORE.EXE(10748:1),Framework] BrowserRemoteClient.CreateNamedPipes() : Named pipes created for communication with the server: {Command = "0a0408bd-b59d-4f2f-a247-13fe5a7d3628.CommandPipe", Async = "0a0408bd-b59d-4f2f-a247-13fe5a7d3628.AsyncPipe"}
[09/28 09:03:05,ArtOfTest.Runner.exe(9616:12),Framework] BrowserRemoted.AsyncListenerThreadEntry() : A new client has connected to the pipe.
[09/28 09:03:05,ArtOfTest.Runner.exe(9616:12),Framework] BrowserRemoted.AsyncListenerThreadEntry() : Now waiting indefinitely for a command from the pipe client...
[09/28 09:03:05,IEXPLORE.EXE(1044:8),Framework] Connector.InjectCode() : Returned from sending WM_COPYDATA (LRESULT=0, GetLastError()=DS_S_SUCCESS).
[09/28 09:03:05,IEXPLORE.EXE(1044:8),Framework] Connector.InjectCode() : ------- Function End -------
[09/28 09:03:05,ArtOfTest.Runner.exe(9616:6),Framework] Manager.AddNewBrowser() : Added client ID "Client_5111ebb3-27bb-48f3-83aa-f83330b52e5b" (now there is/are 3 total).
[09/28 09:03:06,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 3 "Connect to pop-up window : '' - DataDriven: [$(InternalProvidersBaseURL)]".
[09/28 09:03:06,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:03:06,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:03:06,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[09/28 09:03:06,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:03:06,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:03:06,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 4 "Enter text '' in 'PhysicianFirstNameInput' - DataDriven: [$(FirstName)]"...
[09/28 09:03:08,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 4 "Enter text '' in 'PhysicianFirstNameInput' - DataDriven: [$(FirstName)]".
[09/28 09:03:08,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:03:08,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:03:08,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[09/28 09:03:08,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:03:08,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:03:08,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 5 "Enter text '' in 'PhysicianLastNameInput' - DataDriven: [$(LastName)]"...
[09/28 09:03:10,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 5 "Enter text '' in 'PhysicianLastNameInput' - DataDriven: [$(LastName)]".
[09/28 09:03:10,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:03:10,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:03:10,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[09/28 09:03:10,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:03:10,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:03:10,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 6 "Enter text '' in 'ZipCodeInput' - DataDriven: [$(PhysicianZip)]"...
[09/28 09:03:11,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 6 "Enter text '' in 'ZipCodeInput' - DataDriven: [$(PhysicianZip)]".
[09/28 09:03:11,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:03:11,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:03:11,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[09/28 09:03:11,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:03:11,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:03:11,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 7 "Click 'SearchBtn'"...
[09/28 09:03:11,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 7 "Click 'SearchBtn'".
[09/28 09:03:11,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:03:11,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:03:11,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[09/28 09:03:11,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:03:11,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:03:11,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 8 "Wait for Exists 'FirstSearchResultArrow'"...
[09/28 09:03:17,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 8 "Wait for Exists 'FirstSearchResultArrow'".
[09/28 09:03:17,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:03:17,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:03:17,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[09/28 09:03:17,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:03:17,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:03:17,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 9 "Click 'FirstSearchResultArrow'"...
[09/28 09:03:17,ArtOfTest.Runner.exe(9616:4),Framework] Manager.SetActiveBrowser() : Active browser is now "Client_7e060604-61ca-461c-a0b6-c1c0bc24f198".
[09/28 09:03:17,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 9 "Click 'FirstSearchResultArrow'".
[09/28 09:03:17,IEXPLORE.EXE(10748:1),Framework] BrowserRemoteClient.Disconnect() : Sending ClientDisconnectRequest(ClientId="Client_5111ebb3-27bb-48f3-83aa-f83330b52e5b") to pipe server...
[09/28 09:03:17,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:03:17,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:03:17,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[09/28 09:03:17,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:03:17,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:03:17,ArtOfTest.Runner.exe(9616:12),Framework] BrowserRemoted.AsyncListenerThreadEntry() : A ClientDisconnectRequest(ClientId="Client_5111ebb3-27bb-48f3-83aa-f83330b52e5b") has been received from the pipe client.
[09/28 09:03:17,IEXPLORE.EXE(10748:1),Framework] BrowserRemoteClient.Disconnect() : Command pipe is already disconnected.
[09/28 09:03:17,ArtOfTest.Runner.exe(9616:12),Framework] Manager.RemoveBrowser() : Removed client ID "Client_5111ebb3-27bb-48f3-83aa-f83330b52e5b" (2 remaining).
[09/28 09:03:17,ArtOfTest.Runner.exe(9616:12),Framework] BrowserRemoted.End() : Now aborting the listener thread (TID = 12).
[09/28 09:03:18,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 10 "Wait for 'value' attribute 'Contains' value '' on 'AdmittingPhysicianLabel' - DataDriven: [$(LastName)]"...
[09/28 09:03:20,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 10 "Wait for 'value' attribute 'Contains' value '' on 'AdmittingPhysicianLabel' - DataDriven: [$(LastName)]".
[09/28 09:03:20,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:03:20,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:03:20,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[09/28 09:03:20,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:03:20,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:03:20,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 11 "Click 'FacilityLink'"...
[09/28 09:03:20,IEXPLORE.EXE(1044:14),Framework] InternetExplorerActions.BeginAsyncInjection() : Spawned Internet Explorer injection thread (HWND=1051568, AssemblyAndClass="C:\WINDOWS\Microsoft.Net\assembly\GAC_MSIL\ArtOfTest.InternetExplorer\v4.0_2018.2.606.0__5339893a7cefe4d6\ArtOfTest.InternetExplorer.dll?ArtOfTest.InternetExplorer.ArtOfTestPluginEntryPoint", PipeName="Pipe.ArtOfTest.WebAii.BrowserProvisioner_-997853167", ProcessId="7716").
[09/28 09:03:20,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 11 "Click 'FacilityLink'".
[09/28 09:03:20,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:03:20,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:03:20,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[09/28 09:03:20,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:03:20,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:03:20,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 12 "Connect to pop-up window : '' - DataDriven: [$(InternalProvidersBaseURL)]"...
[09/28 09:03:22,IEXPLORE.EXE(1044:9)] First trace message from pool thread "Internet Explorer injection thread" (managed ID = 9, native ID = 13112).
[09/28 09:03:22,IEXPLORE.EXE(1044:9),Framework] Connector.Start() : Process launched (ID=1400, Path="C:\Windows\SysWOW64\rundll32.exe", Arguments=""C:\Program Files (x86)\Progress\Test Studio\Bin\ArtOfTest.Connector.dll",AttachToWindowEntryPoint 854792").
[09/28 09:03:22,IEXPLORE.EXE(1044:9),Framework] Connector.Start() : Process exited (ID=1400, ExitCode=0, Path="C:\Windows\SysWOW64\rundll32.exe", Arguments=""C:\Program Files (x86)\Progress\Test Studio\Bin\ArtOfTest.Connector.dll",AttachToWindowEntryPoint 854792").
[09/28 09:03:22,IEXPLORE.EXE(1044:9),Framework] Connector.InjectCode() : ------- Function Start -------
[09/28 09:03:22,IEXPLORE.EXE(1044:9),Framework] Connector.InjectCode() : Connection string: C:\WINDOWS\Microsoft.Net\assembly\GAC_MSIL\ArtOfTest.InternetExplorer\v4.0_2018.2.606.0__5339893a7cefe4d6\ArtOfTest.InternetExplorer.dll?ArtOfTest.InternetExplorer.ArtOfTestPluginEntryPoint?7716
[09/28 09:03:22,IEXPLORE.EXE(1044:9),Framework] InternetExplorerActions.WaitForDocument() : Attempting to get IHTMLDocument2 from Internet Explorer HWND 854792 on another thread...
[09/28 09:03:22,IEXPLORE.EXE(1044:7)] First trace message from pool thread "WaitAsync polling wait worker" (managed ID = 7, native ID = 1748).
[09/28 09:03:22,IEXPLORE.EXE(1044:7),Framework] <>c__DisplayClass25_3.<WaitForDocument>b__1() : Attempting to get document from window handle
[09/28 09:03:22,IEXPLORE.EXE(1044:9),Framework] InternetExplorerActions.WaitForDocument() : IHTMLDocument2 successfully retrieved.
[09/28 09:03:22,IEXPLORE.EXE(1044:9),Framework] Connector.InjectCode() : Sending WM_COPYDATA to HWND 854792 (lpData = "C:\WINDOWS\Microsoft.Net\assembly\GAC_MSIL\ArtOfTest.InternetExplorer\v4.0_2018.2.606.0__5339893a7cefe4d6\ArtOfTest.InternetExplorer.dll?ArtOfTest.InternetExplorer.ArtOfTestPluginEntryPoint?7716")...
[09/28 09:03:22,IEXPLORE.EXE(11440:1)] First trace message from process 11440: "C:\Program Files (x86)\Internet Explorer\IEXPLORE.EXE" SCODEF:7272 CREDAT:17438 /prefetch:2
[09/28 09:03:22,IEXPLORE.EXE(11440:1)] First trace message from background unnamed thread (managed ID = 1, native ID = 13816).
[09/28 09:03:22,IEXPLORE.EXE(11440:1),Framework] ArtOfTestPluginEntryPoint.Connect() : The .NET Framework is now active inside the hooked process (HWND = 854792, PipeName = "Pipe.ArtOfTest.WebAii.BrowserProvisioner_-997853167", PID = 7716).
[09/28 09:03:22,IEXPLORE.EXE(11440:1),Framework] InternetExplorerActions.WaitForDocument() : Attempting to get IHTMLDocument2 from Internet Explorer HWND 854792 on same thread...
[09/28 09:03:23,IEXPLORE.EXE(11440:1),Framework] InternetExplorerActions.WaitForDocument() : IHTMLDocument2 successfully retrieved.
[09/28 09:03:23,IEXPLORE.EXE(11440:1),Framework] BrowserRemoteClient constructor : The client ID for this hooked process will be "Client_75a17248-719a-4672-a57e-27666360fb22".
[09/28 09:03:23,ArtOfTest.Runner.exe(9616:6),Framework] BrowserProvisioner.ServiceThread() : A new client has connected to the provisioning pipe.
[09/28 09:03:23,ArtOfTest.Runner.exe(9616:12)] First trace message from background thread "Remoted async command listener" (managed ID = 12, native ID = 6872).
[09/28 09:03:23,ArtOfTest.Runner.exe(9616:12),Framework] BrowserRemoted.AsyncListenerThreadEntry() : Not connected; this will now wait indefinitely for a pipe client connection...
[09/28 09:03:23,IEXPLORE.EXE(11440:1),Framework] BrowserRemoteClient.CreateNamedPipes() : Named pipes created for communication with the server: {Command = "919ee271-84f6-4d6c-802d-0ce0230db203.CommandPipe", Async = "919ee271-84f6-4d6c-802d-0ce0230db203.AsyncPipe"}
[09/28 09:03:23,ArtOfTest.Runner.exe(9616:12),Framework] BrowserRemoted.AsyncListenerThreadEntry() : A new client has connected to the pipe.
[09/28 09:03:23,ArtOfTest.Runner.exe(9616:12),Framework] BrowserRemoted.AsyncListenerThreadEntry() : Now waiting indefinitely for a command from the pipe client...
[09/28 09:03:23,IEXPLORE.EXE(1044:9),Framework] Connector.InjectCode() : Returned from sending WM_COPYDATA (LRESULT=0, GetLastError()=DS_S_SUCCESS).
[09/28 09:03:23,IEXPLORE.EXE(1044:9),Framework] Connector.InjectCode() : ------- Function End -------
[09/28 09:03:23,ArtOfTest.Runner.exe(9616:6),Framework] Manager.AddNewBrowser() : Added client ID "Client_75a17248-719a-4672-a57e-27666360fb22" (now there is/are 3 total).
[09/28 09:03:23,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 12 "Connect to pop-up window : '' - DataDriven: [$(InternalProvidersBaseURL)]".
[09/28 09:03:23,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:03:23,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:03:23,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[09/28 09:03:23,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:03:23,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:03:23,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 13 "Click 'PhysicianGroupFacilityRadioBtn'"...
[09/28 09:03:23,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 13 "Click 'PhysicianGroupFacilityRadioBtn'".
[09/28 09:03:23,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:03:23,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:03:23,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[09/28 09:03:23,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:03:23,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:03:23,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 14 "Enter text '' in 'FacilityNameInput' - DataDriven: [$(Facility)]"...
[09/28 09:03:28,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 14 "Enter text '' in 'FacilityNameInput' - DataDriven: [$(Facility)]".
[09/28 09:03:28,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:03:28,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:03:28,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[09/28 09:03:28,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:03:28,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:03:28,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 15 "Enter text '' in 'ZipCodeInput' - DataDriven: [$(FacilityZip)]"...
[09/28 09:03:29,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 15 "Enter text '' in 'ZipCodeInput' - DataDriven: [$(FacilityZip)]".
[09/28 09:03:29,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:03:29,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:03:29,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[09/28 09:03:29,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:03:29,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:03:29,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 16 "Click 'SearchBtn'"...
[09/28 09:03:29,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 16 "Click 'SearchBtn'".
[09/28 09:03:29,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:03:29,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:03:29,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[09/28 09:03:29,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:03:29,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:03:29,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 17 "Wait for Exists 'FirstSearchResultArrow'"...
[09/28 09:03:52,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 17 "Wait for Exists 'FirstSearchResultArrow'".
[09/28 09:03:52,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:03:52,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:03:52,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[09/28 09:03:52,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:03:52,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:03:52,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 18 "Click 'FirstSearchResultArrow'"...
[09/28 09:03:52,ArtOfTest.Runner.exe(9616:4),Framework] Manager.SetActiveBrowser() : Active browser is now "Client_7e060604-61ca-461c-a0b6-c1c0bc24f198".
[09/28 09:03:52,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 18 "Click 'FirstSearchResultArrow'".
[09/28 09:03:52,IEXPLORE.EXE(11440:1),Framework] BrowserRemoteClient.Disconnect() : Sending ClientDisconnectRequest(ClientId="Client_75a17248-719a-4672-a57e-27666360fb22") to pipe server...
[09/28 09:03:52,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:03:52,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:03:52,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[09/28 09:03:52,ArtOfTest.Runner.exe(9616:12),Framework] BrowserRemoted.AsyncListenerThreadEntry() : A ClientDisconnectRequest(ClientId="Client_75a17248-719a-4672-a57e-27666360fb22") has been received from the pipe client.
[09/28 09:03:52,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:03:52,IEXPLORE.EXE(11440:1),Framework] BrowserRemoteClient.Disconnect() : Command pipe is already disconnected.
[09/28 09:03:52,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:03:52,ArtOfTest.Runner.exe(9616:12),Framework] Manager.RemoveBrowser() : Removed client ID "Client_75a17248-719a-4672-a57e-27666360fb22" (2 remaining).
[09/28 09:03:52,ArtOfTest.Runner.exe(9616:12),Framework] BrowserRemoted.End() : Now aborting the listener thread (TID = 12).
[09/28 09:03:52,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 19 "Wait for 'value' attribute 'Contains' value '' on 'FacilityName' - DataDriven: [$(Facility)]"...
[09/28 09:03:54,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 19 "Wait for 'value' attribute 'Contains' value '' on 'FacilityName' - DataDriven: [$(Facility)]".
[09/28 09:03:54,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:03:54,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:03:54,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[09/28 09:03:54,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:03:54,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:03:54,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 20 "Comment: Changing network status refreshes the page to set the selected option, causing actions taken before the refresh to be lost. Waiting for the selected option to appear as selected to avoid race condion between selecting an option finishing and taking the next test step."...
[09/28 09:03:54,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 20 "Comment: Changing network status refreshes the page to set the selected option, causing actions taken before the refresh to be lost. Waiting for the selected option to appear as selected to avoid race condion between selecting an option finishing and taking the next test step.".
[09/28 09:03:54,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:03:54,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:03:54,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[09/28 09:03:54,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:03:54,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:03:55,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 21 "Select 'ByValue' option '' on 'AdmittingPhysicianNetworkStatus' - DataDriven: [$(PhysicianStatus)]"...
[09/28 09:03:55,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 21 "Select 'ByValue' option '' on 'AdmittingPhysicianNetworkStatus' - DataDriven: [$(PhysicianStatus)]".
[09/28 09:03:55,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:03:55,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:03:55,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[09/28 09:03:55,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:03:55,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:03:55,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 22 "Wait for 'InnerText' 'Contains' '' on 'AdmittingPhysicianNetworkStatusSelectedOption' - DataDriven: [$(PhysicianStatus)]"...
[09/28 09:04:25,ArtOfTest.Runner.exe(9616:4),Error] ExecutionEngine.CatchExecuteStepException() : EXCEPTION! (see below)
     Outer Exception Type: ArtOfTest.Common.Design.Exceptions.VerificationWaitException
     Message: Content.InnerText of 'AdmittingPhysicianNetworkStatusSelectedOption' does not match!

   Match Type: 'Contains'
   Expected Result: 'In Network'
   Value at time of failure: 'Out of Network'
     HRESULT: 0x80131500 (Official ID (if app.) = COR_E_EXCEPTION, Error Bit = FAILED, Facility = FACILITY_URT, Code = 5376)
     Call Stack:
          at ArtOfTest.WebAii.Design.Execution.ExecutionEngine.ExecuteStep(Int32 order)

[09/28 09:04:25,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:04:25,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::BreakOnFail
[09/28 09:04:25,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:04:25,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.SetCurrentResult() : VisualDebugger::SetCurrentResult
[09/28 09:04:25,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[09/28 09:04:25,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:04:25,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:04:25,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 1 "Execute test 'OpenIPWizard'"...
[09/28 09:04:25,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.InternalExecuteTest() : Executing test: 'OpenIPWizard', path: 'Pivit incl. claims\Steps\OpenIPWizard.tstest.'
[09/28 09:04:25,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.SetCurrentResult() : VisualDebugger::SetCurrentResult
[09/28 09:04:25,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[09/28 09:04:25,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:04:25,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:04:25,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 1 "Execute test 'SearchByQHID'"...
[09/28 09:04:26,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.InternalExecuteTest() : Executing test: 'SearchByQHID', path: 'Pivit incl. claims\Steps\SearchByQHID.tstest.'
[09/28 09:04:26,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.SetCurrentResult() : VisualDebugger::SetCurrentResult
[09/28 09:04:26,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[09/28 09:04:26,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:04:26,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:04:26,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 1 "Execute test 'NavigateToPivit.tstest'"...
[09/28 09:04:26,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.InternalExecuteTest() : Executing test: 'NavigateToPivit', path: 'Pivit incl. claims\Steps\NavigateToPivit.tstest.'
[09/28 09:04:26,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.SetCurrentResult() : VisualDebugger::SetCurrentResult
[09/28 09:04:26,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[09/28 09:04:26,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:04:26,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:04:26,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 1 "Navigate to : '/nextgen/' - DataDriven: [$(PivitBaseURL)]"...
[09/28 09:04:26,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 1 "Navigate to : '/nextgen/' - DataDriven: [$(PivitBaseURL)]".
[09/28 09:04:26,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:04:26,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:04:26,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 1 "Execute test 'NavigateToPivit.tstest'".
[09/28 09:04:26,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:04:26,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:04:26,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[09/28 09:04:26,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:04:26,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:04:26,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 2 "Enter text '' in 'QHID'"...
[09/28 09:04:26,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 2 "Enter text '' in 'QHID'".
[09/28 09:04:26,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:04:26,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:04:26,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[09/28 09:04:26,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:04:26,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:04:26,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 3 "Click 'SearchButton'"...
[09/28 09:04:26,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 3 "Click 'SearchButton'".
[09/28 09:04:26,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:04:26,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:04:26,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[09/28 09:04:26,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:04:26,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:04:26,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 4 "Wait '30000' msec for url:'' - DataDriven: [$(SearchByQHID)]"...
[09/28 09:04:31,ArtOfTest.Runner.exe(9616:8),DialogPlayback] DialogMonitor.OnWindowCreated() : New dialog detected (ClassName = "#32770", Handle = "3476362").
[09/28 09:04:31,ArtOfTest.Runner.exe(9616:8),Uncategorized] Log.WriteLineInternal() : Unexpected dialog encountered. Taking no action.
[09/28 09:04:31,ArtOfTest.Runner.exe(9616:8),DialogPlayback] DialogMonitor.HandleUnexpectedDialog() : Unexpected dialog encountered. Taking no action.
[09/28 09:04:32,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 4 "Wait '30000' msec for url:'' - DataDriven: [$(SearchByQHID)]".
[09/28 09:04:32,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:04:32,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:04:32,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 1 "Execute test 'SearchByQHID'".
[09/28 09:04:32,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:04:32,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:04:32,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[09/28 09:04:32,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:04:32,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:04:32,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 2 "Click 'IP_OBS_ERAdmitLink'"...
[09/28 09:04:32,IEXPLORE.EXE(1044:14),Framework] InternetExplorerActions.BeginAsyncInjection() : Spawned Internet Explorer injection thread (HWND=396130, AssemblyAndClass="C:\WINDOWS\Microsoft.Net\assembly\GAC_MSIL\ArtOfTest.InternetExplorer\v4.0_2018.2.606.0__5339893a7cefe4d6\ArtOfTest.InternetExplorer.dll?ArtOfTest.InternetExplorer.ArtOfTestPluginEntryPoint", PipeName="Pipe.ArtOfTest.WebAii.BrowserProvisioner_-997853167", ProcessId="7716").
[09/28 09:04:33,IEXPLORE.EXE(1044:12)] First trace message from pool thread "Internet Explorer injection thread" (managed ID = 12, native ID = 15320).
[09/28 09:04:33,IEXPLORE.EXE(1044:12),Framework] Connector.Start() : Process launched (ID=6372, Path="C:\Windows\SysWOW64\rundll32.exe", Arguments=""C:\Program Files (x86)\Progress\Test Studio\Bin\ArtOfTest.Connector.dll",AttachToWindowEntryPoint 395860").
[09/28 09:04:33,IEXPLORE.EXE(1044:12),Framework] Connector.Start() : Process exited (ID=6372, ExitCode=0, Path="C:\Windows\SysWOW64\rundll32.exe", Arguments=""C:\Program Files (x86)\Progress\Test Studio\Bin\ArtOfTest.Connector.dll",AttachToWindowEntryPoint 395860").
[09/28 09:04:33,IEXPLORE.EXE(1044:12),Framework] Connector.InjectCode() : ------- Function Start -------
[09/28 09:04:33,IEXPLORE.EXE(1044:12),Framework] Connector.InjectCode() : Connection string: C:\WINDOWS\Microsoft.Net\assembly\GAC_MSIL\ArtOfTest.InternetExplorer\v4.0_2018.2.606.0__5339893a7cefe4d6\ArtOfTest.InternetExplorer.dll?ArtOfTest.InternetExplorer.ArtOfTestPluginEntryPoint?7716
[09/28 09:04:33,IEXPLORE.EXE(1044:12),Framework] InternetExplorerActions.WaitForDocument() : Attempting to get IHTMLDocument2 from Internet Explorer HWND 395860 on another thread...
[09/28 09:04:33,IEXPLORE.EXE(1044:8)] First trace message from pool thread "WaitAsync polling wait worker" (managed ID = 8, native ID = 7740).
[09/28 09:04:33,IEXPLORE.EXE(1044:8),Framework] <>c__DisplayClass25_3.<WaitForDocument>b__1() : Attempting to get document from window handle
[09/28 09:04:33,IEXPLORE.EXE(1044:12),Framework] InternetExplorerActions.WaitForDocument() : IHTMLDocument2 successfully retrieved.
[09/28 09:04:33,IEXPLORE.EXE(1044:12),Framework] Connector.InjectCode() : Sending WM_COPYDATA to HWND 395860 (lpData = "C:\WINDOWS\Microsoft.Net\assembly\GAC_MSIL\ArtOfTest.InternetExplorer\v4.0_2018.2.606.0__5339893a7cefe4d6\ArtOfTest.InternetExplorer.dll?ArtOfTest.InternetExplorer.ArtOfTestPluginEntryPoint?7716")...
[09/28 09:04:33,IEXPLORE.EXE(1044:13)] First trace message from background unnamed thread (managed ID = 13, native ID = 7088).
[09/28 09:04:33,IEXPLORE.EXE(1044:13),Framework] ArtOfTestPluginEntryPoint.Connect() : The .NET Framework is now active inside the hooked process (HWND = 395860, PipeName = "Pipe.ArtOfTest.WebAii.BrowserProvisioner_-997853167", PID = 7716).
[09/28 09:04:33,IEXPLORE.EXE(1044:13),Framework] InternetExplorerActions.WaitForDocument() : Attempting to get IHTMLDocument2 from Internet Explorer HWND 395860 on same thread...
[09/28 09:04:34,IEXPLORE.EXE(1044:13),Framework] InternetExplorerActions.WaitForDocument() : IHTMLDocument2 successfully retrieved.
[09/28 09:04:34,IEXPLORE.EXE(1044:13),Framework] BrowserRemoteClient constructor : The client ID for this hooked process will be "Client_265c7e52-d3e2-4cb9-8c9e-cea8fdc4da98".
[09/28 09:04:34,ArtOfTest.Runner.exe(9616:6),Framework] BrowserProvisioner.ServiceThread() : A new client has connected to the provisioning pipe.
[09/28 09:04:34,ArtOfTest.Runner.exe(9616:12)] First trace message from background thread "Remoted async command listener" (managed ID = 12, native ID = 1432).
[09/28 09:04:34,ArtOfTest.Runner.exe(9616:12),Framework] BrowserRemoted.AsyncListenerThreadEntry() : Not connected; this will now wait indefinitely for a pipe client connection...
[09/28 09:04:34,IEXPLORE.EXE(1044:13),Framework] BrowserRemoteClient.CreateNamedPipes() : Named pipes created for communication with the server: {Command = "188e1fb4-6618-4db8-9371-06ea3b741bae.CommandPipe", Async = "188e1fb4-6618-4db8-9371-06ea3b741bae.AsyncPipe"}
[09/28 09:04:34,ArtOfTest.Runner.exe(9616:12),Framework] BrowserRemoted.AsyncListenerThreadEntry() : A new client has connected to the pipe.
[09/28 09:04:34,IEXPLORE.EXE(1044:12),Framework] Connector.InjectCode() : Returned from sending WM_COPYDATA (LRESULT=0, GetLastError()=DS_S_SUCCESS).
[09/28 09:04:34,ArtOfTest.Runner.exe(9616:12),Framework] BrowserRemoted.AsyncListenerThreadEntry() : Now waiting indefinitely for a command from the pipe client...
[09/28 09:04:34,IEXPLORE.EXE(1044:12),Framework] Connector.InjectCode() : ------- Function End -------
[09/28 09:04:34,ArtOfTest.Runner.exe(9616:6),Framework] Manager.AddNewBrowser() : Added client ID "Client_265c7e52-d3e2-4cb9-8c9e-cea8fdc4da98" (now there is/are 3 total).
[09/28 09:04:36,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 2 "Click 'IP_OBS_ERAdmitLink'".
[09/28 09:04:36,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:04:36,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:04:36,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[09/28 09:04:36,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:04:36,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:04:36,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 3 "Connect to pop-up window : 'inpatient.aspx'"...
[09/28 09:04:36,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 3 "Connect to pop-up window : 'inpatient.aspx'".
[09/28 09:04:36,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:04:36,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:04:36,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 1 "Execute test 'OpenIPWizard'".
[09/28 09:04:36,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:04:36,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:04:36,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[09/28 09:04:36,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:04:36,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:04:36,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 2 "Click 'AdmittingPhysicianLink'"...
[09/28 09:04:36,IEXPLORE.EXE(1044:13),Framework] InternetExplorerActions.BeginAsyncInjection() : Spawned Internet Explorer injection thread (HWND=3476336, AssemblyAndClass="C:\WINDOWS\Microsoft.Net\assembly\GAC_MSIL\ArtOfTest.InternetExplorer\v4.0_2018.2.606.0__5339893a7cefe4d6\ArtOfTest.InternetExplorer.dll?ArtOfTest.InternetExplorer.ArtOfTestPluginEntryPoint", PipeName="Pipe.ArtOfTest.WebAii.BrowserProvisioner_-997853167", ProcessId="7716").
[09/28 09:04:37,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 2 "Click 'AdmittingPhysicianLink'".
[09/28 09:04:37,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:04:37,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:04:37,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[09/28 09:04:37,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:04:37,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:04:37,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 3 "Connect to pop-up window : '' - DataDriven: [$(InternalProvidersBaseURL)]"...
[09/28 09:04:38,IEXPLORE.EXE(1044:9)] First trace message from pool thread "Internet Explorer injection thread" (managed ID = 9, native ID = 15208).
[09/28 09:04:38,IEXPLORE.EXE(1044:9),Framework] Connector.Start() : Process launched (ID=11880, Path="C:\Windows\SysWOW64\rundll32.exe", Arguments=""C:\Program Files (x86)\Progress\Test Studio\Bin\ArtOfTest.Connector.dll",AttachToWindowEntryPoint 1706920").
[09/28 09:04:38,IEXPLORE.EXE(1044:9),Framework] Connector.Start() : Process exited (ID=11880, ExitCode=0, Path="C:\Windows\SysWOW64\rundll32.exe", Arguments=""C:\Program Files (x86)\Progress\Test Studio\Bin\ArtOfTest.Connector.dll",AttachToWindowEntryPoint 1706920").
[09/28 09:04:38,IEXPLORE.EXE(1044:9),Framework] Connector.InjectCode() : ------- Function Start -------
[09/28 09:04:38,IEXPLORE.EXE(1044:9),Framework] Connector.InjectCode() : Connection string: C:\WINDOWS\Microsoft.Net\assembly\GAC_MSIL\ArtOfTest.InternetExplorer\v4.0_2018.2.606.0__5339893a7cefe4d6\ArtOfTest.InternetExplorer.dll?ArtOfTest.InternetExplorer.ArtOfTestPluginEntryPoint?7716
[09/28 09:04:38,IEXPLORE.EXE(1044:9),Framework] InternetExplorerActions.WaitForDocument() : Attempting to get IHTMLDocument2 from Internet Explorer HWND 1706920 on another thread...
[09/28 09:04:38,IEXPLORE.EXE(1044:8)] First trace message from pool thread "WaitAsync polling wait worker" (managed ID = 8, native ID = 7740).
[09/28 09:04:38,IEXPLORE.EXE(1044:8),Framework] <>c__DisplayClass25_3.<WaitForDocument>b__1() : Attempting to get document from window handle
[09/28 09:04:38,IEXPLORE.EXE(1044:9),Framework] InternetExplorerActions.WaitForDocument() : IHTMLDocument2 successfully retrieved.
[09/28 09:04:38,IEXPLORE.EXE(1044:9),Framework] Connector.InjectCode() : Sending WM_COPYDATA to HWND 1706920 (lpData = "C:\WINDOWS\Microsoft.Net\assembly\GAC_MSIL\ArtOfTest.InternetExplorer\v4.0_2018.2.606.0__5339893a7cefe4d6\ArtOfTest.InternetExplorer.dll?ArtOfTest.InternetExplorer.ArtOfTestPluginEntryPoint?7716")...
[09/28 09:04:39,IEXPLORE.EXE(2604:1)] First trace message from process 2604: "C:\Program Files (x86)\Internet Explorer\IEXPLORE.EXE" SCODEF:7272 CREDAT:17460 /prefetch:2
[09/28 09:04:39,IEXPLORE.EXE(2604:1)] First trace message from background unnamed thread (managed ID = 1, native ID = 9904).
[09/28 09:04:39,IEXPLORE.EXE(2604:1),Framework] ArtOfTestPluginEntryPoint.Connect() : The .NET Framework is now active inside the hooked process (HWND = 1706920, PipeName = "Pipe.ArtOfTest.WebAii.BrowserProvisioner_-997853167", PID = 7716).
[09/28 09:04:39,IEXPLORE.EXE(2604:1),Framework] InternetExplorerActions.WaitForDocument() : Attempting to get IHTMLDocument2 from Internet Explorer HWND 1706920 on same thread...
[09/28 09:04:39,IEXPLORE.EXE(2604:1),Framework] InternetExplorerActions.WaitForDocument() : IHTMLDocument2 successfully retrieved.
[09/28 09:04:39,IEXPLORE.EXE(2604:1),Framework] BrowserRemoteClient constructor : The client ID for this hooked process will be "Client_f51200e8-6295-4723-a521-bc0b005f8992".
[09/28 09:04:39,ArtOfTest.Runner.exe(9616:6),Framework] BrowserProvisioner.ServiceThread() : A new client has connected to the provisioning pipe.
[09/28 09:04:39,ArtOfTest.Runner.exe(9616:21)] First trace message from background thread "Remoted async command listener" (managed ID = 21, native ID = 15140).
[09/28 09:04:39,ArtOfTest.Runner.exe(9616:21),Framework] BrowserRemoted.AsyncListenerThreadEntry() : Not connected; this will now wait indefinitely for a pipe client connection...
[09/28 09:04:39,IEXPLORE.EXE(2604:1),Framework] BrowserRemoteClient.CreateNamedPipes() : Named pipes created for communication with the server: {Command = "2a2c19ab-0ec3-4091-9a04-4ce4aa60ea22.CommandPipe", Async = "2a2c19ab-0ec3-4091-9a04-4ce4aa60ea22.AsyncPipe"}
[09/28 09:04:39,ArtOfTest.Runner.exe(9616:21),Framework] BrowserRemoted.AsyncListenerThreadEntry() : A new client has connected to the pipe.
[09/28 09:04:39,ArtOfTest.Runner.exe(9616:21),Framework] BrowserRemoted.AsyncListenerThreadEntry() : Now waiting indefinitely for a command from the pipe client...
[09/28 09:04:39,IEXPLORE.EXE(1044:9),Framework] Connector.InjectCode() : Returned from sending WM_COPYDATA (LRESULT=0, GetLastError()=DS_S_SUCCESS).
[09/28 09:04:39,IEXPLORE.EXE(1044:9),Framework] Connector.InjectCode() : ------- Function End -------
[09/28 09:04:39,ArtOfTest.Runner.exe(9616:6),Framework] Manager.AddNewBrowser() : Added client ID "Client_f51200e8-6295-4723-a521-bc0b005f8992" (now there is/are 4 total).
[09/28 09:04:39,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 3 "Connect to pop-up window : '' - DataDriven: [$(InternalProvidersBaseURL)]".
[09/28 09:04:39,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:04:39,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:04:39,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[09/28 09:04:39,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:04:39,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:04:39,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 4 "Enter text '' in 'PhysicianFirstNameInput' - DataDriven: [$(FirstName)]"...
[09/28 09:04:41,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 4 "Enter text '' in 'PhysicianFirstNameInput' - DataDriven: [$(FirstName)]".
[09/28 09:04:41,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:04:41,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:04:41,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[09/28 09:04:41,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:04:41,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:04:41,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 5 "Enter text '' in 'PhysicianLastNameInput' - DataDriven: [$(LastName)]"...
[09/28 09:04:42,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 5 "Enter text '' in 'PhysicianLastNameInput' - DataDriven: [$(LastName)]".
[09/28 09:04:42,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:04:42,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:04:42,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[09/28 09:04:42,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:04:42,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:04:43,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 6 "Enter text '' in 'ZipCodeInput' - DataDriven: [$(PhysicianZip)]"...
[09/28 09:04:43,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 6 "Enter text '' in 'ZipCodeInput' - DataDriven: [$(PhysicianZip)]".
[09/28 09:04:43,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:04:43,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:04:44,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[09/28 09:04:44,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:04:44,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:04:44,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 7 "Click 'SearchBtn'"...
[09/28 09:04:44,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 7 "Click 'SearchBtn'".
[09/28 09:04:44,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:04:44,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:04:44,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[09/28 09:04:44,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:04:44,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:04:44,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 8 "Wait for Exists 'FirstSearchResultArrow'"...
[09/28 09:04:46,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 8 "Wait for Exists 'FirstSearchResultArrow'".
[09/28 09:04:46,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:04:46,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:04:46,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[09/28 09:04:46,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:04:46,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:04:46,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 9 "Click 'FirstSearchResultArrow'"...
[09/28 09:04:46,ArtOfTest.Runner.exe(9616:4),Framework] Manager.SetActiveBrowser() : Active browser is now "Client_265c7e52-d3e2-4cb9-8c9e-cea8fdc4da98".
[09/28 09:04:46,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 9 "Click 'FirstSearchResultArrow'".
[09/28 09:04:46,IEXPLORE.EXE(2604:1),Framework] BrowserRemoteClient.Disconnect() : Sending ClientDisconnectRequest(ClientId="Client_f51200e8-6295-4723-a521-bc0b005f8992") to pipe server...
[09/28 09:04:46,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:04:46,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:04:46,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[09/28 09:04:46,ArtOfTest.Runner.exe(9616:21),Framework] BrowserRemoted.AsyncListenerThreadEntry() : A ClientDisconnectRequest(ClientId="Client_f51200e8-6295-4723-a521-bc0b005f8992") has been received from the pipe client.
[09/28 09:04:46,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:04:46,IEXPLORE.EXE(2604:1),Framework] BrowserRemoteClient.Disconnect() : Command pipe is already disconnected.
[09/28 09:04:46,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:04:46,ArtOfTest.Runner.exe(9616:21),Framework] Manager.RemoveBrowser() : Removed client ID "Client_f51200e8-6295-4723-a521-bc0b005f8992" (3 remaining).
[09/28 09:04:46,ArtOfTest.Runner.exe(9616:21),Framework] BrowserRemoted.End() : Now aborting the listener thread (TID = 21).
[09/28 09:04:46,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 10 "Wait for 'value' attribute 'Contains' value '' on 'AdmittingPhysicianLabel' - DataDriven: [$(LastName)]"...
[09/28 09:04:48,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 10 "Wait for 'value' attribute 'Contains' value '' on 'AdmittingPhysicianLabel' - DataDriven: [$(LastName)]".
[09/28 09:04:48,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:04:48,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:04:48,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[09/28 09:04:48,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:04:48,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:04:48,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 11 "Click 'FacilityLink'"...
[09/28 09:04:48,IEXPLORE.EXE(1044:13),Framework] InternetExplorerActions.BeginAsyncInjection() : Spawned Internet Explorer injection thread (HWND=527396, AssemblyAndClass="C:\WINDOWS\Microsoft.Net\assembly\GAC_MSIL\ArtOfTest.InternetExplorer\v4.0_2018.2.606.0__5339893a7cefe4d6\ArtOfTest.InternetExplorer.dll?ArtOfTest.InternetExplorer.ArtOfTestPluginEntryPoint", PipeName="Pipe.ArtOfTest.WebAii.BrowserProvisioner_-997853167", ProcessId="7716").
[09/28 09:04:48,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 11 "Click 'FacilityLink'".
[09/28 09:04:48,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:04:48,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:04:48,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[09/28 09:04:48,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:04:48,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:04:48,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 12 "Connect to pop-up window : '' - DataDriven: [$(InternalProvidersBaseURL)]"...
[09/28 09:04:50,IEXPLORE.EXE(1044:12)] First trace message from pool thread "Internet Explorer injection thread" (managed ID = 12, native ID = 15320).
[09/28 09:04:50,IEXPLORE.EXE(1044:12),Framework] Connector.Start() : Process launched (ID=9580, Path="C:\Windows\SysWOW64\rundll32.exe", Arguments=""C:\Program Files (x86)\Progress\Test Studio\Bin\ArtOfTest.Connector.dll",AttachToWindowEntryPoint 461674").
[09/28 09:04:50,IEXPLORE.EXE(1044:12),Framework] Connector.Start() : Process exited (ID=9580, ExitCode=0, Path="C:\Windows\SysWOW64\rundll32.exe", Arguments=""C:\Program Files (x86)\Progress\Test Studio\Bin\ArtOfTest.Connector.dll",AttachToWindowEntryPoint 461674").
[09/28 09:04:50,IEXPLORE.EXE(1044:12),Framework] Connector.InjectCode() : ------- Function Start -------
[09/28 09:04:50,IEXPLORE.EXE(1044:12),Framework] Connector.InjectCode() : Connection string: C:\WINDOWS\Microsoft.Net\assembly\GAC_MSIL\ArtOfTest.InternetExplorer\v4.0_2018.2.606.0__5339893a7cefe4d6\ArtOfTest.InternetExplorer.dll?ArtOfTest.InternetExplorer.ArtOfTestPluginEntryPoint?7716
[09/28 09:04:50,IEXPLORE.EXE(1044:12),Framework] InternetExplorerActions.WaitForDocument() : Attempting to get IHTMLDocument2 from Internet Explorer HWND 461674 on another thread...
[09/28 09:04:50,IEXPLORE.EXE(1044:9)] First trace message from pool thread "WaitAsync polling wait worker" (managed ID = 9, native ID = 15208).
[09/28 09:04:50,IEXPLORE.EXE(1044:9),Framework] <>c__DisplayClass25_3.<WaitForDocument>b__1() : Attempting to get document from window handle
[09/28 09:04:50,IEXPLORE.EXE(1044:12),Framework] InternetExplorerActions.WaitForDocument() : IHTMLDocument2 successfully retrieved.
[09/28 09:04:50,IEXPLORE.EXE(1044:12),Framework] Connector.InjectCode() : Sending WM_COPYDATA to HWND 461674 (lpData = "C:\WINDOWS\Microsoft.Net\assembly\GAC_MSIL\ArtOfTest.InternetExplorer\v4.0_2018.2.606.0__5339893a7cefe4d6\ArtOfTest.InternetExplorer.dll?ArtOfTest.InternetExplorer.ArtOfTestPluginEntryPoint?7716")...
[09/28 09:04:50,IEXPLORE.EXE(11696:1)] First trace message from process 11696: "C:\Program Files (x86)\Internet Explorer\IEXPLORE.EXE" SCODEF:7272 CREDAT:17470 /prefetch:2
[09/28 09:04:50,IEXPLORE.EXE(11696:1)] First trace message from background unnamed thread (managed ID = 1, native ID = 12024).
[09/28 09:04:50,IEXPLORE.EXE(11696:1),Framework] ArtOfTestPluginEntryPoint.Connect() : The .NET Framework is now active inside the hooked process (HWND = 461674, PipeName = "Pipe.ArtOfTest.WebAii.BrowserProvisioner_-997853167", PID = 7716).
[09/28 09:04:50,IEXPLORE.EXE(11696:1),Framework] InternetExplorerActions.WaitForDocument() : Attempting to get IHTMLDocument2 from Internet Explorer HWND 461674 on same thread...
[09/28 09:04:50,IEXPLORE.EXE(11696:1),Framework] InternetExplorerActions.WaitForDocument() : IHTMLDocument2 successfully retrieved.
[09/28 09:04:50,IEXPLORE.EXE(11696:1),Framework] BrowserRemoteClient constructor : The client ID for this hooked process will be "Client_e0bb59ae-8c52-4561-8ca4-e19db6938615".
[09/28 09:04:50,ArtOfTest.Runner.exe(9616:6),Framework] BrowserProvisioner.ServiceThread() : A new client has connected to the provisioning pipe.
[09/28 09:04:50,ArtOfTest.Runner.exe(9616:21)] First trace message from background thread "Remoted async command listener" (managed ID = 21, native ID = 15008).
[09/28 09:04:50,ArtOfTest.Runner.exe(9616:21),Framework] BrowserRemoted.AsyncListenerThreadEntry() : Not connected; this will now wait indefinitely for a pipe client connection...
[09/28 09:04:50,IEXPLORE.EXE(11696:1),Framework] BrowserRemoteClient.CreateNamedPipes() : Named pipes created for communication with the server: {Command = "e3f2f4dd-c79f-4466-833e-099e54db21b5.CommandPipe", Async = "e3f2f4dd-c79f-4466-833e-099e54db21b5.AsyncPipe"}
[09/28 09:04:51,ArtOfTest.Runner.exe(9616:21),Framework] BrowserRemoted.AsyncListenerThreadEntry() : A new client has connected to the pipe.
[09/28 09:04:51,ArtOfTest.Runner.exe(9616:21),Framework] BrowserRemoted.AsyncListenerThreadEntry() : Now waiting indefinitely for a command from the pipe client...
[09/28 09:04:51,IEXPLORE.EXE(1044:12),Framework] Connector.InjectCode() : Returned from sending WM_COPYDATA (LRESULT=0, GetLastError()=DS_S_SUCCESS).
[09/28 09:04:51,IEXPLORE.EXE(1044:12),Framework] Connector.InjectCode() : ------- Function End -------
[09/28 09:04:51,ArtOfTest.Runner.exe(9616:6),Framework] Manager.AddNewBrowser() : Added client ID "Client_e0bb59ae-8c52-4561-8ca4-e19db6938615" (now there is/are 4 total).
[09/28 09:04:51,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 12 "Connect to pop-up window : '' - DataDriven: [$(InternalProvidersBaseURL)]".
[09/28 09:04:51,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:04:51,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:04:51,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[09/28 09:04:51,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:04:51,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:04:51,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 13 "Click 'PhysicianGroupFacilityRadioBtn'"...
[09/28 09:04:51,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 13 "Click 'PhysicianGroupFacilityRadioBtn'".
[09/28 09:04:51,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:04:51,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:04:51,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[09/28 09:04:51,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:04:51,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:04:51,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 14 "Enter text '' in 'FacilityNameInput' - DataDriven: [$(Facility)]"...
[09/28 09:04:55,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 14 "Enter text '' in 'FacilityNameInput' - DataDriven: [$(Facility)]".
[09/28 09:04:55,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:04:55,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:04:55,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[09/28 09:04:55,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:04:55,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:04:55,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 15 "Enter text '' in 'ZipCodeInput' - DataDriven: [$(FacilityZip)]"...
[09/28 09:04:56,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 15 "Enter text '' in 'ZipCodeInput' - DataDriven: [$(FacilityZip)]".
[09/28 09:04:56,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:04:56,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:04:56,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[09/28 09:04:56,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:04:56,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:04:56,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 16 "Click 'SearchBtn'"...
[09/28 09:04:57,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 16 "Click 'SearchBtn'".
[09/28 09:04:57,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:04:57,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:04:57,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[09/28 09:04:57,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:04:57,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:04:57,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 17 "Wait for Exists 'FirstSearchResultArrow'"...
[09/28 09:04:59,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 17 "Wait for Exists 'FirstSearchResultArrow'".
[09/28 09:04:59,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:04:59,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:04:59,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[09/28 09:04:59,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:04:59,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:04:59,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 18 "Click 'FirstSearchResultArrow'"...
[09/28 09:04:59,ArtOfTest.Runner.exe(9616:4),Framework] Manager.SetActiveBrowser() : Active browser is now "Client_265c7e52-d3e2-4cb9-8c9e-cea8fdc4da98".
[09/28 09:04:59,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 18 "Click 'FirstSearchResultArrow'".
[09/28 09:04:59,IEXPLORE.EXE(11696:1),Framework] BrowserRemoteClient.Disconnect() : Sending ClientDisconnectRequest(ClientId="Client_e0bb59ae-8c52-4561-8ca4-e19db6938615") to pipe server...
[09/28 09:04:59,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:04:59,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:04:59,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[09/28 09:04:59,ArtOfTest.Runner.exe(9616:21),Framework] BrowserRemoted.AsyncListenerThreadEntry() : A ClientDisconnectRequest(ClientId="Client_e0bb59ae-8c52-4561-8ca4-e19db6938615") has been received from the pipe client.
[09/28 09:05:00,ArtOfTest.Runner.exe(9616:21),Framework] Manager.RemoveBrowser() : Removed client ID "Client_e0bb59ae-8c52-4561-8ca4-e19db6938615" (3 remaining).
[09/28 09:05:00,IEXPLORE.EXE(11696:1),Framework] BrowserRemoteClient.Disconnect() : Command pipe is already disconnected.
[09/28 09:05:00,ArtOfTest.Runner.exe(9616:21),Framework] BrowserRemoted.End() : Now aborting the listener thread (TID = 21).
[09/28 09:05:00,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:05:00,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:05:00,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 19 "Wait for 'value' attribute 'Contains' value '' on 'FacilityName' - DataDriven: [$(Facility)]"...
[09/28 09:05:01,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 19 "Wait for 'value' attribute 'Contains' value '' on 'FacilityName' - DataDriven: [$(Facility)]".
[09/28 09:05:01,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:05:01,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:05:01,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[09/28 09:05:01,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:05:01,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:05:01,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 20 "Comment: Changing network status refreshes the page to set the selected option, causing actions taken before the refresh to be lost. Waiting for the selected option to appear as selected to avoid race condion between selecting an option finishing and taking the next test step."...
[09/28 09:05:01,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 20 "Comment: Changing network status refreshes the page to set the selected option, causing actions taken before the refresh to be lost. Waiting for the selected option to appear as selected to avoid race condion between selecting an option finishing and taking the next test step.".
[09/28 09:05:01,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:05:01,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:05:01,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[09/28 09:05:01,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:05:01,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:05:01,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 21 "Select 'ByValue' option '' on 'AdmittingPhysicianNetworkStatus' - DataDriven: [$(PhysicianStatus)]"...
[09/28 09:05:01,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 21 "Select 'ByValue' option '' on 'AdmittingPhysicianNetworkStatus' - DataDriven: [$(PhysicianStatus)]".
[09/28 09:05:01,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:05:01,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:05:01,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[09/28 09:05:01,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:05:01,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:05:01,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 22 "Wait for 'InnerText' 'Contains' '' on 'AdmittingPhysicianNetworkStatusSelectedOption' - DataDriven: [$(PhysicianStatus)]"...
[09/28 09:05:01,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 22 "Wait for 'InnerText' 'Contains' '' on 'AdmittingPhysicianNetworkStatusSelectedOption' - DataDriven: [$(PhysicianStatus)]".
[09/28 09:05:01,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:05:01,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:05:01,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[09/28 09:05:01,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:05:01,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:05:01,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 23 "Select 'ByValue' option '' on 'FacilityNetworkStatus' - DataDriven: [$(FacilityStatus)]"...
[09/28 09:05:01,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 23 "Select 'ByValue' option '' on 'FacilityNetworkStatus' - DataDriven: [$(FacilityStatus)]".
[09/28 09:05:01,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:05:01,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:05:01,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[09/28 09:05:01,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:05:01,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:05:01,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 24 "Wait for 'InnerText' 'Contains' '' on 'FacilityNetworkStatusSelectedOption' - DataDriven: [$(FacilityStatus)]"...
[09/28 09:05:01,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 24 "Wait for 'InnerText' 'Contains' '' on 'FacilityNetworkStatusSelectedOption' - DataDriven: [$(FacilityStatus)]".
[09/28 09:05:01,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:05:01,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:05:01,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[09/28 09:05:01,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:05:01,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:05:01,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 25 "Select 'ByText' option '' on 'StatusSelect' - DataDriven: [$(Status)]"...
[09/28 09:05:01,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 25 "Select 'ByText' option '' on 'StatusSelect' - DataDriven: [$(Status)]".
[09/28 09:05:01,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:05:01,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:05:01,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[09/28 09:05:01,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:05:01,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:05:01,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 26 "Select 'ByText' option '' on 'PlaceOfServiceSelect' - DataDriven: [$(PlaceOfService)]"...
[09/28 09:05:01,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 26 "Select 'ByText' option '' on 'PlaceOfServiceSelect' - DataDriven: [$(PlaceOfService)]".
[09/28 09:05:01,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:05:01,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:05:01,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[09/28 09:05:01,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:05:01,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:05:02,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 27 "Enter text '' in 'AdmissionDate' - DataDriven: [$(AdmissionDate)]"...
[09/28 09:05:04,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 27 "Enter text '' in 'AdmissionDate' - DataDriven: [$(AdmissionDate)]".
[09/28 09:05:04,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:05:04,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:05:04,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[09/28 09:05:04,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:05:04,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:05:04,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 28 "Select 'ByValue' option '' on 'AdmissionTimeSelect' - DataDriven: [$(AdmissionTime)]"...
[09/28 09:05:04,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 28 "Select 'ByValue' option '' on 'AdmissionTimeSelect' - DataDriven: [$(AdmissionTime)]".
[09/28 09:05:04,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:05:04,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:05:04,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[09/28 09:05:04,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:05:04,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:05:04,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 29 "Select 'ByText' option '' on 'AdmissionSourceSelect' - DataDriven: [$(AdmissionSource)]"...
[09/28 09:05:04,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 29 "Select 'ByText' option '' on 'AdmissionSourceSelect' - DataDriven: [$(AdmissionSource)]".
[09/28 09:05:04,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:05:04,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:05:04,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[09/28 09:05:04,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:05:04,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:05:04,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 30 "Enter text '' in 'FacilityURNurseInput' - DataDriven: [$(URNurse)]"...
[09/28 09:05:05,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 30 "Enter text '' in 'FacilityURNurseInput' - DataDriven: [$(URNurse)]".
[09/28 09:05:05,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:05:05,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:05:05,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[09/28 09:05:05,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:05:05,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:05:05,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 31 "Enter text '' in 'FacilityURPhoneInput' - DataDriven: [$(URPhone)]"...
[09/28 09:05:07,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 31 "Enter text '' in 'FacilityURPhoneInput' - DataDriven: [$(URPhone)]".
[09/28 09:05:07,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:05:07,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:05:07,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[09/28 09:05:07,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:05:07,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:05:07,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 32 "Enter text '' in 'FacilityURFaxInput' - DataDriven: [$(URFax)]"...
[09/28 09:05:09,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 32 "Enter text '' in 'FacilityURFaxInput' - DataDriven: [$(URFax)]".
[09/28 09:05:09,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:05:09,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:05:09,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[09/28 09:05:09,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:05:09,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:05:09,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 33 "Enter text '' in 'DiagnosisCode0' - DataDriven: [$(DiagnosisCode)]"...
[09/28 09:05:10,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 33 "Enter text '' in 'DiagnosisCode0' - DataDriven: [$(DiagnosisCode)]".
[09/28 09:05:10,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:05:10,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:05:10,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[09/28 09:05:10,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:05:10,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:05:10,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 34 "Keyboard (KeyPress) - Tab (1 times) on 'DiagnosisCode0'"...
[09/28 09:05:10,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 34 "Keyboard (KeyPress) - Tab (1 times) on 'DiagnosisCode0'".
[09/28 09:05:10,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:05:10,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:05:10,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[09/28 09:05:11,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:05:11,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:05:11,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 35 "Wait for 'value' attribute 'Contains' value '' on 'DiagnosisCode0Description' - DataDriven: [$(DiagnosisCodeDescription)]"...
[09/28 09:05:11,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 35 "Wait for 'value' attribute 'Contains' value '' on 'DiagnosisCode0Description' - DataDriven: [$(DiagnosisCodeDescription)]".
[09/28 09:05:11,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:05:11,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:05:11,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[09/28 09:05:11,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:05:11,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:05:11,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 36 "Check 'RequestedURToCallWithClinicalsCheckbox' to be 'True'"...
[09/28 09:05:11,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 36 "Check 'RequestedURToCallWithClinicalsCheckbox' to be 'True'".
[09/28 09:05:11,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:05:11,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:05:11,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[09/28 09:05:11,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:05:11,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:05:11,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 37 "Check 'LeftVoicemailCheckbox' to be 'True'"...
[09/28 09:05:11,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 37 "Check 'LeftVoicemailCheckbox' to be 'True'".
[09/28 09:05:11,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:05:11,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:05:11,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[09/28 09:05:11,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:05:11,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:05:11,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 38 "Click 'SaveOrEditAuth'"...
[09/28 09:05:16,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 38 "Click 'SaveOrEditAuth'".
[09/28 09:05:16,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:05:16,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:05:16,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[09/28 09:05:16,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:05:16,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:05:16,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 39 "Wait for 'value' attribute 'Exact' value 'Edit' on 'SaveOrEditAuth'"...
[09/28 09:05:16,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 39 "Wait for 'value' attribute 'Exact' value 'Edit' on 'SaveOrEditAuth'".
[09/28 09:05:16,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:05:16,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:05:16,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[09/28 09:05:16,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:05:16,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:05:16,ArtOfTest.Runner.exe(9616:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 40 "Click 'CloseAuth'"...
[09/28 09:05:18,ArtOfTest.Runner.exe(9616:8),DialogPlayback] DialogMonitor.OnWindowCreated() : New dialog detected (ClassName = "#32770", Handle = "2296504").
[09/28 09:05:18,ArtOfTest.Runner.exe(9616:8),Uncategorized] Log.WriteLineInternal() : Unexpected dialog encountered. Taking no action.
[09/28 09:05:18,ArtOfTest.Runner.exe(9616:8),DialogPlayback] DialogMonitor.HandleUnexpectedDialog() : Unexpected dialog encountered. Taking no action.
[09/28 09:05:19,IEXPLORE.EXE(1044:13),Framework] BrowserRemoteClient.Disconnect() : Sending ClientDisconnectRequest(ClientId="Client_265c7e52-d3e2-4cb9-8c9e-cea8fdc4da98") to pipe server...
[09/28 09:05:19,ArtOfTest.Runner.exe(9616:12),Framework] BrowserRemoted.AsyncListenerThreadEntry() : A ClientDisconnectRequest(ClientId="Client_265c7e52-d3e2-4cb9-8c9e-cea8fdc4da98") has been received from the pipe client.
[09/28 09:05:19,IEXPLORE.EXE(1044:13),Framework] BrowserRemoteClient.Disconnect() : Disconnecting command pipe (Handle = 7512).
[09/28 09:05:19,ArtOfTest.Runner.exe(9616:12),Framework] Manager.RemoveBrowser() : Removed client ID "Client_265c7e52-d3e2-4cb9-8c9e-cea8fdc4da98" (2 remaining).
[09/28 09:05:19,ArtOfTest.Runner.exe(9616:12),Framework] Manager.SetActiveBrowser() : Active browser is now "Client_7e060604-61ca-461c-a0b6-c1c0bc24f198".
[09/28 09:05:19,ArtOfTest.Runner.exe(9616:12),Framework] BrowserRemoted.End() : Now aborting the listener thread (TID = 12).
[09/28 09:05:20,ArtOfTest.Runner.exe(9616:4),Error] ExecutionEngine.CatchExecuteStepException() : EXCEPTION! (see below)
     Outer Exception Type: ArtOfTest.WebAii.Exceptions.ExecuteCommandException
     Message: ExecuteCommand failed!
          BrowserCommand (Type:'Information',Info:'DocumentMarkup',Action:'NotSet',Target:'null',Data:'',ClientId:'Client_265c7e52-d3e2-4cb9-8c9e-cea8fdc4da98',HasFrames:'False',FramesInfo:'',TargetFrameIndex:'-1',InError:'False',Response:'')
          InnerException: ArtOfTest.WebAii.Exceptions.ExecuteCommandException: ExecuteCommand failed!
          BrowserCommand (Type:'Information',Info:'DocumentMarkup',Action:'NotSet',Target:'null',Data:'',ClientId:'Client_265c7e52-d3e2-4cb9-8c9e-cea8fdc4da98',HasFrames:'False',FramesInfo:'',TargetFrameIndex:'-1',InError:'False',Response:'')
          InnerException: System.InvalidOperationException: This client 'Client_265c7e52-d3e2-4cb9-8c9e-cea8fdc4da98' is not connected to remote to be able to process command.
          at ArtOfTest.WebAii.Core.Browser.ExecuteCommandInternal(BrowserCommand request)
          ---> System.InvalidOperationException: This client 'Client_265c7e52-d3e2-4cb9-8c9e-cea8fdc4da98' is not connected to remote to be able to process command.
          at ArtOfTest.WebAii.Core.Browser.ExecuteCommandInternal(BrowserCommand request)
          --- End of inner exception stack trace ---
          at ArtOfTest.WebAii.Core.Browser.ExecuteCommandInternal(BrowserCommand request)
          at ArtOfTest.WebAii.Core.Browser.ExecuteCommand(BrowserCommand request, Boolean performDomRefresh, Boolean waitUntilReady)
          at ArtOfTest.WebAii.Core.Browser.RefreshDomTree()
     HRESULT: 0x80131500 (Official ID (if app.) = COR_E_EXCEPTION, Error Bit = FAILED, Facility = FACILITY_URT, Code = 5376)
     Call Stack:
          at ArtOfTest.WebAii.Core.Browser.RefreshDomTree()
          at ArtOfTest.WebAii.Core.Browser.ExecuteCommand(BrowserCommand request, Boolean performDomRefresh, Boolean waitUntilReady)
          at ArtOfTest.WebAii.Core.Browser.ExecuteCommand(BrowserCommand request)
          at ArtOfTest.WebAii.Core.Actions.Click(Element targetElement)
          at ArtOfTest.WebAii.Controls.HtmlControls.HtmlControl.Click()
          at ArtOfTest.WebAii.Controls.HtmlControls.HtmlControl.Click(Boolean isClosing)
          at ArtOfTest.WebAii.Design.IntrinsicTranslators.Descriptors.ClickActionDescriptor.Execute(Browser browser)
          at ArtOfTest.WebAii.Design.Extensibility.HtmlActionDescriptor.Execute(IAutomationHost autoHost)
          at ArtOfTest.WebAii.Design.Execution.ExecutionEngine.ExecuteStep(Int32 order)

     Inner Exception Type: ArtOfTest.WebAii.Exceptions.ExecuteCommandException
     Message: ExecuteCommand failed!
          BrowserCommand (Type:'Information',Info:'DocumentMarkup',Action:'NotSet',Target:'null',Data:'',ClientId:'Client_265c7e52-d3e2-4cb9-8c9e-cea8fdc4da98',HasFrames:'False',FramesInfo:'',TargetFrameIndex:'-1',InError:'False',Response:'')
          InnerException: System.InvalidOperationException: This client 'Client_265c7e52-d3e2-4cb9-8c9e-cea8fdc4da98' is not connected to remote to be able to process command.
          at ArtOfTest.WebAii.Core.Browser.ExecuteCommandInternal(BrowserCommand request)
     HRESULT: 0x80131500 (Official ID (if app.) = COR_E_EXCEPTION, Error Bit = FAILED, Facility = FACILITY_URT, Code = 5376)
     Call Stack:
          at ArtOfTest.WebAii.Core.Browser.ExecuteCommandInternal(BrowserCommand request)
          at ArtOfTest.WebAii.Core.Browser.ExecuteCommand(BrowserCommand request, Boolean performDomRefresh, Boolean waitUntilReady)
          at ArtOfTest.WebAii.Core.Browser.RefreshDomTree()

     Inner Exception Type: System.InvalidOperationException
     Message: This client 'Client_265c7e52-d3e2-4cb9-8c9e-cea8fdc4da98' is not connected to remote to be able to process command.
     HRESULT: 0x80131509 (Official ID (if app.) = COR_E_INVALIDOPERATION, Error Bit = FAILED, Facility = FACILITY_URT, Code = 5385)
     Call Stack:
          at ArtOfTest.WebAii.Core.Browser.ExecuteCommandInternal(BrowserCommand request)

[09/28 09:05:20,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[09/28 09:05:20,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::BreakOnFail
[09/28 09:05:20,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[09/28 09:05:20,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.ShutDown() : VisualDebbuger::ShutDown::Start
[09/28 09:05:20,ArtOfTest.Runner.exe(9616:4),Uncategorized] AdornerWindow.ShutDown() : AdornerWnd::ShutDown::Start
[09/28 09:05:20,ArtOfTest.Runner.exe(9616:4),Uncategorized] AdornerWindow.ShutDown() : AdornerWnd::DetachWrappedWindow::Start
[09/28 09:05:20,ArtOfTest.Runner.exe(9616:4),Uncategorized] AdornerWindow.ShutDown() : AdornerWnd::DetachWrappedWindow::End
[09/28 09:05:20,ArtOfTest.Runner.exe(9616:4),Uncategorized] AdornerWindow.ShutDown() : AdornerWnd::DispatcherBeginInvokeShutdown::Start
[09/28 09:05:20,ArtOfTest.Runner.exe(9616:4),Uncategorized] AdornerWindow.ShutDown() : AdornerWnd::Close::Start
[09/28 09:05:20,ArtOfTest.Runner.exe(9616:4),TestStudio] VisualDebugger.ShutDown() : VisualDebbuger::ShutDown::End
[09/28 09:05:20,ArtOfTest.Runner.exe(9616:4),Framework] BrowserProvisioner.EndService() : Connecting to the provisioning pipe "Pipe.ArtOfTest.WebAii.BrowserProvisioner_-997853167" in order to unblock and eventually terminate it...
[09/28 09:05:20,ArtOfTest.Runner.exe(9616:4),Framework] BrowserProvisioner.EndService() : Provisioning pipe connected.
[09/28 09:05:20,ArtOfTest.Runner.exe(9616:6),Framework] BrowserProvisioner.ServiceThread() : A new client has connected to the provisioning pipe but this will be treated as a termination cue.
[09/28 09:05:20,ArtOfTest.Runner.exe(9616:4),Framework] BrowserProvisioner.EndService() : Provisioning thread terminated.
[09/28 09:05:20,ArtOfTest.Runner.exe(9616:4),DialogPlayback] DialogMonitor.AddDialog() : Added {OnBeforeUnloadDialog(Dismiss=OK)} for monitoring.
[09/28 09:05:20,ArtOfTest.Runner.exe(9616:4),Framework] Manager.RemoveBrowser() : Removed client ID "Client_1ecb1b14-2119-4221-9559-09bbf49b89b0" (1 remaining).
[09/28 09:05:20,ArtOfTest.Runner.exe(9616:4),Framework] BrowserRemoted.End() : Now aborting the listener thread (TID = 13).
[09/28 09:05:20,IEXPLORE.EXE(1044:1),Framework] BrowserRemoteClient.Disconnect() : Sending ClientDisconnectRequest(ClientId="Client_1ecb1b14-2119-4221-9559-09bbf49b89b0") to pipe server...
[09/28 09:05:20,IEXPLORE.EXE(1044:1),Framework] BrowserRemoteClient.Disconnect() : Command pipe is already disconnected.
[09/28 09:05:20,ArtOfTest.Runner.exe(9616:13),Warning] PipeCommunication.SyncPipeRead() : Pipe read broken by thread-abort.
[09/28 09:05:23,ArtOfTest.Runner.exe(9616:4),DialogPlayback] DialogMonitor.AddDialog() : Added {OnBeforeUnloadDialog(Dismiss=OK)} for monitoring.
[09/28 09:05:23,ArtOfTest.Runner.exe(9616:4),Framework] Manager.RemoveBrowser() : Removed client ID "Client_7e060604-61ca-461c-a0b6-c1c0bc24f198" (0 remaining).
[09/28 09:05:23,ArtOfTest.Runner.exe(9616:4),Framework] Manager.SetActiveBrowser() : Active browser is now null.
[09/28 09:05:24,ArtOfTest.Runner.exe(9616:4),Framework] BrowserRemoted.End() : Now aborting the listener thread (TID = 19).
[09/28 09:05:24,IEXPLORE.EXE(1044:14),Framework] BrowserRemoteClient.Disconnect() : Sending ClientDisconnectRequest(ClientId="Client_7e060604-61ca-461c-a0b6-c1c0bc24f198") to pipe server...
[09/28 09:05:24,IEXPLORE.EXE(1044:14),Framework] BrowserRemoteClient.Disconnect() : Command pipe is already disconnected.
[09/28 09:05:24,ArtOfTest.Runner.exe(9616:19),Warning] PipeCommunication.SyncPipeRead() : Pipe read broken by thread-abort.
[09/28 09:05:24,ArtOfTest.Runner.exe(9616:4),DialogPlayback] DialogMonitor.Stop() : Dialog monitoring is being halted.
[09/28 09:05:24,ArtOfTest.Runner.exe(9616:4),UIAutomation] AutomationExtensions.AddAutomationEventHandler() : Added a(n) WindowPatternIdentifiers.WindowOpenedProperty handler on element System.Windows.Automation.AutomationElement scope Descendants.
[09/28 09:05:25,Telerik.TestStudio.Desktop.exe(7716:6)] First trace message from background thread "Callback Listener Thread" (managed ID = 6, native ID = 7884).
[09/28 09:05:25,Telerik.TestStudio.Desktop.exe(7716:6),Warning] PipeCommunication.SyncPipeRead() : Pipe was already disposed at the moment of the read attempt.
