1573087 - Firefox 68.0ESR occupies high CPU and it generates Many (about 119080 ) [Parent][MessageChannel] error

Status: RESOLVED INACTIVE

(Core :: Widget: Gtk, defect, P3)

Description

[Jun Jie Jia]

User Agent: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:68.0) Gecko/20100101 Firefox/68.0

Steps to reproduce:

I invoke firefox 68.0 ESR with Selenium:

System
Version: 0.24.0
Platform: Red Hat Enterprise Linux Server 7.5 (Maipo)
Firefox: Firefox 68.0 ESR
Selenium: 3.141.59

After I playbacked several scripts, the CPU usage was very high, it caused the vm hanging, I tried on several vms, I got the same result

Below is my testcase:

import org.openqa.selenium.By;
import org.openqa.selenium.WebDriver;
import org.openqa.selenium.WebElement;
import org.openqa.selenium.firefox.FirefoxDriver;
import org.openqa.selenium.firefox.FirefoxDriverLogLevel;
import org.openqa.selenium.firefox.FirefoxOptions;
import org.openqa.selenium.firefox.FirefoxProfile;

public class TestOpen {

public static void main(String[] args) throws InterruptedException {
    FirefoxOptions options = new FirefoxOptions();

    FirefoxProfile profile = new FirefoxProfile();
    profile.setAcceptUntrustedCertificates(true);
    profile.setAssumeUntrustedCertificateIssuer(false);
    options.setProfile(profile);

    options.setHeadless(true);
    options.setLogLevel(FirefoxDriverLogLevel.TRACE);
    
    WebDriver driver=new FirefoxDriver(options);  
    driver.get("http://cn.bing.com/");
    WebElement element = driver.findElement(By.id("sb_form_q"));  
    element.sendKeys("bing"); 
    WebElement element1 = driver.findElement(By.id("sb_form_go"));  
    element1.click();
    driver.quit();
    System.out.println("Finish open");
}

Actual results:

I got below channel errror, firstly I think it maybe geckodriver issue, I opened an issue on github to them, but they think it is firefox issue, you can refer to https://github.com/mozilla/geckodriver/issues/1589 :

1563438743605 mozrunner::runner INFO Running command: "/root/testff68/firefox/firefox" "-marionette" "-headless" "-foreground" "-no-remote" "-profile" "/tmp/rust_mozprofile.pbcRCHlNrupJ"
1563438743606 geckodriver::marionette DEBUG Waiting 60s to connect to browser on 127.0.0.1:40043
*** You are running in headless mode.
1563438744289 [e-mail] WARN Loading extension '[e-mail]': Reading manifest: Invalid extension permission: mozillaAddons
1563438744289 [e-mail] WARN Loading extension '[e-mail]': Reading manifest: Invalid extension permission: telemetry
1563438744290 [e-mail] WARN Loading extension '[e-mail]': Reading manifest: Invalid extension permission: resource://pdf.js/
1563438744290 [e-mail] WARN Loading extension '[e-mail]': Reading manifest: Invalid extension permission: about:reader*
1563438744445 Marionette TRACE Received observer notification profile-after-change
1563438744483 Marionette TRACE Received observer notification command-line-startup
1563438744483 Marionette TRACE Received observer notification nsPref:changed
1563438744483 Marionette DEBUG Init aborted (running=false, enabled=true, finalUIStartup=false)
1563438744810 Marionette TRACE Received observer notification toplevel-window-ready
JavaScript error: resource:///modules/sessionstore/SessionStore.jsm, line 1215: uncaught exception: 2147746065
1563438747121 Marionette TRACE Received observer notification marionette-startup-requested
1563438747121 Marionette TRACE Waiting until startup recorder finished recording startup scripts...
1563438747136 Marionette TRACE All scripts recorded.
1563438747136 Marionette DEBUG Setting recommended pref apz.content_response_timeout to 60000
1563438747137 Marionette DEBUG Setting recommended pref browser.contentblocking.introCount to 99
1563438747137 Marionette DEBUG Setting recommended pref browser.download.panel.shown to true
1563438747137 Marionette DEBUG Setting recommended pref browser.pagethumbnails.capturing_disabled to true
1563438747138 Marionette DEBUG Setting recommended pref browser.tabs.disableBackgroundZombification to false
1563438747138 Marionette DEBUG Setting recommended pref browser.tabs.remote.separatePrivilegedContentProcess to false
1563438747138 Marionette DEBUG Setting recommended pref browser.tabs.unloadOnLowMemory to false
1563438747139 Marionette DEBUG Setting recommended pref browser.tabs.warnOnCloseOtherTabs to false
1563438747139 Marionette DEBUG Setting recommended pref browser.urlbar.suggest.searches to false
1563438747141 Marionette DEBUG Setting recommended pref datareporting.policy.dataSubmissionPolicyAccepted to false
1563438747141 Marionette DEBUG Setting recommended pref dom.disable_beforeunload to true
1563438747141 Marionette DEBUG Setting recommended pref dom.file.createInChild to true
1563438747141 Marionette DEBUG Setting recommended pref extensions.getAddons.cache.enabled to false
1563438747141 Marionette DEBUG Setting recommended pref extensions.webservice.discoverURL to http://%(server)s/dummy/discoveryURL
1563438747142 Marionette DEBUG Setting recommended pref network.http.prompt-temp-redirect to false
1563438747142 Marionette DEBUG Setting recommended pref network.http.speculative-parallel-limit to 0
1563438747142 Marionette DEBUG Setting recommended pref security.certerrors.mitm.priming.enabled to false
1563438747142 Marionette DEBUG Setting recommended pref security.notification_enable_delay to 0
1563438747143 Marionette DEBUG Setting recommended pref signon.autofillForms to false
1563438747143 Marionette DEBUG Setting recommended pref toolkit.cosmeticAnimations.enabled to false
1563438747221 Marionette INFO Listening on port 40043
1563438747222 Marionette DEBUG Remote service is active
1563438747242 geckodriver::marionette DEBUG Connection established on 127.0.0.1:40043. Waiting for Marionette handshake
1563438747264 Marionette DEBUG Accepted connection 0 from 127.0.0.1:37012
1563438747267 geckodriver::marionette DEBUG Connected to Marionette
1563438747275 Marionette DEBUG 0 -> [0,1,"WebDriver:NewSession",{"acceptInsecureCerts":true,"browserName":"firefox"}]
1563438747276 Marionette WARN TLS certificate errors will be ignored for this session
1563438747335 Marionette TRACE [4294967297] Frame script loaded
1563438747352 Marionette TRACE [4294967297] Frame script registered
1563438747372 Marionette DEBUG 0 <- [1,1,null,{"sessionId":"24e5563e-a6f8-4e59-8a5c-e00d62c4ffc7","capabilities":{"browserName":"firefox","browserVersion":"68.0" ... ozprofile.pbcRCHlNrupJ","moz:shutdownTimeout":60000,"moz:useNonSpecCompliantPointerOrigin":false,"moz:webdriverClick":true}}]
1563438747382 webdriver::server DEBUG <- 200 OK {"value":{"sessionId":"24e5563e-a6f8-4e59-8a5c-e00d62c4ffc7","capabilities":{"acceptInsecureCerts":true,"browserName":"firefox","browserVersion":"68.0","moz:accessibilityChecks":false,"moz:buildID":"20190705221915","moz:geckodriverVersion":"0.24.0","moz:headless":true,"moz:processID":10081,"moz:profile":"/tmp/rust_mozprofile.pbcRCHlNrupJ","moz:shutdownTimeout":60000,"moz:useNonSpecCompliantPointerOrigin":false,"moz:webdriverClick":true,"pageLoadStrategy":"normal","platformName":"linux","platformVersion":"3.10.0-862.14.4.el7.x86_64","rotatable":false,"setWindowRect":true,"strictFileInteractability":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"unhandledPromptBehavior":"dismiss and notify"}}}
Jul 18, 2019 4:32:27 PM org.openqa.selenium.remote.ProtocolHandshake createSession
INFO: Detected dialect: W3C
1563438747465 webdriver::server DEBUG -> POST /session/24e5563e-a6f8-4e59-8a5c-e00d62c4ffc7/url {
"url": "http:\u002f\u002fcn.bing.com\u002f"
}
1563438747469 Marionette DEBUG 0 -> [0,2,"WebDriver:Navigate",{"url":"http://cn.bing.com/"}]
1563438747962 Marionette TRACE [4294967297] Received DOM event beforeunload for about:blank
1563438753295 Marionette TRACE [4294967297] Received DOM event pagehide for about:blank
1563438754138 Marionette TRACE [4294967297] Received DOM event DOMContentLoaded for http://www.bing.com/?mkt=zh-CN
1563438758320 Marionette TRACE [4294967297] Received DOM event pageshow for http://www.bing.com/?mkt=zh-CN
1563438758329 Marionette DEBUG 0 <- [1,2,null,{"value":null}]
1563438758333 webdriver::server DEBUG <- 200 OK {"value":null}
1563438758348 webdriver::server DEBUG -> POST /session/24e5563e-a6f8-4e59-8a5c-e00d62c4ffc7/element {
"value": "#sb_form_q",
"using": "css selector"
}
1563438758364 Marionette DEBUG 0 -> [0,3,"WebDriver:FindElement",{"using":"css selector","value":"#sb_form_q"}]
1563438758400 Marionette DEBUG 0 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"8d039bbf-c7a2-4269-94e2-9d9cc05ef745"}}]
1563438758401 webdriver::server DEBUG <- 200 OK {"value":{"element-6066-11e4-a52e-4f735466cecf":"8d039bbf-c7a2-4269-94e2-9d9cc05ef745"}}
1563438758417 webdriver::server DEBUG -> POST /session/24e5563e-a6f8-4e59-8a5c-e00d62c4ffc7/element/8d039bbf-c7a2-4269-94e2-9d9cc05ef745/value {
"id": "8d039bbf-c7a2-4269-94e2-9d9cc05ef745",
"text": "IBM",
"value": [
"I",
"B",
"M"
]
}
1563438758420 Marionette DEBUG 0 -> [0,4,"WebDriver:ElementSendKeys",{"id":"8d039bbf-c7a2-4269-94e2-9d9cc05ef745","text":"IBM","value":["I","B","M"]}]
1563438758489 Marionette DEBUG 0 <- [1,4,null,{"value":null}]
1563438758491 webdriver::server DEBUG <- 200 OK {"value":null}
1563438758496 webdriver::server DEBUG -> POST /session/24e5563e-a6f8-4e59-8a5c-e00d62c4ffc7/element {
"value": "#sb_form_go",
"using": "css selector"
}
1563438758498 Marionette DEBUG 0 -> [0,5,"WebDriver:FindElement",{"using":"css selector","value":"#sb_form_go"}]
1563438758581 Marionette DEBUG 0 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"9696c1e6-f945-4f05-af0d-3e1131329b65"}}]
1563438758583 webdriver::server DEBUG <- 200 OK {"value":{"element-6066-11e4-a52e-4f735466cecf":"9696c1e6-f945-4f05-af0d-3e1131329b65"}}
1563438758593 webdriver::server DEBUG -> POST /session/24e5563e-a6f8-4e59-8a5c-e00d62c4ffc7/element/9696c1e6-f945-4f05-af0d-3e1131329b65/click {
"id": "9696c1e6-f945-4f05-af0d-3e1131329b65"
}
1563438758600 Marionette DEBUG 0 -> [0,6,"WebDriver:ElementClick",{"id":"9696c1e6-f945-4f05-af0d-3e1131329b65"}]
1563438758896 Marionette TRACE [4294967297] Received DOM event beforeunload for http://www.bing.com/?mkt=zh-CN
1563438759235 Marionette TRACE [4294967297] Received DOM event pagehide for http://www.bing.com/?mkt=zh-CN
1563438759237 Marionette TRACE [4294967297] Received DOM event unload for http://www.bing.com/?mkt=zh-CN
1563438762018 Marionette TRACE [4294967297] Received DOM event DOMContentLoaded for http://www.bing.com/search?q=IBM&go=Submit+Query&qs=ds&form=QBLH
1563438762482 Marionette TRACE [4294967297] Received DOM event pageshow for http://www.bing.com/search?q=IBM&go=Submit+Query&qs=ds&form=QBLH
1563438762487 Marionette DEBUG 0 <- [1,6,null,{"value":null}]
1563438762504 webdriver::server DEBUG <- 200 OK {"value":null}
1563438762510 webdriver::server DEBUG -> DELETE /session/24e5563e-a6f8-4e59-8a5c-e00d62c4ffc7
1563438762511 Marionette DEBUG 0 -> [0,7,"Marionette:Quit",{"flags":["eForceQuit"]}]
1563438762512 Marionette INFO Stopped listening on port 40043
1563438762615 Marionette TRACE Received observer notification quit-application
1563438762629 Marionette DEBUG 0 <- [1,7,null,{"cause":"shutdown"}]
1563438762679 webdriver::server DEBUG Deleting session
1563438762686 Marionette DEBUG 0 -> [0,8,"Marionette:Quit",{"flags":["eForceQuit"]}]
1563438762687 Marionette DEBUG 0 <- [1,8,{"error":"invalid session id","message":"Tried to run command without establishing a connection","stacktrace":"WebDriver ... t@chrome://marionette/content/server.js:236:9\n_onJSONObjectReady/<@chrome://marionette/content/transport.js:492:20\n"},null]
1563438762694 Marionette DEBUG Closed connection 0
[Parent 10081, Gecko_IOThread] WARNING: pipe error: Broken pipe: file /builds/worker/workspace/build/src/ipc/chromium/src/chrome/common/ipc_channel_posix.cc, line 727

###!!! [Child][MessageChannel] Error: (msgtype=0x530005,name=PHttpChannel::Msg_Cancel) Closed channel: cannot send/recv

[Parent 10081, Gecko_IOThread] WARNING: pipe error: Broken pipe: file /builds/worker/workspace/build/src/ipc/chromium/src/chrome/common/ipc_channel_posix.cc, line 727

###!!! [Parent][MessageChannel] Error: (msgtype=0xA10001,name=PVsync::Msg_Notify) Channel error: cannot send/recv

###!!! [Parent][MessageChannel] Error: (msgtype=0xA10001,name=PVsync::Msg_Notify) Channel error: cannot send/recv

###!!! [Parent][MessageChannel] Error: (msgtype=0xA10001,name=PVsync::Msg_Notify) Channel error: cannot send/recv

###!!! [Parent][MessageChannel] Error: (msgtype=0xA10001,name=PVsync::Msg_Notify) Channel error: cannot send/recv

119080 Error message here

###!!! [Parent][MessageChannel] Error: (msgtype=0xA10001,name=PVsync::Msg_Notify) Channel error: cannot send/recv

1563438765868 Marionette TRACE Received observer notification xpcom-will-shutdown
1563438765868 Marionette DEBUG Resetting recommended pref apz.content_response_timeout
1563438765868 Marionette DEBUG Resetting recommended pref browser.contentblocking.introCount
1563438765869 Marionette DEBUG Resetting recommended pref browser.download.panel.shown
1563438765869 Marionette DEBUG Resetting recommended pref browser.pagethumbnails.capturing_disabled
1563438765869 Marionette DEBUG Resetting recommended pref browser.tabs.disableBackgroundZombification
1563438765869 Marionette DEBUG Resetting recommended pref browser.tabs.remote.separatePrivilegedContentProcess
1563438765869 Marionette DEBUG Resetting recommended pref browser.tabs.unloadOnLowMemory
1563438765869 Marionette DEBUG Resetting recommended pref browser.tabs.warnOnCloseOtherTabs
1563438765870 Marionette DEBUG Resetting recommended pref browser.urlbar.suggest.searches
1563438765870 Marionette DEBUG Resetting recommended pref datareporting.policy.dataSubmissionPolicyAccepted
1563438765870 Marionette DEBUG Resetting recommended pref dom.disable_beforeunload
1563438765871 Marionette DEBUG Resetting recommended pref dom.file.createInChild
1563438765871 Marionette DEBUG Resetting recommended pref extensions.getAddons.cache.enabled
1563438765871 Marionette DEBUG Resetting recommended pref extensions.webservice.discoverURL
1563438765871 Marionette DEBUG Resetting recommended pref network.http.prompt-temp-redirect
1563438765871 Marionette DEBUG Resetting recommended pref network.http.speculative-parallel-limit
1563438765871 Marionette DEBUG Resetting recommended pref security.certerrors.mitm.priming.enabled
1563438765871 Marionette DEBUG Resetting recommended pref security.notification_enable_delay
1563438765871 Marionette DEBUG Resetting recommended pref signon.autofillForms
1563438765871 Marionette DEBUG Resetting recommended pref toolkit.cosmeticAnimations.enabled
1563438765872 Marionette DEBUG Remote service is inactive
1563438766405 geckodriver::marionette DEBUG Browser process stopped: exit code: 0
1563438766482 webdriver::server DEBUG <- 200 OK {"value":null}

Expected results:

Firefox should work well, not occupy too many CPU, also it should not generate the channel errors.

Comment 1

[Jun Jie Jia]

I just got a workaround: after I restart my vm, it can work well

Comment 2

[Virginia (please send need info request to Brindusa Tot)]

Hi Jun Jie Jia,

As per your last comment, I see that the issue has been solved. I'll mark this ticket as Resolved, but feel free to reopen it in case you need any assistance.

Thank you for your report.

Comment 3

[Jun Jie Jia]

Hello,

I reopened this issue since I reproduced it again, please help to check it, thanks !

Comment 4

[Virginia (please send need info request to Brindusa Tot)]

Hi team,

I don't have the proper equipment to reproduce this on my end. Could you please take a look?

Thanks,

Comment 5

[Jun Jie Jia]

Hi guys, is there any update about this issue ? thanks !

Comment 6

[Jun Jie Jia]

We solved this issue with below parameter:

MOZ_FORCE_DISABLE_E10S=1

Comment 7

[Sylvestre Ledru [:Sylvestre]]

I think it is now fixed