Closed Bug 1610266 Opened 5 years ago Closed 5 years ago

Intermittent firefox-ui\tests\functional\sessionstore\test_restore_windows_after_windows_shutdown.py TestWindowsShutdownNormal.test_with_variety | IOError: Process killed after 120s because no connection to Marionette server could be established. Check ge

Categories

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

Product:
Testing
Component:
Firefox UI Tests
Version:
Version 3
Type:
defect

Tracking

(Not tracked)

Status:
RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

References

Details

(Keywords: intermittent-failure)

Filed by: btara [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=285604311&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/GlCUubldRPmFr5CKEuo4lA/runs/0/artifacts/public/logs/live_backing.log

...
[task 2020-01-20T10:25:59.158Z] 10:25:59 INFO - [Parent 3264, Main Thread] WARNING: This method is lossy. Use GetCanonicalPath !: file /builds/worker/workspace/build/src/xpcom/io/nsLocalFileWin.cpp, line 3176
[task 2020-01-20T10:25:59.193Z] 10:25:59 INFO - [Parent 3264, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005: file /builds/worker/workspace/build/src/netwerk/cache/nsCacheService.cpp, line 154
[task 2020-01-20T10:25:59.208Z] 10:25:59 INFO - [Parent 3264, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005: file /builds/worker/workspace/build/src/dom/base/ThirdPartyUtil.cpp, line 401
[task 2020-01-20T10:25:59.223Z] 10:25:59 INFO - [Child 504, Main Thread] WARNING: Trying to request nsIHttpChannel from DocumentChannel, this is likely broken: file /builds/worker/workspace/build/src/netwerk/ipc/DocumentChannel.cpp, line 63
[task 2020-01-20T10:25:59.506Z] 10:25:59 INFO - [Child 504, Main Thread] WARNING: '!aWin', file /builds/worker/workspace/build/src/dom/base/Document.cpp, line 1399
[task 2020-01-20T10:25:59.506Z] 10:25:59 INFO - [Child 504, Main Thread] WARNING: '!aWin', file /builds/worker/workspace/build/src/dom/base/Document.cpp, line 1399
[task 2020-01-20T10:25:59.821Z] 10:25:59 INFO - [Parent 3264, Main Thread] WARNING: Failed to get base domain!: file /builds/worker/workspace/build/src/ipc/glue/BackgroundUtils.cpp, line 351
[task 2020-01-20T10:25:59.856Z] 10:25:59 INFO - [Parent 3264, Main Thread] WARNING: Need BrowserChild to get the nativeWindow from!: file /builds/worker/workspace/build/src/widget/PuppetWidget.cpp, line 1095
[task 2020-01-20T10:25:59.863Z] 10:25:59 INFO - [Parent 3264, Main Thread] WARNING: Need BrowserChild to get the nativeWindow from!: file /builds/worker/workspace/build/src/widget/PuppetWidget.cpp, line 1095
[task 2020-01-20T10:25:59.863Z] 10:25:59 INFO - [Parent 3264, Main Thread] WARNING: Need BrowserChild to get the nativeWindow from!: file /builds/worker/workspace/build/src/widget/PuppetWidget.cpp, line 1095
[task 2020-01-20T10:25:59.866Z] 10:25:59 INFO - [Parent 3264, Main Thread] WARNING: Need BrowserChild to get the nativeWindow from!: file /builds/worker/workspace/build/src/widget/PuppetWidget.cpp, line 1095
[task 2020-01-20T10:25:59.869Z] 10:25:59 INFO - [Parent 3264, Main Thread] WARNING: Need BrowserChild to get the nativeWindow from!: file /builds/worker/workspace/build/src/widget/PuppetWidget.cpp, line 1095
[task 2020-01-20T10:25:59.908Z] 10:25:59 INFO - [Child 4576, 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 2020-01-20T10:26:00.063Z] 10:26:00 INFO - [Child 4576, Main Thread] WARNING: 'NS_FAILED(rv)', file /builds/worker/workspace/build/src/widget/windows/nsLookAndFeel.cpp, line 853
[task 2020-01-20T10:26:00.064Z] 10:26:00 INFO - [Child 4576, Main Thread] WARNING: NS_ENSURE_TRUE(mPresShell) failed: file /builds/worker/workspace/build/src/layout/base/nsPresContext.cpp, line 845
[task 2020-01-20T10:26:00.083Z] 10:26:00 INFO - [Child 4576, Main Thread] WARNING: 'NS_FAILED(GetAccentColor(unused))', file /builds/worker/workspace/build/src/widget/windows/nsLookAndFeel.cpp, line 481
[task 2020-01-20T10:26:00.083Z] 10:26:00 INFO - [Child 4576, Main Thread] WARNING: Fallback to BasicLayerManager: file /builds/worker/workspace/build/src/dom/ipc/BrowserChild.cpp, line 2749
[task 2020-01-20T10:26:00.111Z] 10:26:00 INFO - [Child 4576, Main Thread] WARNING: Fallback to BasicLayerManager: file /builds/worker/workspace/build/src/dom/ipc/BrowserChild.cpp, line 2749
[task 2020-01-20T10:26:00.111Z] 10:26:00 INFO - [Child 4576, Main Thread] WARNING: Fallback to BasicLayerManager: file /builds/worker/workspace/build/src/dom/ipc/BrowserChild.cpp, line 2749
[task 2020-01-20T10:26:00.126Z] 10:26:00 INFO - [Child 4576, Main Thread] WARNING: Fallback to BasicLayerManager: file /builds/worker/workspace/build/src/dom/ipc/BrowserChild.cpp, line 2749
[task 2020-01-20T10:26:00.127Z] 10:26:00 INFO - [Child 4576, Main Thread] WARNING: Fallback to BasicLayerManager: file /builds/worker/workspace/build/src/dom/ipc/BrowserChild.cpp, line 2749
[task 2020-01-20T10:26:00.162Z] 10:26:00 INFO - [2020-01-20T10:26:00Z WARN rkv::backend::impl_safe::environment] Ignoring map_size=16777216
[task 2020-01-20T10:26:00.198Z] 10:26:00 INFO - [Child 4576, Main Thread] WARNING: Trying to request nsIHttpChannel from DocumentChannel, this is likely broken: file /builds/worker/workspace/build/src/netwerk/ipc/DocumentChannel.cpp, line 63
[task 2020-01-20T10:26:00.213Z] 10:26:00 INFO - [Child 4576, Main Thread] WARNING: Trying to request nsIHttpChannel from DocumentChannel, this is likely broken: file /builds/worker/workspace/build/src/netwerk/ipc/DocumentChannel.cpp, line 63
[task 2020-01-20T10:26:00.213Z] 10:26:00 INFO - [Child 4576, Main Thread] WARNING: Trying to request nsIHttpChannel from DocumentChannel, this is likely broken: file /builds/worker/workspace/build/src/netwerk/ipc/DocumentChannel.cpp, line 63
[task 2020-01-20T10:26:00.213Z] 10:26:00 INFO - [Child 4576, Main Thread] WARNING: Trying to request nsIHttpChannel from DocumentChannel, this is likely broken: file /builds/worker/workspace/build/src/netwerk/ipc/DocumentChannel.cpp, line 63
[task 2020-01-20T10:26:00.214Z] 10:26:00 INFO - [Child 4576, Main Thread] WARNING: Trying to request nsIHttpChannel from DocumentChannel, this is likely broken: file /builds/worker/workspace/build/src/netwerk/ipc/DocumentChannel.cpp, line 63
[task 2020-01-20T10:26:00.261Z] 10:26:00 INFO - [Child 4576, Main Thread] WARNING: '!aWin', file /builds/worker/workspace/build/src/dom/base/Document.cpp, line 1399
[task 2020-01-20T10:26:00.261Z] 10:26:00 INFO - [Child 4576, Main Thread] WARNING: '!aWin', file /builds/worker/workspace/build/src/dom/base/Document.cpp, line 1399
[task 2020-01-20T10:27:58.145Z] 10:27:58 INFO - Exiting due to channel error.
[task 2020-01-20T10:27:58.384Z] 10:27:58 INFO - TEST-UNEXPECTED-ERROR | testing\firefox-ui\tests\functional\sessionstore\test_restore_windows_after_windows_shutdown.py TestWindowsShutdownNormal.test_with_variety | IOError: Process killed after 120s because no connection to Marionette server could be established. Check gecko.log for errors
[task 2020-01-20T10:27:58.384Z] 10:27:58 INFO - Traceback (most recent call last):
[task 2020-01-20T10:27:58.384Z] 10:27:58 INFO - File "Z:\task_1579514720\build\venv\lib\site-packages\marionette_harness\marionette_test\testcases.py", line 159, in run
[task 2020-01-20T10:27:58.384Z] 10:27:58 INFO - testMethod()
[task 2020-01-20T10:27:58.384Z] 10:27:58 INFO - File "Z:\task_1579514720\build\tests\firefox-ui\tests\testing\firefox-ui\tests\functional\sessionstore\test_restore_windows_after_windows_shutdown.py", line 55, in test_with_variety
[task 2020-01-20T10:27:58.384Z] 10:27:58 INFO - self.windows_shutdown_with_variety(restart_by_os=False, expect_restore=False)
[task 2020-01-20T10:27:58.384Z] 10:27:58 INFO - File "Z:\task_1579514720\build\tests\firefox-ui\tests\testing\firefox-ui\tests\functional\sessionstore\session_store_test_case.py", line 318, in windows_shutdown_with_variety
[task 2020-01-20T10:27:58.384Z] 10:27:58 INFO - self.marionette.start_session()
[task 2020-01-20T10:27:58.384Z] 10:27:58 INFO - File "Z:\task_1579514720\build\venv\lib\site-packages\marionette_driver\decorators.py", line 26, in _
[task 2020-01-20T10:27:58.384Z] 10:27:58 INFO - return func(*args, **kwargs)
[task 2020-01-20T10:27:58.385Z] 10:27:58 INFO - File "Z:\task_1579514720\build\venv\lib\site-packages\marionette_driver\marionette.py", line 1103, in start_session
[task 2020-01-20T10:27:58.385Z] 10:27:58 INFO - self.start_binary(timeout)
[task 2020-01-20T10:27:58.385Z] 10:27:58 INFO - File "Z:\task_1579514720\build\venv\lib\site-packages\marionette_driver\marionette.py", line 491, in start_binary
[task 2020-01-20T10:27:58.385Z] 10:27:58 INFO - reraise(IOError, msg.format(timeout), tb)
[task 2020-01-20T10:27:58.385Z] 10:27:58 INFO - File "Z:\task_1579514720\build\venv\lib\site-packages\marionette_driver\marionette.py", line 482, in start_binary
[task 2020-01-20T10:27:58.385Z] 10:27:58 INFO - self.raise_for_port(timeout=timeout)
[task 2020-01-20T10:27:58.385Z] 10:27:58 INFO - File "Z:\task_1579514720\build\venv\lib\site-packages\marionette_driver\marionette.py", line 569, in raise_for_port
[task 2020-01-20T10:27:58.385Z] 10:27:58 INFO - self.host, self.port))
[task 2020-01-20T10:27:58.385Z] 10:27:58 INFO - TEST-INFO took 138814ms
[task 2020-01-20T10:27:58.388Z] 10:27:58 ERROR - test_end for testing\firefox-ui\tests\functional\sessionstore\test_restore_windows_after_windows_shutdown.py TestWindowsShutdownNormal.test_with_variety logged while not in progress. Logged with data: {"status": "ERROR", "extra": {"class_name": "test_restore_windows_after_windows_shutdown.TestWindowsShutdownNormal", "method_name": "test_with_variety"}, "expected": "PASS", "test": "testing\firefox-ui\tests\functional\sessionstore\test_restore_windows_after_windows_shutdown.py TestWindowsShutdownNormal.test_with_variety", "message": "InvalidSessionIdException: Please start a session", "stack": "Traceback (most recent call last):\n File "Z:\task_1579514720\build\venv\lib\site-packages\marionette_harness\marionette_test\testcases.py", line 190, in run\n self.tearDown()\n File "Z:\task_1579514720\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_1579514720\build\venv\lib\site-packages\firefox_puppeteer\mixins.py", line 97, in tearDown\n self.marionette.set_context('chrome')\n File "Z:\task_1579514720\build\venv\lib\site-packages\marionette_driver\marionette.py", line 1309, in set_context\n {"value": context})\n File "Z:\task_1579514720\build\venv\lib\site-packages\marionette_driver\decorators.py", line 26, in _\n return func(*args, **kwargs)\n File "Z:\task_1579514720\build\venv\lib\site-packages\marionette_driver\marionette.py", line 587, in _send_message\n raise errors.InvalidSessionIdException("Please start a session")\n"}

Basically bug 1382162 because Firefox hasn't been started correctly, and no browser window was open.

Depends on: 1382162

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

Status: NEW → RESOLVED
Closed: 5 years ago
Resolution: --- → INCOMPLETE

New occurrence: https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=289653563&repo=autoland&lineNumber=7545

Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---

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

Status: REOPENED → RESOLVED
Closed: 5 years ago → 5 years ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.