Closed Bug 1472468 Opened 2 years ago Closed 2 months ago

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 establ

Categories

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

Version 3
defect

Tracking

(Not tracked)

RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

References

(Depends on 1 open bug)

Details

(Keywords: intermittent-failure)

Filed by: toros [at] mozilla.com

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

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

[task 2018-06-30T13:31:40.107Z] 13:31:40     INFO -  1530365500086	Marionette	TRACE	1 -> [0,1227,"Marionette:SetContext",{"value":"chrome"}]
[task 2018-06-30T13:31:40.111Z] 13:31:40     INFO -  1530365500087	Marionette	TRACE	1 <- [1,1227,null,{"value":null}]
[task 2018-06-30T13:31:40.112Z] 13:31:40     INFO -  1530365500090	Marionette	TRACE	1 -> [0,1228,"Marionette:Quit",{"flags":["eForceQuit"]}]
[task 2018-06-30T13:31:40.119Z] 13:31:40     INFO -  1530365500092	Marionette	INFO	Stopped listening on port 2828
[task 2018-06-30T13:31:40.572Z] 13:31:40     INFO -  1530365500562	Marionette	TRACE	1 <- [1,1228,null,{"cause":"shutdown"}]
[task 2018-06-30T13:31:41.021Z] 13:31:41     INFO -  1530365501017	Marionette	DEBUG	Closed connection 1
[task 2018-06-30T13:31:41.062Z] 13:31:41     INFO -  ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2018-06-30T13:31:41.071Z] 13:31:41     INFO -  ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2018-06-30T13:31:41.078Z] 13:31:41     INFO -  ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2018-06-30T13:31:42.173Z] 13:31:42     INFO -  1530365502172	Marionette	DEBUG	Received observer notification xpcom-will-shutdown
[task 2018-06-30T13:31:42.176Z] 13:31:42     INFO -  1530365502173	Marionette	DEBUG	Remote service is inactive
[task 2018-06-30T13:31:43.703Z] 13:31:43     INFO - Application command: /builds/worker/workspace/build/application/firefox/firefox -no-remote -marionette -profile /builds/worker/workspace/build/tmpvJkf35.mozrunner
[task 2018-06-30T13:33:43.790Z] 13:33:43     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 2018-06-30T13:33:43.794Z] 13:33:43     INFO - Traceback (most recent call last):
[task 2018-06-30T13:33:43.795Z] 13:33:43     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-30T13:33:43.796Z] 13:33:43     INFO -     testMethod()
[task 2018-06-30T13:33:43.797Z] 13:33:43     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 62, in test_no_restore_with_quit
[task 2018-06-30T13:33:43.798Z] 13:33:43     INFO -     self.marionette.start_session()
[task 2018-06-30T13:33:43.799Z] 13:33:43     INFO -   File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/decorators.py", line 26, in _
[task 2018-06-30T13:33:43.800Z] 13:33:43     INFO -     return func(*args, **kwargs)
[task 2018-06-30T13:33:43.801Z] 13:33:43     INFO -   File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/marionette.py", line 1227, in start_session
[task 2018-06-30T13:33:43.802Z] 13:33:43     INFO -     self.start_binary(timeout)
[task 2018-06-30T13:33:43.803Z] 13:33:43     INFO -   File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/marionette.py", line 660, in start_binary
[task 2018-06-30T13:33:43.804Z] 13:33:43     INFO -     reraise(IOError, msg.format(timeout), tb)
[task 2018-06-30T13:33:43.805Z] 13:33:43     INFO -   File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/marionette.py", line 651, in start_binary
[task 2018-06-30T13:33:43.806Z] 13:33:43     INFO -     self.raise_for_port(timeout=timeout)
[task 2018-06-30T13:33:43.807Z] 13:33:43     INFO -   File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/marionette.py", line 731, in raise_for_port
[task 2018-06-30T13:33:43.808Z] 13:33:43     INFO -     self.host, self.port))
[task 2018-06-30T13:33:43.809Z] 13:33:43     INFO - TEST-INFO took 161824ms
[task 2018-06-30T13:33:43.810Z] 13:33:43    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": "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/tests/firefox-ui/tests/testing/firefox-ui/tests/functional/sessionstore/session_store_test_case.py\", line 62, in tearDown\n    super(SessionStoreTestCase, 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 1431, 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 750, in _send_message\n    raise errors.MarionetteException(\"Please start a session\")\n"}
Hang during startup. Might depend on bug 1414495.
Depends on: 1414495
https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
Status: NEW → RESOLVED
Closed: 2 years ago
Resolution: --- → INCOMPLETE
https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
Status: REOPENED → RESOLVED
Closed: 2 years ago2 years ago
Resolution: --- → INCOMPLETE
Status: REOPENED → RESOLVED
Closed: 2 years ago1 year ago
Resolution: --- → INCOMPLETE

Recent log failure: https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=239012637&repo=autoland&lineNumber=34090

09:31:57 INFO - Application command: Z:\task_1554801270\build\application\firefox\firefox.exe -no-remote -marionette --wait-for-browser -profile Z:\task_1554801270\build\tmpw7pyrf.mozrunner
09:31:57 INFO - 1554802317830 Marionette TRACE Received observer notification profile-after-change
09:31:57 INFO - 1554802317848 Marionette TRACE Received observer notification command-line-startup
09:31:57 INFO - 1554802317848 Marionette TRACE Received observer notification nsPref:changed
09:31:57 INFO - 1554802317848 Marionette DEBUG Init aborted (running=false, enabled=true, finalUIStartup=false)
09:33:57 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
09:33:57 INFO - Traceback (most recent call last):
09:33:57 INFO - File "Z:\task_1554801270\build\venv\lib\site-packages\marionette_harness\marionette_test\testcases.py", line 159, in run
09:33:57 INFO - testMethod()
09:33:57 INFO - File "Z:\task_1554801270\build\tests\firefox-ui\tests\testing\firefox-ui\tests\functional\sessionstore\test_restore_windows_after_restart_and_quit.py", line 62, in test_no_restore_with_quit
09:33:57 INFO - self.marionette.start_session()
09:33:57 INFO - File "Z:\task_1554801270\build\venv\lib\site-packages\marionette_driver\decorators.py", line 26, in _
09:33:57 INFO - return func(*args, **kwargs)
09:33:57 INFO - File "Z:\task_1554801270\build\venv\lib\site-packages\marionette_driver\marionette.py", line 1103, in start_session
09:33:57 INFO - self.start_binary(timeout)
09:33:57 INFO - File "Z:\task_1554801270\build\venv\lib\site-packages\marionette_driver\marionette.py", line 491, in start_binary
09:33:57 INFO - reraise(IOError, msg.format(timeout), tb)
09:33:57 INFO - File "Z:\task_1554801270\build\venv\lib\site-packages\marionette_driver\marionette.py", line 482, in start_binary
09:33:57 INFO - self.raise_for_port(timeout=timeout)
09:33:57 INFO - File "Z:\task_1554801270\build\venv\lib\site-packages\marionette_driver\marionette.py", line 569, in raise_for_port
09:33:57 INFO - self.host, self.port))
09:33:57 INFO - TEST-INFO took 128974ms
09:33:57 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 "Z:\task_1554801270\build\venv\lib\site-packages\marionette_harness\marionette_test\testcases.py", line 190, in run\n self.tearDown()\n File "Z:\task_1554801270\build\tests\firefox-ui\tests\testing\firefox-ui\tests\functional\sessionstore\session_store_test_case.py", line 65, in tearDown\n super(SessionStoreTestCase, self).tearDown()\n File "Z:\task_1554801270\build\venv\lib\site-packages\firefox_puppeteer\mixins.py", line 97, in tearDown\n self.marionette.set_context('chrome')\n File "Z:\task_1554801270\build\venv\lib\site-packages\marionette_driver\marionette.py", line 1309, in set_context\n {"value": context})\n File "Z:\task_1554801270\build\venv\lib\site-packages\marionette_driver\decorators.py", line 26, in _\n return func(*args, **kwargs)\n File "Z:\task_1554801270\build\venv\lib\site-packages\marionette_driver\marionette.py", line 587, in _send_message\n raise errors.InvalidSessionIdException("Please start a session")\n"}

Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Status: REOPENED → RESOLVED
Closed: 1 year ago1 year ago
Resolution: --- → INCOMPLETE

Recent failure log:
https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=281895261&repo=autoland&lineNumber=34644

[task 2019-12-19T12:47:16.226Z] 12:47:16 INFO - [Parent 1084, Main Thread] WARNING: Need BrowserChild to get the nativeWindow from!: file /builds/worker/workspace/build/src/widget/PuppetWidget.cpp, line 1095
[task 2019-12-19T12:47:16.266Z] 12:47:16 INFO - [Child 4528, Main Thread] WARNING: No CID found when attempting to map contract ID: file /builds/worker/workspace/build/src/xpcom/components/nsComponentManager.cpp, line 721
[task 2019-12-19T12:47:16.411Z] 12:47:16 INFO - [Child 4528, Main Thread] WARNING: 'NS_FAILED(rv)', file /builds/worker/workspace/build/src/widget/windows/nsLookAndFeel.cpp, line 853
[task 2019-12-19T12:47:16.412Z] 12:47:16 INFO - [Child 4528, Main Thread] WARNING: NS_ENSURE_TRUE(mPresShell) failed: file /builds/worker/workspace/build/src/layout/base/nsPresContext.cpp, line 848
[task 2019-12-19T12:47:16.431Z] 12:47:16 INFO - [Child 4528, Main Thread] WARNING: 'NS_FAILED(GetAccentColor(unused))', file /builds/worker/workspace/build/src/widget/windows/nsLookAndFeel.cpp, line 481
[task 2019-12-19T12:47:16.431Z] 12:47:16 INFO - [Child 4528, Main Thread] WARNING: Fallback to BasicLayerManager: file /builds/worker/workspace/build/src/dom/ipc/BrowserChild.cpp, line 2738
[task 2019-12-19T12:47:16.446Z] 12:47:16 INFO - [Child 4528, Main Thread] WARNING: Fallback to BasicLayerManager: file /builds/worker/workspace/build/src/dom/ipc/BrowserChild.cpp, line 2738
[task 2019-12-19T12:47:16.461Z] 12:47:16 INFO - [Child 4528, Main Thread] WARNING: Fallback to BasicLayerManager: file /builds/worker/workspace/build/src/dom/ipc/BrowserChild.cpp, line 2738
[task 2019-12-19T12:47:16.468Z] 12:47:16 INFO - [Child 4528, Main Thread] WARNING: Fallback to BasicLayerManager: file /builds/worker/workspace/build/src/dom/ipc/BrowserChild.cpp, line 2738
[task 2019-12-19T12:47:16.483Z] 12:47:16 INFO - [Child 4528, Main Thread] WARNING: Fallback to BasicLayerManager: file /builds/worker/workspace/build/src/dom/ipc/BrowserChild.cpp, line 2738
[task 2019-12-19T12:47:16.590Z] 12:47:16 INFO - [Child 4528, Main Thread] WARNING: Trying to request nsIHttpChannel from DocumentChannelChild, this is likely broken: file /builds/worker/workspace/build/src/netwerk/ipc/DocumentChannelChild.cpp, line 63
[task 2019-12-19T12:47:16.590Z] 12:47:16 INFO - [Child 4528, Main Thread] WARNING: Trying to request nsIHttpChannel from DocumentChannelChild, this is likely broken: file /builds/worker/workspace/build/src/netwerk/ipc/DocumentChannelChild.cpp, line 63
[task 2019-12-19T12:47:16.625Z] 12:47:16 INFO - [Child 4528, Main Thread] WARNING: Trying to request nsIHttpChannel from DocumentChannelChild, this is likely broken: file /builds/worker/workspace/build/src/netwerk/ipc/DocumentChannelChild.cpp, line 63
[task 2019-12-19T12:47:16.625Z] 12:47:16 INFO - [Child 4528, Main Thread] WARNING: Trying to request nsIHttpChannel from DocumentChannelChild, this is likely broken: file /builds/worker/workspace/build/src/netwerk/ipc/DocumentChannelChild.cpp, line 63
[task 2019-12-19T12:47:16.629Z] 12:47:16 INFO - [Child 4528, Main Thread] WARNING: Trying to request nsIHttpChannel from DocumentChannelChild, this is likely broken: file /builds/worker/workspace/build/src/netwerk/ipc/DocumentChannelChild.cpp, line 63
[task 2019-12-19T12:47:16.688Z] 12:47:16 INFO - [Child 4528, Main Thread] WARNING: '!aWin', file /builds/worker/workspace/build/src/dom/base/Document.cpp, line 1389
[task 2019-12-19T12:47:16.688Z] 12:47:16 INFO - [Child 4528, Main Thread] WARNING: '!aWin', file /builds/worker/workspace/build/src/dom/base/Document.cpp, line 1389
[task 2019-12-19T12:49:14.576Z] 12:49:14 INFO - Exiting due to channel error.
[task 2019-12-19T12:49:14.576Z] 12:49:14 INFO - Exiting due to channel error.
[task 2019-12-19T12:49:14.817Z] 12:49:14 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 2019-12-19T12:49:14.817Z] 12:49:14 INFO - Traceback (most recent call last):
[task 2019-12-19T12:49:14.817Z] 12:49:14 INFO - File "Z:\task_1576758879\build\venv\lib\site-packages\marionette_harness\marionette_test\testcases.py", line 159, in run
[task 2019-12-19T12:49:14.817Z] 12:49:14 INFO - testMethod()
[task 2019-12-19T12:49:14.817Z] 12:49:14 INFO - File "Z:\task_1576758879\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 2019-12-19T12:49:14.817Z] 12:49:14 INFO - self.marionette.start_session()
[task 2019-12-19T12:49:14.817Z] 12:49:14 INFO - File "Z:\task_1576758879\build\venv\lib\site-packages\marionette_driver\decorators.py", line 26, in _
[task 2019-12-19T12:49:14.817Z] 12:49:14 INFO - return func(*args, **kwargs)
[task 2019-12-19T12:49:14.817Z] 12:49:14 INFO - File "Z:\task_1576758879\build\venv\lib\site-packages\marionette_driver\marionette.py", line 1103, in start_session
[task 2019-12-19T12:49:14.818Z] 12:49:14 INFO - self.start_binary(timeout)
[task 2019-12-19T12:49:14.818Z] 12:49:14 INFO - File "Z:\task_1576758879\build\venv\lib\site-packages\marionette_driver\marionette.py", line 491, in start_binary
[task 2019-12-19T12:49:14.818Z] 12:49:14 INFO - reraise(IOError, msg.format(timeout), tb)
[task 2019-12-19T12:49:14.818Z] 12:49:14 INFO - File "Z:\task_1576758879\build\venv\lib\site-packages\marionette_driver\marionette.py", line 482, in start_binary
[task 2019-12-19T12:49:14.818Z] 12:49:14 INFO - self.raise_for_port(timeout=timeout)
[task 2019-12-19T12:49:14.818Z] 12:49:14 INFO - File "Z:\task_1576758879\build\venv\lib\site-packages\marionette_driver\marionette.py", line 569, in raise_for_port
[task 2019-12-19T12:49:14.818Z] 12:49:14 INFO - self.host, self.port))
[task 2019-12-19T12:49:14.818Z] 12:49:14 INFO - TEST-INFO took 142763ms
[task 2019-12-19T12:49:14.821Z] 12:49:14 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 "Z:\task_1576758879\build\venv\lib\site-packages\marionette_harness\marionette_test\testcases.py", line 190, in run\n self.tearDown()\n File "Z:\task_1576758879\build\tests\firefox-ui\tests\testing\firefox-ui\tests\functional\sessionstore\session_store_test_case.py", line 66, in tearDown\n super(SessionStoreTestCase, self).tearDown()\n File "Z:\task_1576758879\build\venv\lib\site-packages\firefox_puppeteer\mixins.py", line 97, in tearDown\n self.marionette.set_context('chrome')\n File "Z:\task_1576758879\build\venv\lib\site-packages\marionette_driver\marionette.py", line 1309, in set_context\n {"value": context})\n File "Z:\task_1576758879\build\venv\lib\site-packages\marionette_driver\decorators.py", line 26, in _\n return func(*args, **kwargs)\n File "Z:\task_1576758879\build\venv\lib\site-packages\marionette_driver\marionette.py", line 587, in _send_message\n raise errors.InvalidSessionIdException("Please start a session")\n"}
[task 2019-12-19T12:49:14.821Z] 12:49:14 INFO - TEST-START | testing\firefox-ui\tests\functional\sessionstore\test_restore_windows_after_restart_and_quit.py TestSessionStoreDisabled.test_restore_with_restart

Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Status: REOPENED → RESOLVED
Closed: 1 year ago4 months ago
Resolution: --- → INCOMPLETE
Status: REOPENED → RESOLVED
Closed: 4 months ago2 months ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.