Selenium: IEDriver 3.2 doesn't work with file input dialog

Created on 1 Mar 2017  路  26Comments  路  Source: SeleniumHQ/selenium

Meta -

OS:
Windows 7 64Bit
Selenium Version: 

<dependency>
<groupId>org.seleniumhq.selenium</groupId>
<artifactId>selenium-server</artifactId>
<version>3.2.0</version>
</dependency>

Browser:
Internet Explorer 11

Browser Version:
11.0.9600.18015

Expected Behavior -

SendKeys to a file input field opens the file dialog and sets the correct path and closes the file dialog.

Actual Behavior -

With IEDriver 32Bit 3.2 no path is set and the dialog stays open. Switching to IEDriver 3.1 (now used because 3.2 doesnt work) or 2.48 (was used before updating to selenium 3) resolves the issue.

Steps to reproduce -

Following codes shows the missbehavior (you need to adjust paths):

import org.openqa.selenium.By;
import org.openqa.selenium.WebElement;
import org.openqa.selenium.ie.InternetExplorerDriver;

public class TestFileUpload {

    public static void main(String[] args) {
        String driverPath_2_48 = "C:/work/temp/iedriver/2.48/IEDriverServer.exe";
        String driverPath_3_1 = "C:/work/temp/iedriver/3.1/IEDriverServer.exe";
        String driverPath_3_2 = "C:/work/temp/iedriver/3.2/IEDriverServer.exe";
        System.setProperty("webdriver.ie.driver", driverPath_3_2);
        InternetExplorerDriver driver = new InternetExplorerDriver();

        driver.get("https://www.google.de/imghp");

        driver.findElement(By.id("qbi")).click();
        driver.findElement(By.xpath("//a[text()='Bild hochladen']")).click();

        WebElement fileInput = driver.findElement(By.id("qbfile"));
        fileInput.sendKeys("C:\\work\\temp\\someImage.jpg");

        try {
            Thread.sleep(2500);
        }
        catch (InterruptedException e) {
            // ignore
        }

        driver.quit();
    }
}
D-IE
Source
picture benzman81

Most helpful comment

@jimevans: I set the timeout to 10secs but didnt help. I can reproduce this with the above snippet everytime. Can't you reproduce it with the above code?

picture benzman81 on 19 Mar 2017
👍2

All 26 comments

Same issue with 3.3 Should we expect fix for that soon? Just to add the same behavior apply to Edge as well!

Rain9333 picture Rain9333 on 14 Mar 2017
👍2

Am currently getting this issue alongside @Rain9333, any estimation when this will be fixed? This worked wonders within IEDriver 3.1.

Jake-Roper picture Jake-Roper on 14 Mar 2017

Has anyone tried setting the ie.fileUploadDialogTimeout capability, which takes a timeout in milliseconds for how long to wait to find the file selection dialog? Literally nothing changed between 3.1 and 3.2 in the IE driver's code in regards to file uploads. The source code was slightly reorganized, but no functional changes were made, and I can empirically demonstrate that it is not a global issue with 3.2 or above of the IE driver. The default for waiting to detect the file upload dialog is still 3 seconds. If someone can provide a log file from the IE driver that might shed light on what is happening, that might be useful. Otherwise, I'll be closing this issue in 7 days' time.

jimevans picture jimevans on 19 Mar 2017

@jimevans: I set the timeout to 10secs but didnt help. I can reproduce this with the above snippet everytime. Can't you reproduce it with the above code?

benzman81 picture benzman81 on 19 Mar 2017
👍2

@jimevans Very strange... When I create my driver like that I can reproduce the issue every time (it hangs out with open file upload):

var options = new InternetExplorerOptions
{
    IntroduceInstabilityByIgnoringProtectedModeSettings = true,
    PageLoadStrategy = InternetExplorerPageLoadStrategy.Normal,
    EnsureCleanSession = true,
    RequireWindowFocus = false,
    FileUploadDialogTimeout = DriverTimeout,
    EnableNativeEvents = true,
    EnableFullPageScreenshot = true,
    UnexpectedAlertBehavior = InternetExplorerUnexpectedAlertBehavior.Accept,
    IgnoreZoomLevel = true
};

//We need that registry in order to enable pop-ups on IE
var regKey = default(RegistryKey);
regKey = Registry.CurrentUser.OpenSubKey("Software\\Microsoft\\Internet Explorer\\New Windows", true);
regKey.SetValue("PopupMgr", 0);
regKey.Close();

var driver = new InternetExplorerDriver(DriverPath, options);

driver.Manage().Window.Size = new Size(1920, 1080);
driver.Manage().Window.Maximize();
driver.Manage().Timeouts().PageLoad = DriverTimeout;

But after I add the logging of the driver for you like that (everything else is the same):

var service = InternetExplorerDriverService.CreateDefaultService();
service.LogFile = @"C:\IE.log";
service.LoggingLevel = InternetExplorerDriverLogLevel.Trace;

var driver = new InternetExplorerDriver(service, options);

It start working. Am I going crazy or there is something weird about that behavior? Any ideas what else we can try?

Rain9333 picture Rain9333 on 20 Mar 2017

I might have update on the issue. This is the log from the driver - this time I get timeout (no matter I set FileUploadDialogTimeout = DriverTimeout which in my case is 15 seconds, it took over 2 minutes).

The error in VS is that one:

Test Name:  MyTest
Test FullName:  MyTest
Test Source:    C:\src\My.feature : line 20
Test Outcome:   Failed
Test Duration:  0:04:08.139

Result StackTrace:  
at OpenQA.Selenium.Remote.HttpCommandExecutor.CreateResponse(WebRequest request)
   at OpenQA.Selenium.Remote.HttpCommandExecutor.Execute(Command commandToExecute)
   at OpenQA.Selenium.Remote.DriverServiceCommandExecutor.Execute(Command commandToExecute)
   at OpenQA.Selenium.Remote.RemoteWebDriver.Execute(String driverCommandToExecute, Dictionary`2 parameters)
   at OpenQA.Selenium.Remote.RemoteWebElement.SendKeys(String text)
--WebException
   at System.Net.HttpWebRequest.GetResponse()
   at OpenQA.Selenium.Remote.HttpCommandExecutor.CreateResponse(WebRequest request)
Result Message: 
OpenQA.Selenium.WebDriverException : The HTTP request to the remote WebDriver server for URL http://localhost:55308/session/9a923fa7-2b8d-4f2b-a501-eb4496c8d5ff/element/8c7ac81b-eafe-4937-9994-a5b45ab282df/value timed out after 60 seconds.
  ----> System.Net.WebException : The request was aborted: The operation has timed out.

This was what was the error on the screen (the file is not in use):
https://i.gyazo.com/7dd2ec42ce5a501f236225da07087281.png

And this is the IE driver log:

T 2017-03-29 17:43:34:883 server.cc(408) Entering Server::SendResponseToClient
T 2017-03-29 17:43:34:883 response.cc(36) Entering Response::Deserialize
T 2017-03-29 17:43:34:883 server.cc(462) Entering Server::SendHttpOk
T 2017-03-29 17:43:34:884 server.cc(174) Entering Server::ProcessRequest
T 2017-03-29 17:43:34:884 server.cc(238) Entering Server::ReadRequestBody
T 2017-03-29 17:43:34:884 server.cc(183) Process request with: URI: /session/9a923fa7-2b8d-4f2b-a501-eb4496c8d5ff/element/8c7ac81b-eafe-4937-9994-a5b45ab282df/value HTTP verb: POST
body: {"value":["C:\\src\\Documents\\TextFile.txt"]}
T 2017-03-29 17:43:34:884 server.cc(272) Entering Server::DispatchCommand
T 2017-03-29 17:43:34:884 server.cc(579) Entering Server::LookupCommand
D 2017-03-29 17:43:34:884 server.cc(281) Command: POST /session/9a923fa7-2b8d-4f2b-a501-eb4496c8d5ff/element/8c7ac81b-eafe-4937-9994-a5b45ab282df/value {"value":["C:\\src\\Documents\\TextFile.txt"]}
T 2017-03-29 17:43:34:884 server.cc(395) Entering Server::LookupSession
T 2017-03-29 17:43:34:884 IESession.cpp(191) Entering IESession::ExecuteCommand
T 2017-03-29 17:43:34:884 IECommandExecutor.cpp(125) Entering IECommandExecutor::OnSetCommand
T 2017-03-29 17:43:34:884 command.cc(31) Entering Command::Deserialize
D 2017-03-29 17:43:34:884 command.cc(36) Raw JSON command: { "name" : "sendKeys", "locator" : { "sessionid" : "9a923fa7-2b8d-4f2b-a501-eb4496c8d5ff", "id" : "8c7ac81b-eafe-4937-9994-a5b45ab282df" }, "parameters" : {"value":["C:\\src\\Documents\\TextFile.txt"]} }
T 2017-03-29 17:43:34:884 IECommandExecutor.cpp(136) Entering IECommandExecutor::OnExecCommand
T 2017-03-29 17:43:34:884 IECommandExecutor.cpp(459) Entering IECommandExecutor::DispatchCommand
T 2017-03-29 17:43:34:884 IECommandExecutor.cpp(574) Entering IECommandExecutor::GetCurrentBrowser
T 2017-03-29 17:43:34:884 IESession.cpp(212) Beginning wait for response length to be not zero
T 2017-03-29 17:43:34:884 IECommandExecutor.cpp(580) Entering IECommandExecutor::GetManagedBrowser
T 2017-03-29 17:43:34:884 IECommandExecutor.cpp(530) Entering IECommandExecutor::IsAlertActive
T 2017-03-29 17:43:34:884 Browser.cpp(713) Entering Browser::GetActiveDialogWindowHandle
T 2017-03-29 17:43:34:884 Browser.cpp(206) Entering Browser::GetContentWindowHandle
D 2017-03-29 17:43:34:885 IECommandExecutor.cpp(544) No alert handle is found
T 2017-03-29 17:43:34:885 IECommandExecutor.cpp(574) Entering IECommandExecutor::GetCurrentBrowser
T 2017-03-29 17:43:34:885 IECommandExecutor.cpp(580) Entering IECommandExecutor::GetManagedBrowser
T 2017-03-29 17:43:34:885 Browser.cpp(206) Entering Browser::GetContentWindowHandle
T 2017-03-29 17:43:34:885 Browser.cpp(416) Entering Browser::GetTopLevelWindowHandle
T 2017-03-29 17:43:34:885 IECommandHandler.cpp(45) Entering IECommandHandler::GetElement
T 2017-03-29 17:43:34:885 IECommandExecutor.cpp(671) Entering IECommandExecutor::GetManagedElement
T 2017-03-29 17:43:34:885 ElementRepository.cpp(34) Entering ElementRepository::GetManagedElement
T 2017-03-29 17:43:34:888 IECommandExecutor.cpp(574) Entering IECommandExecutor::GetCurrentBrowser
T 2017-03-29 17:43:34:888 IECommandExecutor.cpp(580) Entering IECommandExecutor::GetManagedBrowser
T 2017-03-29 17:43:34:888 Browser.cpp(126) Entering Browser::GetDocument
I 2017-03-29 17:43:34:888 Browser.cpp(130) No child frame focus. Focus is on top-level frame
T 2017-03-29 17:43:34:896 Browser.cpp(613) Entering Browser::GetDocumentFromWindow
T 2017-03-29 17:43:34:902 Element.cpp(312) Entering Element::GetLocationOnceScrolledIntoView
T 2017-03-29 17:43:34:902 Element.cpp(443) Entering Element::GetLocation
T 2017-03-29 17:43:34:903 Element.cpp(579) Entering Element::IsInline
D 2017-03-29 17:43:34:904 Element.cpp(489) Element is a block element, using IHTMLElement2::getBoundingClientRect
T 2017-03-29 17:43:34:909 Element.cpp(600) Entering Element::RectHasNonZeroDimensions
T 2017-03-29 17:43:34:911 Element.cpp(616) Entering Element::GetFrameDetails
T 2017-03-29 17:43:34:911 Element.cpp(906) Entering Element::GetContainingDocument
D 2017-03-29 17:43:34:922 Element.cpp(566) Element is not in a frame
T 2017-03-29 17:43:34:922 Element.cpp(855) Entering Element::CalculateClickPoint
T 2017-03-29 17:43:34:922 Element.cpp(794) Entering Element::GetClickableViewPortLocation
T 2017-03-29 17:43:34:922 Element.cpp(906) Entering Element::GetContainingDocument
T 2017-03-29 17:43:34:923 DocumentHost.cpp(271) Entering DocumentHost::GetDocumentMode
T 2017-03-29 17:43:34:928 Element.cpp(881) Entering Element::IsLocationInViewPort
T 2017-03-29 17:43:34:928 Element.cpp(794) Entering Element::GetClickableViewPortLocation
T 2017-03-29 17:43:34:928 Element.cpp(906) Entering Element::GetContainingDocument
T 2017-03-29 17:43:34:929 DocumentHost.cpp(271) Entering DocumentHost::GetDocumentMode
T 2017-03-29 17:43:34:937 Element.cpp(379) Entering Element::IsHiddenByOverflow
T 2017-03-29 17:43:34:937 Element.cpp(906) Entering Element::GetContainingDocument
T 2017-03-29 17:43:34:938 Script.cpp(49) Entering Script::Initialize
T 2017-03-29 17:43:34:938 Script.cpp(99) Entering Script::AddArgument(IHTMLElement*)
T 2017-03-29 17:43:34:938 Script.cpp(105) Entering Script::AddArgument(VARIANT)
T 2017-03-29 17:43:34:938 Script.cpp(169) Entering Script::Execute
T 2017-03-29 17:43:34:938 Script.cpp(477) Entering Script::CreateAnonymousFunction
T 2017-03-29 17:43:34:960 Script.cpp(433) Entering Script::ConvertResultToString
D 2017-03-29 17:43:34:960 Script.cpp(444) Result type is string
D 2017-03-29 17:43:34:960 Element.cpp(358) (x, y, w, h): 1345, 655, 20, 11
D 2017-03-29 17:43:34:962 CommandHandlers\SendKeysCommandHandler.cpp(140) Clicking upload button and starting to handle file selection dialog
D 2017-03-29 17:43:35:063 CommandHandlers\SendKeysCommandHandler.cpp(193) No dialog directly owned by the top-level window found. Beginning search for dialog. Will search for 150 attempts at 100ms intervals.
D 2017-03-29 17:43:35:265 CommandHandlers\SendKeysCommandHandler.cpp(229) Found file upload dialog with handle 0x00130B70. Window has caption 'Choose File to Upload' Starting to look for file name edit control.
D 2017-03-29 17:43:35:465 CommandHandlers\SendKeysCommandHandler.cpp(261) Found edit control on file selection dialog
T 2017-03-29 17:44:34:901 server.cc(174) Entering Server::ProcessRequest
T 2017-03-29 17:44:34:901 server.cc(183) Process request with: URI: /session/9a923fa7-2b8d-4f2b-a501-eb4496c8d5ff/screenshot HTTP verb: GET
body: {}
T 2017-03-29 17:44:34:901 server.cc(272) Entering Server::DispatchCommand
T 2017-03-29 17:44:34:901 server.cc(579) Entering Server::LookupCommand
D 2017-03-29 17:44:34:901 server.cc(281) Command: GET /session/9a923fa7-2b8d-4f2b-a501-eb4496c8d5ff/screenshot {}
T 2017-03-29 17:44:34:901 server.cc(395) Entering Server::LookupSession
T 2017-03-29 17:44:34:901 IESession.cpp(191) Entering IESession::ExecuteCommand
T 2017-03-29 17:44:34:901 IECommandExecutor.cpp(125) Entering IECommandExecutor::OnSetCommand
T 2017-03-29 17:44:34:901 command.cc(31) Entering Command::Deserialize
D 2017-03-29 17:44:34:901 command.cc(36) Raw JSON command: { "name" : "takeScreenshot", "locator" : { "sessionid" : "9a923fa7-2b8d-4f2b-a501-eb4496c8d5ff" }, "parameters" : {} }
T 2017-03-29 17:44:34:902 IESession.cpp(212) Beginning wait for response length to be not zero
T 2017-03-29 17:45:35:910 server.cc(174) Entering Server::ProcessRequest
T 2017-03-29 17:45:35:911 server.cc(183) Process request with: URI: /session/9a923fa7-2b8d-4f2b-a501-eb4496c8d5ff HTTP verb: DELETE
body: {}
T 2017-03-29 17:45:35:911 server.cc(272) Entering Server::DispatchCommand
T 2017-03-29 17:45:35:911 server.cc(579) Entering Server::LookupCommand
D 2017-03-29 17:45:35:911 server.cc(281) Command: DELETE /session/9a923fa7-2b8d-4f2b-a501-eb4496c8d5ff {}
T 2017-03-29 17:45:35:911 server.cc(395) Entering Server::LookupSession
T 2017-03-29 17:45:35:911 IESession.cpp(191) Entering IESession::ExecuteCommand
T 2017-03-29 17:45:35:911 IECommandExecutor.cpp(125) Entering IECommandExecutor::OnSetCommand
T 2017-03-29 17:45:35:911 command.cc(31) Entering Command::Deserialize
D 2017-03-29 17:45:35:911 command.cc(36) Raw JSON command: { "name" : "quit", "locator" : { "sessionid" : "9a923fa7-2b8d-4f2b-a501-eb4496c8d5ff" }, "parameters" : {} }
T 2017-03-29 17:45:35:911 IESession.cpp(212) Beginning wait for response length to be not zero
T 2017-03-29 17:46:34:926 server.cc(174) Entering Server::ProcessRequest
T 2017-03-29 17:46:34:926 server.cc(183) Process request with: URI: /shutdown HTTP verb: GET
body: {}
T 2017-03-29 17:46:34:926 server.cc(462) Entering Server::SendHttpOk
T 2017-03-29 17:46:34:926 IEServer.cpp(79) Entering IEServer::ShutDown
T 2017-03-29 17:46:34:926 WebDriver.cpp(54) Entering StopServer
T 2017-03-29 17:46:34:926 server.cc(165) Entering Server::Stop
Rain9333 picture Rain9333 on 29 Mar 2017

If it helps, there was a security update (MS17-006 / 4012204) for IE11 in about that timeframe. I'm on the 3.1 workaround as well.

AndyUM357 picture AndyUM357 on 29 Mar 2017

I've taken a stab at refactoring the entirety of file upload dialog handling in IE in commit 5c8380b. That commit includes checking in 3.3.0.2 into the prebuilts directory. Anyone having difficulty with file uploading should pull the file from the master branch of the project tree. As always, a log file from the IE driver will continue to be useful in tracking this down.

jimevans picture jimevans on 10 Apr 2017

@Rain9333, @benzman81, @barancev, if each of you could drop that executable into your environments and let me know the results, I'd be appreciative. Also, please note that because the new solution relies on the Microsoft UI Automation library, I'm hopeful that the solution will be viable for Windows 7 and above, but I've only actually tested it against Windows 10 at the moment. It should work with Windows Vista (SP1?) and might work with Windows XP SP3, but I'm not sure I'll go out of my way to resolve the issue if it only shows up on those OS versions. Likewise, the IE driver has not actively supported IE7 or IE8 for nearly a year; if this fix breaks those browser versions, that's just the way it goes.

jimevans picture jimevans on 10 Apr 2017

@jimevans First - thanks for the effort! Unfortunately I still get the same error :/

Tried on Windows 10 Pro (all updates).

This is what I got from the Driver log (still hang out with open file upload dialog):

T 2017-04-10 10:12:04:129 server.cc(408) Entering Server::SendResponseToClient
T 2017-04-10 10:12:04:129 response.cc(36) Entering Response::Deserialize
T 2017-04-10 10:12:04:129 server.cc(462) Entering Server::SendHttpOk
T 2017-04-10 10:12:04:130 server.cc(174) Entering Server::ProcessRequest
T 2017-04-10 10:12:04:130 server.cc(238) Entering Server::ReadRequestBody
T 2017-04-10 10:12:04:130 server.cc(183) Process request with: URI: /session/f0647702-a7fc-4b95-9a81-6a4fed2768c4/element HTTP verb: POST
body: {"using":"id","value":"Body_uxBodyContents_uxContents_uxContents_uxFileUpload_uxBasicFileUpload"}
T 2017-04-10 10:12:04:130 server.cc(272) Entering Server::DispatchCommand
T 2017-04-10 10:12:04:130 server.cc(579) Entering Server::LookupCommand
D 2017-04-10 10:12:04:130 server.cc(281) Command: POST /session/f0647702-a7fc-4b95-9a81-6a4fed2768c4/element {"using":"id","value":"Body_uxBodyContents_uxContents_uxContents_uxFileUpload_uxBasicFileUpload"}
T 2017-04-10 10:12:04:130 server.cc(395) Entering Server::LookupSession
T 2017-04-10 10:12:04:130 IESession.cpp(191) Entering IESession::ExecuteCommand
T 2017-04-10 10:12:04:130 IECommandExecutor.cpp(125) Entering IECommandExecutor::OnSetCommand
T 2017-04-10 10:12:04:130 command.cc(31) Entering Command::Deserialize
D 2017-04-10 10:12:04:130 command.cc(36) Raw JSON command: { "name" : "findElement", "locator" : { "sessionid" : "f0647702-a7fc-4b95-9a81-6a4fed2768c4" }, "parameters" : {"using":"id","value":"Body_uxBodyContents_uxContents_uxContents_uxFileUpload_uxBasicFileUpload"} }
T 2017-04-10 10:12:04:130 IECommandExecutor.cpp(136) Entering IECommandExecutor::OnExecCommand
T 2017-04-10 10:12:04:130 IESession.cpp(212) Beginning wait for response length to be not zero
T 2017-04-10 10:12:04:130 IECommandExecutor.cpp(459) Entering IECommandExecutor::DispatchCommand
T 2017-04-10 10:12:04:130 IECommandExecutor.cpp(574) Entering IECommandExecutor::GetCurrentBrowser
T 2017-04-10 10:12:04:130 IECommandExecutor.cpp(580) Entering IECommandExecutor::GetManagedBrowser
T 2017-04-10 10:12:04:130 IECommandExecutor.cpp(530) Entering IECommandExecutor::IsAlertActive
T 2017-04-10 10:12:04:130 Browser.cpp(713) Entering Browser::GetActiveDialogWindowHandle
T 2017-04-10 10:12:04:130 Browser.cpp(206) Entering Browser::GetContentWindowHandle
D 2017-04-10 10:12:04:131 IECommandExecutor.cpp(544) No alert handle is found
T 2017-04-10 10:12:04:131 IECommandExecutor.cpp(712) Entering IECommandExecutor::LocateElement
T 2017-04-10 10:12:04:131 IECommandExecutor.cpp(695) Entering IECommandExecutor::GetElementFindMethod
T 2017-04-10 10:12:04:131 ElementFinder.cpp(43) Entering ElementFinder::FindElement
T 2017-04-10 10:12:04:131 IECommandExecutor.cpp(574) Entering IECommandExecutor::GetCurrentBrowser
T 2017-04-10 10:12:04:131 IECommandExecutor.cpp(580) Entering IECommandExecutor::GetManagedBrowser
D 2017-04-10 10:12:04:131 ElementFinder.cpp(60) Using FindElement atom to locate element having id = Body_uxBodyContents_uxContents_uxContents_uxFileUpload_uxBasicFileUpload
T 2017-04-10 10:12:04:131 Browser.cpp(126) Entering Browser::GetDocument
I 2017-04-10 10:12:04:131 Browser.cpp(130) No child frame focus. Focus is on top-level frame
T 2017-04-10 10:12:04:138 Browser.cpp(613) Entering Browser::GetDocumentFromWindow
T 2017-04-10 10:12:04:141 Script.cpp(49) Entering Script::Initialize
T 2017-04-10 10:12:04:141 Script.cpp(70) Entering Script::AddArgument(std::wstring)
T 2017-04-10 10:12:04:141 Script.cpp(105) Entering Script::AddArgument(VARIANT)
T 2017-04-10 10:12:04:141 Script.cpp(70) Entering Script::AddArgument(std::wstring)
T 2017-04-10 10:12:04:141 Script.cpp(105) Entering Script::AddArgument(VARIANT)
T 2017-04-10 10:12:04:141 Script.cpp(169) Entering Script::Execute
T 2017-04-10 10:12:04:141 Script.cpp(477) Entering Script::CreateAnonymousFunction
T 2017-04-10 10:12:04:159 Script.cpp(426) Entering Script::ConvertResultToJsonValue
D 2017-04-10 10:12:04:160 VariantUtilities.cpp(100) Result type is JScriptTypeInfo
T 2017-04-10 10:12:04:160 VariantUtilities.cpp(258) Entering Script::GetPropertyNameList
I 2017-04-10 10:12:04:162 VariantUtilities.cpp(184) Unknown type of dispatch is found in result, assuming IHTMLElement
T 2017-04-10 10:12:04:162 IECommandExecutor.cpp(677) Entering IECommandExecutor::AddManagedElement
T 2017-04-10 10:12:04:162 IECommandExecutor.cpp(574) Entering IECommandExecutor::GetCurrentBrowser
T 2017-04-10 10:12:04:162 IECommandExecutor.cpp(580) Entering IECommandExecutor::GetManagedBrowser
T 2017-04-10 10:12:04:163 ElementRepository.cpp(49) Entering ElementRepository::AddManagedElement
D 2017-04-10 10:12:04:163 ElementRepository.cpp(69) Element is not yet managed
T 2017-04-10 10:12:04:163 Browser.cpp(206) Entering Browser::GetContentWindowHandle
T 2017-04-10 10:12:04:163 Element.cpp(44) Entering Element::Element
T 2017-04-10 10:12:04:163 Element.cpp(79) Entering Element::ConvertToJson
T 2017-04-10 10:12:04:163 response.cc(63) Entering Response::SetSuccessResponse
T 2017-04-10 10:12:04:163 response.cc(69) Entering Response::SetResponse
T 2017-04-10 10:12:04:163 IECommandExecutor.cpp(574) Entering IECommandExecutor::GetCurrentBrowser
T 2017-04-10 10:12:04:163 IECommandExecutor.cpp(580) Entering IECommandExecutor::GetManagedBrowser
T 2017-04-10 10:12:04:163 response.cc(51) Entering Response::Serialize
T 2017-04-10 10:12:04:163 IESession.cpp(221) Found non-zero response length
T 2017-04-10 10:12:04:163 IECommandExecutor.cpp(159) Entering IECommandExecutor::OnGetResponse
T 2017-04-10 10:12:04:163 IECommandExecutor.cpp(288) Entering IECommandExecutor::OnIsSessionValid
D 2017-04-10 10:12:04:163 server.cc(355) Response: {"sessionId":"f0647702-a7fc-4b95-9a81-6a4fed2768c4","status":0,"value":{"ELEMENT":"6c2424e1-a306-474f-841b-3b950407aac7","element-6066-11e4-a52e-4f735466cecf":"6c2424e1-a306-474f-841b-3b950407aac7"}}

T 2017-04-10 10:12:04:163 server.cc(408) Entering Server::SendResponseToClient
T 2017-04-10 10:12:04:163 response.cc(36) Entering Response::Deserialize
T 2017-04-10 10:12:04:163 server.cc(462) Entering Server::SendHttpOk
T 2017-04-10 10:12:04:164 server.cc(174) Entering Server::ProcessRequest
T 2017-04-10 10:12:04:164 server.cc(238) Entering Server::ReadRequestBody
T 2017-04-10 10:12:04:164 server.cc(183) Process request with: URI: /session/f0647702-a7fc-4b95-9a81-6a4fed2768c4/element HTTP verb: POST
body: {"using":"id","value":"Body_uxBodyContents_uxContents_uxContents_uxFileUpload_uxBasicFileUpload"}
T 2017-04-10 10:12:04:164 server.cc(272) Entering Server::DispatchCommand
T 2017-04-10 10:12:04:164 server.cc(579) Entering Server::LookupCommand
D 2017-04-10 10:12:04:164 server.cc(281) Command: POST /session/f0647702-a7fc-4b95-9a81-6a4fed2768c4/element {"using":"id","value":"Body_uxBodyContents_uxContents_uxContents_uxFileUpload_uxBasicFileUpload"}
T 2017-04-10 10:12:04:164 server.cc(395) Entering Server::LookupSession
T 2017-04-10 10:12:04:164 IESession.cpp(191) Entering IESession::ExecuteCommand
T 2017-04-10 10:12:04:164 IECommandExecutor.cpp(125) Entering IECommandExecutor::OnSetCommand
T 2017-04-10 10:12:04:164 command.cc(31) Entering Command::Deserialize
D 2017-04-10 10:12:04:164 command.cc(36) Raw JSON command: { "name" : "findElement", "locator" : { "sessionid" : "f0647702-a7fc-4b95-9a81-6a4fed2768c4" }, "parameters" : {"using":"id","value":"Body_uxBodyContents_uxContents_uxContents_uxFileUpload_uxBasicFileUpload"} }
T 2017-04-10 10:12:04:164 IECommandExecutor.cpp(136) Entering IECommandExecutor::OnExecCommand
T 2017-04-10 10:12:04:164 IESession.cpp(212) Beginning wait for response length to be not zero
T 2017-04-10 10:12:04:164 IECommandExecutor.cpp(459) Entering IECommandExecutor::DispatchCommand
T 2017-04-10 10:12:04:164 IECommandExecutor.cpp(574) Entering IECommandExecutor::GetCurrentBrowser
T 2017-04-10 10:12:04:164 IECommandExecutor.cpp(580) Entering IECommandExecutor::GetManagedBrowser
T 2017-04-10 10:12:04:164 IECommandExecutor.cpp(530) Entering IECommandExecutor::IsAlertActive
T 2017-04-10 10:12:04:164 Browser.cpp(713) Entering Browser::GetActiveDialogWindowHandle
T 2017-04-10 10:12:04:164 Browser.cpp(206) Entering Browser::GetContentWindowHandle
D 2017-04-10 10:12:04:164 IECommandExecutor.cpp(544) No alert handle is found
T 2017-04-10 10:12:04:164 IECommandExecutor.cpp(712) Entering IECommandExecutor::LocateElement
T 2017-04-10 10:12:04:164 IECommandExecutor.cpp(695) Entering IECommandExecutor::GetElementFindMethod
T 2017-04-10 10:12:04:164 ElementFinder.cpp(43) Entering ElementFinder::FindElement
T 2017-04-10 10:12:04:164 IECommandExecutor.cpp(574) Entering IECommandExecutor::GetCurrentBrowser
T 2017-04-10 10:12:04:164 IECommandExecutor.cpp(580) Entering IECommandExecutor::GetManagedBrowser
D 2017-04-10 10:12:04:164 ElementFinder.cpp(60) Using FindElement atom to locate element having id = Body_uxBodyContents_uxContents_uxContents_uxFileUpload_uxBasicFileUpload
T 2017-04-10 10:12:04:164 Browser.cpp(126) Entering Browser::GetDocument
I 2017-04-10 10:12:04:164 Browser.cpp(130) No child frame focus. Focus is on top-level frame
T 2017-04-10 10:12:04:172 Browser.cpp(613) Entering Browser::GetDocumentFromWindow
T 2017-04-10 10:12:04:176 Script.cpp(49) Entering Script::Initialize
T 2017-04-10 10:12:04:176 Script.cpp(70) Entering Script::AddArgument(std::wstring)
T 2017-04-10 10:12:04:176 Script.cpp(105) Entering Script::AddArgument(VARIANT)
T 2017-04-10 10:12:04:176 Script.cpp(70) Entering Script::AddArgument(std::wstring)
T 2017-04-10 10:12:04:176 Script.cpp(105) Entering Script::AddArgument(VARIANT)
T 2017-04-10 10:12:04:176 Script.cpp(169) Entering Script::Execute
T 2017-04-10 10:12:04:176 Script.cpp(477) Entering Script::CreateAnonymousFunction
T 2017-04-10 10:12:04:197 Script.cpp(426) Entering Script::ConvertResultToJsonValue
D 2017-04-10 10:12:04:197 VariantUtilities.cpp(100) Result type is JScriptTypeInfo
T 2017-04-10 10:12:04:197 VariantUtilities.cpp(258) Entering Script::GetPropertyNameList
I 2017-04-10 10:12:04:200 VariantUtilities.cpp(184) Unknown type of dispatch is found in result, assuming IHTMLElement
T 2017-04-10 10:12:04:200 IECommandExecutor.cpp(677) Entering IECommandExecutor::AddManagedElement
T 2017-04-10 10:12:04:200 IECommandExecutor.cpp(574) Entering IECommandExecutor::GetCurrentBrowser
T 2017-04-10 10:12:04:200 IECommandExecutor.cpp(580) Entering IECommandExecutor::GetManagedBrowser
T 2017-04-10 10:12:04:200 ElementRepository.cpp(49) Entering ElementRepository::AddManagedElement
D 2017-04-10 10:12:04:200 ElementRepository.cpp(75) Element is already managed
T 2017-04-10 10:12:04:200 Element.cpp(79) Entering Element::ConvertToJson
T 2017-04-10 10:12:04:200 response.cc(63) Entering Response::SetSuccessResponse
T 2017-04-10 10:12:04:200 response.cc(69) Entering Response::SetResponse
T 2017-04-10 10:12:04:200 IECommandExecutor.cpp(574) Entering IECommandExecutor::GetCurrentBrowser
T 2017-04-10 10:12:04:200 IECommandExecutor.cpp(580) Entering IECommandExecutor::GetManagedBrowser
T 2017-04-10 10:12:04:200 response.cc(51) Entering Response::Serialize
T 2017-04-10 10:12:04:206 IESession.cpp(221) Found non-zero response length
T 2017-04-10 10:12:04:206 IECommandExecutor.cpp(159) Entering IECommandExecutor::OnGetResponse
T 2017-04-10 10:12:04:207 IECommandExecutor.cpp(288) Entering IECommandExecutor::OnIsSessionValid
D 2017-04-10 10:12:04:207 server.cc(355) Response: {"sessionId":"f0647702-a7fc-4b95-9a81-6a4fed2768c4","status":0,"value":{"ELEMENT":"6c2424e1-a306-474f-841b-3b950407aac7","element-6066-11e4-a52e-4f735466cecf":"6c2424e1-a306-474f-841b-3b950407aac7"}}

T 2017-04-10 10:12:04:207 server.cc(408) Entering Server::SendResponseToClient
T 2017-04-10 10:12:04:207 response.cc(36) Entering Response::Deserialize
T 2017-04-10 10:12:04:207 server.cc(462) Entering Server::SendHttpOk
T 2017-04-10 10:12:04:708 server.cc(174) Entering Server::ProcessRequest
T 2017-04-10 10:12:04:708 server.cc(238) Entering Server::ReadRequestBody
T 2017-04-10 10:12:04:708 server.cc(183) Process request with: URI: /session/f0647702-a7fc-4b95-9a81-6a4fed2768c4/element/6c2424e1-a306-474f-841b-3b950407aac7/value HTTP verb: POST
body: {"value":["C:\\Compressing Haptic.zip"]}
T 2017-04-10 10:12:04:708 server.cc(272) Entering Server::DispatchCommand
T 2017-04-10 10:12:04:708 server.cc(579) Entering Server::LookupCommand
D 2017-04-10 10:12:04:709 server.cc(281) Command: POST /session/f0647702-a7fc-4b95-9a81-6a4fed2768c4/element/6c2424e1-a306-474f-841b-3b950407aac7/value {"value":["C:\\Compressing Haptic.zip"]}
T 2017-04-10 10:12:04:709 server.cc(395) Entering Server::LookupSession
T 2017-04-10 10:12:04:709 IESession.cpp(191) Entering IESession::ExecuteCommand
T 2017-04-10 10:12:04:709 IECommandExecutor.cpp(125) Entering IECommandExecutor::OnSetCommand
T 2017-04-10 10:12:04:709 command.cc(31) Entering Command::Deserialize
D 2017-04-10 10:12:04:709 command.cc(36) Raw JSON command: { "name" : "sendKeys", "locator" : { "sessionid" : "f0647702-a7fc-4b95-9a81-6a4fed2768c4", "id" : "6c2424e1-a306-474f-841b-3b950407aac7" }, "parameters" : {"value":["C:\\Compressing Haptic.zip"]} }
T 2017-04-10 10:12:04:709 IECommandExecutor.cpp(136) Entering IECommandExecutor::OnExecCommand
T 2017-04-10 10:12:04:709 IECommandExecutor.cpp(459) Entering IECommandExecutor::DispatchCommand
T 2017-04-10 10:12:04:709 IESession.cpp(212) Beginning wait for response length to be not zero
T 2017-04-10 10:12:04:709 IECommandExecutor.cpp(574) Entering IECommandExecutor::GetCurrentBrowser
T 2017-04-10 10:12:04:709 IECommandExecutor.cpp(580) Entering IECommandExecutor::GetManagedBrowser
T 2017-04-10 10:12:04:709 IECommandExecutor.cpp(530) Entering IECommandExecutor::IsAlertActive
T 2017-04-10 10:12:04:709 Browser.cpp(713) Entering Browser::GetActiveDialogWindowHandle
T 2017-04-10 10:12:04:709 Browser.cpp(206) Entering Browser::GetContentWindowHandle
D 2017-04-10 10:12:04:709 IECommandExecutor.cpp(544) No alert handle is found
T 2017-04-10 10:12:04:709 IECommandExecutor.cpp(574) Entering IECommandExecutor::GetCurrentBrowser
T 2017-04-10 10:12:04:709 IECommandExecutor.cpp(580) Entering IECommandExecutor::GetManagedBrowser
T 2017-04-10 10:12:04:709 Browser.cpp(206) Entering Browser::GetContentWindowHandle
T 2017-04-10 10:12:04:709 Browser.cpp(416) Entering Browser::GetTopLevelWindowHandle
T 2017-04-10 10:12:04:710 IECommandHandler.cpp(45) Entering IECommandHandler::GetElement
T 2017-04-10 10:12:04:710 IECommandExecutor.cpp(671) Entering IECommandExecutor::GetManagedElement
T 2017-04-10 10:12:04:710 ElementRepository.cpp(34) Entering ElementRepository::GetManagedElement
T 2017-04-10 10:12:04:714 IECommandExecutor.cpp(574) Entering IECommandExecutor::GetCurrentBrowser
T 2017-04-10 10:12:04:714 IECommandExecutor.cpp(580) Entering IECommandExecutor::GetManagedBrowser
T 2017-04-10 10:12:04:714 Browser.cpp(126) Entering Browser::GetDocument
I 2017-04-10 10:12:04:714 Browser.cpp(130) No child frame focus. Focus is on top-level frame
T 2017-04-10 10:12:04:721 Browser.cpp(613) Entering Browser::GetDocumentFromWindow
T 2017-04-10 10:12:04:725 Element.cpp(312) Entering Element::GetLocationOnceScrolledIntoView
T 2017-04-10 10:12:04:725 Element.cpp(443) Entering Element::GetLocation
T 2017-04-10 10:12:04:726 Element.cpp(579) Entering Element::IsInline
D 2017-04-10 10:12:04:726 Element.cpp(489) Element is a block element, using IHTMLElement2::getBoundingClientRect
T 2017-04-10 10:12:04:729 Element.cpp(600) Entering Element::RectHasNonZeroDimensions
T 2017-04-10 10:12:04:730 Element.cpp(616) Entering Element::GetFrameDetails
T 2017-04-10 10:12:04:731 Element.cpp(906) Entering Element::GetContainingDocument
D 2017-04-10 10:12:04:737 Element.cpp(566) Element is not in a frame
T 2017-04-10 10:12:04:737 Element.cpp(855) Entering Element::CalculateClickPoint
T 2017-04-10 10:12:04:737 Element.cpp(794) Entering Element::GetClickableViewPortLocation
T 2017-04-10 10:12:04:737 Element.cpp(906) Entering Element::GetContainingDocument
T 2017-04-10 10:12:04:738 DocumentHost.cpp(271) Entering DocumentHost::GetDocumentMode
T 2017-04-10 10:12:04:742 Element.cpp(881) Entering Element::IsLocationInViewPort
T 2017-04-10 10:12:04:742 Element.cpp(794) Entering Element::GetClickableViewPortLocation
T 2017-04-10 10:12:04:742 Element.cpp(906) Entering Element::GetContainingDocument
T 2017-04-10 10:12:04:743 DocumentHost.cpp(271) Entering DocumentHost::GetDocumentMode
T 2017-04-10 10:12:04:748 Element.cpp(379) Entering Element::IsHiddenByOverflow
T 2017-04-10 10:12:04:748 Element.cpp(906) Entering Element::GetContainingDocument
T 2017-04-10 10:12:04:749 Script.cpp(49) Entering Script::Initialize
T 2017-04-10 10:12:04:749 Script.cpp(99) Entering Script::AddArgument(IHTMLElement*)
T 2017-04-10 10:12:04:749 Script.cpp(105) Entering Script::AddArgument(VARIANT)
T 2017-04-10 10:12:04:749 Script.cpp(169) Entering Script::Execute
T 2017-04-10 10:12:04:749 Script.cpp(477) Entering Script::CreateAnonymousFunction
T 2017-04-10 10:12:04:767 Script.cpp(433) Entering Script::ConvertResultToString
D 2017-04-10 10:12:04:767 Script.cpp(444) Result type is string
D 2017-04-10 10:12:04:767 Element.cpp(358) (x, y, w, h): 730, 340, 234, 24
D 2017-04-10 10:12:04:770 CommandHandlers\SendKeysCommandHandler.cpp(144) Clicking upload button and starting to handle file selection dialog
W 2017-04-10 10:12:04:959 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:05:095 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:05:215 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:05:333 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:05:451 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:05:568 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:05:687 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:05:802 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:05:917 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:06:034 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:06:151 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:06:268 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:06:387 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:06:505 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:06:620 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:06:738 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:06:856 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:06:974 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:07:092 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:07:211 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:07:328 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:07:445 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:07:561 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:07:677 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:07:795 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:07:912 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:08:028 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:08:145 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:08:263 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:08:380 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:08:495 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:08:611 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:08:726 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:08:844 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:08:961 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:09:081 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:09:198 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:09:316 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:09:434 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:09:552 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:09:669 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:09:784 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:09:901 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:10:017 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:10:136 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:10:252 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:10:368 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:10:485 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:10:603 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:10:718 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:10:834 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:10:951 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:11:069 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:11:187 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:11:307 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:11:424 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:11:543 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:11:662 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:11:778 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:11:894 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:12:012 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:12:126 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:12:244 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:12:361 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:12:477 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:12:592 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:12:706 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:12:823 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:12:940 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:13:059 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:13:176 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:13:296 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:13:412 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:13:529 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:13:645 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:13:759 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:13:878 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:14:002 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:14:120 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:14:243 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:14:363 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:14:481 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:14:604 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:14:718 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:14:831 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:14:958 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:15:077 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:15:193 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:15:314 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:15:431 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:15:547 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:15:664 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:15:780 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:15:896 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:16:013 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:16:128 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:16:245 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:16:386 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:16:509 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:16:637 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:16:761 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:16:879 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:16:997 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:17:114 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:17:230 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:17:347 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:17:470 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:17:593 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:17:712 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:17:828 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:17:944 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:18:067 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:18:185 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:18:307 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:18:422 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:18:541 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:18:664 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:18:780 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:18:897 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:19:015 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:19:140 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:19:260 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:19:386 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:19:511 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:19:628 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:19:749 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:19:873 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:19:991 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:20:113 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:20:232 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:20:353 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:20:473 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:20:596 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:20:718 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:20:837 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:20:959 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:21:084 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:21:203 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:21:322 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:21:440 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:21:561 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:21:686 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:21:808 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:21:928 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:22:051 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:22:169 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:22:285 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:22:403 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:22:520 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:22:636 CommandHandlers\SendKeysCommandHandler.cpp(217) Did not find any dialogs after dialog timeout
W 2017-04-10 10:12:22:636 CommandHandlers\SendKeysCommandHandler.cpp(450) Did not find any dialogs after dialog timeout
T 2017-04-10 10:13:05:731 server.cc(174) Entering Server::ProcessRequest
T 2017-04-10 10:13:05:731 server.cc(238) Entering Server::ReadRequestBody
T 2017-04-10 10:13:05:731 server.cc(183) Process request with: URI: /session/f0647702-a7fc-4b95-9a81-6a4fed2768c4/execute HTTP verb: POST
body: {"script":"return document.readyState","args":[]}
T 2017-04-10 10:13:05:731 server.cc(272) Entering Server::DispatchCommand
T 2017-04-10 10:13:05:731 server.cc(579) Entering Server::LookupCommand
D 2017-04-10 10:13:05:731 server.cc(281) Command: POST /session/f0647702-a7fc-4b95-9a81-6a4fed2768c4/execute {"script":"return document.readyState","args":[]}
T 2017-04-10 10:13:05:731 server.cc(395) Entering Server::LookupSession
T 2017-04-10 10:13:05:731 IESession.cpp(191) Entering IESession::ExecuteCommand
T 2017-04-10 10:13:05:731 IECommandExecutor.cpp(125) Entering IECommandExecutor::OnSetCommand
T 2017-04-10 10:13:05:731 command.cc(31) Entering Command::Deserialize
D 2017-04-10 10:13:05:731 command.cc(36) Raw JSON command: { "name" : "executeScript", "locator" : { "sessionid" : "f0647702-a7fc-4b95-9a81-6a4fed2768c4" }, "parameters" : {"script":"return document.readyState","args":[]} }
T 2017-04-10 10:13:05:732 IESession.cpp(212) Beginning wait for response length to be not zero
T 2017-04-10 10:14:06:749 server.cc(174) Entering Server::ProcessRequest
T 2017-04-10 10:14:06:749 server.cc(183) Process request with: URI: /session/f0647702-a7fc-4b95-9a81-6a4fed2768c4/screenshot HTTP verb: GET
body: {}
T 2017-04-10 10:14:06:749 server.cc(272) Entering Server::DispatchCommand
T 2017-04-10 10:14:06:749 server.cc(579) Entering Server::LookupCommand
D 2017-04-10 10:14:06:749 server.cc(281) Command: GET /session/f0647702-a7fc-4b95-9a81-6a4fed2768c4/screenshot {}
T 2017-04-10 10:14:06:749 server.cc(395) Entering Server::LookupSession
T 2017-04-10 10:14:06:749 IESession.cpp(191) Entering IESession::ExecuteCommand
T 2017-04-10 10:14:06:749 IECommandExecutor.cpp(125) Entering IECommandExecutor::OnSetCommand
T 2017-04-10 10:14:06:749 command.cc(31) Entering Command::Deserialize
D 2017-04-10 10:14:06:749 command.cc(36) Raw JSON command: { "name" : "takeScreenshot", "locator" : { "sessionid" : "f0647702-a7fc-4b95-9a81-6a4fed2768c4" }, "parameters" : {} }
T 2017-04-10 10:14:06:749 IESession.cpp(212) Beginning wait for response length to be not zero
T 2017-04-10 10:15:05:771 server.cc(174) Entering Server::ProcessRequest
T 2017-04-10 10:15:05:771 server.cc(183) Process request with: URI: /session/f0647702-a7fc-4b95-9a81-6a4fed2768c4 HTTP verb: DELETE
body: {}
T 2017-04-10 10:15:05:771 server.cc(272) Entering Server::DispatchCommand
T 2017-04-10 10:15:05:771 server.cc(579) Entering Server::LookupCommand
D 2017-04-10 10:15:05:771 server.cc(281) Command: DELETE /session/f0647702-a7fc-4b95-9a81-6a4fed2768c4 {}
T 2017-04-10 10:15:05:771 server.cc(395) Entering Server::LookupSession
T 2017-04-10 10:15:05:771 IESession.cpp(191) Entering IESession::ExecuteCommand
T 2017-04-10 10:15:05:771 IECommandExecutor.cpp(125) Entering IECommandExecutor::OnSetCommand
T 2017-04-10 10:15:05:771 command.cc(31) Entering Command::Deserialize
D 2017-04-10 10:15:05:771 command.cc(36) Raw JSON command: { "name" : "quit", "locator" : { "sessionid" : "f0647702-a7fc-4b95-9a81-6a4fed2768c4" }, "parameters" : {} }
T 2017-04-10 10:15:05:771 IESession.cpp(212) Beginning wait for response length to be not zero
T 2017-04-10 10:16:06:774 server.cc(174) Entering Server::ProcessRequest
T 2017-04-10 10:16:06:774 server.cc(183) Process request with: URI: /shutdown HTTP verb: GET
body: {}
T 2017-04-10 10:16:06:774 server.cc(462) Entering Server::SendHttpOk
T 2017-04-10 10:16:06:774 IEServer.cpp(79) Entering IEServer::ShutDown
T 2017-04-10 10:16:06:775 WebDriver.cpp(54) Entering StopServer
T 2017-04-10 10:16:06:775 server.cc(165) Entering Server::Stop
Rain9333 picture Rain9333 on 10 Apr 2017

Tried this quickly on Win7 IE11 and still has the same failure as before

benheap picture benheap on 10 Apr 2017

@Rain9333 That's incredibly odd. I ran a test to perform literally 100 file uploads, which worked without issue. Since you're running C#, I'm assuming you have Visual Studio. If you also have the Windows SDK installed, you'll have a tool called Inspect.exe on your machine. This is a tool for looking at the accessibility information of windows in the OS, including Microsoft's UI Automation properties that are available. Can that tool see the Windows file selection dialog? Does it list the IE top-level window as its immediate parent window?

@benheap No log from the driver? Reports that, "it still doesn't work," without the supporting information, are not as helpful as those with it.

jimevans picture jimevans on 10 Apr 2017

Hey @jimevans

Hope this screenshot is useful for you (I delete some private info from it like tittle & names, but should be OK :)).

inspector

And yeah - you assume correctly I use VS 2015, run the tests with NUnit 3 (via SpecFlow). Also have the Windows SDK. Language is C# as well.

Rain9333 picture Rain9333 on 10 Apr 2017

@Rain9333 That helps. I need to check on some things in my dev environment. Are you on the Selenium Slack channel, and will you be available in about 3 hours (I have to get kids out the door for school)?

jimevans picture jimevans on 10 Apr 2017

Just joined if we talk about that one -> seleniumhq.slack.com (same nick). And yeah, I should be available in 2-3 hours Jim :)

Rain9333 picture Rain9333 on 10 Apr 2017

@jimevans apologies, see gist for a passing log and failing log of an attempted upload using the example java code above on Windows 7 IE11
https://gist.github.com/benheap/931ef2e2ce8b22898a33f0714320bec3

benheap picture benheap on 10 Apr 2017

So apparently the legacy method is incredibly sensitive to timing. So sensitive, that even changing the logging level of the driver can cause it to toggle between pass and fail, and even between versions of the IE driver itself. As I said, I've rewritten the file dialog handling to use Microsoft's UI Automation library, which should, in theory, be more reliable. I think I have a lead on the bug that is causing people to see failures using the "fixed" executable. I may have a replacement soon, and will inform here.

jimevans picture jimevans on 10 Apr 2017

Okay, I've pushed a new version of IEDriverServer.exe (3.3.0.3) to the repo in commit d2e43e9. @benheap, @barancev, @Rain9333, @benzman81, please try that and see if it works better.

jimevans picture jimevans on 11 Apr 2017

@jimevans using 3.3.0.3 on Windows 7 IE11 passed 3 times in a row using the sample code in this issue. I've updated my Gist to include the passing logs. Thanks!

benheap picture benheap on 11 Apr 2017

@jimevans will try next week. I am on vacation right now.

benzman81 picture benzman81 on 11 Apr 2017

Works like a charm, great job @jimevans !

Rain9333 picture Rain9333 on 11 Apr 2017

Is this issue now fixed & merged in 3.3.1 ?

Rain9333 picture Rain9333 on 18 Apr 2017

There is no IEDriverServer.exe release 3.3.1. I was waiting to hear from @benzman81 to make sure there was one final confirmation that my changes really did fix the issue. Additionally, the project is beginning to prep for a 3.4 release (no set release date, please don't ask for one). Finally, you can continue to use the 3.3.0.3 binary in the meantime, until an official release is cut.

jimevans picture jimevans on 18 Apr 2017

@jimevans I was able to test it and seems to work now. Great job.

benzman81 picture benzman81 on 19 Apr 2017

The modified driver will be publicly released with the next release of the Selenium project. Until then, if you need this fix, please continue to use the binary checked into the project tree in the prebuilts directory.

jimevans picture jimevans on 19 Apr 2017

I have noticed this issue when our vm's are resized from 8gb to 1 gb and 2gb memory. I personally never had this issue in my local machine as its 8 gb of memory.

nageshreddy picture nageshreddy on 26 Feb 2018
Was this page helpful?
0 / 5 - 0 ratings

Related issues

umairmm picture umairmm  路  42Comments
etwxabh picture etwxabh  路  31Comments
NikolayStanoev picture NikolayStanoev  路  34Comments
puneetkaur11 picture puneetkaur11  路  65Comments
konstin picture konstin  路  33Comments