﻿[11/09 16:30:01,Telerik.TestStudio.RemoteExecutor.exe(4412:46)] First trace message from pool unnamed thread (managed ID = 46, native ID = 5636).
[11/09 16:30:01,Telerik.TestStudio.RemoteExecutor.exe(4412:46),Execution] RunningTestsController.StartTest() : Test run requested for Cloud path=C:\Users\PLK056397\Documents\Test Studio Projects\Iteracje
[11/09 16:30:14,ArtOfTest.Runner.exe(5820:3)] First trace message from process 5820: "C:\Program Files (x86)\Telerik\Test Studio\Bin\ArtOfTest.Runner.exe"  /controller /parentprocesshandle=1496
[11/09 16:30:14,ArtOfTest.Runner.exe(5820:3)] First trace message from foreground thread "Remote.ArtOfTest.Runner.Listener" (managed ID = 3, native ID = 3232).
[11/09 16:30:14,ArtOfTest.Runner.exe(5820:3),TestStudio] Loader.LoadTranslatorGroups() : ------- Function Start -------
[11/09 16:30:16,ArtOfTest.Runner.exe(5820:3),TestStudio] Loader.LoadTranslatorGroups() : ------- Function End -------
[11/09 16:30:22,ArtOfTest.Runner.exe(5820:5)] First trace message from pool unnamed thread (managed ID = 5, native ID = 5204).
[11/09 16:30:22,ArtOfTest.Runner.exe(5820:5),Framework] Manager.LaunchNewApplication() : Process launched (ID=5736, Path="C:\Users\PLK056397\AppData\Local\Apps\2.0\XRG1D3ZH.NNO\8J2TC3YE.112\csdi..tion_e7bd3f72e38e63c1_0001.0000_7172a4be26ead0e6\CSDIP.GUI.OperatorConsole.exe", Arguments="").
[11/09 16:30:22,ArtOfTest.Runner.exe(5820:5),UIAutomation] AutomationExtensions.AddAutomationEventHandler() : Added a(n) WindowPatternIdentifiers.WindowOpenedProperty handler on element System.Windows.Automation.AutomationElement scope Descendants.
[11/09 16:30:22,ArtOfTest.Runner.exe(5820:6)] First trace message from pool thread "WaitAsync polling wait worker" (managed ID = 6, native ID = 844).
[11/09 16:30:22,ArtOfTest.Runner.exe(5820:6),Framework] <>c__DisplayClass4c.<WaitForApplicationToConnect>b__46() : WPF HWNDs detected after 26 msec: {1114694}.
[11/09 16:30:22,ArtOfTest.Runner.exe(5820:6),Framework] Connector.Start() : Process launched (ID=5776, Path="C:\Windows\SysWOW64\rundll32.exe", Arguments=""C:\Program Files (x86)\Telerik\Test Studio\Bin\ArtOfTest.Connector.dll",AttachToWindowEntryPoint 1114694").
[11/09 16:30:24,ArtOfTest.Runner.exe(5820:6),Framework] Connector.Start() : Process exited (ID=5776, ExitCode=0, Path="C:\Windows\SysWOW64\rundll32.exe", Arguments=""C:\Program Files (x86)\Telerik\Test Studio\Bin\ArtOfTest.Connector.dll",AttachToWindowEntryPoint 1114694").
[11/09 16:30:24,ArtOfTest.Runner.exe(5820:6),Framework] Connector.InjectCode() : ------- Function Start -------
[11/09 16:30:24,ArtOfTest.Runner.exe(5820:6),Framework] Connector.InjectCode() : Connection string: C:\Windows\Microsoft.Net\assembly\GAC_MSIL\Telerik.TestingFramework.WpfExtension\v4.0_2015.3.1314.0__3fd81c5c4516f739\Telerik.TestingFramework.WpfExtension.dll?Telerik.TestingFramework.WpfExtension.WpfCommunicationEntryPoint?5820
[11/09 16:30:24,ArtOfTest.Runner.exe(5820:6),Framework] Connector.InjectCode() : Sending WM_COPYDATA to HWND 1114694 (lpData = "C:\Windows\Microsoft.Net\assembly\GAC_MSIL\Telerik.TestingFramework.WpfExtension\v4.0_2015.3.1314.0__3fd81c5c4516f739\Telerik.TestingFramework.WpfExtension.dll?Telerik.TestingFramework.WpfExtension.WpfCommunicationEntryPoint?5820")...
[11/09 16:30:24,CSDIP.GUI.OperatorConsole.exe(5736:1)] First trace message from process 5736: "C:\Users\PLK056397\AppData\Local\Apps\2.0\XRG1D3ZH.NNO\8J2TC3YE.112\csdi..tion_e7bd3f72e38e63c1_0001.0000_7172a4be26ead0e6\CSDIP.GUI.OperatorConsole.exe" 
[11/09 16:30:24,CSDIP.GUI.OperatorConsole.exe(5736:1)] First trace message from foreground thread "WpfCommunicationEntryPoint entry point thread" (managed ID = 1, native ID = 5856).
[11/09 16:30:24,CSDIP.GUI.OperatorConsole.exe(5736:1),Recording] WpfCommunicationEntryPoint.Connect() : The .NET Framework is now active inside the hooked process (hwndString="1114694:Pipe.ArtOfTest.WebAii.BrowserProvisioner_-1607814026:5820").
[11/09 16:30:24,CSDIP.GUI.OperatorConsole.exe(5736:1),Framework] BrowserRemoteClient constructor : The client ID for this hooked process will be "Client_ab21ff12-e937-47fb-bc91-f9525b54183d".
[11/09 16:30:24,ArtOfTest.Runner.exe(5820:7)] First trace message from background thread "Pipe.ArtOfTest.WebAii.BrowserProvisioner" (managed ID = 7, native ID = 5900).
[11/09 16:30:24,ArtOfTest.Runner.exe(5820:7),Framework] BrowserProvisioner.ServiceThread() : A new client has connected to the provisioning pipe.
[11/09 16:30:24,CSDIP.GUI.OperatorConsole.exe(5736:1),Framework] BrowserRemoteClient.CreateNamedPipes() : Named pipes created for communication with the server: {Command = "1dcd0d6d-65fe-4765-bbd1-cfeec24f5625.CommandPipe", Async = "1dcd0d6d-65fe-4765-bbd1-cfeec24f5625.AsyncPipe"}
[11/09 16:30:24,ArtOfTest.Runner.exe(5820:11)] First trace message from background thread "Remoted async command listener" (managed ID = 11, native ID = 5688).
[11/09 16:30:24,ArtOfTest.Runner.exe(5820:11),Framework] BrowserRemoted.AsyncListenerThreadEntry() : Not connected; this will now wait indefinitely for a pipe client connection...
[11/09 16:30:24,ArtOfTest.Runner.exe(5820:11),Framework] BrowserRemoted.AsyncListenerThreadEntry() : A new client has connected to the pipe.
[11/09 16:30:24,ArtOfTest.Runner.exe(5820:11),Framework] BrowserRemoted.AsyncListenerThreadEntry() : Now waiting indefinitely for a command from the pipe client...
[11/09 16:30:24,ArtOfTest.Runner.exe(5820:6),Framework] Connector.InjectCode() : Returned from sending WM_COPYDATA (LRESULT=0, GetLastError()=ERROR_ALREADY_EXISTS).
[11/09 16:30:24,ArtOfTest.Runner.exe(5820:6),Framework] Connector.InjectCode() : ------- Function End -------
[11/09 16:30:24,ArtOfTest.Runner.exe(5820:5),Execution] ExecutionEngine.InternalExecuteTest() : Executing test: 'Zmiana_trybu_obslugi_pociagu_tmp', path: 'Budowanie\Zmiana_trybu_obslugi_pociagu_tmp.tstest.'
[11/09 16:30:24,ArtOfTest.Runner.exe(5820:5),Execution] ExtensionsManager.LoadExtensions() : ------- Function Start -------
[11/09 16:30:24,ArtOfTest.Runner.exe(5820:5),Uncategorized] ExtensionsManager.LoadExtensions() : Got 10 plugin(s) in folder "C:\Program Files (x86)\Telerik\Test Studio\Bin\Plugins".
[11/09 16:30:24,ArtOfTest.Runner.exe(5820:5),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.Jira.BugTracking.dll" from plugins folder.
[11/09 16:30:24,ArtOfTest.Runner.exe(5820:5),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.Load.dll" from plugins folder.
[11/09 16:30:24,ArtOfTest.Runner.exe(5820:5),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.Manual.dll" from plugins folder.
[11/09 16:30:24,ArtOfTest.Runner.exe(5820:5),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.OTA.Interop.dll" from plugins folder.
[11/09 16:30:24,ArtOfTest.Runner.exe(5820:5),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.Plugins.dll" from plugins folder.
[11/09 16:30:25,ArtOfTest.Runner.exe(5820:5),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.ProductGuide.dll" from plugins folder.
[11/09 16:30:25,ArtOfTest.Runner.exe(5820:5),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.Shared.dll" from plugins folder.
[11/09 16:30:25,ArtOfTest.Runner.exe(5820:5),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.TeamPulse.dll" from plugins folder.
[11/09 16:30:25,ArtOfTest.Runner.exe(5820:5),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.Web.dll" from plugins folder.
[11/09 16:30:25,ArtOfTest.Runner.exe(5820:5),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.Wpf.dll" from plugins folder.
[11/09 16:30:25,ArtOfTest.Runner.exe(5820:5),Execution] ExtensionsManager.LoadExtensions() : ------- Function End -------
[11/09 16:30:25,ArtOfTest.Runner.exe(5820:5),TestStudio] VisualDebugger.SetCurrentResult() : VisualDebugger::SetCurrentResult
[11/09 16:30:25,ArtOfTest.Runner.exe(5820:5),DialogPlayback] DialogMonitor.Start() : Beginning monitoring for dialogs from the set {}.
[11/09 16:30:25,ArtOfTest.Runner.exe(5820:5),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[11/09 16:30:25,ArtOfTest.Runner.exe(5820:5),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[11/09 16:30:26,ArtOfTest.Runner.exe(5820:5),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 2 "radtabitem: item 'Warszawa Zachodnia' select action."...
[11/09 16:30:26,ArtOfTest.Runner.exe(5820:9)] First trace message from background thread "UAI Dispatcher Thread" (managed ID = 9, native ID = 5356).
[11/09 16:30:26,ArtOfTest.Runner.exe(5820:9),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[11/09 16:30:26,ArtOfTest.Runner.exe(5820:9),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[11/09 16:30:31,ArtOfTest.Runner.exe(5820:5),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 2 "radtabitem: item 'Warszawa Zachodnia' select action.".
[11/09 16:30:31,ArtOfTest.Runner.exe(5820:5),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[11/09 16:30:31,ArtOfTest.Runner.exe(5820:5),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 3 "Wait for '15000' msec."...
[11/09 16:30:46,ArtOfTest.Runner.exe(5820:5),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 3 "Wait for '15000' msec.".
[11/09 16:30:46,ArtOfTest.Runner.exe(5820:5),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[11/09 16:30:46,ArtOfTest.Runner.exe(5820:5),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[11/09 16:30:46,ArtOfTest.Runner.exe(5820:5),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 4 "[Zmiana_trybu_obslugi_pociagu_CodedStep] : New Coded Step"...
[11/09 16:30:49,ArtOfTest.Runner.exe(5820:9),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[11/09 16:30:49,ArtOfTest.Runner.exe(5820:5),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 4 "[Zmiana_trybu_obslugi_pociagu_CodedStep] : New Coded Step".
[11/09 16:30:49,ArtOfTest.Runner.exe(5820:5),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[11/09 16:30:53,ArtOfTest.Runner.exe(5820:5),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 5 "LeftClick on MniProcessingModeRadmenuitem"...
[11/09 16:30:53,ArtOfTest.Runner.exe(5820:5),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 5 "LeftClick on MniProcessingModeRadmenuitem".
[11/09 16:30:53,ArtOfTest.Runner.exe(5820:5),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[11/09 16:30:54,ArtOfTest.Runner.exe(5820:9),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[11/09 16:30:54,ArtOfTest.Runner.exe(5820:9),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[11/09 16:30:54,ArtOfTest.Runner.exe(5820:9),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[11/09 16:30:55,ArtOfTest.Runner.exe(5820:9),Framework] Manager._windowMonitor_WindowOpened() : New WPF window detected (HWND=2884142, Caption="Zmiana trybu obsługi pociągu przez system CSDIP").
[11/09 16:30:55,ArtOfTest.Runner.exe(5820:9),Framework] Connector.Start() : Process launched (ID=6136, Path="C:\Windows\SysWOW64\rundll32.exe", Arguments=""C:\Program Files (x86)\Telerik\Test Studio\Bin\ArtOfTest.Connector.dll",AttachToWindowEntryPoint 2884142").
[11/09 16:30:55,ArtOfTest.Runner.exe(5820:9),Framework] Connector.Start() : Process exited (ID=6136, ExitCode=0, Path="C:\Windows\SysWOW64\rundll32.exe", Arguments=""C:\Program Files (x86)\Telerik\Test Studio\Bin\ArtOfTest.Connector.dll",AttachToWindowEntryPoint 2884142").
[11/09 16:30:55,ArtOfTest.Runner.exe(5820:9),Framework] Connector.InjectCode() : ------- Function Start -------
[11/09 16:30:55,ArtOfTest.Runner.exe(5820:9),Framework] Connector.InjectCode() : Connection string: C:\Windows\Microsoft.Net\assembly\GAC_MSIL\Telerik.TestingFramework.WpfExtension\v4.0_2015.3.1314.0__3fd81c5c4516f739\Telerik.TestingFramework.WpfExtension.dll?Telerik.TestingFramework.WpfExtension.WpfCommunicationEntryPoint?5820
[11/09 16:30:55,ArtOfTest.Runner.exe(5820:9),Framework] Connector.InjectCode() : Sending WM_COPYDATA to HWND 2884142 (lpData = "C:\Windows\Microsoft.Net\assembly\GAC_MSIL\Telerik.TestingFramework.WpfExtension\v4.0_2015.3.1314.0__3fd81c5c4516f739\Telerik.TestingFramework.WpfExtension.dll?Telerik.TestingFramework.WpfExtension.WpfCommunicationEntryPoint?5820")...
[11/09 16:30:55,CSDIP.GUI.OperatorConsole.exe(5736:1),Recording] WpfCommunicationEntryPoint.Connect() : The .NET Framework is now active inside the hooked process (hwndString="2884142:Pipe.ArtOfTest.WebAii.BrowserProvisioner_-1607814026:5820").
[11/09 16:30:55,CSDIP.GUI.OperatorConsole.exe(5736:1),Framework] BrowserRemoteClient constructor : The client ID for this hooked process will be "Client_5f82a9b5-2084-4a0a-ba66-8049455daee5".
[11/09 16:30:55,ArtOfTest.Runner.exe(5820:7),Framework] BrowserProvisioner.ServiceThread() : A new client has connected to the provisioning pipe.
[11/09 16:30:55,ArtOfTest.Runner.exe(5820:17)] First trace message from background thread "Remoted async command listener" (managed ID = 17, native ID = 632).
[11/09 16:30:55,ArtOfTest.Runner.exe(5820:17),Framework] BrowserRemoted.AsyncListenerThreadEntry() : Not connected; this will now wait indefinitely for a pipe client connection...
[11/09 16:30:55,CSDIP.GUI.OperatorConsole.exe(5736:1),Framework] BrowserRemoteClient.CreateNamedPipes() : Named pipes created for communication with the server: {Command = "c8c02cc9-2287-40f2-ac79-4ddd5a3f2003.CommandPipe", Async = "c8c02cc9-2287-40f2-ac79-4ddd5a3f2003.AsyncPipe"}
[11/09 16:30:55,ArtOfTest.Runner.exe(5820:17),Framework] BrowserRemoted.AsyncListenerThreadEntry() : A new client has connected to the pipe.
[11/09 16:30:55,ArtOfTest.Runner.exe(5820:17),Framework] BrowserRemoted.AsyncListenerThreadEntry() : Now waiting indefinitely for a command from the pipe client...
[11/09 16:30:55,ArtOfTest.Runner.exe(5820:9),Framework] Connector.InjectCode() : Returned from sending WM_COPYDATA (LRESULT=0, GetLastError()=ERROR_ALREADY_EXISTS).
[11/09 16:30:55,ArtOfTest.Runner.exe(5820:9),Framework] Connector.InjectCode() : ------- Function End -------
[11/09 16:30:55,ArtOfTest.Runner.exe(5820:9),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[11/09 16:30:56,ArtOfTest.Runner.exe(5820:5),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 6 "radcombobox: drop down 'Open' action."...
[11/09 16:30:56,ArtOfTest.Runner.exe(5820:9),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[11/09 16:30:57,ArtOfTest.Runner.exe(5820:5),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 6 "radcombobox: drop down 'Open' action.".
[11/09 16:30:57,ArtOfTest.Runner.exe(5820:5),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[11/09 16:30:58,ArtOfTest.Runner.exe(5820:5),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 7 "radcombobox: selecting item 'M - Manualny'"...
[11/09 16:30:58,ArtOfTest.Runner.exe(5820:5),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 7 "radcombobox: selecting item 'M - Manualny'".
[11/09 16:30:58,ArtOfTest.Runner.exe(5820:5),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[11/09 16:31:00,ArtOfTest.Runner.exe(5820:5),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 8 "LeftClick on ZapiszTextblock"...
[11/09 16:31:00,ArtOfTest.Runner.exe(5820:5),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 8 "LeftClick on ZapiszTextblock".
[11/09 16:31:00,ArtOfTest.Runner.exe(5820:5),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[11/09 16:31:00,ArtOfTest.Runner.exe(5820:5),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 9 "Wait for '2000' msec."...
[11/09 16:31:01,CSDIP.GUI.OperatorConsole.exe(5736:1),Framework] BrowserRemoteClient.Disconnect() : Sending ClientDisconnectRequest(ClientId="Client_5f82a9b5-2084-4a0a-ba66-8049455daee5") to pipe server...
[11/09 16:31:01,ArtOfTest.Runner.exe(5820:17),Framework] BrowserRemoted.AsyncListenerThreadEntry() : A ClientDisconnectRequest(ClientId="Client_5f82a9b5-2084-4a0a-ba66-8049455daee5") has been received from the pipe client.
[11/09 16:31:02,ArtOfTest.Runner.exe(5820:5),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 9 "Wait for '2000' msec.".
[11/09 16:31:02,ArtOfTest.Runner.exe(5820:5),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[11/09 16:31:03,CSDIP.GUI.OperatorConsole.exe(5736:1),Error] BrowserRemoteClient.Disconnect() : Could not join the listener thread even after two 1-second wait attempts and an interrupt.
[11/09 16:31:03,CSDIP.GUI.OperatorConsole.exe(5736:1),Framework] BrowserRemoteClient.Disconnect() : Disconnecting command pipe (Handle = 2444).
