Closed Bug 1554440 Opened 6 years ago Closed 6 years ago

Intermittent startupcache/test/browser/browser_startupcache_telemetry.js | application terminated with exit code 1

Categories

(Core :: XPConnect, defect, P5)

Product:
Core
Component:
XPConnect
Type:
defect

Tracking

()

Status:
RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Assigned: kmag)

References

Details

(Keywords: intermittent-failure, regression)

Filed by: dvarga [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=248377718&repo=mozilla-central
Full log: https://queue.taskcluster.net/v1/task/HLi2y7MkR2K5rGy0yKp80g/runs/0/artifacts/public/logs/live_backing.log
Reftest URL: https://hg.mozilla.org/mozilla-central/raw-file/tip/layout/tools/reftest/reftest-analyzer.xhtml#logurl=https://queue.taskcluster.net/v1/task/HLi2y7MkR2K5rGy0yKp80g/runs/0/artifacts/public/logs/live_backing.log&only_show_unexpected=1

23:37:58     INFO - TEST-PASS | startupcache/test/browser/browser_startupcache_telemetry.js | 2 < 9 - 
23:37:58     INFO - Buffered messages finished
23:37:58    ERROR - TEST-UNEXPECTED-FAIL | startupcache/test/browser/browser_startupcache_telemetry.js | application terminated with exit code 1
23:37:58     INFO - runtests.py | Application ran for: 0:00:12.828000
23:37:58     INFO - zombiecheck | Reading PID log: c:\users\task_1558824966\appdata\local\temp\tmp7knxu1pidlog
23:37:58     INFO - ==> process 2688 launched child process 3992 ("Z:\task_1558824966\build\application\firefox\firefox.exe" -contentproc --channel="2688.0.933217232\686606542" -childID 1 -isForBrowser -prefsHandle 2232 -prefMapHandle 2228 -prefsLen 1 -prefMapSize 199876 -parentBuildID 20190525213840 -greomni "Z:\task_1558824966\build\application\firefox\omni.ja" -appomni "Z:\task_1558824966\build\application\firefox\browser\omni.ja" -appdir "Z:\task_1558824966\build\application\firefox\browser" - 2688 "\\.\pipe\gecko-crash-server-pipe.2688" 2320 tab)
23:37:58     INFO - ==> process 2688 launched child process 4000 ("Z:\task_1558824966\build\application\firefox\firefox.exe" -contentproc --channel="2688.6.2089990752\2051401194" -childID 2 -isForBrowser -prefsHandle 2520 -prefMapHandle 2524 -prefsLen 159 -prefMapSize 199876 -parentBuildID 20190525213840 -greomni "Z:\task_1558824966\build\application\firefox\omni.ja" -appomni "Z:\task_1558824966\build\application\firefox\browser\omni.ja" -appdir "Z:\task_1558824966\build\application\firefox\browser" - 2688 "\\.\pipe\gecko-crash-server-pipe.2688" 2536 tab)
23:37:58     INFO - ==> process 2688 launched child process 820 ("Z:\task_1558824966\build\application\firefox\firefox.exe" -contentproc --channel="2688.12.1886128036\1235976543" -childID 3 -isForBrowser -prefsHandle 2724 -prefMapHandle 2728 -prefsLen 159 -prefMapSize 199876 -parentBuildID 20190525213840 -greomni "Z:\task_1558824966\build\application\firefox\omni.ja" -appomni "Z:\task_1558824966\build\application\firefox\browser\omni.ja" -appdir "Z:\task_1558824966\build\application\firefox\browser" - 2688 "\\.\pipe\gecko-crash-server-pipe.2688" 2740 tab)
23:37:58     INFO - ==> process 2688 launched child process 3408 ("Z:\task_1558824966\build\application\firefox\firefox.exe" -contentproc --channel="2688.18.1085163592\1020977846" -childID 4 -isForBrowser -prefsHandle 3588 -prefMapHandle 3592 -prefsLen 8883 -prefMapSize 199876 -parentBuildID 20190525213840 -greomni "Z:\task_1558824966\build\application\firefox\omni.ja" -appomni "Z:\task_1558824966\build\application\firefox\browser\omni.ja" -appdir "Z:\task_1558824966\build\application\firefox\browser" - 2688 "\\.\pipe\gecko-crash-server-pipe.2688" 3604 tab)
23:37:58     INFO - ==> process 2688 launched child process 3944 ("Z:\task_1558824966\build\application\firefox\firefox.exe" -contentproc --channel="2688.24.1831989652\1291373321" -childID 5 -isForBrowser -prefsHandle 3864 -prefMapHandle 3784 -prefsLen 9166 -prefMapSize 199876 -parentBuildID 20190525213840 -greomni "Z:\task_1558824966\build\application\firefox\omni.ja" -appomni "Z:\task_1558824966\build\application\firefox\browser\omni.ja" -appdir "Z:\task_1558824966\build\application\firefox\browser" - 2688 "\\.\pipe\gecko-crash-server-pipe.2688" 3884 tab)
23:37:58     INFO - ==> process 2688 launched child process 4228 ("Z:\task_1558824966\build\application\firefox\firefox.exe" -contentproc --channel="2688.30.1345933554\1102387567" -childID 6 -isForBrowser -prefsHandle 3984 -prefMapHandle 3972 -prefsLen 9166 -prefMapSize 199876 -parentBuildID 20190525213840 -greomni "Z:\task_1558824966\build\application\firefox\omni.ja" -appomni "Z:\task_1558824966\build\application\firefox\browser\omni.ja" -appdir "Z:\task_1558824966\build\application\firefox\browser" - 2688 "\\.\pipe\gecko-crash-server-pipe.2688" 4060 tab)
23:37:58     INFO - ==> process 2688 launched child process 4376 ("Z:\task_1558824966\build\application\firefox\firefox.exe" -contentproc --channel="2688.36.1009581701\1714958173" -childID 7 -isForBrowser -prefsHandle 4248 -prefMapHandle 4220 -prefsLen 9166 -prefMapSize 199876 -parentBuildID 20190525213840 -greomni "Z:\task_1558824966\build\application\firefox\omni.ja" -appomni "Z:\task_1558824966\build\application\firefox\browser\omni.ja" -appdir "Z:\task_1558824966\build\application\firefox\browser" - 2688 "\\.\pipe\gecko-crash-server-pipe.2688" 4260 tab)
23:37:58     INFO - ==> process 2688 launched child process 4528 ("Z:\task_1558824966\build\application\firefox\firefox.exe" -contentproc --channel="2688.42.1852040087\607295078" -childID 8 -isForBrowser -prefsHandle 4220 -prefMapHandle 4360 -prefsLen 9165 -prefMapSize 199876 -parentBuildID 20190525213840 -greomni "Z:\task_1558824966\build\application\firefox\omni.ja" -appomni "Z:\task_1558824966\build\application\firefox\browser\omni.ja" -appdir "Z:\task_1558824966\build\application\firefox\browser" - 2688 "\\.\pipe\gecko-crash-server-pipe.2688" 4448 tab)
23:37:58     INFO - ==> process 2688 launched child process 4656 ("Z:\task_1558824966\build\application\firefox\firefox.exe" -contentproc --channel="2688.48.1760372394\1315774876" -childID 9 -isForBrowser -prefsHandle 4596 -prefMapHandle 4600 -prefsLen 9166 -prefMapSize 199876 -parentBuildID 20190525213840 -greomni "Z:\task_1558824966\build\application\firefox\omni.ja" -appomni "Z:\task_1558824966\build\application\firefox\browser\omni.ja" -appdir "Z:\task_1558824966\build\application\firefox\browser" - 2688 "\\.\pipe\gecko-crash-server-pipe.2688" 4620 tab)
23:37:58     INFO - ==> process 2688 launched child process 4804 ("Z:\task_1558824966\build\application\firefox\firefox.exe" -contentproc --channel="2688.54.688217884\1209627884" -childID 10 -isForBrowser -prefsHandle 4720 -prefMapHandle 4708 -prefsLen 9166 -prefMapSize 199876 -parentBuildID 20190525213840 -greomni "Z:\task_1558824966\build\application\firefox\omni.ja" -appomni "Z:\task_1558824966\build\application\firefox\browser\omni.ja" -appdir "Z:\task_1558824966\build\application\firefox\browser" - 2688 "\\.\pipe\gecko-crash-server-pipe.2688" 4796 tab)
23:37:58     INFO - zombiecheck | Checking for orphan process with PID: 4000
23:37:58     INFO - zombiecheck | Checking for orphan process with PID: 4528
23:37:58     INFO - zombiecheck | Checking for orphan process with PID: 4228
23:37:58     INFO - zombiecheck | Checking for orphan process with PID: 3944
23:37:58     INFO - zombiecheck | Checking for orphan process with PID: 3408
23:37:58     INFO - zombiecheck | Checking for orphan process with PID: 4376
23:37:58     INFO - zombiecheck | Checking for orphan process with PID: 820
23:37:58     INFO - zombiecheck | Checking for orphan process with PID: 4656
23:37:58     INFO - zombiecheck | Checking for orphan process with PID: 3992
23:37:58     INFO - zombiecheck | Checking for orphan process with PID: 4804
23:37:58     INFO - mozcrash Downloading symbols from: https://queue.taskcluster.net/v1/task/cHHNjBkfSwSqEvOBj4W2DQ/artifacts/public/build/target.crashreporter-symbols.zip
23:38:02     INFO - mozcrash Copy/paste: Z:\task_1558824966\build\win32-minidump_stackwalk.exe c:\users\task_1558824966\appdata\local\temp\tmpr9cldd.mozrunner\minidumps\f716031f-a568-41de-8a3d-0c5dab0bb27c.dmp c:\users\task_1558824966\appdata\local\temp\tmpscsrjj
23:38:16     INFO - mozcrash Saved minidump as Z:\task_1558824966\build\blobber_upload_dir\f716031f-a568-41de-8a3d-0c5dab0bb27c.dmp
23:38:16     INFO - mozcrash Saved app info as Z:\task_1558824966\build\blobber_upload_dir\f716031f-a568-41de-8a3d-0c5dab0bb27c.extra
23:38:16     INFO - PROCESS-CRASH | startupcache/test/browser/browser_startupcache_telemetry.js | application crashed [@ mozilla::ScriptPreloader::CacheWriteComplete()]
23:38:16     INFO - Crash dump filename: c:\users\task_1558824966\appdata\local\temp\tmpr9cldd.mozrunner\minidumps\f716031f-a568-41de-8a3d-0c5dab0bb27c.dmp
23:38:16     INFO - Operating system: Windows NT
23:38:16     INFO -                   6.1.7601 Service Pack 1
23:38:16     INFO - CPU: x86
23:38:16     INFO -      GenuineIntel family 6 model 63 stepping 2
23:38:16     INFO -      8 CPUs

Kris, it looks like this is crashing in ScriptPreloader::CacheWriteComplete.

Component: XPCOM → XPConnect
Flags: needinfo?(kmaglione+bmo)

Hm. I guess we must be calling StartCacheWrite twice and having one step on the mSaveThread from another. There's an assertion to prevent that, but it's not a release assert.

That wasn't possible originally, when we cleared mSaveThread from the background thread.

Assignee: nobody → kmaglione+bmo
Flags: needinfo?(kmaglione+bmo)

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

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