Closed Bug 1267003 Opened 10 years ago Closed 10 years ago

test_xultree_animation.xhtml is going to be nearly-permafail on Windows x32 debug when Gecko 47 merges to Beta

Categories

(Core :: Layout: Images, Video, and HTML Frames, defect)

Product:
Core
Component:
Layout: Images, Video, and HTML Frames
Platform:
Unspecified
Windows
Type:
defect
Priority:
Not set
Severity:
normal

Tracking

()

Status:
RESOLVED FIXED
Tracking Flags:
Tracking Status
firefox46 --- unaffected
firefox47 --- fixed
firefox48 --- unaffected

People

(Reporter: RyanVM, Unassigned)

References

Details

(Keywords: assertion, crash)

[Tracking Requested - why for this release]: Nearly permafail test on Win32 debug builds when Gecko 47 merges to Beta. Interestingly, this only hits WinXP/Win7 debug builds on Gecko 47. Trunk-as-Beta simulations for Gecko 48 aren't hitting it. Anyway, we need to figure out whether we should just re-disable this test on 47 or figure out if there's a missing fix that should be uplifted to it so that it can run with stability. https://treeherder.mozilla.org/logviewer.html#?job_id=19893152&repo=try 09:48:02 INFO - 1930 INFO TEST-START | image/test/mochitest/test_xultree_animation.xhtml 09:48:02 INFO - ++DOMWINDOW == 51 (12CDC400) [pid = 532] [serial = 234] [outer = 16175400] 09:48:03 INFO - MEMORY STAT | vsize 512MB | vsizeMaxContiguous 188MB | residentFast 245MB | heapAllocated 68MB 09:48:03 INFO - 1931 INFO TEST-OK | image/test/mochitest/test_xultree_animation.xhtml | took 752ms 09:48:03 INFO - JPEG decoding error: 09:48:03 INFO - Not a JPEG file: starts with 0x6e 0x6f 09:48:03 INFO - JPEG decoding error: 09:48:03 INFO - Not a JPEG file: starts with 0x6e 0x6f 09:48:03 INFO - Corrupt JPEG data: 1865 extraneous bytes before marker 0xd9 09:48:03 INFO - JPEG decoding error: 09:48:03 INFO - Invalid JPEG file structure: missing SOS marker 09:48:03 INFO - JPEG decoding error: 09:48:03 INFO - Not a JPEG file: starts with 0x43 0x6f 09:48:03 INFO - JPEG decoding error: 09:48:03 INFO - Not a JPEG file: starts with 0x6e 0x6f 09:48:03 INFO - Corrupt JPEG data: 1865 extraneous bytes before marker 0xd9 09:48:03 INFO - JPEG decoding error: 09:48:03 INFO - Invalid JPEG file structure: missing SOS marker 09:48:03 INFO - JPEG decoding error: 09:48:03 INFO - Not a JPEG file: starts with 0x43 0x6f 09:48:03 INFO - JavaScript error: chrome://global/content/bindings/textbox.xml, line 174: TypeError: this.inputField is undefined 09:48:03 INFO - ++DOMWINDOW == 52 (16092000) [pid = 532] [serial = 235] [outer = 16175400] 09:48:03 INFO - ++DOMWINDOW == 53 (1043D400) [pid = 532] [serial = 236] [outer = 16175400] 09:48:03 INFO - --DOCSHELL 1320E800 == 12 [pid = 532] [id = 10] 09:48:04 INFO - --DOCSHELL 12E22400 == 11 [pid = 532] [id = 50] 09:48:04 INFO - --DOCSHELL 12E8D400 == 10 [pid = 532] [id = 51] 09:48:04 INFO - --DOCSHELL 13D12800 == 9 [pid = 532] [id = 52] 09:48:04 INFO - --DOCSHELL 1031E800 == 8 [pid = 532] [id = 53] 09:48:04 INFO - --DOCSHELL 10441400 == 7 [pid = 532] [id = 54] 09:48:04 INFO - [532] WARNING: getting z level of unregistered window: file c:/builds/moz2_slave/try-w32-d-00000000000000000000/build/src/xpfe/appshell/nsWindowMediator.cpp, line 623 09:48:04 INFO - [532] WARNING: getting z level of unregistered window: file c:/builds/moz2_slave/try-w32-d-00000000000000000000/build/src/xpfe/appshell/nsWindowMediator.cpp, line 623 09:48:04 INFO - --DOCSHELL 1BDB1000 == 6 [pid = 532] [id = 11] 09:48:04 INFO - --DOMWINDOW == 52 (1336F000) [pid = 532] [serial = 190] [outer = 00000000] [url = http://mochi.test:8888/tests/image/test/mochitest/test_bullet_animation.html] 09:48:04 INFO - --DOMWINDOW == 51 (11082000) [pid = 532] [serial = 194] [outer = 00000000] [url = http://mochi.test:8888/tests/image/test/mochitest/test_changeOfSource2.html] 09:48:04 INFO - --DOMWINDOW == 50 (1264D400) [pid = 532] [serial = 196] [outer = 00000000] [url = http://mochi.test:8888/tests/image/test/mochitest/test_drawDiscardedImage.html] 09:48:04 INFO - --DOMWINDOW == 49 (1320CC00) [pid = 532] [serial = 200] [outer = 00000000] [url = http://mochi.test:8888/tests/image/test/mochitest/test_error_events.html] 09:48:04 INFO - --DOCSHELL 118F4800 == 5 [pid = 532] [id = 2] 09:48:04 INFO - [532] WARNING: cannot post event if not initialized: file c:/builds/moz2_slave/try-w32-d-00000000000000000000/build/src/netwerk/protocol/http/nsHttpConnectionMgr.cpp, line 232 09:48:05 INFO - Assertion failure: false (MOZ_ASSERT_UNREACHABLE: Called Complete more than once), at c:/builds/moz2_slave/try-w32-d-00000000000000000000/build/src/image/SourceBuffer.cpp:400 09:48:05 INFO - #01: mozilla::image::RasterImage::OnImageDataComplete(nsIRequest *,nsISupports *,nsresult,bool) [image/RasterImage.cpp:1016] 09:48:05 INFO - #02: mozilla::image::MultipartImage::OnImageDataComplete(nsIRequest *,nsISupports *,nsresult,bool) [image/MultipartImage.cpp:284] 09:48:05 INFO - #03: imgRequest::OnStopRequest(nsIRequest *,nsISupports *,nsresult) [image/imgRequest.cpp:835] 09:48:05 INFO - #04: nsMultiMixedConv::OnStopRequest(nsIRequest *,nsISupports *,nsresult) [netwerk/streamconv/converters/nsMultiMixedConv.cpp:980] 09:48:05 INFO - #05: ProxyListener::OnStopRequest(nsIRequest *,nsISupports *,nsresult) [image/imgLoader.cpp:2629] 09:48:05 INFO - #06: nsStreamListenerTee::OnStopRequest(nsIRequest *,nsISupports *,nsresult) [netwerk/base/nsStreamListenerTee.cpp:49] 09:48:05 INFO - #07: mozilla::net::nsHttpChannel::OnStopRequest(nsIRequest *,nsISupports *,nsresult) [netwerk/protocol/http/nsHttpChannel.cpp:6125] 09:48:05 INFO - #08: nsInputStreamPump::OnStateStop() [netwerk/base/nsInputStreamPump.cpp:716] 09:48:05 INFO - #09: nsInputStreamPump::OnInputStreamReady(nsIAsyncInputStream *) [netwerk/base/nsInputStreamPump.cpp:435] 09:48:05 INFO - #10: nsInputStreamReadyEvent::Run() [xpcom/io/nsStreamUtils.cpp:96] 09:48:05 INFO - #11: nsThread::ProcessNextEvent(bool,bool *) [xpcom/threads/nsThread.cpp:994] 09:48:05 INFO - #12: NS_ProcessNextEvent(nsIThread *,bool) [xpcom/glue/nsThreadUtils.cpp:297] 09:48:05 INFO - #13: nsThread::Shutdown() [xpcom/threads/nsThread.cpp:810] 09:48:05 INFO - #14: nsSocketTransportService::Shutdown() [netwerk/base/nsSocketTransportService2.cpp:577] 09:48:05 INFO - #15: nsIOService::SetOffline(bool) [netwerk/base/nsIOService.cpp:1068] 09:48:05 INFO - #16: nsIOService::Observe(nsISupports *,char const *,wchar_t const *) [netwerk/base/nsIOService.cpp:1437] 09:48:05 INFO - #17: nsObserverList::NotifyObservers(nsISupports *,char const *,wchar_t const *) [xpcom/ds/nsObserverList.cpp:113] 09:48:05 INFO - #18: nsObserverService::NotifyObservers(nsISupports *,char const *,wchar_t const *) [xpcom/ds/nsObserverService.cpp:307] 09:48:05 INFO - #19: nsXREDirProvider::DoShutdown() [toolkit/xre/nsXREDirProvider.cpp:990] 09:48:05 INFO - #20: ScopedXPCOMStartup::~ScopedXPCOMStartup() [toolkit/xre/nsAppRunner.cpp:1459] 09:48:05 INFO - #21: mozilla::DefaultDelete<ScopedXPCOMStartup>::operator()(ScopedXPCOMStartup *) [mfbt/UniquePtr.h:494] 09:48:05 INFO - #22: XREMain::XRE_main(int,char * * const,nsXREAppData const *) [toolkit/xre/nsAppRunner.cpp:4463] 09:48:05 INFO - #23: XRE_main [toolkit/xre/nsAppRunner.cpp:4540] 09:48:05 INFO - #24: do_main [browser/app/nsBrowserApp.cpp:220] 09:48:05 INFO - #25: NS_internal_main(int,char * *,char * *) [browser/app/nsBrowserApp.cpp:360] 09:48:05 INFO - #26: wmain [toolkit/xre/nsWindowsWMain.cpp:138] 09:48:05 INFO - #27: __tmainCRTStartup [f:/dd/vctools/crt/crtw32/startup/crt0.c:255] 09:48:05 INFO - #28: kernel32.dll + 0x17067 09:48:05 INFO - TEST-INFO | Main app process: exit 1 09:48:05 INFO - 1932 INFO TEST-START | Shutdown 09:48:05 INFO - 1933 INFO Passed: 250 09:48:05 INFO - 1934 INFO Failed: 0 09:48:05 INFO - 1935 INFO Todo: 68 09:48:05 INFO - 1936 INFO Slowest: 102266ms - /tests/image/test/mochitest/test_bug399925.html 09:48:05 INFO - 1937 INFO SimpleTest FINISHED 09:48:05 INFO - 1938 INFO TEST-INFO | Ran 1 Loops 09:48:05 INFO - 1939 INFO SimpleTest FINISHED 09:48:05 WARNING - TEST-UNEXPECTED-FAIL | image/test/mochitest/test_xultree_animation.xhtml | application terminated with exit code 1 09:48:05 INFO - runtests.py | Application ran for: 0:03:07.350000 09:48:05 INFO - zombiecheck | Reading PID log: c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpqiarhvpidlog 09:48:05 INFO - mozcrash Copy/paste: C:\slave\test\build\win32-minidump_stackwalk.exe c:\docume~1\cltbld~1.t-x\locals~1\temp\tmphzajib.mozrunner\minidumps\b24ca777-fc9c-444b-965e-4456685ae5ee.dmp C:\slave\test\build\symbols 09:48:23 INFO - mozcrash Saved minidump as C:\slave\test\build\blobber_upload_dir\b24ca777-fc9c-444b-965e-4456685ae5ee.dmp 09:48:23 INFO - mozcrash Saved app info as C:\slave\test\build\blobber_upload_dir\b24ca777-fc9c-444b-965e-4456685ae5ee.extra 09:48:23 WARNING - PROCESS-CRASH | image/test/mochitest/test_xultree_animation.xhtml | application crashed [@ mozilla::image::SourceBuffer::Complete(nsresult)] 09:48:23 INFO - Crash dump filename: c:\docume~1\cltbld~1.t-x\locals~1\temp\tmphzajib.mozrunner\minidumps\b24ca777-fc9c-444b-965e-4456685ae5ee.dmp 09:48:23 INFO - Operating system: Windows NT 09:48:23 INFO - 5.1.2600 Service Pack 3 09:48:23 INFO - CPU: x86 09:48:23 INFO - GenuineIntel family 6 model 30 stepping 5 09:48:23 INFO - 8 CPUs 09:48:23 INFO - Crash reason: EXCEPTION_BREAKPOINT 09:48:23 INFO - Crash address: 0x3e24501 09:48:23 INFO - Process uptime: 187 seconds 09:48:23 INFO - Thread 0 (crashed) 09:48:23 INFO - 0 xul.dll!mozilla::image::SourceBuffer::Complete(nsresult) [SourceBuffer.cpp:fa3957d2ba72 : 400 + 0x22] 09:48:23 INFO - eip = 0x03e24501 esp = 0x0012f61c ebp = 0x0012f644 ebx = 0x15ef71c0 09:48:23 INFO - esi = 0x00000190 edi = 0x157fef20 eax = 0x068f57a0 ecx = 0x002b0ad9 09:48:23 INFO - edx = 0x00370ea0 efl = 0x00000202 09:48:23 INFO - Found by: given as instruction pointer in context 09:48:23 INFO - 1 xul.dll!mozilla::image::RasterImage::OnImageDataComplete(nsIRequest *,nsISupports *,nsresult,bool) [RasterImage.cpp:fa3957d2ba72 : 1009 + 0x1c] 09:48:23 INFO - eip = 0x03dff6cf esp = 0x0012f64c ebp = 0x0012f688 09:48:23 INFO - Found by: call frame info 09:48:23 INFO - 2 xul.dll!mozilla::image::MultipartImage::OnImageDataComplete(nsIRequest *,nsISupports *,nsresult,bool) [MultipartImage.cpp:fa3957d2ba72 : 281 + 0x17] 09:48:23 INFO - eip = 0x03e1bf09 esp = 0x0012f690 ebp = 0x0012f6a8 09:48:23 INFO - Found by: call frame info 09:48:23 INFO - 3 xul.dll!imgRequest::OnStopRequest(nsIRequest *,nsISupports *,nsresult) [imgRequest.cpp:fa3957d2ba72 : 829 + 0x1c] 09:48:23 INFO - eip = 0x03e0e560 esp = 0x0012f6b0 ebp = 0x0012f6f8 09:48:23 INFO - Found by: call frame info 09:48:23 INFO - 4 xul.dll!nsMultiMixedConv::OnStopRequest(nsIRequest *,nsISupports *,nsresult) [nsMultiMixedConv.cpp:fa3957d2ba72 : 977 + 0x15] 09:48:23 INFO - eip = 0x0340e020 esp = 0x0012f700 ebp = 0x0012f71c 09:48:23 INFO - Found by: call frame info 09:48:23 INFO - 5 xul.dll!ProxyListener::OnStopRequest(nsIRequest *,nsISupports *,nsresult) [imgLoader.cpp:fa3957d2ba72 : 2628 + 0x14] 09:48:23 INFO - eip = 0x03e08017 esp = 0x0012f724 ebp = 0x0012f734 09:48:23 INFO - Found by: call frame info 09:48:23 INFO - 6 xul.dll!nsStreamListenerTee::OnStopRequest(nsIRequest *,nsISupports *,nsresult) [nsStreamListenerTee.cpp:fa3957d2ba72 : 48 + 0x17] 09:48:23 INFO - eip = 0x033c51d2 esp = 0x0012f73c ebp = 0x0012f758 09:48:23 INFO - Found by: call frame info 09:48:23 INFO - 7 xul.dll!mozilla::net::nsHttpChannel::OnStopRequest(nsIRequest *,nsISupports *,nsresult) [nsHttpChannel.cpp:fa3957d2ba72 : 6122 + 0x34] 09:48:23 INFO - eip = 0x03507cea esp = 0x0012f760 ebp = 0x0012f870 09:48:23 INFO - Found by: call frame info 09:48:23 INFO - 8 xul.dll!nsInputStreamPump::OnStateStop() [nsInputStreamPump.cpp:fa3957d2ba72 : 715 + 0x15] 09:48:23 INFO - eip = 0x033a8808 esp = 0x0012f878 ebp = 0x0012f8a0 09:48:23 INFO - Found by: call frame info 09:48:23 INFO - 9 xul.dll!nsInputStreamPump::OnInputStreamReady(nsIAsyncInputStream *) [nsInputStreamPump.cpp:fa3957d2ba72 : 434 + 0xa] 09:48:23 INFO - eip = 0x033a755f esp = 0x0012f8a8 ebp = 0x0012f8c4 09:48:23 INFO - Found by: call frame info 09:48:23 INFO - 10 xul.dll!nsInputStreamReadyEvent::Run() [nsStreamUtils.cpp:fa3957d2ba72 : 94 + 0x10] 09:48:23 INFO - eip = 0x032e8919 esp = 0x0012f8cc ebp = 0x0012f8dc 09:48:23 INFO - Found by: call frame info 09:48:23 INFO - 11 xul.dll!nsThread::ProcessNextEvent(bool,bool *) [nsThread.cpp:fa3957d2ba72 : 994 + 0xe] 09:48:23 INFO - eip = 0x033040a8 esp = 0x0012f8e4 ebp = 0x0012f9c4 09:48:23 INFO - Found by: call frame info 09:48:23 INFO - 12 xul.dll!NS_ProcessNextEvent(nsIThread *,bool) [nsThreadUtils.cpp:fa3957d2ba72 : 297 + 0xd] 09:48:23 INFO - eip = 0x03332d68 esp = 0x0012f9cc ebp = 0x0012f9d8 09:48:23 INFO - Found by: call frame info 09:48:23 INFO - 13 xul.dll!nsThread::Shutdown() [nsThread.cpp:fa3957d2ba72 : 810 + 0xa] 09:48:23 INFO - eip = 0x03308676 esp = 0x0012f9e0 ebp = 0x0012f9f0 09:48:23 INFO - Found by: call frame info 09:48:23 INFO - 14 xul.dll!nsSocketTransportService::Shutdown() [nsSocketTransportService2.cpp:fa3957d2ba72 : 575 + 0x10] 09:48:23 INFO - eip = 0x033cf6b5 esp = 0x0012f9f8 ebp = 0x0012fa1c 09:48:23 INFO - Found by: call frame info 09:48:23 INFO - 15 xul.dll!nsIOService::SetOffline(bool) [nsIOService.cpp:fa3957d2ba72 : 1067 + 0xb] 09:48:23 INFO - eip = 0x033b03c3 esp = 0x0012fa24 ebp = 0x0012fa44 09:48:23 INFO - Found by: call frame info 09:48:23 INFO - 16 xul.dll!nsIOService::Observe(nsISupports *,char const *,wchar_t const *) [nsIOService.cpp:fa3957d2ba72 : 1436 + 0xb] 09:48:23 INFO - eip = 0x033a6f0e esp = 0x0012fa4c ebp = 0x0012fb28 09:48:23 INFO - Found by: call frame info 09:48:23 INFO - 17 xul.dll!nsObserverList::NotifyObservers(nsISupports *,char const *,wchar_t const *) [nsObserverList.cpp:fa3957d2ba72 : 113 + 0x1a] 09:48:23 INFO - eip = 0x032c4703 esp = 0x0012fb30 ebp = 0x0012fb48 09:48:23 INFO - Found by: call frame info 09:48:23 INFO - 18 xul.dll!nsObserverService::NotifyObservers(nsISupports *,char const *,wchar_t const *) [nsObserverService.cpp:fa3957d2ba72 : 303 + 0xe] 09:48:23 INFO - eip = 0x032c482c esp = 0x0012fb50 ebp = 0x0012fb64 09:48:23 INFO - Found by: call frame info 09:48:23 INFO - 19 xul.dll!nsXREDirProvider::DoShutdown() [nsXREDirProvider.cpp:fa3957d2ba72 : 989 + 0x1a] 09:48:23 INFO - eip = 0x05733da5 esp = 0x0012fb6c ebp = 0x0012fb98 09:48:23 INFO - Found by: call frame info 09:48:23 INFO - 20 xul.dll!ScopedXPCOMStartup::~ScopedXPCOMStartup() [nsAppRunner.cpp:fa3957d2ba72 : 1458 + 0xb] 09:48:23 INFO - eip = 0x05729133 esp = 0x0012fba0 ebp = 0x0012fbac 09:48:23 INFO - Found by: call frame info 09:48:23 INFO - 21 xul.dll!mozilla::DefaultDelete<ScopedXPCOMStartup>::operator()(ScopedXPCOMStartup *) [UniquePtr.h:fa3957d2ba72 : 494 + 0xe] 09:48:23 INFO - eip = 0x05729661 esp = 0x0012fbb4 ebp = 0x0012fbb4 09:48:23 INFO - Found by: call frame info 09:48:23 INFO - 22 xul.dll!XREMain::XRE_main(int,char * * const,nsXREAppData const *) [nsAppRunner.cpp:fa3957d2ba72 : 4460 + 0x12] 09:48:23 INFO - eip = 0x0572e987 esp = 0x0012fbbc ebp = 0x0012fbdc 09:48:23 INFO - Found by: call frame info 09:48:23 INFO - 23 xul.dll!XRE_main [nsAppRunner.cpp:fa3957d2ba72 : 4540 + 0x12] 09:48:23 INFO - eip = 0x0573141d esp = 0x0012fbe4 ebp = 0x0012fcf0 09:48:23 INFO - Found by: call frame info 09:48:23 INFO - 24 firefox.exe!do_main [nsBrowserApp.cpp:fa3957d2ba72 : 220 + 0x1c] 09:48:23 INFO - eip = 0x004025de esp = 0x0012fcf8 ebp = 0x0012fe90 09:48:23 INFO - Found by: call frame info 09:48:23 INFO - 25 firefox.exe!NS_internal_main(int,char * *,char * *) [nsBrowserApp.cpp:fa3957d2ba72 : 360 + 0xf] 09:48:23 INFO - eip = 0x00401edc esp = 0x0012fe98 ebp = 0x0012ff3c 09:48:23 INFO - Found by: call frame info 09:48:23 INFO - 26 firefox.exe!wmain [nsWindowsWMain.cpp:fa3957d2ba72 : 135 + 0xd] 09:48:23 INFO - eip = 0x00402966 esp = 0x0012ff44 ebp = 0x0012ff78 09:48:23 INFO - Found by: call frame info 09:48:23 INFO - 27 firefox.exe!__tmainCRTStartup [crt0.c : 255 + 0x12] 09:48:23 INFO - eip = 0x00404d09 esp = 0x0012ff80 ebp = 0x0012ffc0 09:48:23 INFO - Found by: call frame info 09:48:23 INFO - 28 kernel32.dll!BaseProcessStart + 0x23 09:48:23 INFO - eip = 0x7c817067 esp = 0x0012ffc8 ebp = 0x0012fff0 09:48:23 INFO - Found by: call frame info
Flags: needinfo?(bugmail.mozilla)
This is also the last test that runs in the directory, so it could also be a shutdown crash masquerading as an issue with that specific test. The JPEG decoding errors also appear in passing logs on Gecko 47, FWIW. They do not appear to be present in the Gecko 48 logs.
Looks to me like the crash happens during shutdown, after the xuktree test has successfully completed. It just gets the blame because it's the last test. https://bugzilla.mozilla.org/show_bug.cgi?id=1137032 looks related (same unreachable code crash). tnikkel, any ideas? I don't have any objections to disabling this test on windows but i doubt it will help; the error will likely just move to a different test.
Flags: needinfo?(bugmail.mozilla) → needinfo?(tnikkel)
This might be https://bugzilla.mozilla.org/show_bug.cgi?id=698882#c130. I'll try a new push today with that backed out from Aurora.
Flags: needinfo?(ryanvm)
Yep, this was fixed by philor's backout.
Blocks: 698882
No longer blocks: 1261158
Status: NEW → RESOLVED
Closed: 10 years ago
status-firefox47: affected → fixed
tracking-firefox47: ? → ---
Flags: needinfo?(tnikkel)
Flags: needinfo?(ryanvm)
Resolution: --- → FIXED
So bug 698882 caused those imagelib asserts to fail? Or are those imagelib asserts still happening somewhere? Sorry I couldn't quite follow all the logic here.
Flags: needinfo?(ryanvm)
All I know is what's in the other bug, sorry. You should probably ask Dragana for more information.
Flags: needinfo?(ryanvm)
Product: Core → Core Graveyard
Product: Core Graveyard → Core
You need to log in before you can comment on or make changes to this bug.