Closed Bug 1052740 Opened 5 years ago Closed 5 years ago

Intermittent Shutdown | application crashed [@ mozilla::LazyIdleThread::ShutdownThread()] after "Assertion failure: !mThreadIsShuttingDown (Huh?!), at xpcom\threads\LazyIdleThread.cpp:275"

Categories

(Core :: XPCOM, defect)

x86
Windows 8
defect
Not set

Tracking

()

RESOLVED FIXED
mozilla34
Tracking Status
firefox32 --- unaffected
firefox33 --- unaffected
firefox34 --- fixed
firefox-esr24 --- unaffected
firefox-esr31 --- unaffected

People

(Reporter: RyanVM, Assigned: bent.mozilla)

Details

(Keywords: assertion, crash, intermittent-failure)

Attachments

(1 file, 1 obsolete file)

https://tbpl.mozilla.org/php/getParsedLog.php?id=45773377&tree=Mozilla-Central

WINNT 6.2 mozilla-central debug test mochitest-1 on 2014-08-12 11:38:23 PDT for push c359b1a03ba2
slave: t-w864-ix-082

12:27:10     INFO -  34526 INFO TEST-START | /tests/dom/battery/test/test_battery_basics.html
12:27:10     INFO -  34527 INFO ++DOMWINDOW == 106 (0AA47C00) [pid = 3896] [serial = 6728] [outer = 160CD400]
12:27:10     INFO -  34528 INFO TEST-OK | /tests/dom/battery/test/test_battery_basics.html | took 133ms
12:27:10     INFO -  34529 INFO ++DOMWINDOW == 107 (0E78D000) [pid = 3896] [serial = 6729] [outer = 160CD400]
12:27:10     INFO -  34530 INFO ++DOMWINDOW == 108 (0F45EC00) [pid = 3896] [serial = 6730] [outer = 160CD400]
12:27:10     INFO -  34531 INFO --DOCSHELL 07FA3C00 == 20 [pid = 3896] [id = 7]
12:27:10     INFO -  34532 INFO ###!!! [Parent][OnMaybeDequeueOne] Error: Channel closing: too late to send/recv, messages will be lost
12:27:10     INFO -  34533 INFO [Parent 3896] WARNING: getting z level of unregistered window: file c:\builds\moz2_slave\m-cen-w32-d-000000000000000000\build\xpfe\appshell\nsWindowMediator.cpp, line 591
12:27:10     INFO -  34534 INFO [Parent 3896] WARNING: getting z level of unregistered window: file c:\builds\moz2_slave\m-cen-w32-d-000000000000000000\build\xpfe\appshell\nsWindowMediator.cpp, line 591
12:27:10     INFO -  34535 INFO --DOCSHELL 0F4D8400 == 19 [pid = 3896] [id = 3]
12:27:10     INFO -  34536 INFO --DOCSHELL 0B443C00 == 18 [pid = 3896] [id = 1]
12:27:11     INFO -  34537 INFO --DOCSHELL 0F4D8C00 == 17 [pid = 3896] [id = 4]
12:27:11     INFO -  34538 INFO --DOCSHELL 02EA1400 == 16 [pid = 3896] [id = 8]
12:27:11     INFO -  34539 INFO --DOCSHELL 0DBD4C00 == 15 [pid = 3896] [id = 2]
12:27:11     INFO -  34540 INFO --DOCSHELL 16482C00 == 14 [pid = 3896] [id = 1496]
12:27:11     INFO -  34541 INFO --DOCSHELL 16482400 == 13 [pid = 3896] [id = 1495]
12:27:11     INFO -  34542 INFO --DOCSHELL 16481C00 == 12 [pid = 3896] [id = 1494]
12:27:11     INFO -  34543 INFO --DOCSHELL 16481400 == 11 [pid = 3896] [id = 1493]
12:27:11     INFO -  34544 INFO --DOCSHELL 1647B400 == 10 [pid = 3896] [id = 1492]
12:27:11     INFO -  34545 INFO --DOCSHELL 1647A400 == 9 [pid = 3896] [id = 1491]
12:27:11     INFO -  34546 INFO --DOCSHELL 16479C00 == 8 [pid = 3896] [id = 1490]
12:27:11     INFO -  34547 INFO --DOCSHELL 13459000 == 7 [pid = 3896] [id = 1489]
12:27:11     INFO -  34548 INFO --DOCSHELL 12B97400 == 6 [pid = 3896] [id = 1488]
12:27:11     INFO -  34549 INFO --DOCSHELL 128EBC00 == 5 [pid = 3896] [id = 1487]
12:27:11     INFO -  34550 INFO --DOCSHELL 0AA46400 == 4 [pid = 3896] [id = 1486]
12:27:11     INFO -  34551 INFO --DOCSHELL 0A671400 == 3 [pid = 3896] [id = 1485]
12:27:11     INFO -  34552 INFO Assertion failure: !mThreadIsShuttingDown (Huh?!), at c:\builds\moz2_slave\m-cen-w32-d-000000000000000000\build\xpcom\threads\LazyIdleThread.cpp:275
12:27:11     INFO -  34553 INFO mozilla::LazyIdleThread::Notify(nsITimer *) [xpcom/threads/LazyIdleThread.cpp:491]
12:27:11     INFO -  34554 INFO nsTimerImpl::Fire() [xpcom/threads/nsTimerImpl.cpp:635]
12:27:11     INFO -  34555 INFO nsTimerEvent::Run() [xpcom/threads/nsTimerImpl.cpp:716]
12:27:11     INFO -  34556 INFO nsThread::ProcessNextEvent(bool,bool *) [xpcom/threads/nsThread.cpp:770]
12:27:11     INFO -  34557 INFO NS_ProcessNextEvent(nsIThread *,bool) [xpcom/glue/nsThreadUtils.cpp:265]
12:27:11     INFO -  34558 INFO nsThread::Shutdown() [xpcom/threads/nsThread.cpp:599]
12:27:11     INFO -  34559 INFO mozilla::LazyIdleThread::ShutdownThread() [xpcom/threads/LazyIdleThread.cpp:295]
12:27:11     INFO -  34560 INFO mozilla::LazyIdleThread::Shutdown() [xpcom/threads/LazyIdleThread.cpp:445]
12:27:11     INFO -  34561 INFO mozilla::widget::JumpListBuilder::Observe(nsISupports *,char const *,wchar_t const *) [widget/windows/JumpListBuilder.cpp:531]
12:27:11     INFO -  34562 INFO nsObserverList::NotifyObservers(nsISupports *,char const *,wchar_t const *) [xpcom/ds/nsObserverList.cpp:100]
12:27:11     INFO -  34563 INFO nsObserverService::NotifyObservers(nsISupports *,char const *,wchar_t const *) [xpcom/ds/nsObserverService.cpp:333]
12:27:11     INFO -  34564 INFO nsXREDirProvider::DoShutdown() [toolkit/xre/nsXREDirProvider.cpp:900]
12:27:11     INFO -  34565 INFO ScopedXPCOMStartup::~ScopedXPCOMStartup() [toolkit/xre/nsAppRunner.cpp:1201]
12:27:11     INFO -  34566 INFO XREMain::XRE_main(int,char * * const,nsXREAppData const *) [toolkit/xre/nsAppRunner.cpp:4117]
12:27:11     INFO -  34567 INFO XRE_main [toolkit/xre/nsAppRunner.cpp:4306]
12:27:11     INFO -  34568 INFO do_main [browser/app/nsBrowserApp.cpp:282]
12:27:11     INFO -  34569 INFO NS_internal_main(int,char * *) [browser/app/nsBrowserApp.cpp:643]
12:27:11     INFO -  34570 INFO wmain [toolkit/xre/nsWindowsWMain.cpp:112]
12:27:11     INFO -  34571 INFO __tmainCRTStartup [f:/dd/vctools/crt_bld/self_x86/crt/src/crtexe.c:552]
12:27:11     INFO -  34572 INFO KERNEL32 + 0x28543
12:27:11     INFO -  34573 INFO ntdll + 0x5ac69
12:27:11     INFO -  34574 INFO ntdll + 0x5ac3c
12:27:13     INFO -  TEST-INFO | Main app process: exit status 80000003
12:27:13     INFO -  34575 INFO TEST-START | Shutdown
12:27:13     INFO -  34576 INFO Passed:  134362
12:27:13     INFO -  34577 INFO Failed:  0
12:27:13     INFO -  34578 INFO Todo:    380
12:27:13     INFO -  34579 INFO Slowest: 113568ms - /tests/content/media/test/test_playback.html
12:27:13     INFO -  34580 INFO SimpleTest FINISHED
12:27:13     INFO -  34581 INFO TEST-INFO | Ran 1 Loops
12:27:13     INFO -  34582 INFO SimpleTest FINISHED
12:27:13  WARNING -  TEST-UNEXPECTED-FAIL | Shutdown | application terminated with exit code 2147483651
12:27:13     INFO -  runtests.py | Application ran for: 0:46:31.217000
12:27:13     INFO -  zombiecheck | Reading PID log: c:\users\cltbld~1.t-w\appdata\local\temp\tmpa3ba34pidlog
12:27:13     INFO -  ==> process 3896 launched child process 732 ("C:\slave\test\build\application\firefox\plugin-container.exe" --channel=3896.12e3b3e0.1370138271 "c:\users\cltbld~1.t-w\appdata\local\temp\tmpuatquj.mozrunner\plugins\nptest.dll" -greomni "C:\slave\test\build\application\firefox\omni.ja" -appomni "C:\slave\test\build\application\firefox\browser\omni.ja" -appdir "C:\slave\test\build\application\firefox\browser" - 3896 "\\.\pipe\gecko-crash-server-pipe.3896" plugin)
12:27:13     INFO -  ==> process 3896 launched child process 3456 ("C:\slave\test\build\application\firefox\plugin-container.exe" --channel=3896.12d61500.839081041 -greomni "C:\slave\test\build\application\firefox\omni.ja" -appomni "C:\slave\test\build\application\firefox\browser\omni.ja" -appdir "C:\slave\test\build\application\firefox\browser"  3896 "\\.\pipe\gecko-crash-server-pipe.3896" tab)
12:27:13     INFO -  ==> process 3896 launched child process 3184 ("C:\slave\test\build\application\firefox\plugin-container.exe" --channel=3896.e76ad60.1334500412 "c:\users\cltbld~1.t-w\appdata\local\temp\tmpuatquj.mozrunner\plugins\nptest.dll" -greomni "C:\slave\test\build\application\firefox\omni.ja" -appomni "C:\slave\test\build\application\firefox\browser\omni.ja" -appdir "C:\slave\test\build\application\firefox\browser" - 3896 "\\.\pipe\gecko-crash-server-pipe.3896" plugin)
12:27:13     INFO -  ==> process 3896 launched child process 3212 ("C:\slave\test\build\application\firefox\plugin-container.exe" --channel=3896.e76b300.1344509635 "c:\users\cltbld~1.t-w\appdata\local\temp\tmpuatquj.mozrunner\plugins\nptest.dll" -greomni "C:\slave\test\build\application\firefox\omni.ja" -appomni "C:\slave\test\build\application\firefox\browser\omni.ja" -appdir "C:\slave\test\build\application\firefox\browser" - 3896 "\\.\pipe\gecko-crash-server-pipe.3896" plugin)
12:27:13     INFO -  ==> process 3896 launched child process 2816 ("C:\slave\test\build\application\firefox\plugin-container.exe" --channel=3896.12861aa0.2134702077 "c:\users\cltbld~1.t-w\appdata\local\temp\tmpuatquj.mozrunner\plugins\nptest.dll" -greomni "C:\slave\test\build\application\firefox\omni.ja" -appomni "C:\slave\test\build\application\firefox\browser\omni.ja" -appdir "C:\slave\test\build\application\firefox\browser" - 3896 "\\.\pipe\gecko-crash-server-pipe.3896" plugin)
12:27:13     INFO -  ==> process 3896 launched child process 316 ("C:\slave\test\build\application\firefox\plugin-container.exe" --channel=3896.e76c860.1682808395 "c:\users\cltbld~1.t-w\appdata\local\temp\tmpuatquj.mozrunner\plugins\nptest.dll" -greomni "C:\slave\test\build\application\firefox\omni.ja" -appomni "C:\slave\test\build\application\firefox\browser\omni.ja" -appdir "C:\slave\test\build\application\firefox\browser" - 3896 "\\.\pipe\gecko-crash-server-pipe.3896" plugin)
12:27:13     INFO -  ==> process 3896 launched child process 4580 ("C:\slave\test\build\application\firefox\plugin-container.exe" --channel=3896.ab57b00.1880064444 -greomni "C:\slave\test\build\application\firefox\omni.ja" -appomni "C:\slave\test\build\application\firefox\browser\omni.ja" -appdir "C:\slave\test\build\application\firefox\browser"  3896 "\\.\pipe\gecko-crash-server-pipe.3896" tab)
12:27:13     INFO -  ==> process 3896 launched child process 1420 ("C:\slave\test\build\application\firefox\plugin-container.exe" --channel=3896.aefc300.2834967 -greomni "C:\slave\test\build\application\firefox\omni.ja" -appomni "C:\slave\test\build\application\firefox\browser\omni.ja" -appdir "C:\slave\test\build\application\firefox\browser"  3896 "\\.\pipe\gecko-crash-server-pipe.3896" tab)
12:27:20  WARNING -  PROCESS-CRASH | Shutdown | application crashed [@ mozilla::LazyIdleThread::ShutdownThread()]
12:27:20     INFO -  Crash dump filename: c:\users\cltbld~1.t-w\appdata\local\temp\tmpuatquj.mozrunner\minidumps\e1330db8-37d9-4f93-b5f6-7738397595bf.dmp
12:27:20     INFO -  Operating system: Windows NT
12:27:20     INFO -                    6.2.9200
12:27:20     INFO -  CPU: x86
12:27:20     INFO -       GenuineIntel family 6 model 30 stepping 5
12:27:20     INFO -       8 CPUs
12:27:20     INFO -  Crash reason:  EXCEPTION_BREAKPOINT
12:27:20     INFO -  Crash address: 0x712c1201
12:27:20     INFO -  Thread 0 (crashed)
12:27:20     INFO -   0  xul.dll!mozilla::LazyIdleThread::ShutdownThread() [LazyIdleThread.cpp:c359b1a03ba2 : 246 + 0x4e]
12:27:20     INFO -      eip = 0x712c1201   esp = 0x010bf5b0   ebp = 0x010bf624   ebx = 0x00000000
12:27:20     INFO -      esi = 0x1405c9a0   edi = 0x72db1128   eax = 0x00000000   ecx = 0x6f5dff12
12:27:20     INFO -      edx = 0x010bd998   efl = 0x00000212
12:27:20     INFO -      Found by: given as instruction pointer in context
12:27:20     INFO -   1  xul.dll!mozilla::LazyIdleThread::Notify(nsITimer *) [LazyIdleThread.cpp:c359b1a03ba2 : 491 + 0x7]
12:27:20     INFO -      eip = 0x712c173d   esp = 0x010bf62c   ebp = 0x010bf644
12:27:20     INFO -      Found by: call frame info
12:27:20     INFO -   2  xul.dll!nsTimerImpl::Fire() [nsTimerImpl.cpp:c359b1a03ba2 : 621 + 0x6]
12:27:20     INFO -      eip = 0x712bef79   esp = 0x010bf64c   ebp = 0x010bf700
12:27:20     INFO -      Found by: call frame info
12:27:20     INFO -   3  xul.dll!nsTimerEvent::Run() [nsTimerImpl.cpp:c359b1a03ba2 : 711 + 0xe]
12:27:20     INFO -      eip = 0x712bf0d7   esp = 0x010bf708   ebp = 0x010bf730
12:27:20     INFO -      Found by: call frame info
12:27:20     INFO -   4  xul.dll!nsThread::ProcessNextEvent(bool,bool *) [nsThread.cpp:c359b1a03ba2 : 770 + 0xd]
12:27:20     INFO -      eip = 0x712c263f   esp = 0x010bf738   ebp = 0x010bf794
12:27:20     INFO -      Found by: call frame info
12:27:20     INFO -   5  xul.dll!NS_ProcessNextEvent(nsIThread *,bool) [nsThreadUtils.cpp:c359b1a03ba2 : 265 + 0xc]
12:27:20     INFO -      eip = 0x712dfae1   esp = 0x010bf79c   ebp = 0x010bf7a8
12:27:20     INFO -      Found by: call frame info
12:27:20     INFO -   6  xul.dll!nsThread::Shutdown() [nsThread.cpp:c359b1a03ba2 : 599 + 0x9]
12:27:20     INFO -      eip = 0x712c230c   esp = 0x010bf7b0   ebp = 0x010bf7e4
12:27:20     INFO -      Found by: call frame info
12:27:20     INFO -   7  xul.dll!mozilla::LazyIdleThread::ShutdownThread() [LazyIdleThread.cpp:c359b1a03ba2 : 295 + 0x10]
12:27:20     INFO -      eip = 0x712c13d8   esp = 0x010bf7ec   ebp = 0x010bf864
12:27:20     INFO -      Found by: call frame info
12:27:20     INFO -   8  xul.dll!mozilla::LazyIdleThread::Shutdown() [LazyIdleThread.cpp:c359b1a03ba2 : 445 + 0xa]
12:27:20     INFO -      eip = 0x712c1633   esp = 0x010bf86c   ebp = 0x010bf87c
12:27:20     INFO -      Found by: call frame info
Looks like bent is to blame for LazyIdle stuff!
Flags: needinfo?(bent.mozilla)
Attached patch Patch, v1 (obsolete) — Splinter Review
Just need to switch the order of shutting down the timer and the thread.
Assignee: nobody → bent.mozilla
Status: NEW → ASSIGNED
Attachment #8476061 - Flags: review?(benjamin)
Flags: needinfo?(bent.mozilla)
Attachment #8476061 - Flags: review?(benjamin) → review+
Attached patch Patch, v2Splinter Review
Sorry, needed one additional null check.
Attachment #8476061 - Attachment is obsolete: true
Attachment #8481453 - Flags: review?(benjamin)
Attachment #8481453 - Flags: review?(benjamin) → review+
https://hg.mozilla.org/mozilla-central/rev/6a6b450605c5
Status: ASSIGNED → RESOLVED
Closed: 5 years ago
Resolution: --- → FIXED
Target Milestone: --- → mozilla34
You need to log in before you can comment on or make changes to this bug.