Closed
Bug 1469273
Opened 7 years ago
Closed 7 years ago
Intermittent testing/firefox-ui/tests/puppeteer/test_security.py TestSecurity.test_get_address_from_certificate | MarionetteException: Please start a session
Categories
(Testing :: Firefox UI Tests, defect, P5)
Tracking
(Not tracked)
RESOLVED
INCOMPLETE
People
(Reporter: intermittent-bug-filer, Unassigned)
References
Details
(Keywords: intermittent-failure)
Filed by: aiakab [at] mozilla.com
https://treeherder.mozilla.org/logviewer.html#?job_id=183582504&repo=autoland
https://queue.taskcluster.net/v1/task/XlrS9YBYTeW3DA8LQW-E6A/runs/0/artifacts/public/logs/live_backing.log
[task 2018-06-18T09:07:01.716Z] 09:07:01 INFO - TEST-START | testing/firefox-ui/tests/puppeteer/test_toolbars.py
[task 2018-06-18T09:07:01.717Z] 09:07:01 INFO - TEST-SKIP | testing/firefox-ui/tests/puppeteer/test_toolbars.py | took 0ms
[task 2018-06-18T09:07:01.719Z] 09:07:01 INFO - TEST-START | testing/firefox-ui/tests/functional/safebrowsing/test_initial_download.py
[task 2018-06-18T09:07:01.720Z] 09:07:01 INFO - TEST-SKIP | testing/firefox-ui/tests/functional/safebrowsing/test_initial_download.py | took 0ms
[task 2018-06-18T09:07:01.722Z] 09:07:01 INFO - TEST-START | testing/firefox-ui/tests/puppeteer/test_security.py TestSecurity.test_get_address_from_certificate
[task 2018-06-18T09:07:01.723Z] 09:07:01 INFO - 1529312821714 Marionette DEBUG Closed connection 2
[task 2018-06-18T09:07:01.725Z] 09:07:01 INFO - 1529312821720 Marionette DEBUG Accepted connection 3 from 127.0.0.1:46674
[task 2018-06-18T09:07:01.734Z] 09:07:01 INFO - 1529312821726 Marionette TRACE 3 -> [0,1,"WebDriver:NewSession",{}]
[task 2018-06-18T09:07:01.738Z] 09:07:01 INFO - 1529312821734 Marionette DEBUG Register listener.js for window 2147483649
[task 2018-06-18T09:07:01.746Z] 09:07:01 INFO - 1529312821742 Marionette TRACE 3 <- [1,1,null,{"sessionId":"d4864936-8cec-45da-8ecf-db2424d672c6","capabilities":{"browserName":"firefox","browserVersion":"62.0a ... /builds/worker/workspace/build/tmpTjKuHT.mozrunner","moz:useNonSpecCompliantPointerOrigin":false,"moz:webdriverClick":true}}]
[task 2018-06-18T09:07:01.750Z] 09:07:01 INFO - 1529312821748 Marionette TRACE 3 -> [0,2,"WebDriver:SetTimeouts",{"script":30000}]
[task 2018-06-18T09:07:01.766Z] 09:07:01 INFO - 1529312821756 Marionette TRACE 3 <- [1,2,null,{"value":null}]
[task 2018-06-18T09:07:01.782Z] 09:07:01 INFO - 1529312821777 Marionette TRACE 3 -> [0,3,"WebDriver:SetTimeouts",{"pageLoad":300000}]
[task 2018-06-18T09:07:01.790Z] 09:07:01 INFO - 1529312821785 Marionette TRACE 3 <- [1,3,null,{"value":null}]
[task 2018-06-18T09:07:01.791Z] 09:07:01 INFO - 1529312821789 Marionette TRACE 3 -> [0,4,"WebDriver:SetTimeouts",{"implicit":0}]
[task 2018-06-18T09:07:01.795Z] 09:07:01 INFO - 1529312821793 Marionette TRACE 3 <- [1,4,null,{"value":null}]
[task 2018-06-18T09:07:01.798Z] 09:07:01 INFO - 1529312821797 Marionette TRACE 3 -> [0,5,"WebDriver:GetWindowHandles",{}]
[task 2018-06-18T09:07:01.802Z] 09:07:01 INFO - 1529312821800 Marionette TRACE 3 <- [1,5,null,["2147483649"]]
[task 2018-06-18T09:07:01.804Z] 09:07:01 INFO - 1529312821802 Marionette TRACE 3 -> [0,6,"WebDriver:GetWindowHandles",{}]
[task 2018-06-18T09:07:01.807Z] 09:07:01 INFO - 1529312821806 Marionette TRACE 3 <- [1,6,null,["2147483649"]]
[task 2018-06-18T09:07:01.811Z] 09:07:01 INFO - 1529312821809 Marionette TRACE 3 -> [0,7,"Marionette:SetContext",{"value":"chrome"}]
[task 2018-06-18T09:07:01.812Z] 09:07:01 INFO - 1529312821810 Marionette TRACE 3 <- [1,7,null,{"value":null}]
[task 2018-06-18T09:07:01.821Z] 09:07:01 INFO - 1529312821814 Marionette TRACE 3 -> [0,8,"WebDriver:GetChromeWindowHandle",{}]
[task 2018-06-18T09:07:01.821Z] 09:07:01 INFO - 1529312821815 Marionette TRACE 3 <- [1,8,null,{"value":"3"}]
[task 2018-06-18T09:07:01.821Z] 09:07:01 INFO - 1529312821817 Marionette TRACE 3 -> [0,9,"WebDriver:GetChromeWindowHandle",{}]
[task 2018-06-18T09:07:01.821Z] 09:07:01 INFO - 1529312821819 Marionette TRACE 3 <- [1,9,null,{"value":"3"}]
[task 2018-06-18T09:07:01.830Z] 09:07:01 INFO - 1529312821821 Marionette TRACE 3 -> [0,10,"Marionette:GetContext",{}]
[task 2018-06-18T09:07:01.830Z] 09:07:01 INFO - 1529312821823 Marionette TRACE 3 <- [1,10,null,{"value":"chrome"}]
[task 2018-06-18T09:07:01.830Z] 09:07:01 INFO - 1529312821825 Marionette TRACE 3 -> [0,11,"Marionette:SetContext",{"value":"chrome"}]
[task 2018-06-18T09:07:01.830Z] 09:07:01 INFO - 1529312821827 Marionette TRACE 3 <- [1,11,null,{"value":null}]
[task 2018-06-18T09:07:01.831Z] 09:07:01 INFO - 1529312821829 Marionette TRACE 3 -> [0,12,"WebDriver:GetChromeWindowHandle",{}]
[task 2018-06-18T09:07:01.833Z] 09:07:01 INFO - 1529312821831 Marionette TRACE 3 <- [1,12,null,{"value":"3"}]
[task 2018-06-18T09:07:01.837Z] 09:07:01 INFO - 1529312821835 Marionette TRACE 3 -> [0,13,"Marionette:GetWindowType",{}]
[task 2018-06-18T09:07:01.840Z] 09:07:01 INFO - 1529312821838 Marionette TRACE 3 <- [1,13,null,{"value":"navigator:browser"}]
[task 2018-06-18T09:07:01.844Z] 09:07:01 INFO - 1529312821841 Marionette TRACE 3 -> [0,14,"WebDriver:GetChromeWindowHandles",{}]
[task 2018-06-18T09:07:01.844Z] 09:07:01 INFO - 1529312821843 Marionette TRACE 3 <- [1,14,null,["3"]]
[task 2018-06-18T09:07:01.848Z] 09:07:01 INFO - 1529312821846 Marionette TRACE 3 -> [0,15,"Marionette:GetContext",{}]
[task 2018-06-18T09:07:01.848Z] 09:07:01 INFO - 1529312821847 Marionette TRACE 3 <- [1,15,null,{"value":"chrome"}]
[task 2018-06-18T09:07:01.852Z] 09:07:01 INFO - 1529312821850 Marionette TRACE 3 -> [0,16,"Marionette:SetContext",{"value":"chrome"}]
[task 2018-06-18T09:07:01.853Z] 09:07:01 INFO - 1529312821851 Marionette TRACE 3 <- [1,16,null,{"value":null}]
[task 2018-06-18T09:07:01.873Z] 09:07:01 INFO - 1529312821868 Marionette TRACE 3 -> [0,17,"WebDriver:ExecuteScript",{"scriptTimeout":null,"newSandbox":true,"args":["3"],"filename":"../../venv/lib/python2.7/sit ... wWithId(Number(arguments[0]));\n return win.document.readyState == 'complete';","sandbox":"default","line":159}]
[task 2018-06-18T09:07:01.889Z] 09:07:01 INFO - 1529312821879 Marionette TRACE 3 <- [1,17,null,{"value":true}]
[task 2018-06-18T09:07:01.890Z] 09:07:01 INFO - 1529312821882 Marionette TRACE 3 -> [0,18,"Marionette:SetContext",{"value":"chrome"}]
[task 2018-06-18T09:07:01.891Z] 09:07:01 INFO - 1529312821883 Marionette TRACE 3 <- [1,18,null,{"value":null}]
[task 2018-06-18T09:07:01.892Z] 09:07:01 INFO - 1529312821885 Marionette TRACE 3 -> [0,19,"Marionette:SetContext",{"value":"chrome"}]
[task 2018-06-18T09:07:01.893Z] 09:07:01 INFO - 1529312821887 Marionette TRACE 3 <- [1,19,null,{"value":null}]
[task 2018-06-18T09:07:01.896Z] 09:07:01 INFO - 1529312821894 Marionette TRACE 3 -> [0,20,"WebDriver:GetChromeWindowHandles",{}]
[task 2018-06-18T09:07:01.900Z] 09:07:01 INFO - 1529312821896 Marionette TRACE 3 <- [1,20,null,["3"]]
[task 2018-06-18T09:07:01.902Z] 09:07:01 INFO - 1529312821900 Marionette TRACE 3 -> [0,21,"WebDriver:SwitchToWindow",{"focus":true,"name":"3"}]
[task 2018-06-18T09:07:01.905Z] 09:07:01 INFO - 1529312821904 Marionette TRACE 3 <- [1,21,null,{"value":null}]
[task 2018-06-18T09:07:01.909Z] 09:07:01 INFO - 1529312821907 Marionette TRACE 3 -> [0,22,"WebDriver:GetChromeWindowHandle",{}]
[task 2018-06-18T09:07:01.911Z] 09:07:01 INFO - 1529312821908 Marionette TRACE 3 <- [1,22,null,{"value":"3"}]
[task 2018-06-18T09:07:01.914Z] 09:07:01 INFO - 1529312821913 Marionette TRACE 3 -> [0,23,"Marionette:GetContext",{}]
[task 2018-06-18T09:07:01.918Z] 09:07:01 INFO - 1529312821915 Marionette TRACE 3 <- [1,23,null,{"value":"chrome"}]
[task 2018-06-18T09:07:01.926Z] 09:07:01 INFO - 1529312821921 Marionette TRACE 3 -> [0,24,"Marionette:SetContext",{"value":"chrome"}]
[task 2018-06-18T09:07:01.927Z] 09:07:01 INFO - 1529312821922 Marionette TRACE 3 <- [1,24,null,{"value":null}]
[task 2018-06-18T09:07:01.928Z] 09:07:01 INFO - 1529312821924 Marionette TRACE 3 -> [0,25,"WebDriver:GetChromeWindowHandle",{}]
[task 2018-06-18T09:07:01.935Z] 09:07:01 INFO - 1529312821929 Marionette TRACE 3 <- [1,25,null,{"value":"3"}]
[task 2018-06-18T09:07:01.936Z] 09:07:01 INFO - 1529312821931 Marionette TRACE 3 -> [0,26,"Marionette:GetWindowType",{}]
[task 2018-06-18T09:07:01.937Z] 09:07:01 INFO - 1529312821932 Marionette TRACE 3 <- [1,26,null,{"value":"navigator:browser"}]
[task 2018-06-18T09:07:01.938Z] 09:07:01 INFO - 1529312821935 Marionette TRACE 3 -> [0,27,"WebDriver:GetChromeWindowHandles",{}]
[task 2018-06-18T09:07:01.941Z] 09:07:01 INFO - 1529312821939 Marionette TRACE 3 <- [1,27,null,["3"]]
[task 2018-06-18T09:07:01.945Z] 09:07:01 INFO - 1529312821943 Marionette TRACE 3 -> [0,28,"Marionette:GetContext",{}]
[task 2018-06-18T09:07:01.946Z] 09:07:01 INFO - 1529312821944 Marionette TRACE 3 <- [1,28,null,{"value":"chrome"}]
[task 2018-06-18T09:07:01.954Z] 09:07:01 INFO - 1529312821948 Marionette TRACE 3 -> [0,29,"Marionette:SetContext",{"value":"chrome"}]
[task 2018-06-18T09:07:01.955Z] 09:07:01 INFO - 1529312821949 Marionette TRACE 3 <- [1,29,null,{"value":null}]
[task 2018-06-18T09:07:01.956Z] 09:07:01 INFO - 1529312821952 Marionette TRACE 3 -> [0,30,"WebDriver:ExecuteScript",{"scriptTimeout":null,"newSandbox":true,"args":["3"],"filename":"../../venv/lib/python2.7/sit ... wWithId(Number(arguments[0]));\n return win.document.readyState == 'complete';","sandbox":"default","line":159}]
[task 2018-06-18T09:07:01.973Z] 09:07:01 INFO - 1529312821965 Marionette TRACE 3 <- [1,30,null,{"value":true}]
[task 2018-06-18T09:07:01.976Z] 09:07:01 INFO - 1529312821967 Marionette TRACE 3 -> [0,31,"Marionette:SetContext",{"value":"chrome"}]
[task 2018-06-18T09:07:01.977Z] 09:07:01 INFO - 1529312821968 Marionette TRACE 3 <- [1,31,null,{"value":null}]
[task 2018-06-18T09:07:01.978Z] 09:07:01 INFO - 1529312821971 Marionette TRACE 3 -> [0,32,"Marionette:SetContext",{"value":"chrome"}]
[task 2018-06-18T09:07:01.979Z] 09:07:01 INFO - 1529312821972 Marionette TRACE 3 <- [1,32,null,{"value":null}]
[task 2018-06-18T09:07:01.995Z] 09:07:01 INFO - 1529312821985 Marionette TRACE 3 -> [0,33,"Marionette:GetContext",{}]
[task 2018-06-18T09:07:01.996Z] 09:07:01 INFO - 1529312821986 Marionette TRACE 3 <- [1,33,null,{"value":"chrome"}]
[task 2018-06-18T09:07:01.997Z] 09:07:01 INFO - 1529312821989 Marionette TRACE 3 -> [0,34,"Marionette:SetContext",{"value":"chrome"}]
[task 2018-06-18T09:07:01.998Z] 09:07:01 INFO - 1529312821990 Marionette TRACE 3 <- [1,34,null,{"value":null}]
[task 2018-06-18T09:07:01.999Z] 09:07:01 INFO - 1529312821993 Marionette TRACE 3 -> [0,35,"WebDriver:ExecuteScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"../../venv/lib/python2.7/site-packages/firefox_puppeteer/ui/windows.py","script":"window.focus();","sandbox":"default","line":140}]
[task 2018-06-18T09:07:02.016Z] 09:07:02 INFO - 1529312822006 Marionette TRACE 3 <- [1,35,null,{"value":null}]
[task 2018-06-18T09:07:02.017Z] 09:07:02 INFO - 1529312822008 Marionette TRACE 3 -> [0,36,"Marionette:SetContext",{"value":"chrome"}]
[task 2018-06-18T09:07:02.019Z] 09:07:02 INFO - 1529312822009 Marionette TRACE 3 <- [1,36,null,{"value":null}]
[task 2018-06-18T09:07:02.020Z] 09:07:02 INFO - 1529312822011 Marionette TRACE 3 -> [0,37,"Marionette:GetContext",{}]
[task 2018-06-18T09:07:02.021Z] 09:07:02 INFO - 1529312822012 Marionette TRACE 3 <- [1,37,null,{"value":"chrome"}]
[task 2018-06-18T09:07:02.022Z] 09:07:02 INFO - 1529312822014 Marionette TRACE 3 -> [0,38,"Marionette:SetContext",{"value":"chrome"}]
[task 2018-06-18T09:07:02.025Z] 09:07:02 INFO - 1529312822023 Marionette TRACE 3 <- [1,38,null,{"value":null}]
[task 2018-06-18T09:07:02.029Z] 09:07:02 INFO - 1529312822027 Marionette TRACE 3 -> [0,39,"WebDriver:ExecuteScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"../../venv/lib/python2.7/site-p ... .outerWindowID.toString();\n }\n\n return null;","sandbox":"default","line":61}]
[task 2018-06-18T09:07:02.037Z] 09:07:02 INFO - 1529312822035 Marionette TRACE 3 <- [1,39,null,{"value":"3"}]
[task 2018-06-18T09:07:02.045Z] 09:07:02 INFO - 1529312822042 Marionette TRACE 3 -> [0,40,"Marionette:SetContext",{"value":"chrome"}]
[task 2018-06-18T09:07:02.046Z] 09:07:02 INFO - 1529312822043 Marionette TRACE 3 <- [1,40,null,{"value":null}]
[task 2018-06-18T09:07:02.054Z] 09:07:02 INFO - 1529312822047 Marionette TRACE 3 -> [0,41,"Marionette:GetContext",{}]
[task 2018-06-18T09:07:02.056Z] 09:07:02 INFO - 1529312822048 Marionette TRACE 3 <- [1,41,null,{"value":"chrome"}]
[task 2018-06-18T09:07:02.056Z] 09:07:02 INFO - 1529312822051 Marionette TRACE 3 -> [0,42,"Marionette:SetContext",{"value":"content"}]
[task 2018-06-18T09:07:02.057Z] 09:07:02 INFO - 1529312822052 Marionette TRACE 3 <- [1,42,null,{"value":null}]
[task 2018-06-18T09:07:02.066Z] 09:07:02 INFO - 1529312822058 Marionette TRACE 3 -> [0,43,"WebDriver:Navigate",{"url":"about:about"}]
[task 2018-06-18T09:07:02.086Z] 09:07:02 INFO - 1529312822079 Marionette DEBUG Received DOM event beforeunload for about:blank
[task 2018-06-18T09:07:02.124Z] 09:07:02 INFO - 1529312822114 Marionette DEBUG Received DOM event pagehide for about:blank
[task 2018-06-18T09:07:02.124Z] 09:07:02 INFO - 1529312822117 Marionette DEBUG Received DOM event unload for about:blank
[task 2018-06-18T09:07:02.140Z] 09:07:02 INFO - 1529312822129 Marionette DEBUG Received observer notification outer-window-destroyed for 2147483649
[task 2018-06-18T09:07:02.221Z] 09:07:02 INFO - 1529312822214 Marionette DEBUG Register listener.js for window 23
[task 2018-06-18T09:07:02.383Z] 09:07:02 INFO - JavaScript error: resource:///modules/PageStyleHandler.jsm, line 55: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIDocShell.tabChild]
[task 2018-06-18T09:07:02.439Z] 09:07:02 INFO - 1529312822435 Marionette DEBUG Check readyState interactive for about:blank
[task 2018-06-18T09:07:02.455Z] 09:07:02 INFO - 1529312822445 Marionette DEBUG Received DOM event DOMContentLoaded for about:blank
[task 2018-06-18T09:07:02.754Z] 09:07:02 INFO - 1529312822744 Marionette DEBUG Received DOM event DOMContentLoaded for about:about
[task 2018-06-18T09:07:02.810Z] 09:07:02 INFO - 1529312822805 Marionette DEBUG Received DOM event pageshow for about:about
[task 2018-06-18T09:07:02.811Z] 09:07:02 INFO - JavaScript error: resource:///modules/PageStyleHandler.jsm, line 55: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIDocShell.tabChild]
[task 2018-06-18T09:07:02.881Z] 09:07:02 INFO - 1529312822876 Marionette TRACE 3 <- [1,43,null,{"value":null}]
[task 2018-06-18T09:07:02.900Z] 09:07:02 INFO - 1529312822898 Marionette TRACE 3 -> [0,44,"Marionette:SetContext",{"value":"chrome"}]
[task 2018-06-18T09:07:02.901Z] 09:07:02 INFO - 1529312822900 Marionette TRACE 3 <- [1,44,null,{"value":null}]
[task 2018-06-18T09:07:02.918Z] 09:07:02 INFO - 1529312822908 Marionette TRACE 3 -> [0,45,"Marionette:GetContext",{}]
[task 2018-06-18T09:07:02.919Z] 09:07:02 INFO - 1529312822909 Marionette TRACE 3 <- [1,45,null,{"value":"chrome"}]
[task 2018-06-18T09:07:02.919Z] 09:07:02 INFO - 1529312822912 Marionette TRACE 3 -> [0,46,"Marionette:SetContext",{"value":"content"}]
[task 2018-06-18T09:07:02.919Z] 09:07:02 INFO - 1529312822913 Marionette TRACE 3 <- [1,46,null,{"value":null}]
[task 2018-06-18T09:07:02.920Z] 09:07:02 INFO - 1529312822917 Marionette TRACE 3 -> [0,47,"WebDriver:Navigate",{"url":"https://ssl-ev.mozqa.com"}]
[task 2018-06-18T09:07:02.936Z] 09:07:02 INFO - 1529312822931 Marionette DEBUG Received DOM event beforeunload for about:about
[task 2018-06-18T09:07:02.964Z] 09:07:02 INFO - 1529312822961 Marionette DEBUG Received DOM event pagehide for about:about
[task 2018-06-18T09:07:02.965Z] 09:07:02 INFO - 1529312822962 Marionette DEBUG Received DOM event unload for about:about
[task 2018-06-18T09:07:03.013Z] 09:07:03 INFO - 1529312823010 Marionette DEBUG Received observer notification outer-window-destroyed for 23
[task 2018-06-18T09:07:04.428Z] 09:07:04 INFO - ###!!! [Child][MessageChannel] Error: (msgtype=0x2D00FA,name=PContent::Msg_AccumulateChildKeyedHistograms) Channel closing: too late to send/recv, messages will be lost
[task 2018-06-18T09:07:04.428Z] 09:07:04 INFO - ###!!! [Child][MessageChannel] Error: (msgtype=0x2D00FE,name=PContent::Msg_RecordDiscardedData) Channel closing: too late to send/recv, messages will be lost
[task 2018-06-18T09:07:07.969Z] 09:07:07 INFO - ###!!! [Child][MessageChannel] Error: (msgtype=0x2D00F9,name=PContent::Msg_AccumulateChildHistograms) Channel closing: too late to send/recv, messages will be lost
[task 2018-06-18T09:07:07.970Z] 09:07:07 INFO - ###!!! [Child][MessageChannel] Error: (msgtype=0x2D00FE,name=PContent::Msg_RecordDiscardedData) Channel closing: too late to send/recv, messages will be lost
[task 2018-06-18T09:07:12.310Z] 09:07:12 INFO - ###!!! [Child][MessageChannel] Error: (msgtype=0x2D00F9,name=PContent::Msg_AccumulateChildHistograms) Channel closing: too late to send/recv, messages will be lost
[task 2018-06-18T09:07:12.310Z] 09:07:12 INFO - ###!!! [Child][MessageChannel] Error: (msgtype=0x2D00FE,name=PContent::Msg_RecordDiscardedData) Channel closing: too late to send/recv, messages will be lost
[task 2018-06-18T09:07:14.307Z] 09:07:14 INFO - ###!!! [Child][MessageChannel] Error: (msgtype=0x2D00F9,name=PContent::Msg_AccumulateChildHistograms) Channel closing: too late to send/recv, messages will be lost
[task 2018-06-18T09:07:14.309Z] 09:07:14 INFO - ###!!! [Child][MessageChannel] Error: (msgtype=0x2D00FA,name=PContent::Msg_AccumulateChildKeyedHistograms) Channel closing: too late to send/recv, messages will be lost
[task 2018-06-18T09:07:14.310Z] 09:07:14 INFO - ###!!! [Child][MessageChannel] Error: (msgtype=0x2D00FE,name=PContent::Msg_RecordDiscardedData) Channel closing: too late to send/recv, messages will be lost
[task 2018-06-18T09:07:17.798Z] 09:07:17 INFO - ###!!! [Child][MessageChannel] Error: (msgtype=0x550027,name=PNecko::Msg_RemoveRequestContext) Channel closing: too late to send/recv, messages will be lost
[task 2018-06-18T09:07:18.625Z] 09:07:18 INFO - ###!!! [Child][MessageChannel] Error: (msgtype=0x2D00F9,name=PContent::Msg_AccumulateChildHistograms) Channel closing: too late to send/recv, messages will be lost
[task 2018-06-18T09:07:18.625Z] 09:07:18 INFO - ###!!! [Child][MessageChannel] Error: (msgtype=0x2D00FE,name=PContent::Msg_RecordDiscardedData) Channel closing: too late to send/recv, messages will be lost
[task 2018-06-18T09:07:20.623Z] 09:07:20 INFO - ###!!! [Child][MessageChannel] Error: (msgtype=0x2D00F9,name=PContent::Msg_AccumulateChildHistograms) Channel closing: too late to send/recv, messages will be lost
[task 2018-06-18T09:07:20.623Z] 09:07:20 INFO - ###!!! [Child][MessageChannel] Error: (msgtype=0x2D00FA,name=PContent::Msg_AccumulateChildKeyedHistograms) Channel closing: too late to send/recv, messages will be lost
[task 2018-06-18T09:07:20.624Z] 09:07:20 INFO - ###!!! [Child][MessageChannel] Error: (msgtype=0x2D00FE,name=PContent::Msg_RecordDiscardedData) Channel closing: too late to send/recv, messages will be lost
[task 2018-06-18T09:07:44.926Z] 09:07:44 INFO - ###!!! [Child][MessageChannel] Error: (msgtype=0x2D00FB,name=PContent::Msg_UpdateChildScalars) Channel closing: too late to send/recv, messages will be lost
[task 2018-06-18T09:07:44.926Z] 09:07:44 INFO - ###!!! [Child][MessageChannel] Error: (msgtype=0x2D00FE,name=PContent::Msg_RecordDiscardedData) Channel closing: too late to send/recv, messages will be lost
[task 2018-06-18T09:07:59.144Z] 09:07:59 INFO - ###!!! [Child][MessageChannel] Error: (msgtype=0x2D00F9,name=PContent::Msg_AccumulateChildHistograms) Channel closing: too late to send/recv, messages will be lost
[task 2018-06-18T09:07:59.146Z] 09:07:59 INFO - ###!!! [Child][MessageChannel] Error: (msgtype=0x2D00FE,name=PContent::Msg_RecordDiscardedData) Channel closing: too late to send/recv, messages will be lost
[task 2018-06-18T09:13:03.023Z] 09:13:03 INFO - 1529313183017 Marionette DEBUG Closed connection 3
[task 2018-06-18T09:15:04.154Z] 09:15:04 INFO - TEST-UNEXPECTED-ERROR | testing/firefox-ui/tests/puppeteer/test_security.py TestSecurity.test_get_address_from_certificate | MarionetteException: Please start a session
[task 2018-06-18T09:15:04.154Z] 09:15:04 INFO - Traceback (most recent call last):
[task 2018-06-18T09:15:04.154Z] 09:15:04 INFO - File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_harness/marionette_test/testcases.py", line 159, in run
[task 2018-06-18T09:15:04.154Z] 09:15:04 INFO - testMethod()
[task 2018-06-18T09:15:04.154Z] 09:15:04 INFO - File "/builds/worker/workspace/build/tests/firefox-ui/tests/testing/firefox-ui/tests/puppeteer/test_security.py", line 16, in test_get_address_from_certificate
[task 2018-06-18T09:15:04.154Z] 09:15:04 INFO - self.marionette.navigate(url)
[task 2018-06-18T09:15:04.154Z] 09:15:04 INFO - File "/usr/lib/python2.7/contextlib.py", line 35, in __exit__
[task 2018-06-18T09:15:04.155Z] 09:15:04 INFO - self.gen.throw(type, value, traceback)
[task 2018-06-18T09:15:04.155Z] 09:15:04 INFO - File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/marionette.py", line 1437, in using_context
[task 2018-06-18T09:15:04.155Z] 09:15:04 INFO - self.set_context(scope)
[task 2018-06-18T09:15:04.156Z] 09:15:04 INFO - File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/marionette.py", line 1414, in set_context
[task 2018-06-18T09:15:04.156Z] 09:15:04 INFO - {"value": context})
[task 2018-06-18T09:15:04.156Z] 09:15:04 INFO - File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/decorators.py", line 26, in _
[task 2018-06-18T09:15:04.156Z] 09:15:04 INFO - return func(*args, **kwargs)
[task 2018-06-18T09:15:04.156Z] 09:15:04 INFO - File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/marionette.py", line 729, in _send_message
[task 2018-06-18T09:15:04.156Z] 09:15:04 INFO - raise errors.MarionetteException("Please start a session")
[task 2018-06-18T09:15:04.156Z] 09:15:04 INFO - TEST-INFO took 482439ms
[task 2018-06-18T09:15:04.159Z] 09:15:04 ERROR - test_end for testing/firefox-ui/tests/puppeteer/test_security.py TestSecurity.test_get_address_from_certificate logged while not in progress. Logged with data: {"status": "ERROR", "extra": {"class_name": "test_security.TestSecurity", "method_name": "test_get_address_from_certificate"}, "expected": "PASS", "test": "testing/firefox-ui/tests/puppeteer/test_security.py TestSecurity.test_get_address_from_certificate", "message": "MarionetteException: Please start a session", "stack": "Traceback (most recent call last):\n File \"/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_harness/marionette_test/testcases.py\", line 190, in run\n self.tearDown()\n File \"/builds/worker/workspace/build/venv/lib/python2.7/site-packages/firefox_puppeteer/mixins.py\", line 97, in tearDown\n self.marionette.set_context('chrome')\n File \"/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/marionette.py\", line 1414, in set_context\n {\"value\": context})\n File \"/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/decorators.py\", line 26, in _\n return func(*args, **kwargs)\n File \"/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/marionette.py\", line 729, in _send_message\n raise errors.MarionetteException(\"Please start a session\")\n"}
[task 2018-06-18T09:15:04.160Z] 09:15:04 INFO - TEST-START | testing/firefox-ui/tests/puppeteer/test_security.py TestSecurity.test_get_certificate
[task 2018-06-18T09:15:04.161Z] 09:15:04 INFO - Application command: /builds/worker/workspace/build/application/firefox/firefox -no-remote -marionette -profile /builds/worker/workspace/build/tmplJRB4H.mozrunner
[task 2018-06-18T09:15:06.238Z] 09:15:06 INFO - 1529313306231 Marionette DEBUG Received observer notification profile-after-change
[task 2018-06-18T09:15:06.368Z] 09:15:06 INFO - 1529313306361 Marionette DEBUG Received observer notification command-line-startup
[task 2018-06-18T09:15:06.368Z] 09:15:06 INFO - 1529313306361 Marionette DEBUG Received observer notification nsPref:changed
[task 2018-06-18T09:15:06.368Z] 09:15:06 INFO - 1529313306361 Marionette DEBUG Init aborted (running=false, enabled=true, finalUIStartup=false)
[task 2018-06-18T09:15:09.733Z] 09:15:09 INFO - 1529313309728 Marionette DEBUG Received observer notification sessionstore-windows-restored
[task 2018-06-18T09:15:09.733Z] 09:15:09 INFO - 1529313309728 Marionette DEBUG Waiting for delayed startup...
[task 2018-06-18T09:15:11.253Z] 09:15:11 INFO - 1529313311243 Marionette DEBUG Waiting for startup tests...
[task 2018-06-18T09:15:11.606Z] 09:15:11 INFO - 1529313311595 Marionette INFO Listening on port 2828
[task 2018-06-18T09:15:11.606Z] 09:15:11 INFO - 1529313311596 Marionette DEBUG Remote service is active
[task 2018-06-18T09:15:11.646Z] 09:15:11 INFO - 1529313311641 Marionette DEBUG Accepted connection 0 from 127.0.0.1:47074
[task 2018-06-18T09:15:11.662Z] 09:15:11 INFO - 1529313311656 Marionette DEBUG Closed connection 0
[task 2018-06-18T09:15:11.664Z] 09:15:11 INFO - 1529313311661 Marionette DEBUG Accepted connection 1 from 127.0.0.1:47076
[task 2018-06-18T09:15:11.685Z] 09:15:11 INFO - 1529313311679 Marionette TRACE 1 -> [0,1,"WebDriver:NewSession",{}]
[task 2018-06-18T09:15:12.251Z] 09:15:12 INFO - 1529313312244 Marionette DEBUG Register listener.js for window 2147483649
|
||
Comment 1•7 years ago
|
||
The message channel has busted and caused a connection shutdown. We can do more investigation if that failures happens more frequently.
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
|
|
||
Updated•7 years ago
|
OS: Unspecified → Linux
Hardware: Unspecified → x86_64
|
||
Comment 4•7 years ago
|
||
Status: NEW → RESOLVED
Closed: 7 years ago
Resolution: --- → INCOMPLETE
You need to log in
before you can comment on or make changes to this bug.
Description
•