1657875 - Intermittent testing/firefox-ui/tests/functional/sessionstore/test_restore_windows_after_restart_and_quit.py TestSessionStoreDisabled.test_no_restore_with_quit | IOError: Process killed after 120s because no connection to Marionette server could be

Status: RESOLVED INCOMPLETE

(Testing :: Firefox UI Tests, defect, P5)

Description

[Treeherder Bug Filer]

Filed by: archaeopteryx [at] coole-files.de
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=312252146&repo=try
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/U5Cah0isSG-bCDBCOOoapA/runs/0/artifacts/public/logs/live_backing.log

---

[task 2020-08-06T11:32:29.976Z] 11:32:29 INFO - 1596713549974 Marionette TRACE Received DOM event focus for [object HTMLDocument]
[task 2020-08-06T11:32:29.981Z] 11:32:29 INFO - 1596713549976 Marionette DEBUG 1 <- [1,120,null,{"value":null}]
[task 2020-08-06T11:32:29.981Z] 11:32:29 INFO - 1596713549977 Marionette DEBUG 1 -> [0,121,"WebDriver:GetChromeWindowHandle",{}]
[task 2020-08-06T11:32:29.982Z] 11:32:29 INFO - 1596713549977 Marionette DEBUG 1 <- [1,121,null,{"value":"56"}]
[task 2020-08-06T11:32:29.983Z] 11:32:29 INFO - 1596713549978 Marionette DEBUG 1 -> [0,122,"Marionette:GetContext",{}]
[task 2020-08-06T11:32:29.983Z] 11:32:29 INFO - 1596713549979 Marionette DEBUG 1 <- [1,122,null,{"value":"chrome"}]
[task 2020-08-06T11:32:29.983Z] 11:32:29 INFO - 1596713549980 Marionette DEBUG 1 -> [0,123,"Marionette:SetContext",{"value":"chrome"}]
[task 2020-08-06T11:32:29.984Z] 11:32:29 INFO - 1596713549980 Marionette DEBUG 1 <- [1,123,null,{"value":null}]
[task 2020-08-06T11:32:29.985Z] 11:32:29 INFO - 1596713549981 Marionette DEBUG 1 -> [0,124,"WebDriver:SwitchToWindow",{"handle":"56","focus":true}]
[task 2020-08-06T11:32:29.986Z] 11:32:29 INFO - 1596713549981 Marionette DEBUG 1 <- [1,124,null,{"value":null}]
[task 2020-08-06T11:32:29.986Z] 11:32:29 INFO - 1596713549983 Marionette DEBUG 1 -> [0,125,"WebDriver:ExecuteScript",{"script":"return gBrowser.tabs.map(tab => {\n return tab.linkedBrowser.c ... ilename":"tests/testing/firefox-ui/tests/functional/sessionstore/session_store_test_case.py","sandbox":"default","line":149}]
[task 2020-08-06T11:32:29.988Z] 11:32:29 INFO - 1596713549985 Marionette DEBUG 1 <- [1,125,null,{"value":["http://127.0.0.1:39631/layout/mozilla_projects.html","http://127.0.0.1:39631/layout/mozilla_mission.html"]}]
[task 2020-08-06T11:32:29.988Z] 11:32:29 INFO - 1596713549986 Marionette DEBUG 1 -> [0,126,"Marionette:SetContext",{"value":"chrome"}]
[task 2020-08-06T11:32:29.989Z] 11:32:29 INFO - 1596713549987 Marionette DEBUG 1 <- [1,126,null,{"value":null}]
[task 2020-08-06T11:32:29.996Z] 11:32:29 INFO - 1596713549988 Marionette DEBUG 1 -> [0,127,"WebDriver:SwitchToWindow",{"handle":"56","focus":true}]
[task 2020-08-06T11:32:29.996Z] 11:32:29 INFO - 1596713549988 Marionette DEBUG 1 <- [1,127,null,{"value":null}]
[task 2020-08-06T11:32:29.997Z] 11:32:29 INFO - 1596713549989 Marionette DEBUG 1 -> [0,128,"Marionette:AcceptConnections",{"value":false}]
[task 2020-08-06T11:32:29.998Z] 11:32:29 INFO - 1596713549990 Marionette INFO Stopped listening on port 2828
[task 2020-08-06T11:32:29.998Z] 11:32:29 INFO - 1596713549990 Marionette DEBUG 1 <- [1,128,null,{"value":null}]
[task 2020-08-06T11:32:29.999Z] 11:32:29 INFO - 1596713549990 Marionette DEBUG 1 -> [0,129,"Marionette:GetContext",{}]
[task 2020-08-06T11:32:30.000Z] 11:32:29 INFO - 1596713549991 Marionette DEBUG 1 <- [1,129,null,{"value":"chrome"}]
[task 2020-08-06T11:32:30.000Z] 11:32:29 INFO - 1596713549991 Marionette DEBUG 1 -> [0,130,"Marionette:SetContext",{"value":"chrome"}]
[task 2020-08-06T11:32:30.002Z] 11:32:29 INFO - 1596713549992 Marionette DEBUG 1 <- [1,130,null,{"value":null}]
[task 2020-08-06T11:32:30.002Z] 11:32:29 INFO - 1596713549994 Marionette DEBUG 1 -> [0,131,"WebDriver:ExecuteScript",{"script":"Components.utils.import("resource://gre/modules/Services.jsm");\n ... args":[],"filename":"../../venv/lib/python2.7/site-packages/marionette_driver/marionette.py","sandbox":"default","line":905}]
[task 2020-08-06T11:32:30.003Z] 11:32:30 INFO - 1596713549996 Marionette DEBUG 1 <- [1,131,null,{"value":false}]
[task 2020-08-06T11:32:30.004Z] 11:32:30 INFO - 1596713549997 Marionette DEBUG 1 -> [0,132,"Marionette:SetContext",{"value":"chrome"}]
[task 2020-08-06T11:32:30.007Z] 11:32:30 INFO - 1596713550003 Marionette DEBUG 1 <- [1,132,null,{"value":null}]
[task 2020-08-06T11:32:30.015Z] 11:32:30 INFO - 1596713550009 Marionette DEBUG 1 -> [0,133,"Marionette:Quit",{"flags":["eForceQuit"]}]
[task 2020-08-06T11:32:30.138Z] 11:32:30 INFO - 1596713550132 Marionette TRACE Received observer notification quit-application
[task 2020-08-06T11:32:30.154Z] 11:32:30 INFO - 1596713550147 Marionette DEBUG 1 <- [1,133,null,{"cause":"shutdown"}]
[task 2020-08-06T11:32:30.355Z] 11:32:30 INFO - 1596713550347 Marionette DEBUG Closed connection 1
[task 2020-08-06T11:32:30.819Z] 11:32:30 INFO - 1596713550815 Marionette TRACE Received observer notification xpcom-will-shutdown
[task 2020-08-06T11:32:30.820Z] 11:32:30 INFO - 1596713550816 Marionette DEBUG Marionette stopped listening
[task 2020-08-06T11:32:30.960Z] 11:32:30 INFO - Application command: /builds/worker/workspace/build/application/firefox/firefox -no-remote -marionette -profile /builds/worker/workspace/build/tmpKUoSNY.mozrunner
[task 2020-08-06T11:34:31.013Z] 11:34:31 INFO - TEST-UNEXPECTED-ERROR | testing/firefox-ui/tests/functional/sessionstore/test_restore_windows_after_restart_and_quit.py TestSessionStoreDisabled.test_no_restore_with_quit | IOError: Process killed after 120s because no connection to Marionette server could be established. Check gecko.log for errors

Comment 1

[Intermittent Failures Robot]

1 failures in 4618 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• try: 1

Platform breakdown:

• linux1804-64-shippable: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1657875&startday=2020-08-03&endday=2020-08-09&tree=all

Comment 2

[Henrik Skupin [:whimboo][⌚️UTC+1]]

Firefox didn't start-up at all for the second invocation of the command. Looks like a one-off failure so far.

Comment 3

[BugBot [:suhaib / :marco/ :calixte]]

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit auto_nag documentation.

Comment 4

[Andreea Pavel [:apavel]]

Recent failure log: https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=316721938&repo=try&lineNumber=1753

[task 2020-09-25T14:12:27.126Z] 14:12:27 INFO - TEST-START | testing/firefox-ui/tests/functional/sessionstore/test_restore_windows_after_restart_and_quit.py TestSessionStoreDisabled.test_no_restore_with_quit
[task 2020-09-25T14:12:27.127Z] 14:12:27 INFO - 1601043147123 Marionette DEBUG Accepted connection 2 from 127.0.0.1:49664
[task 2020-09-25T14:12:27.127Z] 14:12:27 INFO - 1601043147124 Marionette DEBUG 2 -> [0,1,"WebDriver:NewSession",{"strictFileInteractability":true}]
[task 2020-09-25T14:12:27.127Z] 14:12:27 INFO - 1601043147125 Marionette TRACE [17] Frame script loaded
[task 2020-09-25T14:12:27.131Z] 14:12:27 INFO - 1601043147129 Marionette DEBUG 2 <- [1,1,null,{"sessionId":"2275dcc5-b24e-44af-b7fd-bd827f107719","capabilities":{"browserName":"firefox","browserVersion":"83.0" ... ld/tmpdmSGdg.mozrunner","moz:shutdownTimeout":60000,"moz:useNonSpecCompliantPointerOrigin":false,"moz:webdriverClick":true}}]
[task 2020-09-25T14:12:27.132Z] 14:12:27 INFO - 1601043147130 Marionette DEBUG 2 -> [0,2,"WebDriver:SetTimeouts",{"script":30000}]
[task 2020-09-25T14:12:27.132Z] 14:12:27 INFO - 1601043147130 Marionette DEBUG 2 <- [1,2,null,{"value":null}]
[task 2020-09-25T14:12:27.133Z] 14:12:27 INFO - 1601043147131 Marionette DEBUG 2 -> [0,3,"WebDriver:SetTimeouts",{"pageLoad":300000}]
[task 2020-09-25T14:12:27.134Z] 14:12:27 INFO - 1601043147131 Marionette DEBUG 2 <- [1,3,null,{"value":null}]
[task 2020-09-25T14:12:27.134Z] 14:12:27 INFO - 1601043147132 Marionette DEBUG 2 -> [0,4,"WebDriver:SetTimeouts",{"implicit":0}]
[task 2020-09-25T14:12:27.136Z] 14:12:27 INFO - 1601043147134 Marionette DEBUG 2 <- [1,4,null,{"value":null}]
[task 2020-09-25T14:12:27.137Z] 14:12:27 INFO - 1601043147135 Marionette DEBUG 2 -> [0,5,"WebDriver:GetChromeWindowHandle",{}]
[task 2020-09-25T14:12:27.138Z] 14:12:27 INFO - 1601043147135 Marionette DEBUG 2 <- [1,5,null,{"value":"6"}]
[task 2020-09-25T14:12:27.138Z] 14:12:27 INFO - 1601043147136 Marionette DEBUG 2 -> [0,6,"WebDriver:GetChromeWindowHandles",{}]
[task 2020-09-25T14:12:27.139Z] 14:12:27 INFO - 1601043147136 Marionette DEBUG 2 <- [1,6,null,["6"]]
[task 2020-09-25T14:12:27.139Z] 14:12:27 INFO - 1601043147137 Marionette DEBUG 2 -> [0,7,"WebDriver:GetWindowHandle",{}]
[task 2020-09-25T14:12:27.140Z] 14:12:27 INFO - 1601043147137 Marionette DEBUG 2 <- [1,7,null,{"value":"17"}]
[task 2020-09-25T14:12:27.141Z] 14:12:27 INFO - 1601043147139 Marionette DEBUG 2 -> [0,8,"WebDriver:GetWindowHandles",{}]
[task 2020-09-25T14:12:27.143Z] 14:12:27 INFO - 1601043147139 Marionette DEBUG 2 <- [1,8,null,["17"]]
[task 2020-09-25T14:12:27.144Z] 14:12:27 INFO - 1601043147141 Marionette DEBUG 2 -> [0,9,"Marionette:SetContext",{"value":"chrome"}]
[task 2020-09-25T14:12:27.144Z] 14:12:27 INFO - 1601043147141 Marionette DEBUG 2 <- [1,9,null,{"value":null}]
[task 2020-09-25T14:12:27.145Z] 14:12:27 INFO - 1601043147142 Marionette DEBUG 2 -> [0,10,"Marionette:GetContext",{}]
[task 2020-09-25T14:12:27.145Z] 14:12:27 INFO - 1601043147142 Marionette DEBUG 2 <- [1,10,null,{"value":"chrome"}]
[task 2020-09-25T14:12:27.146Z] 14:12:27 INFO - 1601043147143 Marionette DEBUG 2 -> [0,11,"Marionette:SetContext",{"value":"chrome"}]
[task 2020-09-25T14:12:27.147Z] 14:12:27 INFO - 1601043147143 Marionette DEBUG 2 <- [1,11,null,{"value":null}]
[task 2020-09-25T14:12:27.160Z] 14:12:27 INFO - 1601043147148 Marionette DEBUG 2 -> [0,12,"WebDriver:ExecuteScript",{"script":"let prefInterface = Components.classes["@mozilla.org/preferences-service;1"]\n ... args":[],"filename":"../../venv/lib/python2.7/site-packages/marionette_driver/marionette.py","sandbox":"default","line":847}]
[task 2020-09-25T14:12:27.160Z] 14:12:27 INFO - 1601043147150 Marionette DEBUG 2 <- [1,12,null,{"value":false}]
[task 2020-09-25T14:12:27.161Z] 14:12:27 INFO - 1601043147150 Marionette DEBUG 2 -> [0,13,"Marionette:SetContext",{"value":"chrome"}]
[task 2020-09-25T14:12:27.161Z] 14:12:27 INFO - 1601043147150 Marionette DEBUG 2 <- [1,13,null,{"value":null}]
[task 2020-09-25T14:12:27.161Z] 14:12:27 INFO - 1601043147151 Marionette DEBUG 2 -> [0,14,"Marionette:GetContext",{}]
[task 2020-09-25T14:12:27.162Z] 14:12:27 INFO - 1601043147151 Marionette DEBUG 2 <- [1,14,null,{"value":"chrome"}]
[task 2020-09-25T14:12:27.163Z] 14:12:27 INFO - 1601043147151 Marionette DEBUG 2 -> [0,15,"WebDriver:DeleteSession",{}]
[task 2020-09-25T14:12:27.163Z] 14:12:27 INFO - 1601043147153 Marionette DEBUG 2 <- [1,15,null,{"value":null}]
[task 2020-09-25T14:12:27.220Z] 14:12:27 INFO - Application command: /builds/worker/workspace/build/application/firefox/firefox -no-remote -marionette -profile /builds/worker/workspace/build/tmprE6XRk.mozrunner
[task 2020-09-25T14:12:27.630Z] 14:12:27 INFO - 1601043147626 Marionette TRACE Marionette enabled
[task 2020-09-25T14:12:27.707Z] 14:12:27 INFO - 1601043147695 Marionette TRACE Received observer notification toplevel-window-ready

[task 2020-09-25T14:12:36.354Z] 14:12:36 INFO - 1601043156353 Marionette INFO Stopped listening on port 2828
[task 2020-09-25T14:12:36.355Z] 14:12:36 INFO - 1601043156353 Marionette DEBUG 1 <- [1,128,null,{"value":null}]
[task 2020-09-25T14:12:36.358Z] 14:12:36 INFO - 1601043156355 Marionette DEBUG 1 -> [0,129,"Marionette:GetContext",{}]
[task 2020-09-25T14:12:36.358Z] 14:12:36 INFO - 1601043156356 Marionette DEBUG 1 <- [1,129,null,{"value":"chrome"}]
[task 2020-09-25T14:12:36.358Z] 14:12:36 INFO - 1601043156356 Marionette DEBUG 1 -> [0,130,"Marionette:SetContext",{"value":"chrome"}]
[task 2020-09-25T14:12:36.359Z] 14:12:36 INFO - 1601043156356 Marionette DEBUG 1 <- [1,130,null,{"value":null}]
[task 2020-09-25T14:12:36.362Z] 14:12:36 INFO - 1601043156360 Marionette DEBUG 1 -> [0,131,"WebDriver:ExecuteScript",{"script":"Components.utils.import("resource://gre/modules/Services.jsm");\n ... args":[],"filename":"../../venv/lib/python2.7/site-packages/marionette_driver/marionette.py","sandbox":"default","line":905}]
[task 2020-09-25T14:12:36.365Z] 14:12:36 INFO - 1601043156363 Marionette DEBUG 1 <- [1,131,null,{"value":false}]
[task 2020-09-25T14:12:36.365Z] 14:12:36 INFO - 1601043156364 Marionette DEBUG 1 -> [0,132,"Marionette:SetContext",{"value":"chrome"}]
[task 2020-09-25T14:12:36.365Z] 14:12:36 INFO - 1601043156364 Marionette DEBUG 1 <- [1,132,null,{"value":null}]
[task 2020-09-25T14:12:36.369Z] 14:12:36 INFO - 1601043156366 Marionette DEBUG 1 -> [0,133,"Marionette:Quit",{"flags":["eForceQuit"]}]
[task 2020-09-25T14:12:36.525Z] 14:12:36 INFO - 1601043156519 Marionette TRACE Received observer notification quit-application
[task 2020-09-25T14:12:36.533Z] 14:12:36 INFO - 1601043156528 Marionette DEBUG 1 <- [1,133,null,{"cause":"shutdown"}]
[task 2020-09-25T14:12:36.718Z] 14:12:36 INFO - 1601043156715 Marionette DEBUG Closed connection 1
[task 2020-09-25T14:12:36.935Z] 14:12:36 INFO - 1601043156927 Marionette TRACE Received observer notification xpcom-will-shutdown
[task 2020-09-25T14:12:36.936Z] 14:12:36 INFO - 1601043156927 Marionette DEBUG Marionette stopped listening
[task 2020-09-25T14:12:37.092Z] 14:12:37 INFO - Application command: /builds/worker/workspace/build/application/firefox/firefox -no-remote -marionette -profile /builds/worker/workspace/build/tmprE6XRk.mozrunner
[task 2020-09-25T14:14:37.157Z] 14:14:37 INFO - TEST-UNEXPECTED-ERROR | testing/firefox-ui/tests/functional/sessionstore/test_restore_windows_after_restart_and_quit.py TestSessionStoreDisabled.test_no_restore_with_quit | IOError: Process killed after 120s because no connection to Marionette server could be established. Check gecko.log for errors
[task 2020-09-25T14:14:37.157Z] 14:14:37 INFO - Traceback (most recent call last):
[task 2020-09-25T14:14:37.158Z] 14:14:37 INFO - File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_harness/marionette_test/testcases.py", line 196, in run
[task 2020-09-25T14:14:37.158Z] 14:14:37 INFO - testMethod()
[task 2020-09-25T14:14:37.158Z] 14:14:37 INFO - File "/builds/worker/workspace/build/tests/firefox-ui/tests/testing/firefox-ui/tests/functional/sessionstore/test_restore_windows_after_restart_and_quit.py", line 63, in test_no_restore_with_quit
[task 2020-09-25T14:14:37.158Z] 14:14:37 INFO - self.marionette.start_session()
[task 2020-09-25T14:14:37.158Z] 14:14:37 INFO - File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/decorators.py", line 26, in _
[task 2020-09-25T14:14:37.158Z] 14:14:37 INFO - return func(*args, **kwargs)
[task 2020-09-25T14:14:37.158Z] 14:14:37 INFO - File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/marionette.py", line 1107, in start_session
[task 2020-09-25T14:14:37.158Z] 14:14:37 INFO - self.start_binary(timeout)
[task 2020-09-25T14:14:37.159Z] 14:14:37 INFO - File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/marionette.py", line 493, in start_binary
[task 2020-09-25T14:14:37.159Z] 14:14:37 INFO - reraise(IOError, IOError(msg.format(timeout)), sys.exc_info()[2])
[task 2020-09-25T14:14:37.159Z] 14:14:37 INFO - File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/marionette.py", line 485, in start_binary
[task 2020-09-25T14:14:37.160Z] 14:14:37 INFO - self.raise_for_port(timeout=timeout)
[task 2020-09-25T14:14:37.160Z] 14:14:37 INFO - File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/marionette.py", line 573, in raise_for_port
[task 2020-09-25T14:14:37.161Z] 14:14:37 INFO - self.host, self.port))
[task 2020-09-25T14:14:37.161Z] 14:14:37 INFO - TEST-INFO took 130031ms
[task 2020-09-25T14:14:37.162Z] 14:14:37 ERROR - test_end for testing/firefox-ui/tests/functional/sessionstore/test_restore_windows_after_restart_and_quit.py TestSessionStoreDisabled.test_no_restore_with_quit logged while not in progress. Logged with data: {"status": "ERROR", "extra": {"class_name": "test_restore_windows_after_restart_and_quit.TestSessionStoreDisabled", "method_name": "test_no_restore_with_quit"}, "expected": "PASS", "test": "testing/firefox-ui/tests/functional/sessionstore/test_restore_windows_after_restart_and_quit.py TestSessionStoreDisabled.test_no_restore_with_quit", "message": "InvalidSessionIdException: 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 227, in run\n self.tearDown()\n File "/builds/worker/workspace/build/tests/firefox-ui/tests/testing/firefox-ui/tests/functional/sessionstore/session_store_test_case.py", line 72, in tearDown\n super(SessionStoreTestCase, self).tearDown()\n File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_harness/runner/mixins/window_manager.py", line 25, in tearDown\n if len(self.marionette.chrome_window_handles) > len(self.start_windows):\n File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/marionette.py", line 1261, in chrome_window_handles\n return self._send_message("WebDriver:GetChromeWindowHandles")\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 591, in _send_message\n raise errors.InvalidSessionIdException("Please start a session")\n"}

Comment 5

[Intermittent Failures Robot]

1 failures in 4019 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• try: 1

Platform breakdown:

• linux1804-64-shippable: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1657875&startday=2020-09-21&endday=2020-09-27&tree=all

Comment 6

[Intermittent Failures Robot]

1 failures in 3694 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-central: 1

Platform breakdown:

• macosx1014-64-shippable: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1657875&startday=2020-10-12&endday=2020-10-18&tree=all

Comment 7

[Intermittent Failures Robot]

1 failures in 4230 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 1

Platform breakdown:

• macosx1014-64: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1657875&startday=2020-10-19&endday=2020-10-25&tree=all

Comment 8

[BugBot [:suhaib / :marco/ :calixte]]

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit auto_nag documentation.

Comment 9

[Razvan Maries]

New occurrence: https://treeherder.mozilla.org/logviewer?job_id=324269995&repo=autoland&lineNumber=1295

Comment 10

[Intermittent Failures Robot]

1 failures in 4060 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 1

Platform breakdown:

• macosx1014-64-qr: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1657875&startday=2020-12-07&endday=2020-12-13&tree=all

Comment 11

[BugBot [:suhaib / :marco/ :calixte]]

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit auto_nag documentation.

Comment 12

[Andreea Pavel [:apavel]]

Recent failure log: https://treeherder.mozilla.org/logviewer?job_id=337470319&repo=mozilla-esr78&lineNumber=1732

[task 2021-04-22T17:36:35.031Z] 17:36:34 INFO - 1619112994961 Marionette INFO Stopped listening on port 2828
[task 2021-04-22T17:36:35.035Z] 17:36:34 INFO - 1619112994961 Marionette DEBUG 1 <- [1,128,null,{"value":null}]
[task 2021-04-22T17:36:35.037Z] 17:36:34 INFO - 1619112994962 Marionette DEBUG 1 -> [0,129,"Marionette:GetContext",{}]
[task 2021-04-22T17:36:35.039Z] 17:36:34 INFO - 1619112994962 Marionette DEBUG 1 <- [1,129,null,{"value":"chrome"}]
[task 2021-04-22T17:36:35.041Z] 17:36:34 INFO - 1619112994963 Marionette DEBUG 1 -> [0,130,"Marionette:SetContext",{"value":"chrome"}]
[task 2021-04-22T17:36:35.043Z] 17:36:34 INFO - 1619112994963 Marionette DEBUG 1 <- [1,130,null,{"value":null}]
[task 2021-04-22T17:36:35.045Z] 17:36:34 INFO - 1619112994965 Marionette DEBUG 1 -> [0,131,"WebDriver:ExecuteScript",{"script":"Components.utils.import("resource://gre/modules/Services.jsm");\n ... args":[],"filename":"../../venv/lib/python2.7/site-packages/marionette_driver/marionette.py","sandbox":"default","line":905}]
[task 2021-04-22T17:36:35.047Z] 17:36:34 INFO - 1619112994968 Marionette DEBUG 1 <- [1,131,null,{"value":false}]
[task 2021-04-22T17:36:35.049Z] 17:36:34 INFO - 1619112994972 Marionette DEBUG 1 -> [0,132,"Marionette:SetContext",{"value":"chrome"}]
[task 2021-04-22T17:36:35.052Z] 17:36:34 INFO - 1619112994972 Marionette DEBUG 1 <- [1,132,null,{"value":null}]
[task 2021-04-22T17:36:35.055Z] 17:36:34 INFO - 1619112994973 Marionette DEBUG 1 -> [0,133,"Marionette:Quit",{"flags":["eForceQuit"]}]
[task 2021-04-22T17:36:35.188Z] 17:36:35 INFO - 1619112995182 Marionette TRACE Received observer notification quit-application
[task 2021-04-22T17:36:35.207Z] 17:36:35 INFO - 1619112995197 Marionette DEBUG 1 <- [1,133,null,{"cause":"shutdown"}]
[task 2021-04-22T17:36:35.384Z] 17:36:35 INFO - 1619112995374 Marionette DEBUG Closed connection 1
[task 2021-04-22T17:36:37.556Z] 17:36:37 INFO - 1619112997553 Marionette TRACE Received observer notification xpcom-will-shutdown
[task 2021-04-22T17:36:37.557Z] 17:36:37 INFO - 1619112997553 Marionette DEBUG Marionette stopped listening
[task 2021-04-22T17:36:37.694Z] 17:36:37 INFO - Application command: /builds/worker/workspace/build/application/firefox/firefox -no-remote -marionette -profile /builds/worker/workspace/build/tmpTh1Xfw.mozrunner
[task 2021-04-22T17:38:37.749Z] 17:38:37 INFO - TEST-UNEXPECTED-ERROR | testing/firefox-ui/tests/functional/sessionstore/test_restore_windows_after_restart_and_quit.py TestSessionStoreDisabled.test_no_restore_with_quit | IOError: Process killed after 120s because no connection to Marionette server could be established. Check gecko.log for errors
[task 2021-04-22T17:38:37.749Z] 17:38:37 INFO - Traceback (most recent call last):
[task 2021-04-22T17:38:37.750Z] 17:38:37 INFO - File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_harness/marionette_test/testcases.py", line 191, in run
[task 2021-04-22T17:38:37.751Z] 17:38:37 INFO - testMethod()
[task 2021-04-22T17:38:37.751Z] 17:38:37 INFO - File "/builds/worker/workspace/build/tests/firefox-ui/tests/testing/firefox-ui/tests/functional/sessionstore/test_restore_windows_after_restart_and_quit.py", line 63, in test_no_restore_with_quit
[task 2021-04-22T17:38:37.752Z] 17:38:37 INFO - self.marionette.start_session()
[task 2021-04-22T17:38:37.752Z] 17:38:37 INFO - File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/decorators.py", line 26, in _
[task 2021-04-22T17:38:37.753Z] 17:38:37 INFO - return func(*args, **kwargs)
[task 2021-04-22T17:38:37.753Z] 17:38:37 INFO - File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/marionette.py", line 1107, in start_session
[task 2021-04-22T17:38:37.754Z] 17:38:37 INFO - self.start_binary(timeout)
[task 2021-04-22T17:38:37.754Z] 17:38:37 INFO - File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/marionette.py", line 493, in start_binary
[task 2021-04-22T17:38:37.755Z] 17:38:37 INFO - reraise(IOError, IOError(msg.format(timeout)), sys.exc_info()[2])
[task 2021-04-22T17:38:37.755Z] 17:38:37 INFO - File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/marionette.py", line 485, in start_binary
[task 2021-04-22T17:38:37.756Z] 17:38:37 INFO - self.raise_for_port(timeout=timeout)
[task 2021-04-22T17:38:37.756Z] 17:38:37 INFO - File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/marionette.py", line 573, in raise_for_port
[task 2021-04-22T17:38:37.757Z] 17:38:37 INFO - self.host, self.port))
[task 2021-04-22T17:38:37.757Z] 17:38:37 INFO - TEST-INFO took 131621ms
[task 2021-04-22T17:38:37.758Z] 17:38:37 ERROR - test_end for testing/firefox-ui/tests/functional/sessionstore/test_restore_windows_after_restart_and_quit.py TestSessionStoreDisabled.test_no_restore_with_quit logged while not in progress. Logged with data: {"status": "ERROR", "extra": {"class_name": "test_restore_windows_after_restart_and_quit.TestSessionStoreDisabled", "method_name": "test_no_restore_with_quit"}, "expected": "PASS", "test": "testing/firefox-ui/tests/functional/sessionstore/test_restore_windows_after_restart_and_quit.py TestSessionStoreDisabled.test_no_restore_with_quit", "message": "InvalidSessionIdException: 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 222, in run\n self.tearDown()\n File "/builds/worker/workspace/build/tests/firefox-ui/tests/testing/firefox-ui/tests/functional/sessionstore/session_store_test_case.py", line 72, in tearDown\n super(SessionStoreTestCase, self).tearDown()\n File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_harness/runner/mixins/window_manager.py", line 25, in tearDown\n if len(self.marionette.chrome_window_handles) > len(self.start_windows):\n File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/marionette.py", line 1261, in chrome_window_handles\n return self._send_message("WebDriver:GetChromeWindowHandles")\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 591, in _send_message\n raise errors.InvalidSessionIdException("Please start a session")\n"}

Comment 13

[Intermittent Failures Robot]

1 failures in 3224 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-esr78: 1

Platform and build breakdown:

• linux1804-64-shippable: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1657875&startday=2021-04-19&endday=2021-04-25&tree=all

Comment 14

[BugBot [:suhaib / :marco/ :calixte]]

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit auto_nag documentation.

Comment 15

[Natalia Csoregi [:nataliaCs]]

https://treeherder.mozilla.org/logviewer?job_id=346477795&repo=mozilla-esr78&lineNumber=1731

Comment 16

[Intermittent Failures Robot]

2 failures in 3131 pushes (0.001 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-esr78: 2

Platform and build breakdown:

• linux1804-64-shippable: 2
  • opt: 2

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1657875&startday=2021-07-26&endday=2021-08-01&tree=all

Comment 17

[BugBot [:suhaib / :marco/ :calixte]]

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit auto_nag documentation.