Skip to content
This repository has been archived by the owner on Nov 29, 2018. It is now read-only.

RemoteWebDriver to get LogType=performance produces exception, works fine with ChromeDriver #8457

Closed
lukeis opened this issue Mar 4, 2016 · 12 comments
Closed

RemoteWebDriver to get LogType=performance produces exception, works fine with ChromeDriver #8457

lukeis opened this issue Mar 4, 2016 · 12 comments
Assignees
@lukeis
Labels
Component-Grid Priority-Medium Restrict-AddIssueComment-Commit Status-Fixed Type-Defect

Comments

@lukeis
Copy link
Member

lukeis commented Mar 4, 2016

Originally reported on Google Code with ID 8457

Using RemoteWebDriver to obtain performance logs from LogType produces and exception.
 If you use a general ChromeDriver object, the performance logs are produced.

What steps will reproduce the problem?
1. Create a driver instance using RemoteWebDriver:

Does NOT produce performance logs:
        desiredCapabilites = DesiredCapabilities.firefox()
        LoggingPreferences logPrefs = new LoggingPreferences()
        logPrefs.enable(LogType.PERFORMANCE, Level.ALL)
        desiredCapabilites.setCapability(CapabilityType.LOGGING_PREFS, logPrefs)
        driver = { new RemoteWebDriver(new URL(seleniumGridUrl), desiredCapabilites)
}

Produces performance logs without issue:
        desiredCapabilites = DesiredCapabilities.chrome()
        LoggingPreferences logPrefs = new LoggingPreferences()
        logPrefs.enable(LogType.PERFORMANCE, Level.ALL)
        desiredCapabilites.setCapability(CapabilityType.LOGGING_PREFS, logPrefs)
        driver = { new ChromeDriver(desiredCapabilites) }

2. Execute driver.manage().logs().get(LogType.PERFORMANCE)

3. Not results are found, exception thrown.


What is the expected output?
To get a list (possibly empty) of performance logs

 What do you see instead?
org.openqa.selenium.WebDriverException: unknown error: log type 'performance' not found
  (Session info: chrome=40.0.2214.94)
  (Driver info: chromedriver=2.14.313457 (3d645c400edf2e2c500566c9aa096063e707c9cf),platform=Mac
OS X 10.10.2 x86_64) (WARNING: The server did not provide any stacktrace information)
Command duration or timeout: 2 milliseconds
Build info: version: '2.44.0', revision: '76d78cf', time: '2014-10-23 20:02:37'
System info: host: 'N/A', ip: 'N/A', os.name: 'Mac OS X', os.arch: 'x86_64', os.version:
'10.10.2', java.version: '1.7.0_71'
Session ID: bde2c7d069c27dc7bbd60625806f33a0
Driver info: org.openqa.selenium.chrome.ChromeDriver
Capabilities [{platform=MAC, acceptSslCerts=true, javascriptEnabled=true, browserName=chrome,
chrome={userDataDir=/var/folders/74/nn0zw7d576jbgvdsnjyhwkmw0000gn/T/.org.chromium.Chromium.yK7ES9},
rotatable=false, locationContextEnabled=true, mobileEmulationEnabled=false, version=40.0.2214.94,
takesHeapSnapshot=true, cssSelectorsEnabled=true, databaseEnabled=false, handlesAlerts=true,
browserConnectionEnabled=false, webStorageEnabled=true, nativeEvents=true, applicationCacheEnabled=false,
takesScreenshot=true}]
Command duration or timeout: 126 milliseconds
Build info: version: '2.43.1', revision: '5163bceef1bc36d43f3dc0b83c88998168a363a0',
time: '2014-09-10 09:43:55'
System info: host: 'N/A', ip: 'N/A', os.name: 'Mac OS X', os.arch: 'x86_64', os.version:
'10.10.2', java.version: '1.7.0_55'
Session ID: 2bbb10fd-9e1d-46dc-b500-a5e08227c100
Driver info: org.openqa.selenium.remote.RemoteWebDriver
Capabilities [{platform=MAC, acceptSslCerts=true, javascriptEnabled=true, browserName=chrome,
chrome={userDataDir=/var/folders/74/nn0zw7d576jbgvdsnjyhwkmw0000gn/T/.org.chromium.Chromium.yK7ES9},
rotatable=false, locationContextEnabled=true, mobileEmulationEnabled=false, webdriver.remote.sessionid=2bbb10fd-9e1d-46dc-b500-a5e08227c100,
version=40.0.2214.94, takesHeapSnapshot=true, databaseEnabled=false, cssSelectorsEnabled=true,
handlesAlerts=true, browserConnectionEnabled=false, nativeEvents=true, webStorageEnabled=true,
applicationCacheEnabled=false, takesScreenshot=true}]
    at org.openqa.selenium.remote.ErrorHandler.createThrowable(ErrorHandler.java:204)
    at org.openqa.selenium.remote.ErrorHandler.throwIfResponseFailed(ErrorHandler.java:156)
    at org.openqa.selenium.remote.RemoteWebDriver.execute(RemoteWebDriver.java:599)
    at org.openqa.selenium.remote.RemoteExecuteMethod.execute(RemoteExecuteMethod.java:34)
    at org.openqa.selenium.remote.RemoteLogs.getRemoteEntries(RemoteLogs.java:65)
    at org.openqa.selenium.remote.RemoteLogs.get(RemoteLogs.java:61)

Selenium version:2.43.1 and 2.44.0
OS:OSX Yosemite, Windows7, Windows8
Browser:Chrome
Browser version:40.0.2214.93

Reported by email@stephencopp.com on 2015-02-04 17:06:52
@lukeis
Copy link
Member Author

lukeis commented Mar 4, 2016 

Moved to: Issue chromedriver:1064

Reported by barancev on 2015-03-21 12:52:53

  • Status changed: Moved

@lukeis lukeis self-assigned this Mar 4, 2016
@lukeis
Copy link
Member Author

lukeis commented Mar 4, 2016 

By the way, I can't reproduce the issue. Here is the code:

    DesiredCapabilities desiredCapabilites = DesiredCapabilities.chrome();
    LoggingPreferences logPrefs = new LoggingPreferences();
    logPrefs.enable(LogType.PERFORMANCE, Level.ALL);
    desiredCapabilites.setCapability(CapabilityType.LOGGING_PREFS, logPrefs);
    RemoteWebDriver driver = new RemoteWebDriver(desiredCapabilites);
    try {
      System.out.println(driver.manage().logs().getAvailableLogTypes());
      driver.get("http://localhost/");
      for (LogEntry logEntry : driver.manage().logs().get(LogType.PERFORMANCE).getAll())
{
        System.out.println(logEntry);
      }

    } finally {
      driver.quit();
    }

The console output on the server side:

16:50:13.005 INFO - Executing: [new session: Capabilities [{loggingPrefs=org.ope
nqa.selenium.logging.LoggingPreferences@64bb5a5e, browserName=chrome, version=,
platform=ANY}]])
16:50:13.006 INFO - Creating a new session for Capabilities [{loggingPrefs=org.o
penqa.selenium.logging.LoggingPreferences@64bb5a5e, browserName=chrome, version=
, platform=ANY}]
Starting ChromeDriver 2.14.313457 (3d645c400edf2e2c500566c9aa096063e707c9cf) on
port 19863
Only local connections are allowed.
16:50:15.631 INFO - Done: [new session: Capabilities [{loggingPrefs=org.openqa.s
elenium.logging.LoggingPreferences@64bb5a5e, browserName=chrome, version=, platf
orm=ANY}]]
16:50:15.653 INFO - Executing: [fetching available log types])
16:50:15.660 INFO - Done: [fetching available log types]
16:50:15.739 INFO - Executing: [get: http://localhost/])
16:50:29.728 INFO - Done: [get: http://localhost/]
16:50:29.753 INFO - Executing: [fetching logs for: performance])
16:50:29.798 INFO - Done: [fetching logs for: performance]
16:50:29.904 INFO - Executing: [delete session: 538d4407-d685-4292-a519-01aa25f0
d412])
16:50:31.040 INFO - Done: [delete session: 538d4407-d685-4292-a519-01aa25f0d412]

The console output on the client side:

[performance, browser, driver, client, server]
[2015-03-21T16:50:20+0400] [INFO] {"message":{"method":"Network.responseReceived","params":{"frameId":"28480.1","loaderId":"28480.2","requestId":"28480.1","response":{"connectionId":0,"connectionReused":false,"encodedDataLength":-1,"fromDiskCache":false,"fromServiceWorker":false,"headers":{"Access-Control-Allow-Origin":"*","Content-Type":"text/plain;charset=US-ASCII"},"mimeType":"text/plain","protocol":"data","status":200,"statusText":"OK","url":"data:,"},"timestamp":1426942217.5344,"type":"Other"}},"webview":"C359224A-06E5-42B6-8D1B-52687733920A"}
[2015-03-21T16:50:20+0400] [INFO] {"message":{"method":"Network.loadingFinished","params":{"encodedDataLength":0,"requestId":"28480.1","timestamp":1426942217.5344}},"webview":"C359224A-06E5-42B6-8D1B-52687733920A"}
[2015-03-21T16:50:20+0400] [INFO] {"message":{"method":"Page.frameNavigated","params":{"frame":{"id":"28480.1","loaderId":"28480.2","mimeType":"text/plain","securityOrigin":"://","url":"data:,"}}},"webview":"C359224A-06E5-42B6-8D1B-52687733920A"}
[2015-03-21T16:50:21+0400] [INFO] {"message":{"method":"Page.loadEventFired","params":{"timestamp":1426942220.99924}},"webview":"C359224A-06E5-42B6-8D1B-52687733920A"}
[2015-03-21T16:50:21+0400] [INFO] {"message":{"method":"Page.frameStoppedLoading","params":{"frameId":"28480.1"}},"webview":"C359224A-06E5-42B6-8D1B-52687733920A"}
[2015-03-21T16:50:21+0400] [INFO] {"message":{"method":"Page.domContentEventFired","params":{"timestamp":1426942220.99927}},"webview":"C359224A-06E5-42B6-8D1B-52687733920A"}
....

Reported by barancev on 2015-03-21 12:56:02

@lukeis
Copy link
Member Author

lukeis commented Mar 4, 2016 

Can you try instantiating the RemoteWebDriver with the constructor containing a remote
URL like my example?  Selenium is hosted elsewhere; otherwise, I would just use the
ChromeDriver instantiation.

public RemoteWebDriver(java.net.URL remoteAddress, Capabilities desiredCapabilities)

Reported by email@stephencopp.com on 2015-03-21 16:58:00

@lukeis
Copy link
Member Author

lukeis commented Mar 4, 2016 

new RemoteWebDriver(desiredCapabilites)
is equivalent to
new RemoteWebDriver(new URL("http://localhost:4444/wd/hub"), desiredCapabilites)
see
https://github.com/SeleniumHQ/selenium/blob/master/java/client/src/org/openqa/selenium/remote/HttpCommandExecutor.java#L70-L72

Unfortunately I can't try MacOS, may be it is platform-specific issue.

Reported by barancev on 2015-03-22 08:31:03

@lukeis
Copy link
Member Author

lukeis commented Mar 4, 2016 

This is not platform specific; I can repo it on both Mac and Windows.

This occurs when using the Selenium Grid, typically a remote URL, not a localhost URL.
 To reproduce locally:

Start a selenium grid locally:
java -jar selenium-server-standalone-2.45.0.jar -role node  -hub http://localhost:4444/grid/register
-browser browserName=chrome,maxInstances=1

Start a selenium node locally:
java -jar selenium-server-standalone-2.45.0.jar -role hub

Run your same scenario again:
    DesiredCapabilities desiredCapabilites = DesiredCapabilities.chrome();
    LoggingPreferences logPrefs = new LoggingPreferences();
    logPrefs.enable(LogType.PERFORMANCE, Level.ALL);
    desiredCapabilites.setCapability(CapabilityType.LOGGING_PREFS, logPrefs);
    RemoteWebDriver driver = new RemoteWebDriver(desiredCapabilites);
    try {
      System.out.println(driver.manage().logs().getAvailableLogTypes());
      driver.get("http://localhost/");
      for (LogEntry logEntry : driver.manage().logs().get(LogType.PERFORMANCE).getAll())
{
        System.out.println(logEntry);
      }

    } finally {
      driver.quit();
    }

Reported by stephencopp on 2015-03-23 16:13:52

@lukeis
Copy link
Member Author

lukeis commented Mar 4, 2016

Reported by gmanikpure@chromium.org on 2015-03-23 17:34:50

  • Status changed: Untriaged

@lukeis
Copy link
Member Author

lukeis commented Mar 4, 2016 

Reproduced

Reported by barancev on 2015-03-28 17:45:57

  • Status changed: Accepted

@lukeis
Copy link
Member Author

lukeis commented Mar 4, 2016

Reported by barancev on 2015-03-28 17:45:58

  • Status changed: Accepted

@lukeis
Copy link
Member Author

lukeis commented Mar 4, 2016

Reported by barancev on 2015-03-28 17:46:54

  • Labels added: Component-Grid

@lukeis
Copy link
Member Author

lukeis commented Mar 4, 2016 

Fixed by https://github.com/SeleniumHQ/selenium/commit/01bf9a0372b98a50026b6a4411504ce8341f3c51

Reported by barancev on 2015-03-28 19:29:39

  • Status changed: Fixed
  • Labels removed: Status-Untriaged

@lukeis
Copy link
Member Author

lukeis commented Mar 4, 2016 

exellent, thank you!

Reported by email@stephencopp.com on 2015-03-30 15:51:59

@lukeis
Copy link
Member Author

lukeis commented Mar 4, 2016

Reported by luke.semerau on 2015-09-17 18:25:11

  • Labels added: Restrict-AddIssueComment-Commit

@lukeis lukeis closed this as completed Mar 4, 2016
@SeleniumHQ SeleniumHQ locked and limited conversation to collaborators Mar 4, 2016
Sign up for free to subscribe to this conversation on GitHub. Already have an account? Sign in.
Assignees

@lukeis lukeis

Labels
Component-Grid Priority-Medium Restrict-AddIssueComment-Commit Status-Fixed Type-Defect
Projects
None yet
Milestone
No milestone
Development

No branches or pull requests
1 participant
@lukeis