Closed Bug 1439549 Opened 7 years ago Closed 7 years ago

Intermittent testing/marionette/harness/marionette_harness/tests/unit/test_marionette.py TestMarionette.test_raise_for_port_non_existing_process | IOError: Process killed after 120s because no connection to Marionette server could be established.

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 INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

References

Details

(Keywords: intermittent-failure)

Filed by: apavel [at] mozilla.com https://treeherder.mozilla.org/logviewer.html#?job_id=163151808&repo=autoland https://queue.taskcluster.net/v1/task/NRJJHKOxQw6LFIFLgx6yew/runs/1/artifacts/public/logs/live_backing.log [task 2018-02-20T11:32:25.441Z] 11:32:25 INFO - TEST-PASS | testing/marionette/harness/marionette_harness/tests/unit/test_marionette.py TestMarionette.test_disable_enable_new_connections | took 1264ms [task 2018-02-20T11:32:25.442Z] 11:32:25 INFO - TEST-START | testing/marionette/harness/marionette_harness/tests/unit/test_marionette.py TestMarionette.test_raise_for_port_non_existing_process [task 2018-02-20T11:32:25.458Z] 11:32:25 INFO - 1519126345446 Marionette DEBUG Accepted connection 9 from 127.0.0.1:60066 [task 2018-02-20T11:32:25.459Z] 11:32:25 INFO - 1519126345450 Marionette DEBUG Closed connection 7 [task 2018-02-20T11:32:25.479Z] 11:32:25 INFO - 1519126345462 Marionette TRACE 9 -> [0,1,"newSession",{}] [task 2018-02-20T11:32:25.479Z] 11:32:25 INFO - 1519126345476 Marionette DEBUG Register listener.js for window 2147483649 [task 2018-02-20T11:32:25.496Z] 11:32:25 INFO - --DOCSHELL 0x7f91ab76c000 == 5 [pid = 798] [id = {3573c2b8-8afa-4fd8-b8d4-560d84564fa3}] [task 2018-02-20T11:32:25.524Z] 11:32:25 INFO - 1519126345520 Marionette TRACE 9 <- [1,1,null,{"sessionId":"302c367c-dd24-496e-8a96-f3dc7e9731b4","capabilities":{"browserName":"firefox","browserVersion":"60.0a ... essID":798,"moz:profile":"/tmp/tmpdf40jm.mozrunner","moz:useNonSpecCompliantPointerOrigin":false,"moz:webdriverClick":true}}] [task 2018-02-20T11:32:25.541Z] 11:32:25 INFO - 1519126345530 Marionette TRACE 9 -> [0,2,"setTimeouts",{"script":30000}] [task 2018-02-20T11:32:25.541Z] 11:32:25 INFO - 1519126345534 Marionette TRACE 9 <- [1,2,null,{}] [task 2018-02-20T11:32:25.549Z] 11:32:25 INFO - 1519126345544 Marionette TRACE 9 -> [0,3,"setTimeouts",{"pageLoad":300000}] [task 2018-02-20T11:32:25.554Z] 11:32:25 INFO - 1519126345550 Marionette TRACE 9 <- [1,3,null,{}] [task 2018-02-20T11:32:25.555Z] 11:32:25 INFO - 1519126345552 Marionette TRACE 9 -> [0,4,"setTimeouts",{"implicit":0}] [task 2018-02-20T11:32:25.563Z] 11:32:25 INFO - 1519126345558 Marionette TRACE 9 <- [1,4,null,{}] [task 2018-02-20T11:32:25.568Z] 11:32:25 INFO - 1519126345562 Marionette TRACE 9 -> [0,5,"deleteSession",{}] [task 2018-02-20T11:32:25.579Z] 11:32:25 INFO - 1519126345570 Marionette TRACE 9 <- [1,5,null,{}] [task 2018-02-20T11:32:25.600Z] 11:32:25 INFO - Application command: /builds/worker/workspace/build/application/firefox/firefox -no-remote -marionette -profile /tmp/tmpdf40jm.mozrunner [task 2018-02-20T11:32:26.647Z] 11:32:26 INFO - 1519126346638 Marionette DEBUG Received observer notification profile-after-change [task 2018-02-20T11:32:26.812Z] 11:32:26 INFO - 1519126346806 Marionette DEBUG Received observer notification command-line-startup [task 2018-02-20T11:32:26.814Z] 11:32:26 INFO - 1519126346808 Marionette DEBUG Received observer notification nsPref:changed [task 2018-02-20T11:32:26.891Z] 11:32:26 INFO - ++DOCSHELL 0x7fcd72738000 == 1 [pid = 912] [id = {1fd279d7-5536-49a2-9b59-add53cbf0a23}] [task 2018-02-20T11:32:26.892Z] 11:32:26 INFO - ++DOMWINDOW == 1 (0x7fcd7a4e6a40) [pid = 912] [serial = 1] [outer = (nil)] [task 2018-02-20T11:32:26.893Z] 11:32:26 INFO - ++DOMWINDOW == 2 (0x7fcd7270e000) [pid = 912] [serial = 2] [outer = 0x7fcd7a4e6a40] [task 2018-02-20T11:32:26.970Z] 11:32:26 INFO - ++DOCSHELL 0x7fcd7010b800 == 2 [pid = 912] [id = {e4fbc12e-bf0e-4fa7-8c12-38d2d3b7c1c8}] [task 2018-02-20T11:32:26.971Z] 11:32:26 INFO - ++DOMWINDOW == 3 (0x7fcd7a4e7010) [pid = 912] [serial = 3] [outer = (nil)] [task 2018-02-20T11:32:26.972Z] 11:32:26 INFO - ++DOMWINDOW == 4 (0x7fcd727dfc00) [pid = 912] [serial = 4] [outer = 0x7fcd7a4e7010] [task 2018-02-20T11:32:27.050Z] 11:32:27 INFO - ++DOMWINDOW == 5 (0x7fcd727e6000) [pid = 912] [serial = 5] [outer = 0x7fcd7a4e6a40] [task 2018-02-20T11:32:27.152Z] 11:32:27 INFO - ++DOCSHELL 0x7fcd6f64a800 == 3 [pid = 912] [id = {b6f31eaa-a99e-4b75-8ebf-8a7fd35e3146}] [task 2018-02-20T11:32:27.153Z] 11:32:27 INFO - ++DOMWINDOW == 6 (0x7fcd7a4e7200) [pid = 912] [serial = 6] [outer = (nil)] [task 2018-02-20T11:32:27.599Z] 11:32:27 INFO - ++DOCSHELL 0x7fcd6e329800 == 4 [pid = 912] [id = {378b7c1c-f3b5-4c33-b844-14fbee8fa4b3}] [task 2018-02-20T11:32:27.600Z] 11:32:27 INFO - ++DOMWINDOW == 7 (0x7fcd7a4e77d0) [pid = 912] [serial = 7] [outer = (nil)] [task 2018-02-20T11:32:27.742Z] 11:32:27 INFO - ++DOMWINDOW == 8 (0x7fcd6e48e000) [pid = 912] [serial = 8] [outer = 0x7fcd7a4e77d0] [task 2018-02-20T11:32:28.127Z] 11:32:28 INFO - ++DOMWINDOW == 9 (0x7fcd6d426800) [pid = 912] [serial = 9] [outer = 0x7fcd7a4e7200] [task 2018-02-20T11:32:28.216Z] 11:32:28 INFO - Couldn't convert chrome URL: chrome://branding/locale/brand.properties [task 2018-02-20T11:32:28.434Z] 11:32:28 INFO - ++DOCSHELL 0x7f8af0961000 == 1 [pid = 952] [id = {b7d6de89-fb78-4276-9828-61efdb90cbdb}] [task 2018-02-20T11:32:28.495Z] 11:32:28 INFO - ++DOMWINDOW == 1 (0x7f8af4988660) [pid = 952] [serial = 1] [outer = (nil)] [task 2018-02-20T11:32:28.580Z] 11:32:28 INFO - ++DOMWINDOW == 2 (0x7f8af3a4dc00) [pid = 952] [serial = 2] [outer = 0x7f8af4988660] [task 2018-02-20T11:32:28.725Z] 11:32:28 INFO - ++DOMWINDOW == 3 (0x7f8af3abfc00) [pid = 952] [serial = 3] [outer = 0x7f8af4988660] [task 2018-02-20T11:34:25.652Z] 11:34:25 INFO - TEST-UNEXPECTED-ERROR | testing/marionette/harness/marionette_harness/tests/unit/test_marionette.py TestMarionette.test_raise_for_port_non_existing_process | IOError: Process killed after 120s because no connection to Marionette server could be established. Check gecko.log for errors [task 2018-02-20T11:34:25.653Z] 11:34:25 INFO - Traceback (most recent call last): [task 2018-02-20T11:34:25.654Z] 11:34:25 INFO - File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_harness/marionette_test/testcases.py", line 190, in run [task 2018-02-20T11:34:25.655Z] 11:34:25 INFO - self.tearDown() [task 2018-02-20T11:34:25.656Z] 11:34:25 INFO - File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_harness/marionette_test/testcases.py", line 338, in tearDown [task 2018-02-20T11:34:25.657Z] 11:34:25 INFO - self.marionette.start_session() [task 2018-02-20T11:34:25.657Z] 11:34:25 INFO - File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/decorators.py", line 26, in _ [task 2018-02-20T11:34:25.658Z] 11:34:25 INFO - return func(*args, **kwargs) [task 2018-02-20T11:34:25.659Z] 11:34:25 INFO - File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/marionette.py", line 1211, in start_session [task 2018-02-20T11:34:25.660Z] 11:34:25 INFO - self.start_binary(timeout) [task 2018-02-20T11:34:25.660Z] 11:34:25 INFO - File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/marionette.py", line 645, in start_binary [task 2018-02-20T11:34:25.661Z] 11:34:25 INFO - reraise(IOError, msg.format(timeout), tb) [task 2018-02-20T11:34:25.662Z] 11:34:25 INFO - File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/marionette.py", line 636, in start_binary [task 2018-02-20T11:34:25.663Z] 11:34:25 INFO - self.raise_for_port(timeout=timeout) [task 2018-02-20T11:34:25.663Z] 11:34:25 INFO - File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/marionette.py", line 716, in raise_for_port [task 2018-02-20T11:34:25.664Z] 11:34:25 INFO - self.host, self.port)) [task 2018-02-20T11:34:25.665Z] 11:34:25 INFO - TEST-INFO took 120208ms
No `sessionstore-windows-restored` notification has been sent. So it depends on bug 1382162.
Depends on: 1382162
https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
Status: NEW → RESOLVED
Closed: 7 years ago
Resolution: --- → INCOMPLETE
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.