[07/02 10:11:47,Telerik.TestStudio.Scheduling.Client.exe(5348:7)] First trace message from pool unnamed thread (managed ID = 7, native ID = 6324).
[07/02 10:11:47,Telerik.TestStudio.Scheduling.Client.exe(5348:7),Framework] FireFoxInstallation.EnumFireFoxInstallations() : Valid Firefox 43.0.0.1 (x86) installation found at "C:\Program Files (x86)\Mozilla Firefox\firefox.exe".
[07/02 10:11:47,Telerik.TestStudio.Scheduling.Client.exe(5348:7),Framework] FireFoxInstallation.EnumFireFoxInstallations() : Valid Firefox 67.0.0.0 (x64) installation found at "C:\Program Files\Mozilla Firefox\firefox.exe".
[07/02 10:11:47,Telerik.TestStudio.Scheduling.Client.exe(5348:7),Framework] FireFoxInstallation.GetExecutableData() : Automatically selected Firefox 67.0.0.0 (x64).
[07/02 10:11:57,Telerik.TestStudio.Desktop.exe(5972:26),TestStudio] StorageUploader.UploadToCloudAsync() : ------- Function Start -------
[07/02 10:11:58,Telerik.TestStudio.Desktop.exe(5972:19)] First trace message from pool unnamed thread (managed ID = 19, native ID = 5184).
[07/02 10:11:58,Telerik.TestStudio.Desktop.exe(5972:19),TestStudio] StorageUploader.UploadToCloudAsync() : ------- Function End -------
[07/02 10:12:09,Telerik.TestStudio.RemoteExecutor.exe(424:28)] First trace message from pool unnamed thread (managed ID = 28, native ID = 2024).
[07/02 10:12:09,Telerik.TestStudio.RemoteExecutor.exe(424:28),Execution] RemoteExecutorPipeClient.StartTest() : Test run requested for test with path 'Login to myTASB.tstest'. Project path: 'C:\TASB\TFS\Test Automation\Enterprise Smoke Test Suite'.
[07/02 10:12:09,Telerik.TestStudio.RemoteExecutor.exe(424:23)] First trace message from pool unnamed thread (managed ID = 23, native ID = 5304).
[07/02 10:12:09,Telerik.TestStudio.RemoteExecutor.exe(424:23),Warning] CloudRunnableGUITestBuilder.CreateLocalCacheAsync() : Started getting project from storage
[07/02 10:12:09,Telerik.TestStudio.RemoteExecutor.exe(424:26)] First trace message from pool unnamed thread (managed ID = 26, native ID = 3276).
[07/02 10:12:09,Telerik.TestStudio.RemoteExecutor.exe(424:26),Execution] CloudStorageProjectOpener.DownloadAndSaveTestsAsync() : ------- Function Start -------
[07/02 10:12:17,Telerik.TestStudio.RemoteExecutor.exe(424:12)] First trace message from pool unnamed thread (managed ID = 12, native ID = 6720).
[07/02 10:12:17,Telerik.TestStudio.RemoteExecutor.exe(424:12),Execution] CloudStorageProjectOpener.DownloadAndSaveTestsAsync() : ------- Function End -------
[07/02 10:12:17,Telerik.TestStudio.RemoteExecutor.exe(424:12),Warning] CloudRunnableGUITestBuilder.CreateLocalCacheAsync() : Finished getting project from storage
[07/02 10:12:17,Telerik.TestStudio.RemoteExecutor.exe(424:36)] First trace message from pool unnamed thread (managed ID = 36, native ID = 4576).
[07/02 10:12:17,Telerik.TestStudio.RemoteExecutor.exe(424:36),TestStudio] <>c__DisplayClass35_0.<LoadInternal>b__1() : Extract elements time: 00:00:00.2940972
[07/02 10:12:17,Telerik.TestStudio.RemoteExecutor.exe(424:36),TestStudio] <>c__DisplayClass35_0.<LoadInternal>b__1() : Merge elements time: 00:00:00.0734312
[07/02 10:12:17,Telerik.TestStudio.RemoteExecutor.exe(424:36),TestStudio] <>c__DisplayClass35_0.<LoadInternal>b__1() : Total Elements count: 169
[07/02 10:12:18,ArtOfTest.Runner.exe(4744:3)] First trace message from process 4744: "C:\Program Files (x86)\Progress\Test Studio\Bin\ArtOfTest.Runner.exe"  /controller /parentprocesshandle=3076
[07/02 10:12:18,ArtOfTest.Runner.exe(4744:3)] First trace message from foreground thread "Remote.ArtOfTest.Runner.Listener" (managed ID = 3, native ID = 772).
[07/02 10:12:18,ArtOfTest.Runner.exe(4744:3),TestStudio] Loader.LoadTranslatorGroups() : ------- Function Start -------
[07/02 10:12:19,ArtOfTest.Runner.exe(4744:3),TestStudio] Loader.LoadTranslatorGroups() : ------- Function End -------
[07/02 10:12:19,ArtOfTest.Runner.exe(4744:4)] First trace message from pool unnamed thread (managed ID = 4, native ID = 5956).
[07/02 10:12:19,ArtOfTest.Runner.exe(4744:4),TestStudio] Manager.GetDialogMatchProvider() : The dialog matches assembly loaded successfully.
[07/02 10:12:19,ArtOfTest.Runner.exe(4744:4),Framework] Manager.KillNonAutomatedBrowsers() : Closing non-automated 'iexplore' browser instances
[07/02 10:12:19,ArtOfTest.Runner.exe(4744:4),Framework] InternetExplorerActions.LaunchNewBrowserInstance() : Process launched (ID=7048, Path="C:\Program Files\Internet Explorer\iexplore.exe", Arguments="-nomerge about:blank").
[07/02 10:12:19,ArtOfTest.Runner.exe(4744:4),Framework] InternetExplorerActions.LaunchNewBrowserInstance() : Attempting to attach on IE frame (HWND=788074)...
[07/02 10:12:20,ArtOfTest.Runner.exe(4744:4),Framework] Connector.Start() : Process launched (ID=2664, Path="C:\Windows\SysWOW64\rundll32.exe", Arguments=""C:\Program Files (x86)\Progress\Test Studio\Bin\ArtOfTest.Connector.dll",AttachToWindowEntryPoint 329302").
[07/02 10:12:20,ArtOfTest.Runner.exe(4744:4),Framework] Connector.Start() : Process exited (ID=2664, ExitCode=0, Path="C:\Windows\SysWOW64\rundll32.exe", Arguments=""C:\Program Files (x86)\Progress\Test Studio\Bin\ArtOfTest.Connector.dll",AttachToWindowEntryPoint 329302").
[07/02 10:12:20,ArtOfTest.Runner.exe(4744:4),Framework] Connector.InjectCode() : ------- Function Start -------
[07/02 10:12:20,ArtOfTest.Runner.exe(4744:4),Framework] Connector.InjectCode() : Connection string: C:\Windows\Microsoft.Net\assembly\GAC_MSIL\ArtOfTest.InternetExplorer\v4.0_2019.1.408.0__5339893a7cefe4d6\ArtOfTest.InternetExplorer.dll?ArtOfTest.InternetExplorer.ArtOfTestPluginEntryPoint?424
[07/02 10:12:20,ArtOfTest.Runner.exe(4744:4),Framework] InternetExplorerActions.WaitForDocument() : Attempting to get IHTMLDocument2 from Internet Explorer HWND 329302 on another thread...
[07/02 10:12:20,ArtOfTest.Runner.exe(4744:9)] First trace message from pool thread "WaitAsync polling wait worker" (managed ID = 9, native ID = 4000).
[07/02 10:12:20,ArtOfTest.Runner.exe(4744:9),Framework] <>c__DisplayClass26_3.<WaitForDocument>b__1() : Attempting to get document from window handle
[07/02 10:12:20,ArtOfTest.Runner.exe(4744:4),Framework] InternetExplorerActions.WaitForDocument() : IHTMLDocument2 successfully retrieved.
[07/02 10:12:20,ArtOfTest.Runner.exe(4744:4),Framework] Connector.InjectCode() : Sending WM_COPYDATA to HWND 329302 (lpData = "C:\Windows\Microsoft.Net\assembly\GAC_MSIL\ArtOfTest.InternetExplorer\v4.0_2019.1.408.0__5339893a7cefe4d6\ArtOfTest.InternetExplorer.dll?ArtOfTest.InternetExplorer.ArtOfTestPluginEntryPoint?424")...
[07/02 10:12:20,IEXPLORE.EXE(7052:1)] First trace message from process 7052: "C:\Program Files (x86)\Internet Explorer\IEXPLORE.EXE" SCODEF:7048 CREDAT:275457 /prefetch:2
[07/02 10:12:20,IEXPLORE.EXE(7052:1)] First trace message from background unnamed thread (managed ID = 1, native ID = 5236).
[07/02 10:12:20,IEXPLORE.EXE(7052:1),Framework] ArtOfTestPluginEntryPoint.Connect() : The .NET Framework is now active inside the hooked process (HWND = 329302, PipeName = "Pipe.ArtOfTest.WebAii.BrowserProvisioner_-1493991063", PID = 424).
[07/02 10:12:20,IEXPLORE.EXE(7052:1),Framework] InternetExplorerActions.WaitForDocument() : Attempting to get IHTMLDocument2 from Internet Explorer HWND 329302 on same thread...
[07/02 10:12:21,IEXPLORE.EXE(7052:1),Framework] InternetExplorerActions.WaitForDocument() : IHTMLDocument2 successfully retrieved.
[07/02 10:12:21,IEXPLORE.EXE(7052:1),Framework] BrowserRemoteClient constructor : The client ID for this hooked process will be "Client_3b08f71d-2d2a-4506-bda1-57ed1419ad2a".
[07/02 10:12:21,ArtOfTest.Runner.exe(4744:6)] First trace message from background thread "Pipe.ArtOfTest.WebAii.BrowserProvisioner" (managed ID = 6, native ID = 5776).
[07/02 10:12:21,ArtOfTest.Runner.exe(4744:6),Framework] BrowserProvisioner.ServiceThread() : A new client has connected to the provisioning pipe.
[07/02 10:12:21,ArtOfTest.Runner.exe(4744:13)] First trace message from background thread "Remoted async command listener" (managed ID = 13, native ID = 6148).
[07/02 10:12:21,ArtOfTest.Runner.exe(4744:13),Framework] BrowserRemoted.AsyncListenerThreadEntry() : Not connected; this will now wait indefinitely for a pipe client connection...
[07/02 10:12:21,IEXPLORE.EXE(7052:1),Framework] BrowserRemoteClient.CreateNamedPipes() : Named pipes created for communication with the server: {Command = "1063c40f-4a97-4539-8296-ff31678c887d.CommandPipe", Async = "1063c40f-4a97-4539-8296-ff31678c887d.AsyncPipe"}
[07/02 10:12:21,ArtOfTest.Runner.exe(4744:13),Framework] BrowserRemoted.AsyncListenerThreadEntry() : A new client has connected to the pipe.
[07/02 10:12:21,ArtOfTest.Runner.exe(4744:13),Framework] BrowserRemoted.AsyncListenerThreadEntry() : Now waiting indefinitely for a command from the pipe client...
[07/02 10:12:21,ArtOfTest.Runner.exe(4744:6),Framework] Manager.AddNewBrowser() : Added client ID "Client_3b08f71d-2d2a-4506-bda1-57ed1419ad2a" (now there is/are 1 total).
[07/02 10:12:21,ArtOfTest.Runner.exe(4744:4),Framework] Connector.InjectCode() : Returned from sending WM_COPYDATA (LRESULT=0, GetLastError()=ERROR_ALREADY_EXISTS).
[07/02 10:12:21,ArtOfTest.Runner.exe(4744:4),Framework] Connector.InjectCode() : ------- Function End -------
[07/02 10:12:21,ArtOfTest.Runner.exe(4744:4),Execution] ExecutionEngine.InternalExecuteTest() : Executing test: 'Login to myTASB', path: 'Login to myTASB.tstest.'
[07/02 10:12:21,ArtOfTest.Runner.exe(4744:4),Execution] ExtensionsManager.LoadExtensions() : ------- Function Start -------
[07/02 10:12:21,ArtOfTest.Runner.exe(4744:4),Uncategorized] ExtensionsManager.LoadExtensions() : Got 14 plugin(s) in folder "C:\Program Files (x86)\Progress\Test Studio\Bin\Plugins".
[07/02 10:12:21,ArtOfTest.Runner.exe(4744:4),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "LibGit2Sharp.dll" from plugins folder.
[07/02 10:12:21,ArtOfTest.Runner.exe(4744:4),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.Functional.dll" from plugins folder.
[07/02 10:12:21,ArtOfTest.Runner.exe(4744:4),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.Jira.BugTracking.dll" from plugins folder.
[07/02 10:12:21,ArtOfTest.Runner.exe(4744:4),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.Load.dll" from plugins folder.
[07/02 10:12:21,ArtOfTest.Runner.exe(4744:4),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.Manual.dll" from plugins folder.
[07/02 10:12:21,ArtOfTest.Runner.exe(4744:4),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.OTA.Interop.dll" from plugins folder.
[07/02 10:12:21,ArtOfTest.Runner.exe(4744:4),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.Plugins.dll" from plugins folder.
[07/02 10:12:21,ArtOfTest.Runner.exe(4744:4),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.ScreenRecorderExtension.dll" from plugins folder.
[07/02 10:12:21,ArtOfTest.Runner.exe(4744:4),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.Shared.dll" from plugins folder.
[07/02 10:12:21,ArtOfTest.Runner.exe(4744:4),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.SourceControl.dll" from plugins folder.
[07/02 10:12:21,ArtOfTest.Runner.exe(4744:4),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.SourceControl.Git.dll" from plugins folder.
[07/02 10:12:21,ArtOfTest.Runner.exe(4744:4),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.SourceControl.Git.TS.dll" from plugins folder.
[07/02 10:12:21,ArtOfTest.Runner.exe(4744:4),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.Web.dll" from plugins folder.
[07/02 10:12:21,ArtOfTest.Runner.exe(4744:4),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.Wpf.dll" from plugins folder.
[07/02 10:12:21,ArtOfTest.Runner.exe(4744:4),Execution] ExtensionsManager.LoadExtensions() : ------- Function End -------
[07/02 10:12:21,ArtOfTest.Runner.exe(4744:4),TestStudio] VisualDebugger.SetCurrentResult() : VisualDebugger::SetCurrentResult
[07/02 10:12:21,ArtOfTest.Runner.exe(4744:4),DialogPlayback] DialogMonitor.Start() : Beginning monitoring for dialogs from the set {}.
[07/02 10:12:21,ArtOfTest.Runner.exe(4744:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[07/02 10:12:21,ArtOfTest.Runner.exe(4744:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 1 "Navigate to : '' - DataDriven: [$(URL)][$(URL)]"...
[07/02 10:12:21,ArtOfTest.Runner.exe(4744:4),Uncategorized] Log.WriteLineInternal() : ENVIRONMENT :PROD
[07/02 10:12:23,ArtOfTest.Runner.exe(4744:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 1 "Navigate to : '' - DataDriven: [$(URL)][$(URL)]".
[07/02 10:12:23,ArtOfTest.Runner.exe(4744:4),TestStudio] VisualDebugger.ShutDown() : VisualDebbuger::ShutDown::Start
[07/02 10:12:23,ArtOfTest.Runner.exe(4744:4),TestStudio] VisualDebugger.ShutDown() : VisualDebbuger::ShutDown::End
[07/02 10:12:23,ArtOfTest.Runner.exe(4744:4),Framework] BrowserProvisioner.EndService() : Connecting to the provisioning pipe "Pipe.ArtOfTest.WebAii.BrowserProvisioner_-1493991063" in order to unblock and eventually terminate it...
[07/02 10:12:23,ArtOfTest.Runner.exe(4744:4),Framework] BrowserProvisioner.EndService() : Provisioning pipe connected.
[07/02 10:12:23,ArtOfTest.Runner.exe(4744:6),Framework] BrowserProvisioner.ServiceThread() : A new client has connected to the provisioning pipe but this will be treated as a termination cue.
[07/02 10:12:23,ArtOfTest.Runner.exe(4744:4),Framework] BrowserProvisioner.EndService() : Provisioning thread terminated.
[07/02 10:12:23,ArtOfTest.Runner.exe(4744:4),DialogPlayback] DialogMonitor.AddDialog() : Added {OnBeforeUnloadDialog(Dismiss=OK)} for monitoring.
[07/02 10:12:23,ArtOfTest.Runner.exe(4744:4),Framework] Manager.RemoveBrowser() : Removed client ID "Client_3b08f71d-2d2a-4506-bda1-57ed1419ad2a" (0 remaining).
[07/02 10:12:23,ArtOfTest.Runner.exe(4744:4),Framework] Manager.SetActiveBrowser() : Active browser is now null.
[07/02 10:12:23,ArtOfTest.Runner.exe(4744:4),Framework] BrowserRemoted.End() : Now aborting the listener thread (TID = 13).
[07/02 10:12:23,IEXPLORE.EXE(7052:1),Framework] BrowserRemoteClient.Disconnect() : Sending ClientDisconnectRequest(ClientId="Client_3b08f71d-2d2a-4506-bda1-57ed1419ad2a") to pipe server...
[07/02 10:12:23,IEXPLORE.EXE(7052:1),Framework] BrowserRemoteClient.Disconnect() : Command pipe is already disconnected.
[07/02 10:12:23,ArtOfTest.Runner.exe(4744:13),Warning] PipeCommunication.SyncPipeRead() : Pipe read broken by thread-abort.
[07/02 10:12:23,ArtOfTest.Runner.exe(4744:18)] First trace message from foreground thread "AnnotatorUIThread" (managed ID = 18, native ID = 6892).
[07/02 10:12:23,ArtOfTest.Runner.exe(4744:18),Uncategorized] AdornerWindow.ShutDown() : AdornerWnd::ShutDown::Start
[07/02 10:12:23,ArtOfTest.Runner.exe(4744:18),Uncategorized] AdornerWindow.ShutDown() : AdornerWnd::LocationListener::Disposed
[07/02 10:12:23,ArtOfTest.Runner.exe(4744:18),Uncategorized] AdornerWindow.ShutDown() : AdornerWnd::DetachWrappedWindow::Start
[07/02 10:12:23,ArtOfTest.Runner.exe(4744:18),Uncategorized] AdornerWindow.ShutDown() : AdornerWnd::DetachWrappedWindow::End
[07/02 10:12:23,ArtOfTest.Runner.exe(4744:18),Uncategorized] AdornerWindow.ShutDown() : AdornerWnd::DispatcherBeginInvokeShutdown::Start
[07/02 10:12:23,ArtOfTest.Runner.exe(4744:18),Uncategorized] AdornerWindow.ShutDown() : AdornerWnd::Close::Start
[07/02 10:12:24,ArtOfTest.Runner.exe(4744:4),DialogPlayback] DialogMonitor.Stop() : Dialog monitoring is being halted.
[07/02 10:12:24,ArtOfTest.Runner.exe(4744:4),UIAutomation] AutomationExtensions.AddAutomationEventHandler() : Added a(n) WindowPatternIdentifiers.WindowOpenedProperty handler on element System.Windows.Automation.AutomationElement scope Descendants.
[07/02 10:12:24,Telerik.TestStudio.RemoteExecutor.exe(424:8)] First trace message from background thread "Callback Listener Thread" (managed ID = 8, native ID = 2252).
[07/02 10:12:24,Telerik.TestStudio.RemoteExecutor.exe(424:8),Execution] TestPlatform.FinishTestRunAndPublishResultsAsync() : Finished running test platform - attempting publish
[07/02 10:12:24,Telerik.TestStudio.RemoteExecutor.exe(424:8),Framework] FireFoxInstallation.EnumFireFoxInstallations() : Valid Firefox 43.0.0.1 (x86) installation found at "C:\Program Files (x86)\Mozilla Firefox\firefox.exe".
[07/02 10:12:24,Telerik.TestStudio.RemoteExecutor.exe(424:8),Framework] FireFoxInstallation.EnumFireFoxInstallations() : Valid Firefox 67.0.0.0 (x64) installation found at "C:\Program Files\Mozilla Firefox\firefox.exe".
[07/02 10:12:24,Telerik.TestStudio.RemoteExecutor.exe(424:8),Framework] FireFoxInstallation.GetExecutableData() : Automatically selected Firefox 67.0.0.0 (x64).
[07/02 10:12:29,Telerik.TestStudio.RemoteExecutor.exe(424:28),Execution] RemoteExecutorPipeClient.StartTest() : Test run requested for test with path 'myTASB_CA.tstest'. Project path: 'C:\TASB\TFS\Test Automation\Enterprise Smoke Test Suite'.
[07/02 10:12:29,ArtOfTest.Runner.exe(4744:5)] First trace message from pool unnamed thread (managed ID = 5, native ID = 2244).
[07/02 10:12:29,ArtOfTest.Runner.exe(4744:5),TestStudio] Manager.GetDialogMatchProvider() : The dialog matches assembly loaded successfully.
[07/02 10:12:29,ArtOfTest.Runner.exe(4744:5),Framework] Manager.KillNonAutomatedBrowsers() : Closing non-automated 'iexplore' browser instances
[07/02 10:12:29,ArtOfTest.Runner.exe(4744:5),Framework] InternetExplorerActions.LaunchNewBrowserInstance() : Process launched (ID=4824, Path="C:\Program Files\Internet Explorer\iexplore.exe", Arguments="-nomerge about:blank").
[07/02 10:12:30,ArtOfTest.Runner.exe(4744:5),Framework] InternetExplorerActions.LaunchNewBrowserInstance() : Attempting to attach on IE frame (HWND=329230)...
[07/02 10:12:30,ArtOfTest.Runner.exe(4744:5),Framework] Connector.Start() : Process launched (ID=1244, Path="C:\Windows\SysWOW64\rundll32.exe", Arguments=""C:\Program Files (x86)\Progress\Test Studio\Bin\ArtOfTest.Connector.dll",AttachToWindowEntryPoint 329346").
[07/02 10:12:30,ArtOfTest.Runner.exe(4744:5),Framework] Connector.Start() : Process exited (ID=1244, ExitCode=0, Path="C:\Windows\SysWOW64\rundll32.exe", Arguments=""C:\Program Files (x86)\Progress\Test Studio\Bin\ArtOfTest.Connector.dll",AttachToWindowEntryPoint 329346").
[07/02 10:12:30,ArtOfTest.Runner.exe(4744:5),Framework] Connector.InjectCode() : ------- Function Start -------
[07/02 10:12:30,ArtOfTest.Runner.exe(4744:5),Framework] Connector.InjectCode() : Connection string: C:\Windows\Microsoft.Net\assembly\GAC_MSIL\ArtOfTest.InternetExplorer\v4.0_2019.1.408.0__5339893a7cefe4d6\ArtOfTest.InternetExplorer.dll?ArtOfTest.InternetExplorer.ArtOfTestPluginEntryPoint?424
[07/02 10:12:30,ArtOfTest.Runner.exe(4744:5),Framework] InternetExplorerActions.WaitForDocument() : Attempting to get IHTMLDocument2 from Internet Explorer HWND 329346 on another thread...
[07/02 10:12:30,ArtOfTest.Runner.exe(4744:4)] First trace message from pool thread "WaitAsync polling wait worker" (managed ID = 4, native ID = 5956).
[07/02 10:12:30,ArtOfTest.Runner.exe(4744:4),Framework] <>c__DisplayClass26_3.<WaitForDocument>b__1() : Attempting to get document from window handle
[07/02 10:12:30,ArtOfTest.Runner.exe(4744:5),Framework] InternetExplorerActions.WaitForDocument() : IHTMLDocument2 successfully retrieved.
[07/02 10:12:30,ArtOfTest.Runner.exe(4744:5),Framework] Connector.InjectCode() : Sending WM_COPYDATA to HWND 329346 (lpData = "C:\Windows\Microsoft.Net\assembly\GAC_MSIL\ArtOfTest.InternetExplorer\v4.0_2019.1.408.0__5339893a7cefe4d6\ArtOfTest.InternetExplorer.dll?ArtOfTest.InternetExplorer.ArtOfTestPluginEntryPoint?424")...
[07/02 10:12:30,IEXPLORE.EXE(7104:1)] First trace message from process 7104: "C:\Program Files (x86)\Internet Explorer\IEXPLORE.EXE" SCODEF:4824 CREDAT:275457 /prefetch:2
[07/02 10:12:30,IEXPLORE.EXE(7104:1)] First trace message from background unnamed thread (managed ID = 1, native ID = 2788).
[07/02 10:12:30,IEXPLORE.EXE(7104:1),Framework] ArtOfTestPluginEntryPoint.Connect() : The .NET Framework is now active inside the hooked process (HWND = 329346, PipeName = "Pipe.ArtOfTest.WebAii.BrowserProvisioner_-889009336", PID = 424).
[07/02 10:12:30,IEXPLORE.EXE(7104:1),Framework] InternetExplorerActions.WaitForDocument() : Attempting to get IHTMLDocument2 from Internet Explorer HWND 329346 on same thread...
[07/02 10:12:31,IEXPLORE.EXE(7104:1),Framework] InternetExplorerActions.WaitForDocument() : IHTMLDocument2 successfully retrieved.
[07/02 10:12:31,IEXPLORE.EXE(7104:1),Framework] BrowserRemoteClient constructor : The client ID for this hooked process will be "Client_fbf7841f-99ea-49d0-9b4c-87a35ce05257".
[07/02 10:12:31,ArtOfTest.Runner.exe(4744:15)] First trace message from background thread "Pipe.ArtOfTest.WebAii.BrowserProvisioner" (managed ID = 15, native ID = 6900).
[07/02 10:12:31,ArtOfTest.Runner.exe(4744:15),Framework] BrowserProvisioner.ServiceThread() : A new client has connected to the provisioning pipe.
[07/02 10:12:31,ArtOfTest.Runner.exe(4744:7)] First trace message from background thread "Remoted async command listener" (managed ID = 7, native ID = 3228).
[07/02 10:12:31,ArtOfTest.Runner.exe(4744:7),Framework] BrowserRemoted.AsyncListenerThreadEntry() : Not connected; this will now wait indefinitely for a pipe client connection...
[07/02 10:12:31,IEXPLORE.EXE(7104:1),Framework] BrowserRemoteClient.CreateNamedPipes() : Named pipes created for communication with the server: {Command = "077bb02d-486a-4022-9291-e026587e1647.CommandPipe", Async = "077bb02d-486a-4022-9291-e026587e1647.AsyncPipe"}
[07/02 10:12:31,ArtOfTest.Runner.exe(4744:7),Framework] BrowserRemoted.AsyncListenerThreadEntry() : A new client has connected to the pipe.
[07/02 10:12:31,ArtOfTest.Runner.exe(4744:7),Framework] BrowserRemoted.AsyncListenerThreadEntry() : Now waiting indefinitely for a command from the pipe client...
[07/02 10:12:31,ArtOfTest.Runner.exe(4744:15),Framework] Manager.AddNewBrowser() : Added client ID "Client_fbf7841f-99ea-49d0-9b4c-87a35ce05257" (now there is/are 1 total).
[07/02 10:12:31,ArtOfTest.Runner.exe(4744:5),Framework] Connector.InjectCode() : Returned from sending WM_COPYDATA (LRESULT=0, GetLastError()=ERROR_ALREADY_EXISTS).
[07/02 10:12:31,ArtOfTest.Runner.exe(4744:5),Framework] Connector.InjectCode() : ------- Function End -------
[07/02 10:12:31,ArtOfTest.Runner.exe(4744:5),Execution] ExecutionEngine.InternalExecuteTest() : Executing test: 'myTASB_CA', path: 'myTASB_CA.tstest.'
[07/02 10:12:31,ArtOfTest.Runner.exe(4744:5),TestStudio] VisualDebugger.SetCurrentResult() : VisualDebugger::SetCurrentResult
[07/02 10:12:31,ArtOfTest.Runner.exe(4744:5),DialogPlayback] DialogMonitor.Start() : Beginning monitoring for dialogs from the set {}.
[07/02 10:12:31,ArtOfTest.Runner.exe(4744:5),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[07/02 10:12:31,ArtOfTest.Runner.exe(4744:5),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 1 "Navigate to : '' - myTASB"...
[07/02 10:12:31,ArtOfTest.Runner.exe(4744:5),Uncategorized] Log.WriteLineInternal() : ENVIRONMENT :PROD
[07/02 10:12:32,ArtOfTest.Runner.exe(4744:5),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 1 "Navigate to : '' - myTASB".
[07/02 10:12:32,ArtOfTest.Runner.exe(4744:5),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[07/02 10:12:32,ArtOfTest.Runner.exe(4744:5),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 2 "Click 'ForgotUserLink'"...
[07/02 10:12:32,IEXPLORE.EXE(7104:4)] First trace message from background thread "IECommandProcessor Dispatcher Thread" (managed ID = 4, native ID = 5096).
[07/02 10:12:32,IEXPLORE.EXE(7104:4),Framework] IEFramesCollection.BuildFramesInternal() : EXCEPTION! (see below)
     Situation: Error retrieving document from frame
     Outer Exception Type: System.UnauthorizedAccessException
     Message: Access is denied. (Exception from HRESULT: 0x80070005 (E_ACCESSDENIED))
     HRESULT: 0x80070005 (Official ID (if app.) = COR_E_UNAUTHORIZEDACCESS, Error Bit = FAILED, Facility = FACILITY_WIN32, Code = ERROR_ACCESS_DENIED)
     Call Stack:
          at System.RuntimeType.ForwardCallToInvokeMember(String memberName, BindingFlags flags, Object target, Int32[] aWrapperTypes, MessageData& msgData)
          at mshtml.DispHTMLFrameElement.getBoundingClientRect()
          at ArtOfTest.InternetExplorer.IEFramesCollection.IsBrowserValid(IWebBrowser2 browser)
          at ArtOfTest.InternetExplorer.IEFramesCollection.BuildFramesInternal(IHTMLDocument2 topDocument, String parentID, Dictionary`2 frames)

[07/02 10:12:32,ArtOfTest.Runner.exe(4744:5),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 2 "Click 'ForgotUserLink'".
[07/02 10:12:32,ArtOfTest.Runner.exe(4744:5),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[07/02 10:12:32,ArtOfTest.Runner.exe(4744:5),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 3 "Enter text '' in 'UserIDText' - DataDriven: [$(StageUserID)]"...
[07/02 10:12:32,ArtOfTest.Runner.exe(4744:5),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 3 "Enter text '' in 'UserIDText' - DataDriven: [$(StageUserID)]".
[07/02 10:12:32,ArtOfTest.Runner.exe(4744:5),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[07/02 10:12:32,ArtOfTest.Runner.exe(4744:5),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 4 "Verify 'TextContent' 'Contains' 'Forgot User ID or Password' on 'ForgotUserSpan'"...
[07/02 10:12:32,ArtOfTest.Runner.exe(4744:5),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 4 "Verify 'TextContent' 'Contains' 'Forgot User ID or Password' on 'ForgotUserSpan'".
[07/02 10:12:32,ArtOfTest.Runner.exe(4744:5),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[07/02 10:12:32,ArtOfTest.Runner.exe(4744:5),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 5 "Click 'SubmitButtonSubmit'"...
[07/02 10:12:32,ArtOfTest.Runner.exe(4744:5),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 5 "Click 'SubmitButtonSubmit'".
[07/02 10:12:32,ArtOfTest.Runner.exe(4744:5),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[07/02 10:12:32,ArtOfTest.Runner.exe(4744:5),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 6 "Verify 'TextContent' 'Contains' 'Please verify your identity by answering the security question below.If you cannot remember your answer, please.' on 'PleaseVerifyTableCell'"...
[07/02 10:12:32,ArtOfTest.Runner.exe(4744:5),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 6 "Verify 'TextContent' 'Contains' 'Please verify your identity by answering the security question below.If you cannot remember your answer, please.' on 'PleaseVerifyTableCell'".
[07/02 10:12:32,ArtOfTest.Runner.exe(4744:5),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[07/02 10:12:32,ArtOfTest.Runner.exe(4744:5),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 7 "Click 'CancelButton'"...
[07/02 10:12:33,ArtOfTest.Runner.exe(4744:5),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 7 "Click 'CancelButton'".
[07/02 10:12:33,ArtOfTest.Runner.exe(4744:5),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[07/02 10:12:33,ArtOfTest.Runner.exe(4744:5),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 8 "Verify 'TextContent' 'Contains' 'Need help?' on 'NeedHelpLink'"...
[07/02 10:12:33,ArtOfTest.Runner.exe(4744:5),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 8 "Verify 'TextContent' 'Contains' 'Need help?' on 'NeedHelpLink'".
[07/02 10:12:33,ArtOfTest.Runner.exe(4744:5),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[07/02 10:12:33,ArtOfTest.Runner.exe(4744:5),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 9 "Click 'NeedHelpLink'"...
[07/02 10:12:33,ArtOfTest.Runner.exe(4744:5),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 9 "Click 'NeedHelpLink'".
[07/02 10:12:33,ArtOfTest.Runner.exe(4744:5),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[07/02 10:12:33,ArtOfTest.Runner.exe(4744:5),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 10 "Enter text 'AutoTestUser' in 'NameText'"...
[07/02 10:12:33,ArtOfTest.Runner.exe(4744:5),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 10 "Enter text 'AutoTestUser' in 'NameText'".
[07/02 10:12:33,ArtOfTest.Runner.exe(4744:5),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[07/02 10:12:33,ArtOfTest.Runner.exe(4744:5),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 11 "Enter text 'TASB' in 'DistrictOrgText'"...
[07/02 10:12:33,ArtOfTest.Runner.exe(4744:5),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 11 "Enter text 'TASB' in 'DistrictOrgText'".
[07/02 10:12:33,ArtOfTest.Runner.exe(4744:5),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[07/02 10:12:33,ArtOfTest.Runner.exe(4744:5),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 12 "Enter text '' in 'EmailText' - DataDriven: [$(stageemail)]"...
[07/02 10:12:33,ArtOfTest.Runner.exe(4744:5),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 12 "Enter text '' in 'EmailText' - DataDriven: [$(stageemail)]".
[07/02 10:12:33,ArtOfTest.Runner.exe(4744:5),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[07/02 10:12:33,ArtOfTest.Runner.exe(4744:5),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 13 "Enter text 'Tester' in 'TitleText'"...
[07/02 10:12:33,ArtOfTest.Runner.exe(4744:5),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 13 "Enter text 'Tester' in 'TitleText'".
[07/02 10:12:33,ArtOfTest.Runner.exe(4744:5),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[07/02 10:12:33,ArtOfTest.Runner.exe(4744:5),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 14 "Select 'ByValue' option 'Other' on 'PositionSelect'"...
[07/02 10:12:33,ArtOfTest.Runner.exe(4744:5),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 14 "Select 'ByValue' option 'Other' on 'PositionSelect'".
[07/02 10:12:33,ArtOfTest.Runner.exe(4744:5),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[07/02 10:12:33,ArtOfTest.Runner.exe(4744:5),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 15 "Enter text 'smoke test' in 'MessageTextArea'"...
[07/02 10:12:34,ArtOfTest.Runner.exe(4744:5),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 15 "Enter text 'smoke test' in 'MessageTextArea'".
[07/02 10:12:34,ArtOfTest.Runner.exe(4744:5),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[07/02 10:12:34,ArtOfTest.Runner.exe(4744:5),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 16 "Click 'SubmitButtonSubmit'"...
[07/02 10:12:35,ArtOfTest.Runner.exe(4744:5),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 16 "Click 'SubmitButtonSubmit'".
[07/02 10:12:35,ArtOfTest.Runner.exe(4744:5),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[07/02 10:12:35,ArtOfTest.Runner.exe(4744:5),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 17 "Verify 'TextContent' 'Contains' 'Confirmation' on 'ConfirmationSpan'"...
[07/02 10:12:35,ArtOfTest.Runner.exe(4744:5),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 17 "Verify 'TextContent' 'Contains' 'Confirmation' on 'ConfirmationSpan'".
[07/02 10:12:35,ArtOfTest.Runner.exe(4744:5),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[07/02 10:12:35,ArtOfTest.Runner.exe(4744:5),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 18 "Click 'OperationSubmit'"...
[07/02 10:12:35,ArtOfTest.Runner.exe(4744:5),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 18 "Click 'OperationSubmit'".
[07/02 10:12:35,ArtOfTest.Runner.exe(4744:5),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[07/02 10:12:35,ArtOfTest.Runner.exe(4744:5),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 19 "Click 'RequestAccessLink'"...
[07/02 10:12:36,ArtOfTest.Runner.exe(4744:5),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 19 "Click 'RequestAccessLink'".
[07/02 10:12:36,ArtOfTest.Runner.exe(4744:5),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[07/02 10:12:36,ArtOfTest.Runner.exe(4744:5),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 20 "Verify 'TextContent' 'Contains' 'Request Account Access' on 'RequestSpan'"...
[07/02 10:12:36,ArtOfTest.Runner.exe(4744:5),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 20 "Verify 'TextContent' 'Contains' 'Request Account Access' on 'RequestSpan'".
[07/02 10:12:36,ArtOfTest.Runner.exe(4744:5),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[07/02 10:12:36,ArtOfTest.Runner.exe(4744:5),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 21 "Enter text '' in 'NameText' - DataDriven: [$(UserName)]"...
[07/02 10:12:36,ArtOfTest.Runner.exe(4744:5),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 21 "Enter text '' in 'NameText' - DataDriven: [$(UserName)]".
[07/02 10:12:36,ArtOfTest.Runner.exe(4744:5),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[07/02 10:12:36,ArtOfTest.Runner.exe(4744:5),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 22 "Enter text 'TASB' in 'DistrictOrgText'"...
[07/02 10:12:36,ArtOfTest.Runner.exe(4744:5),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 22 "Enter text 'TASB' in 'DistrictOrgText'".
[07/02 10:12:36,ArtOfTest.Runner.exe(4744:5),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[07/02 10:12:36,ArtOfTest.Runner.exe(4744:5),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 23 "Enter text 'stage_autotester@tasb.org' in 'EmailText' - DataDriven: [$(stageemail)]"...
[07/02 10:12:36,ArtOfTest.Runner.exe(4744:5),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 23 "Enter text 'stage_autotester@tasb.org' in 'EmailText' - DataDriven: [$(stageemail)]".
[07/02 10:12:36,ArtOfTest.Runner.exe(4744:5),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[07/02 10:12:36,ArtOfTest.Runner.exe(4744:5),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 24 "Enter text 'Tester' in 'TitleText'"...
[07/02 10:12:36,ArtOfTest.Runner.exe(4744:5),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 24 "Enter text 'Tester' in 'TitleText'".
[07/02 10:12:36,ArtOfTest.Runner.exe(4744:5),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[07/02 10:12:36,ArtOfTest.Runner.exe(4744:5),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 25 "Click 'TableCell'"...
[07/02 10:12:36,ArtOfTest.Runner.exe(4744:5),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 25 "Click 'TableCell'".
[07/02 10:12:36,ArtOfTest.Runner.exe(4744:5),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[07/02 10:12:36,ArtOfTest.Runner.exe(4744:5),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 26 "Select 'ByValue' option 'Other' on 'PositionSelect'"...
[07/02 10:12:36,ArtOfTest.Runner.exe(4744:5),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 26 "Select 'ByValue' option 'Other' on 'PositionSelect'".
[07/02 10:12:36,ArtOfTest.Runner.exe(4744:5),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[07/02 10:12:36,ArtOfTest.Runner.exe(4744:5),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 27 "Check 'TASBServicesTypeList0IsSelectedCheckBox' to be 'True'"...
[07/02 10:12:36,ArtOfTest.Runner.exe(4744:5),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 27 "Check 'TASBServicesTypeList0IsSelectedCheckBox' to be 'True'".
[07/02 10:12:36,ArtOfTest.Runner.exe(4744:5),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[07/02 10:12:36,ArtOfTest.Runner.exe(4744:5),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 28 "Enter text 'smoke test -ignore request access Please Backspacei' in 'AdditionalInformationTextArea'"...
[07/02 10:12:36,ArtOfTest.Runner.exe(4744:5),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 28 "Enter text 'smoke test -ignore request access Please Backspacei' in 'AdditionalInformationTextArea'".
[07/02 10:12:36,ArtOfTest.Runner.exe(4744:5),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[07/02 10:12:36,ArtOfTest.Runner.exe(4744:5),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 29 "Click 'SubmitButtonSubmit'"...
[07/02 10:12:36,ArtOfTest.Runner.exe(4744:5),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 29 "Click 'SubmitButtonSubmit'".
[07/02 10:12:36,ArtOfTest.Runner.exe(4744:5),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[07/02 10:12:36,ArtOfTest.Runner.exe(4744:5),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 30 "Click 'LogOffLink'"...
[07/02 10:12:37,ArtOfTest.Runner.exe(4744:5),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 30 "Click 'LogOffLink'".
[07/02 10:12:37,ArtOfTest.Runner.exe(4744:5),TestStudio] VisualDebugger.ShutDown() : VisualDebbuger::ShutDown::Start
[07/02 10:12:37,ArtOfTest.Runner.exe(4744:5),TestStudio] VisualDebugger.ShutDown() : VisualDebbuger::ShutDown::End
[07/02 10:12:37,ArtOfTest.Runner.exe(4744:5),Framework] BrowserProvisioner.EndService() : Connecting to the provisioning pipe "Pipe.ArtOfTest.WebAii.BrowserProvisioner_-889009336" in order to unblock and eventually terminate it...
[07/02 10:12:37,ArtOfTest.Runner.exe(4744:5),Framework] BrowserProvisioner.EndService() : Provisioning pipe connected.
[07/02 10:12:37,ArtOfTest.Runner.exe(4744:15),Framework] BrowserProvisioner.ServiceThread() : A new client has connected to the provisioning pipe but this will be treated as a termination cue.
[07/02 10:12:37,ArtOfTest.Runner.exe(4744:5),Framework] BrowserProvisioner.EndService() : Provisioning thread terminated.
[07/02 10:12:37,ArtOfTest.Runner.exe(4744:5),DialogPlayback] DialogMonitor.AddDialog() : Added {OnBeforeUnloadDialog(Dismiss=OK)} for monitoring.
[07/02 10:12:37,ArtOfTest.Runner.exe(4744:5),Framework] Manager.RemoveBrowser() : Removed client ID "Client_fbf7841f-99ea-49d0-9b4c-87a35ce05257" (0 remaining).
[07/02 10:12:37,ArtOfTest.Runner.exe(4744:5),Framework] Manager.SetActiveBrowser() : Active browser is now null.
[07/02 10:12:37,ArtOfTest.Runner.exe(4744:5),Framework] BrowserRemoted.End() : Now aborting the listener thread (TID = 7).
[07/02 10:12:37,IEXPLORE.EXE(7104:1),Framework] BrowserRemoteClient.Disconnect() : Sending ClientDisconnectRequest(ClientId="Client_fbf7841f-99ea-49d0-9b4c-87a35ce05257") to pipe server...
[07/02 10:12:37,IEXPLORE.EXE(7104:1),Framework] BrowserRemoteClient.Disconnect() : Command pipe is already disconnected.
[07/02 10:12:37,ArtOfTest.Runner.exe(4744:7),Warning] PipeCommunication.SyncPipeRead() : Pipe read broken by thread-abort.
[07/02 10:12:37,ArtOfTest.Runner.exe(4744:23)] First trace message from foreground thread "AnnotatorUIThread" (managed ID = 23, native ID = 5716).
[07/02 10:12:37,ArtOfTest.Runner.exe(4744:23),Uncategorized] AdornerWindow.ShutDown() : AdornerWnd::ShutDown::Start
[07/02 10:12:37,ArtOfTest.Runner.exe(4744:23),Uncategorized] AdornerWindow.ShutDown() : AdornerWnd::LocationListener::Disposed
[07/02 10:12:37,ArtOfTest.Runner.exe(4744:23),Uncategorized] AdornerWindow.ShutDown() : AdornerWnd::DetachWrappedWindow::Start
[07/02 10:12:37,ArtOfTest.Runner.exe(4744:23),Uncategorized] AdornerWindow.ShutDown() : AdornerWnd::DetachWrappedWindow::End
[07/02 10:12:37,ArtOfTest.Runner.exe(4744:23),Uncategorized] AdornerWindow.ShutDown() : AdornerWnd::DispatcherBeginInvokeShutdown::Start
[07/02 10:12:37,ArtOfTest.Runner.exe(4744:23),Uncategorized] AdornerWindow.ShutDown() : AdornerWnd::Close::Start
[07/02 10:12:37,ArtOfTest.Runner.exe(4744:5),DialogPlayback] DialogMonitor.Stop() : Dialog monitoring is being halted.
[07/02 10:12:37,ArtOfTest.Runner.exe(4744:5),UIAutomation] AutomationExtensions.AddAutomationEventHandler() : Added a(n) WindowPatternIdentifiers.WindowOpenedProperty handler on element System.Windows.Automation.AutomationElement scope Descendants.
[07/02 10:12:37,Telerik.TestStudio.RemoteExecutor.exe(424:8),Execution] TestPlatform.FinishTestRunAndPublishResultsAsync() : Finished running test platform - attempting publish
[07/02 10:12:37,Telerik.TestStudio.RemoteExecutor.exe(424:8),Framework] FireFoxInstallation.EnumFireFoxInstallations() : Valid Firefox 43.0.0.1 (x86) installation found at "C:\Program Files (x86)\Mozilla Firefox\firefox.exe".
[07/02 10:12:37,Telerik.TestStudio.RemoteExecutor.exe(424:8),Framework] FireFoxInstallation.EnumFireFoxInstallations() : Valid Firefox 67.0.0.0 (x64) installation found at "C:\Program Files\Mozilla Firefox\firefox.exe".
[07/02 10:12:37,Telerik.TestStudio.RemoteExecutor.exe(424:8),Framework] FireFoxInstallation.GetExecutableData() : Automatically selected Firefox 67.0.0.0 (x64).
[07/02 10:17:54,Telerik.TestStudio.Desktop.exe(5972:1),Uncategorized] Orchestrator._iUIProject_TestSelected() : Test 'Login to myTASB' selected.
