Closed Bug 1486343 Opened 6 years ago Closed 5 years ago

Intermittent testing\firefox-ui\tests\functional\sessionstore\test_restore_windows_after_windows_shutdown.py TestWindowsShutdownForcedSessionRestore.test_manual_restart | IOError: Process killed because a requested application quit did not happen

Categories

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

Version 3
defect

Tracking

(Not tracked)

RESOLVED FIXED

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=196017156&repo=mozilla-central

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

23:38:08     INFO - TEST-UNEXPECTED-ERROR | testing\firefox-ui\tests\functional\sessionstore\test_restore_windows_after_windows_shutdown.py TestWindowsShutdownForcedSessionRestore.test_manual_restart | IOError: Process killed because a requested application quit did not happen within 120s. Check gecko.log for errors.
23:38:08     INFO - Traceback (most recent call last):
23:38:08     INFO -   File "Z:\task_1535325047\build\venv\lib\site-packages\marionette_harness\marionette_test\testcases.py", line 159, in run
23:38:08     INFO -     testMethod()
23:38:08     INFO -   File "Z:\task_1535325047\build\tests\firefox-ui\tests\testing\firefox-ui\tests\functional\sessionstore\test_restore_windows_after_windows_shutdown.py", line 69, in test_manual_restart
23:38:08     INFO -     self.windows_shutdown_with_variety(restart_by_os=False, expect_restore=False)
23:38:08     INFO -   File "Z:\task_1535325047\build\tests\firefox-ui\tests\testing\firefox-ui\tests\functional\sessionstore\session_store_test_case.py", line 310, in windows_shutdown_with_variety
23:38:08     INFO -     self.marionette.quit(in_app=True, callback=lambda: self.simulate_os_shutdown())
23:38:08     INFO -   File "Z:\task_1535325047\build\venv\lib\site-packages\marionette_driver\decorators.py", line 26, in _
23:38:08     INFO -     return func(*args, **kwargs)
23:38:08     INFO -   File "Z:\task_1535325047\build\venv\lib\site-packages\marionette_driver\marionette.py", line 1113, in quit
23:38:08     INFO -     raise IOError(message.format(self.DEFAULT_SHUTDOWN_TIMEOUT))
23:38:08     INFO - TEST-INFO took 187925ms
23:38:08    ERROR - test_end for testing\firefox-ui\tests\functional\sessionstore\test_restore_windows_after_windows_shutdown.py TestWindowsShutdownForcedSessionRestore.test_manual_restart logged while not in progress. Logged with data: {"status": "ERROR", "extra": {"class_name": "test_restore_windows_after_windows_shutdown.TestWindowsShutdownForcedSessionRestore", "method_name": "test_manual_restart"}, "expected": "PASS", "test": "testing\\firefox-ui\\tests\\functional\\sessionstore\\test_restore_windows_after_windows_shutdown.py TestWindowsShutdownForcedSessionRestore.test_manual_restart", "message": "InvalidSessionIdException: Please start a session", "stack": "Traceback (most recent call last):\n  File \"Z:\\task_1535325047\\build\\venv\\lib\\site-packages\\marionette_harness\\marionette_test\\testcases.py\", line 190, in run\n    self.tearDown()\n  File \"Z:\\task_1535325047\\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_1535325047\\build\\venv\\lib\\site-packages\\firefox_puppeteer\\mixins.py\", line 97, in tearDown\n    self.marionette.set_context('chrome')\n  File \"Z:\\task_1535325047\\build\\venv\\lib\\site-packages\\marionette_driver\\marionette.py\", line 1428, in set_context\n    {\"value\": context})\n  File \"Z:\\task_1535325047\\build\\venv\\lib\\site-packages\\marionette_driver\\decorators.py\", line 26, in _\n    return func(*args, **kwargs)\n  File \"Z:\\task_1535325047\\build\\venv\\lib\\site-packages\\marionette_driver\\marionette.py\", line 744, in _send_message\n    raise errors.InvalidSessionIdException(\"Please start a session\")\n"}
23:38:08     INFO - TEST-START | testing\firefox-ui\tests\functional\sessionstore\test_restore_windows_after_windows_shutdown.py TestWindowsShutdownForcedSessionRestore.test_os_restart
23:38:08     INFO - Application command: Z:\task_1535325047\build\application\firefox\firefox.exe -no-remote -marionette -profile Z:\task_1535325047\build\tmpy6bagr.mozrunner
23:38:08     INFO -  DLL blocklist was unable to intercept AppInit DLLs.
23:38:09     INFO -  [1548, Main Thread] WARNING: Failed to load startupcache file correctly, removing!: file z:/build/build/src/startupcache/StartupCache.cpp, line 217
23:38:13     INFO -  [1548, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80040111: file z:/build/build/src/netwerk/protocol/res/SubstitutingProtocolHandler.cpp, line 342
23:38:13     INFO -  1535326693628	Marionette	DEBUG	Received observer notification profile-after-change
23:38:13     INFO -  [1548, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80520012: file z:/build/build/src/extensions/cookie/nsPermissionManager.cpp, line 2930
23:38:13     INFO -  ++DOCSHELL 000001FE6CF6B800 == 1 [pid = 1548] [id = {b2980d6c-ab55-471e-a14e-4061327a0f2d}]
23:38:13     INFO -  ++DOMWINDOW == 1 (000001FE62127800) [pid = 1548] [serial = 1] [outer = 0000000000000000]
23:38:13     INFO -  ++DOMWINDOW == 2 (000001FE6DC07800) [pid = 1548] [serial = 2] [outer = 000001FE62127800]
23:38:13     INFO -  1535326693736	Marionette	DEBUG	Received observer notification toplevel-window-ready
23:38:13     INFO -  1535326693929	Marionette	DEBUG	Received observer notification command-line-startup
23:38:13     INFO -  1535326693930	Marionette	DEBUG	Received observer notification nsPref:changed
23:38:13     INFO -  1535326693932	Marionette	DEBUG	Init aborted (running=false, enabled=true, finalUIStartup=false)
23:38:13     INFO -  ++DOCSHELL 000001FE6B863800 == 2 [pid = 1548] [id = {c255b562-ca87-45b1-9c63-bfa09d6820a6}]
23:38:13     INFO -  ++DOMWINDOW == 3 (000001FE62127600) [pid = 1548] [serial = 3] [outer = 0000000000000000]
23:38:13     INFO -  ++DOMWINDOW == 4 (000001FE6B887400) [pid = 1548] [serial = 4] [outer = 000001FE62127600]
23:38:14     INFO -  ++DOCSHELL 000001FE6B862000 == 3 [pid = 1548] [id = {045b7011-d749-46dc-a055-7c28f7ce5c3e}]
23:38:14     INFO -  ++DOMWINDOW == 5 (000001FE6B8E9400) [pid = 1548] [serial = 5] [outer = 0000000000000000]
23:38:14     INFO -  ++DOMWINDOW == 6 (000001FE6B88A000) [pid = 1548] [serial = 6] [outer = 000001FE6B8E9400]
23:38:14     INFO -  1535326694391	Marionette	DEBUG	Received observer notification toplevel-window-ready
23:38:14     INFO -  [1548, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80040111: file z:/build/build/src/netwerk/protocol/res/SubstitutingProtocolHandler.cpp, line 342
Caused by an assertion as raised during GC/CC cycles. This is already filed as bug 1485985.
Depends on: 1485985
https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
Status: NEW → RESOLVED
Closed: 6 years ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → VERIFIED

Most likely fixed by the upgrade of Windows workers to 1803 by bug 1522896.

Status: VERIFIED → RESOLVED
Closed: 6 years ago5 years ago
Depends on: 1522896
Resolution: INCOMPLETE → FIXED
You need to log in before you can comment on or make changes to this bug.