Closed Bug 1415164 Opened 7 years ago Closed 7 years ago

Intermittent test_modal_dialogs.py TestTabModalAlerts.test_onbeforeunload_accept | TimeoutException: Timeout loading page after 300000ms

Categories

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

Product:
Testing
Component:
Marionette Client and Harness
Version:
Version 3
Type:
defect

Tracking

(Not tracked)

Status:
RESOLVED WORKSFORME

People

(Reporter: intermittent-bug-filer, Unassigned)

References

Details

(Keywords: intermittent-failure)

Filed by: archaeopteryx [at] coole-files.de https://treeherder.mozilla.org/logviewer.html#?job_id=142690771&repo=mozilla-inbound https://queue.taskcluster.net/v1/task/NH2POgN1TCaSiGwxG1Dykw/runs/0/artifacts/public/logs/live_backing.log [task 2017-11-07T13:02:45.427Z] 13:02:45 INFO - 1510059765419 Marionette TRACE 20 <- [1,18,{"error":"timeout","message":"Timeout loading page after 300000ms","stacktrace":"WebDriverError@chrome://marionette/content/error.js:172:5\nTimeoutError@chrome://marionette/content/error.js:442:5\nnotify@chro ... r.js:408:13\nasync*navigate@chrome://marionette/content/listener.js:407:13\nget@chrome://marionette/content/listener.js:1150:5\n"},null] [task 2017-11-07T13:02:45.429Z] 13:02:45 INFO - 1510059765425 Marionette TRACE 20 -> [0,19,"getContext",{}] [task 2017-11-07T13:02:45.434Z] 13:02:45 INFO - 1510059765430 Marionette TRACE 20 <- [1,19,null,{"value":"content"}] [task 2017-11-07T13:02:45.442Z] 13:02:45 INFO - 1510059765437 Marionette TRACE 20 -> [0,20,"setContext",{"value":"chrome"}] [task 2017-11-07T13:02:45.444Z] 13:02:45 INFO - 1510059765439 Marionette TRACE 20 <- [1,20,null,{}] [task 2017-11-07T13:02:45.449Z] 13:02:45 INFO - 1510059765446 Marionette TRACE 20 -> [0,21,"takeScreenshot",{"highlights":null,"full":true,"hash":false,"id":null,"scroll":true}] [task 2017-11-07T13:02:45.465Z] 13:02:45 INFO - [Parent 4493, Main Thread] WARNING: Remote iframe not rendered: file /builds/worker/workspace/build/src/layout/ipc/RenderFrameParent.cpp, line 183 [task 2017-11-07T13:02:45.543Z] 13:02:45 INFO - 1510059765533 Marionette TRACE 20 <- [1,21,null,{"value":"iVBORw0KGgoAAAANSUhEUgAABQAAAAQQCAYAAAC9RfbYAAAgAElEQVR4nOzdb6ht530f+P2y0Pd93TcNuDSQ0pIJJJCUwhSCoZ28SNpQDOOgGwpF8pAXTgqNiwU+d ... 2vD+WZLFYLBaLxWKxWCwWi8VisVjXsm49ZlHsMYvic9xiZOsjYy+MrY9sMfa52z73GNd2vMtisVgsFovFYrFYLBaLxWKxrmH9DXEWWeZ3vRtnAAAAAElFTkSuQmCC"}] [task 2017-11-07T13:02:45.583Z] 13:02:45 INFO - 1510059765578 Marionette TRACE 20 -> [0,22,"setContext",{"value":"content"}] [task 2017-11-07T13:02:45.585Z] 13:02:45 INFO - 1510059765581 Marionette TRACE 20 <- [1,22,null,{}] [task 2017-11-07T13:02:45.593Z] 13:02:45 INFO - 1510059765586 Marionette TRACE 20 -> [0,23,"getContext",{}] [task 2017-11-07T13:02:45.595Z] 13:02:45 INFO - 1510059765590 Marionette TRACE 20 <- [1,23,null,{"value":"content"}] [task 2017-11-07T13:02:45.603Z] 13:02:45 INFO - 1510059765598 Marionette TRACE 20 -> [0,24,"setContext",{"value":"content"}] [task 2017-11-07T13:02:45.605Z] 13:02:45 INFO - 1510059765600 Marionette TRACE 20 <- [1,24,null,{}] [task 2017-11-07T13:02:45.606Z] 13:02:45 INFO - 1510059765603 Marionette TRACE 20 -> [0,25,"getPageSource",{}] [task 2017-11-07T13:02:45.615Z] 13:02:45 INFO - 1510059765609 Marionette TRACE 20 <- [1,25,{"error":"unexpected alert open","message":"","stacktrace":"WebDriverError@chrome://marionette/content/error.js:172:5\nUnexpectedAlertOpenError@chrome://marionette/content/error.js:456:5 ... ync*onPacket@chrome://marionette/content/server.js:508:8\n_onJSONObjectReady/<@chrome://marionette/content/transport.js:500:9\n"},null] [task 2017-11-07T13:02:45.616Z] 13:02:45 INFO - 1510059765612 Marionette TRACE 20 -> [0,26,"setContext",{"value":"content"}] [task 2017-11-07T13:02:45.618Z] 13:02:45 INFO - 1510059765615 Marionette TRACE 20 <- [1,26,null,{}] [task 2017-11-07T13:02:45.627Z] 13:02:45 WARNING - Failed to gather test failure debug: [task 2017-11-07T13:02:45.628Z] 13:02:45 WARNING - stacktrace: [task 2017-11-07T13:02:45.629Z] 13:02:45 WARNING - WebDriverError@chrome://marionette/content/error.js:172:5 [task 2017-11-07T13:02:45.630Z] 13:02:45 WARNING - UnexpectedAlertOpenError@chrome://marionette/content/error.js:456:5 [task 2017-11-07T13:02:45.632Z] 13:02:45 WARNING - assert.that/<@chrome://marionette/content/assert.js:385:13 [task 2017-11-07T13:02:45.633Z] 13:02:45 WARNING - assert.noUserPrompt@chrome://marionette/content/assert.js:158:3 [task 2017-11-07T13:02:45.635Z] 13:02:45 WARNING - GeckoDriver.prototype.getPageSource@chrome://marionette/content/driver.js:1187:3 [task 2017-11-07T13:02:45.636Z] 13:02:45 WARNING - Async*despatch@chrome://marionette/content/server.js:560:20 [task 2017-11-07T13:02:45.638Z] 13:02:45 WARNING - async*execute@chrome://marionette/content/server.js:534:11 [task 2017-11-07T13:02:45.641Z] 13:02:45 WARNING - async*onPacket/<@chrome://marionette/content/server.js:509:15 [task 2017-11-07T13:02:45.642Z] 13:02:45 WARNING - async*onPacket@chrome://marionette/content/server.js:508:8 [task 2017-11-07T13:02:45.644Z] 13:02:45 WARNING - _onJSONObjectReady/<@chrome://marionette/content/transport.js:500:9 [task 2017-11-07T13:02:45.645Z] 13:02:45 WARNING - [task 2017-11-07T13:02:45.667Z] 13:02:45 INFO - TEST-UNEXPECTED-ERROR | test_modal_dialogs.py TestTabModalAlerts.test_onbeforeunload_accept | TimeoutException: Timeout loading page after 300000ms [task 2017-11-07T13:02:45.668Z] 13:02:45 INFO - stacktrace: [task 2017-11-07T13:02:45.668Z] 13:02:45 INFO - WebDriverError@chrome://marionette/content/error.js:172:5 [task 2017-11-07T13:02:45.670Z] 13:02:45 INFO - TimeoutError@chrome://marionette/content/error.js:442:5 [task 2017-11-07T13:02:45.671Z] 13:02:45 INFO - notify@chrome://marionette/content/listener.js:334:13 [task 2017-11-07T13:02:45.672Z] 13:02:45 INFO - openRemotePrompt@jar:file:///builds/worker/workspace/build/application/firefox/omni.ja!/components/nsPrompter.js:512:5 [task 2017-11-07T13:02:45.673Z] 13:02:45 INFO - openPrompt@jar:file:///builds/worker/workspace/build/application/firefox/omni.ja!/components/nsPrompter.js:546:17 [task 2017-11-07T13:02:45.674Z] 13:02:45 INFO - confirmEx@jar:file:///builds/worker/workspace/build/application/firefox/omni.ja!/components/nsPrompter.js:711:9 [task 2017-11-07T13:02:45.675Z] 13:02:45 INFO - get/<@chrome://marionette/content/listener.js:1151:7 [task 2017-11-07T13:02:45.676Z] 13:02:45 INFO - navigate/<@chrome://marionette/content/listener.js:408:13 [task 2017-11-07T13:02:45.678Z] 13:02:45 INFO - async*navigate@chrome://marionette/content/listener.js:407:13 [task 2017-11-07T13:02:45.679Z] 13:02:45 INFO - get@chrome://marionette/content/listener.js:1150:5 [task 2017-11-07T13:02:45.680Z] 13:02:45 INFO - Traceback (most recent call last): [task 2017-11-07T13:02:45.683Z] 13:02:45 INFO - File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_harness/marionette_test/testcases.py", line 156, in run [task 2017-11-07T13:02:45.684Z] 13:02:45 INFO - testMethod() [task 2017-11-07T13:02:45.685Z] 13:02:45 INFO - File "/builds/worker/workspace/build/tests/marionette/tests/testing/marionette/harness/marionette_harness/tests/unit/test_modal_dialogs.py", line 194, in test_onbeforeunload_accept [task 2017-11-07T13:02:45.685Z] 13:02:45 INFO - self.marionette.navigate("about:blank") [task 2017-11-07T13:02:45.686Z] 13:02:45 INFO - File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/marionette.py", line 1651, in navigate [task 2017-11-07T13:02:45.687Z] 13:02:45 INFO - self._send_message("get", {"url": url}) [task 2017-11-07T13:02:45.688Z] 13:02:45 INFO - File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/decorators.py", line 23, in _ [task 2017-11-07T13:02:45.689Z] 13:02:45 INFO - return func(*args, **kwargs) [task 2017-11-07T13:02:45.690Z] 13:02:45 INFO - File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/marionette.py", line 759, in _send_message [task 2017-11-07T13:02:45.691Z] 13:02:45 INFO - self._handle_error(err) [task 2017-11-07T13:02:45.692Z] 13:02:45 INFO - File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/marionette.py", line 783, in _handle_error [task 2017-11-07T13:02:45.693Z] 13:02:45 INFO - raise errors.lookup(error)(message, stacktrace=stacktrace)
Most likely caused by the changes on bug 1411393. Lets see how often this really happens.
Blocks: 1411393
Not been seen in a while so closing
Status: NEW → RESOLVED
Closed: 7 years ago
Resolution: --- → WORKSFORME
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.