[03/27 17:26:27,Telerik.TestStudio.RemoteExecutor.exe(6964:72)] First trace message from pool unnamed thread (managed ID = 72, native ID = 8400).
[03/27 17:26:27,Telerik.TestStudio.RemoteExecutor.exe(6964:72),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'.
[03/27 17:26:27,Telerik.TestStudio.RemoteExecutor.exe(6964:70)] First trace message from pool unnamed thread (managed ID = 70, native ID = 14264).
[03/27 17:26:27,Telerik.TestStudio.RemoteExecutor.exe(6964:70),Warning] CloudRunnableGUITestBuilder.CreateLocalCacheAsync() : Started getting project from storage
[03/27 17:26:27,Telerik.TestStudio.RemoteExecutor.exe(6964:64)] First trace message from pool unnamed thread (managed ID = 64, native ID = 10012).
[03/27 17:26:27,Telerik.TestStudio.RemoteExecutor.exe(6964:64),Execution] CloudStorageProjectOpener.DownloadAndSaveTestsAsync() : ------- Function Start -------
[03/27 17:26:33,Telerik.TestStudio.RemoteExecutor.exe(6964:5)] First trace message from pool unnamed thread (managed ID = 5, native ID = 11184).
[03/27 17:26:33,Telerik.TestStudio.RemoteExecutor.exe(6964:5),Execution] CloudStorageProjectOpener.DownloadAndSaveTestsAsync() : ------- Function End -------
[03/27 17:26:33,Telerik.TestStudio.RemoteExecutor.exe(6964:5),Warning] CloudRunnableGUITestBuilder.CreateLocalCacheAsync() : Finished getting project from storage
[03/27 17:26:34,Telerik.TestStudio.RemoteExecutor.exe(6964:5),TestStudio] <>c__DisplayClass35_1.<LoadInternal>b__2() : Extract elements time: 00:00:00.2688078
[03/27 17:26:34,Telerik.TestStudio.RemoteExecutor.exe(6964:5),TestStudio] <>c__DisplayClass35_1.<LoadInternal>b__2() : Merge elements time: 00:00:00.0632142
[03/27 17:26:34,Telerik.TestStudio.RemoteExecutor.exe(6964:5),TestStudio] <>c__DisplayClass35_1.<LoadInternal>b__2() : Total Elements count: 140
[03/27 17:26:35,ArtOfTest.Runner.exe(3876:3)] First trace message from process 3876: "C:\Program Files (x86)\Progress\Test Studio\Bin\ArtOfTest.Runner.exe"  /controller /parentprocesshandle=1408
[03/27 17:26:35,ArtOfTest.Runner.exe(3876:3)] First trace message from foreground thread "Remote.ArtOfTest.Runner.Listener" (managed ID = 3, native ID = 11456).
[03/27 17:26:35,ArtOfTest.Runner.exe(3876:3),TestStudio] Loader.LoadTranslatorGroups() : ------- Function Start -------
[03/27 17:26:36,ArtOfTest.Runner.exe(3876:3),TestStudio] Loader.LoadTranslatorGroups() : ------- Function End -------
[03/27 17:26:36,ArtOfTest.Runner.exe(3876:4)] First trace message from pool unnamed thread (managed ID = 4, native ID = 13384).
[03/27 17:26:36,ArtOfTest.Runner.exe(3876:4),TestStudio] Manager.GetDialogMatchProvider() : The dialog matches assembly loaded successfully.
[03/27 17:26:36,ArtOfTest.Runner.exe(3876:4),Framework] Manager.KillNonAutomatedBrowsers() : Closing non-automated 'iexplore' browser instances
[03/27 17:26:36,ArtOfTest.Runner.exe(3876:4),Framework] InternetExplorerActions.LaunchNewBrowserInstance() : Process launched (ID=13948, Path="C:\Program Files\Internet Explorer\iexplore.exe", Arguments="-nomerge about:blank").
[03/27 17:26:36,ArtOfTest.Runner.exe(3876:4),Framework] InternetExplorerActions.LaunchNewBrowserInstance() : Attempting to attach on IE frame (HWND=462186)...
[03/27 17:26:37,ArtOfTest.Runner.exe(3876:4),Framework] Connector.Start() : Process launched (ID=10696, Path="C:\Windows\SysWOW64\rundll32.exe", Arguments=""C:\Program Files (x86)\Progress\Test Studio\Bin\ArtOfTest.Connector.dll",AttachToWindowEntryPoint 1903484").
[03/27 17:26:37,ArtOfTest.Runner.exe(3876:4),Framework] Connector.Start() : Process exited (ID=10696, ExitCode=0, Path="C:\Windows\SysWOW64\rundll32.exe", Arguments=""C:\Program Files (x86)\Progress\Test Studio\Bin\ArtOfTest.Connector.dll",AttachToWindowEntryPoint 1903484").
[03/27 17:26:37,ArtOfTest.Runner.exe(3876:4),Framework] Connector.InjectCode() : ------- Function Start -------
[03/27 17:26:37,ArtOfTest.Runner.exe(3876:4),Framework] Connector.InjectCode() : Connection string: C:\Windows\Microsoft.Net\assembly\GAC_MSIL\ArtOfTest.InternetExplorer\v4.0_2019.1.212.0__5339893a7cefe4d6\ArtOfTest.InternetExplorer.dll?ArtOfTest.InternetExplorer.ArtOfTestPluginEntryPoint?6964
[03/27 17:26:37,ArtOfTest.Runner.exe(3876:4),Framework] InternetExplorerActions.WaitForDocument() : Attempting to get IHTMLDocument2 from Internet Explorer HWND 1903484 on another thread...
[03/27 17:26:37,ArtOfTest.Runner.exe(3876:9)] First trace message from pool thread "WaitAsync polling wait worker" (managed ID = 9, native ID = 13196).
[03/27 17:26:37,ArtOfTest.Runner.exe(3876:9),Framework] <>c__DisplayClass26_3.<WaitForDocument>b__1() : Attempting to get document from window handle
[03/27 17:26:37,ArtOfTest.Runner.exe(3876:4),Framework] InternetExplorerActions.WaitForDocument() : IHTMLDocument2 successfully retrieved.
[03/27 17:26:37,ArtOfTest.Runner.exe(3876:4),Framework] Connector.InjectCode() : Sending WM_COPYDATA to HWND 1903484 (lpData = "C:\Windows\Microsoft.Net\assembly\GAC_MSIL\ArtOfTest.InternetExplorer\v4.0_2019.1.212.0__5339893a7cefe4d6\ArtOfTest.InternetExplorer.dll?ArtOfTest.InternetExplorer.ArtOfTestPluginEntryPoint?6964")...
[03/27 17:26:37,IEXPLORE.EXE(6980:1)] First trace message from process 6980: "C:\Program Files (x86)\Internet Explorer\IEXPLORE.EXE" SCODEF:13948 CREDAT:275457 /prefetch:2
[03/27 17:26:37,IEXPLORE.EXE(6980:1)] First trace message from background unnamed thread (managed ID = 1, native ID = 12872).
[03/27 17:26:37,IEXPLORE.EXE(6980:1),Framework] ArtOfTestPluginEntryPoint.Connect() : The .NET Framework is now active inside the hooked process (HWND = 1903484, PipeName = "Pipe.ArtOfTest.WebAii.BrowserProvisioner_1808566357", PID = 6964).
[03/27 17:26:37,IEXPLORE.EXE(6980:1),Framework] InternetExplorerActions.WaitForDocument() : Attempting to get IHTMLDocument2 from Internet Explorer HWND 1903484 on same thread...
[03/27 17:26:37,IEXPLORE.EXE(6980:1),Framework] InternetExplorerActions.WaitForDocument() : IHTMLDocument2 successfully retrieved.
[03/27 17:26:37,IEXPLORE.EXE(6980:1),Framework] BrowserRemoteClient constructor : The client ID for this hooked process will be "Client_26f85172-aba4-4f3e-adcf-d119ac299009".
[03/27 17:26:37,ArtOfTest.Runner.exe(3876:6)] First trace message from background thread "Pipe.ArtOfTest.WebAii.BrowserProvisioner" (managed ID = 6, native ID = 8436).
[03/27 17:26:37,ArtOfTest.Runner.exe(3876:6),Framework] BrowserProvisioner.ServiceThread() : A new client has connected to the provisioning pipe.
[03/27 17:26:37,ArtOfTest.Runner.exe(3876:13)] First trace message from background thread "Remoted async command listener" (managed ID = 13, native ID = 7804).
[03/27 17:26:37,ArtOfTest.Runner.exe(3876:13),Framework] BrowserRemoted.AsyncListenerThreadEntry() : Not connected; this will now wait indefinitely for a pipe client connection...
[03/27 17:26:37,IEXPLORE.EXE(6980:1),Framework] BrowserRemoteClient.CreateNamedPipes() : Named pipes created for communication with the server: {Command = "3d55abd7-e16b-4beb-821f-a0dd95e2f325.CommandPipe", Async = "3d55abd7-e16b-4beb-821f-a0dd95e2f325.AsyncPipe"}
[03/27 17:26:37,ArtOfTest.Runner.exe(3876:13),Framework] BrowserRemoted.AsyncListenerThreadEntry() : A new client has connected to the pipe.
[03/27 17:26:37,ArtOfTest.Runner.exe(3876:13),Framework] BrowserRemoted.AsyncListenerThreadEntry() : Now waiting indefinitely for a command from the pipe client...
[03/27 17:26:37,ArtOfTest.Runner.exe(3876:6),Framework] Manager.AddNewBrowser() : Added client ID "Client_26f85172-aba4-4f3e-adcf-d119ac299009" (now there is/are 1 total).
[03/27 17:26:37,ArtOfTest.Runner.exe(3876:4),Framework] Connector.InjectCode() : Returned from sending WM_COPYDATA (LRESULT=0, GetLastError()=ERROR_ALREADY_EXISTS).
[03/27 17:26:37,ArtOfTest.Runner.exe(3876:4),Framework] Connector.InjectCode() : ------- Function End -------
[03/27 17:26:37,ArtOfTest.Runner.exe(3876:4),Execution] ExecutionEngine.InternalExecuteTest() : Executing test: 'Login to myTASB', path: 'Login to myTASB.tstest.'
[03/27 17:26:37,ArtOfTest.Runner.exe(3876:4),Execution] ExtensionsManager.LoadExtensions() : ------- Function Start -------
[03/27 17:26:37,ArtOfTest.Runner.exe(3876:4),Uncategorized] ExtensionsManager.LoadExtensions() : Got 14 plugin(s) in folder "C:\Program Files (x86)\Progress\Test Studio\Bin\Plugins".
[03/27 17:26:37,ArtOfTest.Runner.exe(3876:4),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "LibGit2Sharp.dll" from plugins folder.
[03/27 17:26:37,ArtOfTest.Runner.exe(3876:4),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.Functional.dll" from plugins folder.
[03/27 17:26:37,ArtOfTest.Runner.exe(3876:4),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.Jira.BugTracking.dll" from plugins folder.
[03/27 17:26:38,ArtOfTest.Runner.exe(3876:4),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.Load.dll" from plugins folder.
[03/27 17:26:38,ArtOfTest.Runner.exe(3876:4),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.Manual.dll" from plugins folder.
[03/27 17:26:38,ArtOfTest.Runner.exe(3876:4),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.OTA.Interop.dll" from plugins folder.
[03/27 17:26:38,ArtOfTest.Runner.exe(3876:4),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.Plugins.dll" from plugins folder.
[03/27 17:26:38,ArtOfTest.Runner.exe(3876:4),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.ScreenRecorderExtension.dll" from plugins folder.
[03/27 17:26:38,ArtOfTest.Runner.exe(3876:4),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.Shared.dll" from plugins folder.
[03/27 17:26:38,ArtOfTest.Runner.exe(3876:4),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.SourceControl.dll" from plugins folder.
[03/27 17:26:38,ArtOfTest.Runner.exe(3876:4),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.SourceControl.Git.dll" from plugins folder.
[03/27 17:26:38,ArtOfTest.Runner.exe(3876:4),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.SourceControl.Git.TS.dll" from plugins folder.
[03/27 17:26:38,ArtOfTest.Runner.exe(3876:4),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.Web.dll" from plugins folder.
[03/27 17:26:38,ArtOfTest.Runner.exe(3876:4),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.Wpf.dll" from plugins folder.
[03/27 17:26:38,ArtOfTest.Runner.exe(3876:4),Execution] ExtensionsManager.LoadExtensions() : ------- Function End -------
[03/27 17:26:38,ArtOfTest.Runner.exe(3876:4),TestStudio] VisualDebugger.SetCurrentResult() : VisualDebugger::SetCurrentResult
[03/27 17:26:38,ArtOfTest.Runner.exe(3876:4),DialogPlayback] DialogMonitor.Start() : Beginning monitoring for dialogs from the set {}.
[03/27 17:26:38,ArtOfTest.Runner.exe(3876:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[03/27 17:26:38,ArtOfTest.Runner.exe(3876:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 1 "Navigate to : '' - DataDriven: [$(URL)][$(URL)]"...
[03/27 17:26:38,ArtOfTest.Runner.exe(3876:4),Uncategorized] Log.WriteLineInternal() : ENVIRONMENT :STAGE
[03/27 17:26:39,ArtOfTest.Runner.exe(3876:4),Uncategorized] Log.WriteLineInternal() : ENVIRONMENT :test123
[03/27 17:26:39,ArtOfTest.Runner.exe(3876:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 1 "Navigate to : '' - DataDriven: [$(URL)][$(URL)]".
[03/27 17:26:39,ArtOfTest.Runner.exe(3876:4),TestStudio] VisualDebugger.ShutDown() : VisualDebbuger::ShutDown::Start
[03/27 17:26:39,ArtOfTest.Runner.exe(3876:4),TestStudio] VisualDebugger.ShutDown() : VisualDebbuger::ShutDown::End
[03/27 17:26:39,ArtOfTest.Runner.exe(3876:4),Framework] BrowserProvisioner.EndService() : Connecting to the provisioning pipe "Pipe.ArtOfTest.WebAii.BrowserProvisioner_1808566357" in order to unblock and eventually terminate it...
[03/27 17:26:39,ArtOfTest.Runner.exe(3876:4),Framework] BrowserProvisioner.EndService() : Provisioning pipe connected.
[03/27 17:26:39,ArtOfTest.Runner.exe(3876:6),Framework] BrowserProvisioner.ServiceThread() : A new client has connected to the provisioning pipe but this will be treated as a termination cue.
[03/27 17:26:39,ArtOfTest.Runner.exe(3876:4),Framework] BrowserProvisioner.EndService() : Provisioning thread terminated.
[03/27 17:26:39,ArtOfTest.Runner.exe(3876:4),DialogPlayback] DialogMonitor.AddDialog() : Added {OnBeforeUnloadDialog(Dismiss=OK)} for monitoring.
[03/27 17:26:39,ArtOfTest.Runner.exe(3876:4),Framework] Manager.RemoveBrowser() : Removed client ID "Client_26f85172-aba4-4f3e-adcf-d119ac299009" (0 remaining).
[03/27 17:26:39,ArtOfTest.Runner.exe(3876:4),Framework] Manager.SetActiveBrowser() : Active browser is now null.
[03/27 17:26:39,ArtOfTest.Runner.exe(3876:4),Framework] BrowserRemoted.End() : Now aborting the listener thread (TID = 13).
[03/27 17:26:39,IEXPLORE.EXE(6980:1),Framework] BrowserRemoteClient.Disconnect() : Sending ClientDisconnectRequest(ClientId="Client_26f85172-aba4-4f3e-adcf-d119ac299009") to pipe server...
[03/27 17:26:39,IEXPLORE.EXE(6980:1),Framework] BrowserRemoteClient.Disconnect() : Command pipe is already disconnected.
[03/27 17:26:39,ArtOfTest.Runner.exe(3876:13),Warning] PipeCommunication.SyncPipeRead() : Pipe read broken by thread-abort.
[03/27 17:26:39,ArtOfTest.Runner.exe(3876:4),DialogPlayback] DialogMonitor.Stop() : Dialog monitoring is being halted.
[03/27 17:26:39,ArtOfTest.Runner.exe(3876:4),UIAutomation] AutomationExtensions.AddAutomationEventHandler() : Added a(n) WindowPatternIdentifiers.WindowOpenedProperty handler on element System.Windows.Automation.AutomationElement scope Descendants.
[03/27 17:26:40,Telerik.TestStudio.RemoteExecutor.exe(6964:28)] First trace message from background thread "Callback Listener Thread" (managed ID = 28, native ID = 11044).
[03/27 17:26:40,Telerik.TestStudio.RemoteExecutor.exe(6964:28),Execution] TestPlatform.FinishTestRunAndPublishResultsAsync() : Finished running test platform - attempting publish
[03/27 17:26:40,Telerik.TestStudio.RemoteExecutor.exe(6964:28),Framework] FireFoxInstallation.EnumFireFoxInstallations() : Valid Firefox 43.0.0.1 (x86) installation found at "C:\Program Files (x86)\Mozilla Firefox\firefox.exe".
[03/27 17:26:40,Telerik.TestStudio.RemoteExecutor.exe(6964:28),Framework] FireFoxInstallation.EnumFireFoxInstallations() : Valid Firefox 65.0.0.2 (x64) installation found at "C:\Program Files\Mozilla Firefox\firefox.exe".
[03/27 17:26:40,Telerik.TestStudio.RemoteExecutor.exe(6964:28),Framework] FireFoxInstallation.GetExecutableData() : Automatically selected Firefox 65.0.0.2 (x64).
[03/27 17:27:10,Telerik.TestStudio.RemoteExecutor.exe(6964:28),Execution] RunnerController.CleanupRunnerProcess() : Test execution was terminated, because ArtOfTest.Runner failed to respond properly.
[03/27 17:29:24,Telerik.TestStudio.Scheduling.Setup.exe(6552:1)] First trace message from process 6552: "C:\Program Files (x86)\Progress\Test Studio\Bin\Telerik.TestStudio.Scheduling.Setup.exe" 
[03/27 17:29:24,Telerik.TestStudio.Scheduling.Setup.exe(6552:1)] First trace message from foreground unnamed thread (managed ID = 1, native ID = 13900).
[03/27 17:29:24,Telerik.TestStudio.Scheduling.Setup.exe(6552:1),Execution] ServiceConfiguration.SaveAsConfig() : Config file found.
[03/27 17:29:24,Telerik.TestStudio.Scheduling.Setup.exe(6552:1),Execution] ServiceConfiguration.SaveAsConfig() : Config file found.
