Closed Bug 1571965 Opened 6 years ago Closed 5 years ago

Intermittent TEST-UNEXPECTED-TIMEOUT | browser/components/sessionstore/test/browser_crashedTabs.js | application timed out after 370 seconds with no output

Categories

(Firefox :: Session Restore, defect, P5)

Product:
Firefox
Component:
Session Restore
Type:
defect

Tracking

()

Status:
RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure, regression)

Filed by: aciure [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=260242630&repo=mozilla-central
Full log: https://queue.taskcluster.net/v1/task/ewh_be-7RgSDE_lbPz6iBw/runs/0/artifacts/public/logs/live_backing.log

01:24:55 INFO - TEST-PASS | browser/components/sessionstore/test/browser_crashedTabs.js | Should be a remote browser -
01:24:55 INFO - Console message: [JavaScript Error: "The character encoding of the HTML document was not declared. The document will render with garbled text in some browser configurations if the document contains characters from outside the US-ASCII range. The character encoding of the page must be declared in the document or in the transfer protocol." {file: "data:text/html,<html><body>A%20regular,%20everyday,%20normal%20page." line: 0}]
01:24:55 INFO - Buffered messages finished
01:24:55 ERROR - TEST-UNEXPECTED-TIMEOUT | browser/components/sessionstore/test/browser_crashedTabs.js | application timed out after 370 seconds with no output
01:24:55 ERROR - Force-terminating active process(es).
01:24:55 INFO - Determining child pids from psutil...
01:24:55 INFO - [9624]
01:24:55 INFO - ==> process 9624 launched child process 6340 ("C:\tasks\task_1565137174\build\application\firefox\firefox.exe" -contentproc --channel="9624.0.1417204276\532177472" -parentBuildID 20190806214154 -prefsHandle 2064 -prefMapHandle 2060 -prefsLen 1 -prefMapSize 213781 -greomni "C:\tasks\task_1565137174\build\application\firefox\omni.ja" -appomni "C:\tasks\task_1565137174\build\application\firefox\browser\omni.ja" -appdir "C:\tasks\task_1565137174\build\application\firefox\browser" - 9624 "\.\pipe\gecko-crash-server-pipe.9624" 2152 gpu)
01:24:55 INFO - ==> process 9624 launched child process 1828 ("C:\tasks\task_1565137174\build\application\firefox\firefox.exe" -contentproc --channel="9624.6.2141538728\422146659" -childID 1 -isForBrowser -prefsHandle 3096 -prefMapHandle 2032 -prefsLen 2090 -prefMapSize 213781 -parentBuildID 20190806214154 -greomni "C:\tasks\task_1565137174\build\application\firefox\omni.ja" -appomni "C:\tasks\task_1565137174\build\application\firefox\browser\omni.ja" -appdir "C:\tasks\task_1565137174\build\application\firefox\browser" - 9624 "\.\pipe\gecko-crash-server-pipe.9624" 3144 tab)
01:24:55 INFO - ==> process 9624 launched child process 9356 ("C:\tasks\task_1565137174\build\application\firefox\firefox.exe" -contentproc --channel="9624.13.1623753150\2118538461" -childID 2 -isForBrowser -prefsHandle 3308 -prefMapHandle 3304 -prefsLen 2187 -prefMapSize 213781 -parentBuildID 20190806214154 -greomni "C:\tasks\task_1565137174\build\application\firefox\omni.ja" -appomni "C:\tasks\task_1565137174\build\application\firefox\browser\omni.ja" -appdir "C:\tasks\task_1565137174\build\application\firefox\browser" - 9624 "\.\pipe\gecko-crash-server-pipe.9624" 3316 tab)
01:24:55 INFO - ==> process 9624 launched child process 6976 ("C:\tasks\task_1565137174\build\application\firefox\firefox.exe" -contentproc --channel="9624.20.1064930637\830816190" -childID 3 -isForBrowser -prefsHandle 3532 -prefMapHandle 3540 -prefsLen 2248 -prefMapSize 213781 -parentBuildID 20190806214154 -greomni "C:\tasks\task_1565137174\build\application\firefox\omni.ja" -appomni "C:\tasks\task_1565137174\build\application\firefox\browser\omni.ja" -appdir "C:\tasks\task_1565137174\build\application\firefox\browser" - 9624 "\.\pipe\gecko-crash-server-pipe.9624" 3556 tab)
01:24:55 INFO - ==> process 9624 launched child process 2572 ("C:\tasks\task_1565137174\build\application\firefox\firefox.exe" -contentproc --channel="9624.27.1093161896\1688788941" -childID 4 -isForBrowser -prefsHandle 4596 -prefMapHandle 4600 -prefsLen 10624 -prefMapSize 213781 -parentBuildID 20190806214154 -greomni "C:\tasks\task_1565137174\build\application\firefox\omni.ja" -appomni "C:\tasks\task_1565137174\build\application\firefox\browser\omni.ja" -appdir "C:\tasks\task_1565137174\build\application\firefox\browser" - 9624 "\.\pipe\gecko-crash-server-pipe.9624" 4412 tab)
01:24:55 INFO - ==> process 9624 launched child process 9312 ("C:\tasks\task_1565137174\build\application\firefox\firefox.exe" -contentproc --channel="9624.34.982367475\1677034055" -childID 5 -isForBrowser -prefsHandle 4860 -prefMapHandle 4896 -prefsLen 10962 -prefMapSize 213781 -parentBuildID 20190806214154 -greomni "C:\tasks\task_1565137174\build\application\firefox\omni.ja" -appomni "C:\tasks\task_1565137174\build\application\firefox\browser\omni.ja" -appdir "C:\tasks\task_1565137174\build\application\firefox\browser" - 9624 "\.\pipe\gecko-crash-server-pipe.9624" 4936 tab)
01:24:55 INFO - ==> process 9624 launched child process 1148 ("C:\tasks\task_1565137174\build\application\firefox\firefox.exe" -contentproc --channel="9624.41.1320836070\884537492" -childID 6 -isForBrowser -prefsHandle 5232 -prefMapHandle 5236 -prefsLen 10959 -prefMapSize 213781 -parentBuildID 20190806214154 -greomni "C:\tasks\task_1565137174\build\application\firefox\omni.ja" -appomni "C:\tasks\task_1565137174\build\application\firefox\browser\omni.ja" -appdir "C:\tasks\task_1565137174\build\application\firefox\browser" - 9624 "\.\pipe\gecko-crash-server-pipe.9624" 5244 tab)
01:24:55 INFO - ==> process 9624 launched child process 8372 ("C:\tasks\task_1565137174\build\application\firefox\firefox.exe" -contentproc --channel="9624.48.766423114\1797600384" -childID 7 -isForBrowser -prefsHandle 5468 -prefMapHandle 5472 -prefsLen 11017 -prefMapSize 213781 -parentBuildID 20190806214154 -greomni "C:\tasks\task_1565137174\build\application\firefox\omni.ja" -appomni "C:\tasks\task_1565137174\build\application\firefox\browser\omni.ja" -appdir "C:\tasks\task_1565137174\build\application\firefox\browser" - 9624 "\.\pipe\gecko-crash-server-pipe.9624" 5480 tab)
01:24:55 INFO - ==> process 9624 launched child process 9616 ("C:\tasks\task_1565137174\build\application\firefox\firefox.exe" -contentproc --channel="9624.55.1507395039\398212433" -childID 8 -isForBrowser -prefsHandle 5244 -prefMapHandle 5656 -prefsLen 11017 -prefMapSize 213781 -parentBuildID 20190806214154 -greomni "C:\tasks\task_1565137174\build\application\firefox\omni.ja" -appomni "C:\tasks\task_1565137174\build\application\firefox\browser\omni.ja" -appdir "C:\tasks\task_1565137174\build\application\firefox\browser" - 9624 "\.\pipe\gecko-crash-server-pipe.9624" 5680 tab)
01:24:55 INFO - ==> process 9624 launched child process 2680 ("C:\tasks\task_1565137174\build\application\firefox\firefox.exe" -contentproc --channel="9624.62.1360110306\367097053" -childID 9 -isForBrowser -prefsHandle 5884 -prefMapHandle 5916 -prefsLen 11017 -prefMapSize 213781 -parentBuildID 20190806214154 -greomni "C:\tasks\task_1565137174\build\application\firefox\omni.ja" -appomni "C:\tasks\task_1565137174\build\application\firefox\browser\omni.ja" -appdir "C:\tasks\task_1565137174\build\application\firefox\browser" - 9624 "\.\pipe\gecko-crash-server-pipe.9624" 5924 tab)
01:24:55 INFO - ==> process 9624 launched child process 2364 ("C:\tasks\task_1565137174\build\application\firefox\firefox.exe" -contentproc --channel="9624.69.1089867464\415074833" -childID 10 -isForBrowser -prefsHandle 6096 -prefMapHandle 6100 -prefsLen 11017 -prefMapSize 213781 -parentBuildID 20190806214154 -greomni "C:\tasks\task_1565137174\build\application\firefox\omni.ja" -appomni "C:\tasks\task_1565137174\build\application\firefox\browser\omni.ja" -appdir "C:\tasks\task_1565137174\build\application\firefox\browser" - 9624 "\.\pipe\gecko-crash-server-pipe.9624" 5872 tab)
01:24:55 INFO - ==> process 9624 launched child process 9556 ("C:\tasks\task_1565137174\build\application\firefox\firefox.exe" -contentproc --channel="9624.76.219457618\1705577770" -childID 11 -isForBrowser -prefsHandle 3780 -prefMapHandle 3768 -prefsLen 12194 -prefMapSize 213781 -parentBuildID 20190806214154 -greomni "C:\tasks\task_1565137174\build\application\firefox\omni.ja" -appomni "C:\tasks\task_1565137174\build\application\firefox\browser\omni.ja" -appdir "C:\tasks\task_1565137174\build\application\firefox\browser" - 9624 "\.\pipe\gecko-crash-server-pipe.9624" 6296 tab)
01:24:55 INFO - ==> process 9624 launched child process 7924 ("C:\tasks\task_1565137174\build\application\firefox\firefox.exe" -contentproc --channel="9624.83.58325214\413681493" -childID 12 -isForBrowser -prefsHandle 4816 -prefMapHandle 4808 -prefsLen 12383 -prefMapSize 213781 -parentBuildID 20190806214154 -greomni "C:\tasks\task_1565137174\build\application\firefox\omni.ja" -appomni "C:\tasks\task_1565137174\build\application\firefox\browser\omni.ja" -appdir "C:\tasks\task_1565137174\build\application\firefox\browser" - 9624 "\.\pipe\gecko-crash-server-pipe.9624" 4812 tab)
01:24:55 INFO - ==> process 9624 launched child process 3308 ("C:\tasks\task_1565137174\build\application\firefox\firefox.exe" -contentproc --channel="9624.90.370527402\1272035626" -childID 13 -isForBrowser -prefsHandle 4932 -prefMapHandle 3636 -prefsLen 12383 -prefMapSize 213781 -parentBuildID 20190806214154 -greomni "C:\tasks\task_1565137174\build\application\firefox\omni.ja" -appomni "C:\tasks\task_1565137174\build\application\firefox\browser\omni.ja" -appdir "C:\tasks\task_1565137174\build\application\firefox\browser" - 9624 "\.\pipe\gecko-crash-server-pipe.9624" 5296 tab)
01:24:55 INFO - ==> process 9624 launched child process 9956 ("C:\tasks\task_1565137174\build\application\firefox\firefox.exe" -contentproc --channel="9624.97.1700280221\349526826" -childID 14 -isForBrowser -prefsHandle 5488 -prefMapHandle 5492 -prefsLen 12393 -prefMapSize 213781 -parentBuildID 20190806214154 -greomni "C:\tasks\task_1565137174\build\application\firefox\omni.ja" -appomni "C:\tasks\task_1565137174\build\application\firefox\browser\omni.ja" -appdir "C:\tasks\task_1565137174\build\application\firefox\browser" - 9624 "\.\pipe\gecko-crash-server-pipe.9624" 4652 tab)
01:24:55 INFO - ==> process 9624 launched child process 8120 ("C:\tasks\task_1565137174\build\application\firefox\firefox.exe" -contentproc --channel="9624.104.221688451\1792278615" -childID 15 -isForBrowser -prefsHandle 5324 -prefMapHandle 5340 -prefsLen 12393 -prefMapSize 213781 -parentBuildID 20190806214154 -greomni "C:\tasks\task_1565137174\build\application\firefox\omni.ja" -appomni "C:\tasks\task_1565137174\build\application\firefox\browser\omni.ja" -appdir "C:\tasks\task_1565137174\build\application\firefox\browser" - 9624 "\.\pipe\gecko-crash-server-pipe.9624" 4676 tab)
01:24:55 INFO - ==> process 9624 launched child process 7380 ("C:\tasks\task_1565137174\build\application\firefox\firefox.exe" -contentproc --channel="9624.111.926573969\1440240370" -childID 16 -isForBrowser -prefsHandle 5380 -prefMapHandle 6392 -prefsLen 12393 -prefMapSize 213781 -parentBuildID 20190806214154 -greomni "C:\tasks\task_1565137174\build\application\firefox\omni.ja" -appomni "C:\tasks\task_1565137174\build\application\firefox\browser\omni.ja" -appdir "C:\tasks\task_1565137174\build\application\firefox\browser" - 9624 "\.\pipe\gecko-crash-server-pipe.9624" 5412 tab)
01:24:55 INFO - Found child pids: set([6976, 9312, 7380, 1828, 8120, 9556, 2572, 9356, 3308, 9616, 2680, 9956, 8372, 7924, 2364, 9624, 6340, 1148])
01:24:55 INFO - Failed to get child procs
01:24:55 INFO - Killing process: 6976
01:24:55 INFO - TEST-INFO | started process screenshot
01:24:56 INFO - TEST-INFO | screenshot: exit 0
01:24:56 INFO - mozcrash Using C:\tasks\task_1565137174\build\tests\bin\minidumpwriter.exe to write a dump to c:\users\testdroid.yoga-031\appdata\local\temp\tmplwpziv.mozrunner\minidumps\13e0d671-b6f9-495f-8908-2da99f8c5514.dmp for [6976]
01:24:56 INFO - Error 0x8007012B in MiniDumpWriteDump
01:24:56 ERROR - mozcrash minidumpwriter exited with status: 1
01:24:56 WARNING - mozcrash kill_pid(): unable to terminate pid 6976: 5
01:24:56 INFO - Killing process: 9312
01:24:56 INFO - Not taking screenshot here: see the one that was previously logged
01:24:56 INFO - mozcrash Using C:\tasks\task_1565137174\build\tests\bin\minidumpwriter.exe to write a dump to c:\users\testdroid.yoga-031\appdata\local\temp\tmplwpziv.mozrunner\minidumps\2ec6bc63-ec2b-4e28-ba40-70efc6cbfedc.dmp for [9312]
01:24:56 INFO - Error 0x8007012B in MiniDumpWriteDump
01:24:56 ERROR - mozcrash minidumpwriter exited with status: 1
01:24:56 WARNING - mozcrash kill_pid(): unable to terminate pid 9312: 5
01:24:56 INFO - Killing process: 7380

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup

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

Recent failure log: https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=267203685&repo=mozilla-central&lineNumber=3214

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: 6 years ago → 6 years ago
Resolution: --- → INCOMPLETE

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

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: 6 years ago → 5 years ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.