Closed Bug 1417556 Opened 7 years ago Closed 7 years ago

Intermittent Automation Error: mozprocess timed out after 1000 seconds running ['/builds/worker/workspace/build/venv/bin/python', '-u', hang in test_navigation.py TestNavigate.test_navigate_timeout_error_remoteness_change

Categories

(Testing :: Marionette Client and Harness, defect, P5)

Version 3
defect

Tracking

(firefox59 fixed)

RESOLVED FIXED
Tracking Status
firefox59 --- fixed

People

(Reporter: intermittent-bug-filer, Assigned: whimboo)

References

(Depends on 1 open bug)

Details

(Keywords: intermittent-failure)

Filed by: archaeopteryx [at] coole-files.de

https://treeherder.mozilla.org/logviewer.html#?job_id=145007668&repo=autoland

https://queue.taskcluster.net/v1/task/BccfMrIRTZW21oYMbMp7Gw/runs/0/artifacts/public/logs/live_backing.log

[task 2017-11-15T16:18:37.729Z] 16:18:37     INFO -  TEST-START: test_navigation.py TestNavigate.test_navigate_timeout_error_remoteness_change
[task 2017-11-15T16:18:37.730Z] 16:18:37     INFO -  1510762717726	Marionette	TRACE	479 <- [1,5,null,{"value":null}]
[task 2017-11-15T16:18:37.738Z] 16:18:37     INFO -  1510762717731	Marionette	TRACE	479 -> [0,6,"getCurrentChromeWindowHandle",{}]
[task 2017-11-15T16:18:37.742Z] 16:18:37     INFO -  1510762717736	Marionette	TRACE	479 <- [1,6,null,{"value":"3"}]
[task 2017-11-15T16:18:37.751Z] 16:18:37     INFO -  1510762717747	Marionette	TRACE	479 -> [0,7,"getChromeWindowHandles",{}]
[task 2017-11-15T16:18:37.752Z] 16:18:37     INFO -  1510762717750	Marionette	TRACE	479 <- [1,7,null,["3"]]
[task 2017-11-15T16:18:37.760Z] 16:18:37     INFO -  1510762717756	Marionette	TRACE	479 -> [0,8,"getWindowHandle",{}]
[task 2017-11-15T16:18:37.764Z] 16:18:37     INFO -  1510762717759	Marionette	TRACE	479 <- [1,8,null,{"value":"8589934593"}]
[task 2017-11-15T16:18:37.772Z] 16:18:37     INFO -  1510762717765	Marionette	TRACE	479 -> [0,9,"getWindowHandles",{}]
[task 2017-11-15T16:18:37.772Z] 16:18:37     INFO -  1510762717769	Marionette	TRACE	479 <- [1,9,null,["8589934593"]]
[task 2017-11-15T16:18:37.781Z] 16:18:37     INFO -  1510762717774	Marionette	TRACE	479 -> [0,10,"get",{"url":"http://127.0.0.1:36088/windowHandles.html"}]
[task 2017-11-15T16:18:37.817Z] 16:18:37     INFO -  1510762717811	Marionette	DEBUG	Received DOM event "beforeunload" for "http://127.0.0.1:36088/windowHandles.html"
[task 2017-11-15T16:18:37.858Z] 16:18:37     INFO -  1510762717856	Marionette	DEBUG	Received DOM event "pagehide" for "http://127.0.0.1:36088/windowHandles.html"
[task 2017-11-15T16:18:37.867Z] 16:18:37     INFO -  1510762717862	Marionette	DEBUG	Received DOM event "unload" for "http://127.0.0.1:36088/windowHandles.html"
[task 2017-11-15T16:18:37.867Z] 16:18:37     INFO -  ++DOMWINDOW == 87 (0x7f5907b44800) [pid = 1600] [serial = 548] [outer = 0x7f59165e7800]
[task 2017-11-15T16:18:37.923Z] 16:18:37     INFO -  1510762717912	Marionette	DEBUG	Received DOM event "DOMContentLoaded" for "http://127.0.0.1:36088/windowHandles.html"
[task 2017-11-15T16:18:37.939Z] 16:18:37     INFO -  1510762717928	Marionette	DEBUG	Received DOM event "pageshow" for "http://127.0.0.1:36088/windowHandles.html"
[task 2017-11-15T16:18:37.975Z] 16:18:37     INFO -  1510762717964	Marionette	TRACE	479 <- [1,10,null,{}]
[task 2017-11-15T16:18:38.017Z] 16:18:38     INFO -  1510762718010	Marionette	TRACE	479 -> [0,11,"getWindowHandles",{}]
[task 2017-11-15T16:18:38.017Z] 16:18:38     INFO -  1510762718015	Marionette	TRACE	479 <- [1,11,null,["8589934593"]]
[task 2017-11-15T16:18:38.033Z] 16:18:38     INFO -  1510762718027	Marionette	TRACE	479 -> [0,12,"findElement",{"using":"id","value":"new-blank-tab"}]
[task 2017-11-15T16:18:38.054Z] 16:18:38     INFO -  1510762718049	Marionette	TRACE	479 <- [1,12,null,{"value":{"uuid":"b0daa481-9f1d-4f15-a6f2-9f9be4c8666d"}}]
[task 2017-11-15T16:18:38.055Z] 16:18:38     INFO -  1510762718051	Marionette	TRACE	479 -> [0,13,"clickElement",{"id":"b0daa481-9f1d-4f15-a6f2-9f9be4c8666d"}]
[task 2017-11-15T16:18:38.156Z] 16:18:38     INFO -  ++DOCSHELL 0x7f5907b59800 == 28 [pid = 1600] [id = {e2acee7c-8469-4140-aaa2-ea1543c70870}]
[task 2017-11-15T16:18:38.156Z] 16:18:38     INFO -  ++DOMWINDOW == 88 (0x7f59056bf000) [pid = 1600] [serial = 549] [outer = (nil)]
[task 2017-11-15T16:18:38.172Z] 16:18:38     INFO -  1510762718167	Marionette	TRACE	479 <- [1,13,null,{}]
[task 2017-11-15T16:18:38.190Z] 16:18:38     INFO -  [Parent 1392, Main Thread] WARNING: [nsFrameLoader] ReallyStartLoadingInternal tried but couldn't show remote browser.
[task 2017-11-15T16:18:38.191Z] 16:18:38     INFO -  : file /builds/worker/workspace/build/src/dom/base/nsFrameLoader.cpp, line 904
[task 2017-11-15T16:18:38.194Z] 16:18:38     INFO -  ++DOMWINDOW == 89 (0x7f5907b5f800) [pid = 1600] [serial = 550] [outer = 0x7f59056bf000]
[task 2017-11-15T16:18:38.297Z] 16:18:38     INFO -  1510762718290	Marionette	TRACE	479 -> [0,14,"getWindowHandles",{}]
[task 2017-11-15T16:18:38.298Z] 16:18:38     INFO -  1510762718293	Marionette	TRACE	479 <- [1,14,null,["8589934593"]]
[task 2017-11-15T16:18:38.333Z] 16:18:38     INFO -  1510762718322	Marionette	DEBUG	Register listener.js for window 8589935141
[task 2017-11-15T16:18:38.371Z] 16:18:38     INFO -  --DOMWINDOW == 59 (0x7fb6deffe800) [pid = 1392] [serial = 213] [outer = (nil)] [url = about:robots]
[task 2017-11-15T16:18:38.372Z] 16:18:38     INFO -  --DOMWINDOW == 58 (0x7fb6deff5800) [pid = 1392] [serial = 212] [outer = (nil)] [url = about:blank]
[task 2017-11-15T16:18:38.373Z] 16:18:38     INFO -  --DOMWINDOW == 57 (0x7fb6e2007800) [pid = 1392] [serial = 211] [outer = (nil)] [url = about:blank]
[task 2017-11-15T16:18:38.373Z] 16:18:38     INFO -  --DOMWINDOW == 56 (0x7fb6e203e000) [pid = 1392] [serial = 217] [outer = (nil)] [url = about:robots]
[task 2017-11-15T16:18:38.374Z] 16:18:38     INFO -  --DOMWINDOW == 55 (0x7fb6e2020800) [pid = 1392] [serial = 216] [outer = (nil)] [url = about:blank]
[task 2017-11-15T16:18:38.374Z] 16:18:38     INFO -  --DOMWINDOW == 54 (0x7fb6e2048800) [pid = 1392] [serial = 215] [outer = (nil)] [url = about:blank]
[task 2017-11-15T16:18:38.375Z] 16:18:38     INFO -  1510762718371	Marionette	TRACE	479 -> [0,15,"getWindowHandles",{}]
[task 2017-11-15T16:18:38.384Z] 16:18:38     INFO -  1510762718377	Marionette	TRACE	479 <- [1,15,null,["8589934593"]]
[task 2017-11-15T16:18:38.490Z] 16:18:38     INFO -  ++DOMWINDOW == 90 (0x7f5907b6c800) [pid = 1600] [serial = 551] [outer = 0x7f59056bf000]
[task 2017-11-15T16:18:38.547Z] 16:18:38     INFO -  1510762718542	Marionette	TRACE	479 -> [0,16,"getWindowHandles",{}]
[task 2017-11-15T16:18:38.549Z] 16:18:38     INFO -  1510762718545	Marionette	TRACE	479 <- [1,16,null,["8589934593","8589935141"]]
[task 2017-11-15T16:18:38.636Z] 16:18:38     INFO -  1510762718632	Marionette	TRACE	479 -> [0,17,"getWindowHandles",{}]
[task 2017-11-15T16:18:38.638Z] 16:18:38     INFO -  1510762718634	Marionette	TRACE	479 <- [1,17,null,["8589934593","8589935141"]]
[task 2017-11-15T16:18:38.653Z] 16:18:38     INFO -  1510762718648	Marionette	TRACE	479 -> [0,18,"switchToWindow",{"focus":true,"name":"8589935141"}]
[task 2017-11-15T16:18:38.655Z] 16:18:38     INFO -  1510762718651	Marionette	TRACE	479 <- [1,18,null,{}]
[task 2017-11-15T16:18:38.662Z] 16:18:38     INFO -  1510762718657	Marionette	TRACE	479 -> [0,19,"getTimeouts",{}]
[task 2017-11-15T16:18:38.664Z] 16:18:38     INFO -  1510762718658	Marionette	TRACE	479 <- [1,19,null,{"implicit":0,"pageLoad":300000,"script":30000}]
[task 2017-11-15T16:18:38.666Z] 16:18:38     INFO -  1510762718661	Marionette	TRACE	479 -> [0,20,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_navigation.py","script":"return window.history.length;","sandbox":"default","line":69}]
[task 2017-11-15T16:18:38.690Z] 16:18:38     INFO -  1510762718680	Marionette	TRACE	479 <- [1,20,null,{"value":1}]
[task 2017-11-15T16:18:38.691Z] 16:18:38     INFO -  1510762718682	Marionette	TRACE	479 -> [0,21,"getContext",{}]
[task 2017-11-15T16:18:38.691Z] 16:18:38     INFO -  1510762718683	Marionette	TRACE	479 <- [1,21,null,{"value":"content"}]
[task 2017-11-15T16:18:38.691Z] 16:18:38     INFO -  1510762718685	Marionette	TRACE	479 -> [0,22,"setContext",{"value":"chrome"}]
[task 2017-11-15T16:18:38.691Z] 16:18:38     INFO -  1510762718687	Marionette	TRACE	479 <- [1,22,null,{}]
[task 2017-11-15T16:18:38.692Z] 16:18:38     INFO -  1510762718689	Marionette	TRACE	479 -> [0,23,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"decorators.py","script":"\n                    try {\n                      return Services.appinfo.browserTabsRemoteAutostart;\n                    } catch (e) {\n                      return false;\n                    }\n                ","sandbox":"default","line":64}]
[task 2017-11-15T16:18:38.710Z] 16:18:38     INFO -  1510762718698	Marionette	TRACE	479 <- [1,23,null,{"value":true}]
[task 2017-11-15T16:18:38.710Z] 16:18:38     INFO -  1510762718700	Marionette	TRACE	479 -> [0,24,"setContext",{"value":"content"}]
[task 2017-11-15T16:18:38.710Z] 16:18:38     INFO -  1510762718702	Marionette	TRACE	479 <- [1,24,null,{}]
[task 2017-11-15T16:18:38.710Z] 16:18:38     INFO -  1510762718703	Marionette	TRACE	479 -> [0,25,"getContext",{}]
[task 2017-11-15T16:18:38.710Z] 16:18:38     INFO -  1510762718705	Marionette	TRACE	479 <- [1,25,null,{"value":"content"}]
[task 2017-11-15T16:18:38.710Z] 16:18:38     INFO -  1510762718707	Marionette	TRACE	479 -> [0,26,"setContext",{"value":"chrome"}]
[task 2017-11-15T16:18:38.714Z] 16:18:38     INFO -  1510762718708	Marionette	TRACE	479 <- [1,26,null,{}]
[task 2017-11-15T16:18:38.716Z] 16:18:38     INFO -  1510762718713	Marionette	TRACE	479 -> [0,27,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_navigation.py","script":"\n              Components.utils.import(\"resource://gre/modules/AppConstants.jsm\");\n\n              let win = null;\n\n           ...          } else {\n                return null;\n              }\n\n              return tabBrowser.isRemoteBrowser;\n            ","sandbox":"default","line":103}]
[task 2017-11-15T16:18:38.733Z] 16:18:38     INFO -  1510762718723	Marionette	TRACE	479 <- [1,27,null,{"value":true}]
[task 2017-11-15T16:18:38.734Z] 16:18:38     INFO -  1510762718731	Marionette	TRACE	479 -> [0,28,"setContext",{"value":"content"}]
[task 2017-11-15T16:18:38.735Z] 16:18:38     INFO -  1510762718733	Marionette	TRACE	479 <- [1,28,null,{}]
[task 2017-11-15T16:18:38.744Z] 16:18:38     INFO -  1510762718739	Marionette	TRACE	479 -> [0,29,"get",{"url":"about:robots"}]
[task 2017-11-15T16:18:38.753Z] 16:18:38     INFO -  1510762718748	Marionette	DEBUG	Received DOM event "beforeunload" for "about:blank"
[task 2017-11-15T16:18:38.777Z] 16:18:38     INFO -  ++DOCSHELL 0x7fb6deff9000 == 8 [pid = 1392] [id = {c81a8a12-fd81-46a8-b8d8-fb495be04326}]
[task 2017-11-15T16:18:38.777Z] 16:18:38     INFO -  ++DOMWINDOW == 55 (0x7fb6deffa000) [pid = 1392] [serial = 224] [outer = (nil)]
[task 2017-11-15T16:18:38.834Z] 16:18:38     INFO -  ++DOMWINDOW == 56 (0x7fb6e2006000) [pid = 1392] [serial = 225] [outer = 0x7fb6deffa000]
[task 2017-11-15T16:18:38.841Z] 16:18:38     INFO -  1510762718839	Marionette	DEBUG	Received DOM event "pagehide" for "about:blank"
[task 2017-11-15T16:18:38.849Z] 16:18:38     INFO -  1510762718843	Marionette	DEBUG	Received DOM event "unload" for "about:blank"
[task 2017-11-15T16:18:38.857Z] 16:18:38     INFO -  1510762718851	Marionette	DEBUG	Received observer notification "outer-window-destroyed" for "8589935141"
[task 2017-11-15T16:18:38.865Z] 16:18:38     INFO -  1510762718860	Marionette	DEBUG	Register listener.js for window 224
[task 2017-11-15T16:18:38.968Z] 16:18:38     INFO -  ++DOMWINDOW == 57 (0x7fb6deff8000) [pid = 1392] [serial = 226] [outer = 0x7fb6deffa000]
[task 2017-11-15T16:18:39.037Z] 16:18:39     INFO -  1510762719034	Marionette	DEBUG	Check readyState "interactive for "about:blank"
[task 2017-11-15T16:18:39.053Z] 16:18:39     INFO -  1510762719050	Marionette	DEBUG	Received DOM event "DOMContentLoaded" for "about:blank"
[task 2017-11-15T16:18:39.074Z] 16:18:39     INFO -  ++DOMWINDOW == 58 (0x7fb6deffd800) [pid = 1392] [serial = 227] [outer = 0x7fb6deffa000]
[task 2017-11-15T16:18:39.171Z] 16:18:39     INFO -  [Parent 1392, Main Thread] WARNING: attempt to modify an immutable nsStandardURL: file /builds/worker/workspace/build/src/netwerk/base/nsStandardURL.cpp, line 1698
[task 2017-11-15T16:18:39.179Z] 16:18:39     INFO -  1510762719174	Marionette	DEBUG	Received DOM event "DOMContentLoaded" for "about:robots"
[task 2017-11-15T16:18:39.216Z] 16:18:39     INFO -  1510762719210	Marionette	DEBUG	Received DOM event "pageshow" for "about:robots"
[task 2017-11-15T16:18:39.240Z] 16:18:39     INFO -  1510762719237	Marionette	TRACE	479 <- [1,29,null,{}]
[task 2017-11-15T16:18:39.248Z] 16:18:39     INFO -  1510762719243	Marionette	TRACE	479 -> [0,30,"getContext",{}]
[task 2017-11-15T16:18:39.249Z] 16:18:39     INFO -  1510762719245	Marionette	TRACE	479 <- [1,30,null,{"value":"content"}]
[task 2017-11-15T16:18:39.267Z] 16:18:39     INFO -  1510762719257	Marionette	TRACE	479 -> [0,31,"setContext",{"value":"chrome"}]
[task 2017-11-15T16:18:39.268Z] 16:18:39     INFO -  1510762719259	Marionette	TRACE	479 <- [1,31,null,{}]
[task 2017-11-15T16:18:39.270Z] 16:18:39     INFO -  1510762719264	Marionette	TRACE	479 -> [0,32,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_navigation.py","script":"\n              Components.utils.import(\"resource://gre/modules/AppConstants.jsm\");\n\n              let win = null;\n\n           ...          } else {\n                return null;\n              }\n\n              return tabBrowser.isRemoteBrowser;\n            ","sandbox":"default","line":103}]
[task 2017-11-15T16:18:39.287Z] 16:18:39     INFO -  1510762719278	Marionette	TRACE	479 <- [1,32,null,{"value":false}]
[task 2017-11-15T16:18:39.288Z] 16:18:39     INFO -  1510762719285	Marionette	TRACE	479 -> [0,33,"setContext",{"value":"content"}]
[task 2017-11-15T16:18:39.292Z] 16:18:39     INFO -  1510762719289	Marionette	TRACE	479 <- [1,33,null,{}]
[task 2017-11-15T16:18:39.294Z] 16:18:39     INFO -  1510762719293	Marionette	TRACE	479 -> [0,34,"setTimeouts",{"pageLoad":500}]
[task 2017-11-15T16:18:39.298Z] 16:18:39     INFO -  1510762719296	Marionette	TRACE	479 <- [1,34,null,{}]
[task 2017-11-15T16:18:39.315Z] 16:18:39     INFO -  1510762719306	Marionette	TRACE	479 -> [0,35,"get",{"url":"http://127.0.0.1:36088/slow"}]
[task 2017-11-15T16:18:39.315Z] 16:18:39     INFO -  1510762719313	Marionette	DEBUG	Received DOM event "beforeunload" for "about:robots"
[task 2017-11-15T16:18:40.166Z] 16:18:40     INFO -  1510762720162	Marionette	TRACE	479 <- [1,35,{"error":"timeout","message":"Timeout loading page after 500ms","stacktrace":"WebDriverError@chrome://marionette/content/error.js:172:5\nTimeoutError@chrome://marionette/content/error.js:442:5\nnotify@chrome://marionette/content/listener.js:336:13\n"},null]
[task 2017-11-15T16:18:40.189Z] 16:18:40     INFO -  ++DOCSHELL 0x7f3ee6524800 == 1 [pid = 1901] [id = {1dfe4ac6-e75f-4414-a772-6b9c310446bf}]
[task 2017-11-15T16:18:40.190Z] 16:18:40     INFO -  ++DOMWINDOW == 1 (0x7f3ee6525000) [pid = 1901] [serial = 1] [outer = (nil)]
[task 2017-11-15T16:18:40.291Z] 16:18:40     INFO -  1510762720286	Marionette	TRACE	479 -> [0,36,"getContext",{}]
[task 2017-11-15T16:18:40.291Z] 16:18:40     INFO -  1510762720288	Marionette	TRACE	479 <- [1,36,null,{"value":"content"}]
[task 2017-11-15T16:18:40.295Z] 16:18:40     INFO -  1510762720291	Marionette	TRACE	479 -> [0,37,"setContext",{"value":"chrome"}]
[task 2017-11-15T16:18:40.298Z] 16:18:40     INFO -  1510762720294	Marionette	TRACE	479 <- [1,37,null,{}]
[task 2017-11-15T16:18:40.306Z] 16:18:40     INFO -  1510762720300	Marionette	TRACE	479 -> [0,38,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_navigation.py","script":"\n              Components.utils.import(\"resource://gre/modules/AppConstants.jsm\");\n\n              let win = null;\n\n           ...          } else {\n                return null;\n              }\n\n              return tabBrowser.isRemoteBrowser;\n            ","sandbox":"default","line":103}]
[task 2017-11-15T16:18:40.322Z] 16:18:40     INFO -  1510762720313	Marionette	TRACE	479 <- [1,38,null,{"value":true}]
[task 2017-11-15T16:18:40.323Z] 16:18:40     INFO -  1510762720321	Marionette	TRACE	479 -> [0,39,"setContext",{"value":"content"}]
[task 2017-11-15T16:18:40.325Z] 16:18:40     INFO -  1510762720323	Marionette	TRACE	479 <- [1,39,null,{}]
[task 2017-11-15T16:18:40.335Z] 16:18:40     INFO -  1510762720328	Marionette	TRACE	479 -> [0,40,"setTimeouts",{"script":30000}]
[task 2017-11-15T16:18:40.336Z] 16:18:40     INFO -  1510762720332	Marionette	TRACE	479 <- [1,40,null,{}]
[task 2017-11-15T16:18:40.344Z] 16:18:40     INFO -  1510762720337	Marionette	TRACE	479 -> [0,41,"setTimeouts",{"pageLoad":300000}]
[task 2017-11-15T16:18:40.345Z] 16:18:40     INFO -  1510762720340	Marionette	TRACE	479 <- [1,41,null,{}]
[task 2017-11-15T16:18:40.364Z] 16:18:40     INFO -  ++DOMWINDOW == 2 (0x7f3ee662c000) [pid = 1901] [serial = 2] [outer = 0x7f3ee6525000]
[task 2017-11-15T16:18:40.422Z] 16:18:40     INFO -  1510762720410	Marionette	TRACE	479 -> [0,42,"setTimeouts",{"implicit":0}]
[task 2017-11-15T16:18:40.422Z] 16:18:40     INFO -  1510762720413	Marionette	TRACE	479 <- [1,42,null,{}]
[task 2017-11-15T16:18:40.423Z] 16:18:40     INFO -  1510762720416	Marionette	TRACE	479 -> [0,43,"switchToParentFrame",{}]
[task 2017-11-15T16:18:40.604Z] 16:18:40     INFO -  1510762720599	Marionette	DEBUG	Register listener.js for window 23622320129

...

[task 2017-11-15T16:22:45.258Z] 16:22:45     INFO -  [Parent 1392, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80070057: file /builds/worker/workspace/build/src/netwerk/base/nsChannelClassifier.cpp, line 344
[task 2017-11-15T16:22:45.260Z] 16:22:45     INFO -  [Parent 1392, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80070057: file /builds/worker/workspace/build/src/netwerk/base/nsChannelClassifier.cpp, line 344
[task 2017-11-15T16:39:25.300Z] 16:39:25     INFO - Automation Error: mozprocess timed out after 1000 seconds running ['/builds/worker/workspace/build/venv/bin/python', '-u', '/builds/worker/workspace/build/tests/marionette/harness/marionette_harness/runtests.py', '--headless', '--gecko-log=-', '--log-raw=-', '-vv', '--log-raw=/builds/worker/workspace/build/blobber_upload_dir/marionette_raw.log', '--log-errorsummary=/builds/worker/workspace/build/blobber_upload_dir/marionette_errorsummary.log', '--log-html=/builds/worker/workspace/build/blobber_upload_dir/report.html', '--binary=/builds/worker/workspace/build/application/firefox/firefox', '--address=localhost:2828', '--symbols-path=/builds/worker/workspace/build/symbols', '/builds/worker/workspace/build/tests/marionette/tests/testing/marionette/harness/marionette_harness/tests/unit-tests.ini']
[task 2017-11-15T16:39:25.319Z] 16:39:25    ERROR - timed out after 1000 seconds of no output
Hm, this is caused by a hang when calling `switchToParentFrame`:

[task 2017-11-15T16:18:40.423Z] 16:18:40     INFO -  1510762720416	Marionette	TRACE	479 -> [0,43,"switchToParentFrame",{}]
[task 2017-11-15T16:18:40.604Z] 16:18:40     INFO -  1510762720599	Marionette	DEBUG	Register listener.js for window 23622320129
[

Then nothing more happens. It sounds similar to bug 1399032, maybe lets wait for that.
Depends on: 1399032
Depends on: 1418227
This timed out with 1000s because of None as socket_timeout. This was fixed with 1418227. Now this should report a socket timeout again.
Assignee: nobody → hskupin
Status: NEW → RESOLVED
Closed: 7 years ago
Resolution: --- → FIXED
Product: Testing → Remote Protocol
Moving bug to Testing::Marionette Client and Harness component per bug 1815831.
Component: Marionette → Marionette Client and Harness
Product: Remote Protocol → Testing
You need to log in before you can comment on or make changes to this bug.