Closed Bug 1267003 Opened 8 years ago Closed 8 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: 8 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.