1463927 - Intermittent dom/file/tests/test_fileapi_slice_realFile_1.html | application crashed [@ MOZ_CrashOOL] after application terminated with exit code 1

Status: RESOLVED INCOMPLETE

(Core :: DOM: File, defect, P5)

Description

[Treeherder Bug Filer]

Filed by: apavel [at] mozilla.com

https://treeherder.mozilla.org/logviewer.html#?job_id=179823717&repo=mozilla-inbound

https://queue.taskcluster.net/v1/task/Zno3-3x0R8er4LxzMkg6IQ/runs/0/artifacts/public/logs/live_backing.log

14:02:23     INFO -  Buffered messages logged at 14:02:23
14:02:23     INFO -  3520 INFO TEST-PASS | dom/file/tests/test_fileapi_slice_realFile_1.html | [XHR] Content of result in test XMLHttpRequest send of fileFile slice()
14:02:23     INFO -  3521 INFO TEST-PASS | dom/file/tests/test_fileapi_slice_realFile_1.html | [XHR] lengthComputable in test XMLHttpRequest send of fileFile slice()
14:02:23     INFO -  3522 INFO TEST-PASS | dom/file/tests/test_fileapi_slice_realFile_1.html | [XHR] Loaded length in test XMLHttpRequest send of fileFile slice()
14:02:23     INFO -  3523 INFO TEST-PASS | dom/file/tests/test_fileapi_slice_realFile_1.html | [XHR] Total length in test XMLHttpRequest send of fileFile slice()
14:02:23     INFO -  3524 INFO TEST-PASS | dom/file/tests/test_fileapi_slice_realFile_1.html | fileFile slice(0) type
14:02:23     INFO -  3525 INFO TEST-PASS | dom/file/tests/test_fileapi_slice_realFile_1.html | fileFile slice(0) size
14:02:23     INFO -  3526 INFO TEST-PASS | dom/file/tests/test_fileapi_slice_realFile_1.html | fileFile slice(0) data size
14:02:23     INFO -  3527 INFO TEST-PASS | dom/file/tests/test_fileapi_slice_realFile_1.html | [FileReader] readyState in test FileReader.readAsBinaryString of fileFile slice(0)
14:02:23     INFO -  3528 INFO TEST-PASS | dom/file/tests/test_fileapi_slice_realFile_1.html | [FileReader] no error in test FileReader.readAsBinaryString of fileFile slice(0)
14:02:23     INFO -  3529 INFO TEST-PASS | dom/file/tests/test_fileapi_slice_realFile_1.html | [FileReader] Length of result in test FileReader.readAsBinaryString of fileFile slice(0)
14:02:23     INFO -  3530 INFO TEST-PASS | dom/file/tests/test_fileapi_slice_realFile_1.html | [FileReader] Content of result in test FileReader.readAsBinaryString of fileFile slice(0)
14:02:23     INFO -  3531 INFO TEST-PASS | dom/file/tests/test_fileapi_slice_realFile_1.html | [FileReader] lengthComputable in test FileReader.readAsBinaryString of fileFile slice(0)
14:02:23     INFO -  3532 INFO TEST-PASS | dom/file/tests/test_fileapi_slice_realFile_1.html | [FileReader] Loaded length in test FileReader.readAsBinaryString of fileFile slice(0)
14:02:23     INFO -  3533 INFO TEST-PASS | dom/file/tests/test_fileapi_slice_realFile_1.html | [FileReader] Total length in test FileReader.readAsBinaryString of fileFile slice(0)
14:02:23     INFO -  3534 INFO TEST-PASS | dom/file/tests/test_fileapi_slice_realFile_1.html | [XHR] readyState in test XMLHttpRequest load of fileFile slice(0)
14:02:23     INFO -  3535 INFO TEST-PASS | dom/file/tests/test_fileapi_slice_realFile_1.html | [XHR] no error in test XMLHttpRequest load of fileFile slice(0)
14:02:23     INFO -  3536 INFO TEST-PASS | dom/file/tests/test_fileapi_slice_realFile_1.html | [XHR] Length of result in test XMLHttpRequest load of fileFile slice(0)
14:02:23     INFO -  3537 INFO TEST-PASS | dom/file/tests/test_fileapi_slice_realFile_1.html | [XHR] Content of result in test XMLHttpRequest load of fileFile slice(0)
14:02:23     INFO -  3538 INFO TEST-PASS | dom/file/tests/test_fileapi_slice_realFile_1.html | [XHR] lengthComputable in test XMLHttpRequest load of fileFile slice(0)
14:02:23     INFO -  3539 INFO TEST-PASS | dom/file/tests/test_fileapi_slice_realFile_1.html | [XHR] Loaded length in test XMLHttpRequest load of fileFile slice(0)
14:02:23     INFO -  3540 INFO TEST-PASS | dom/file/tests/test_fileapi_slice_realFile_1.html | [XHR] Total length in test XMLHttpRequest load of fileFile slice(0)
14:02:23     INFO -  3541 INFO TEST-PASS | dom/file/tests/test_fileapi_slice_realFile_1.html | Correct number of items
14:02:23     INFO -  3542 INFO TEST-PASS | dom/file/tests/test_fileapi_slice_realFile_1.html | Correct name (A)
14:02:23     INFO -  3543 INFO TEST-PASS | dom/file/tests/test_fileapi_slice_realFile_1.html | Wrong number of headers (A)
14:02:23     INFO -  3544 INFO TEST-PASS | dom/file/tests/test_fileapi_slice_realFile_1.html | Length of correct value
14:02:23     INFO -  3545 INFO TEST-PASS | dom/file/tests/test_fileapi_slice_realFile_1.html | Content of correct value
14:02:23     INFO -  3546 INFO TEST-PASS | dom/file/tests/test_fileapi_slice_realFile_1.html | Correct name (B)
14:02:23     INFO -  3547 INFO TEST-PASS | dom/file/tests/test_fileapi_slice_realFile_1.html | Correct content type (B)
14:02:23     INFO -  3548 INFO TEST-PASS | dom/file/tests/test_fileapi_slice_realFile_1.html | Wrong number of headers (B)
14:02:23     INFO -  Buffered messages finished
14:02:23    ERROR -  3549 ERROR TEST-UNEXPECTED-FAIL | dom/file/tests/test_fileapi_slice_realFile_1.html | application terminated with exit code 1
14:02:23     INFO -  runtests.py | Application ran for: 0:00:28.103000
14:02:23     INFO -  zombiecheck | Reading PID log: c:\users\task_1527081175\appdata\local\temp\tmp6o4ptjpidlog
14:02:23     INFO -  ==> process 1092 launched child process 1372 ("Z:\task_1527081175\build\application\firefox\firefox.exe" -contentproc --channel="1092.0.1971261927\476142880" -childID 1 -isForBrowser -prefsHandle 1384 -prefsLen 16342 -schedulerPrefs 0001,2 -parentBuildID 20180523123927 -greomni "Z:\task_1527081175\build\application\firefox\omni.ja" -appomni "Z:\task_1527081175\build\application\firefox\browser\omni.ja" -appdir "Z:\task_1527081175\build\application\firefox\browser" - 1092 "\\.\pipe\gecko-crash-server-pipe.1092" 2092 tab)
14:02:23     INFO -  ==> process 1092 launched child process 968 ("Z:\task_1527081175\build\application\firefox\firefox.exe" -contentproc --channel="1092.6.1070085269\1815973850" -childID 2 -isForBrowser -prefsHandle 2416 -prefsLen 16342 -schedulerPrefs 0001,2 -parentBuildID 20180523123927 -greomni "Z:\task_1527081175\build\application\firefox\omni.ja" -appomni "Z:\task_1527081175\build\application\firefox\browser\omni.ja" -appdir "Z:\task_1527081175\build\application\firefox\browser" - 1092 "\\.\pipe\gecko-crash-server-pipe.1092" 2424 tab)
14:02:23     INFO -  ==> process 1092 launched child process 1412 ("Z:\task_1527081175\build\application\firefox\firefox.exe" -contentproc --channel="1092.12.809879497\1252051193" -childID 3 -isForBrowser -prefsHandle 2668 -prefsLen 17652 -schedulerPrefs 0001,2 -parentBuildID 20180523123927 -greomni "Z:\task_1527081175\build\application\firefox\omni.ja" -appomni "Z:\task_1527081175\build\application\firefox\browser\omni.ja" -appdir "Z:\task_1527081175\build\application\firefox\browser" - 1092 "\\.\pipe\gecko-crash-server-pipe.1092" 2780 tab)
14:02:23     INFO -  zombiecheck | Checking for orphan process with PID: 968
14:02:23     INFO -  zombiecheck | Checking for orphan process with PID: 1412
14:02:23     INFO -  zombiecheck | Checking for orphan process with PID: 1372
14:02:23     INFO -  mozcrash Copy/paste: Z:\task_1527081175\build\win32-minidump_stackwalk.exe c:\users\task_1527081175\appdata\local\temp\tmphcoeg3.mozrunner\minidumps\030899e8-eb55-4230-b7e9-43577b6a87f0.dmp Z:\task_1527081175\build\symbols
14:02:32     INFO -  mozcrash Saved minidump as Z:\task_1527081175\build\blobber_upload_dir\030899e8-eb55-4230-b7e9-43577b6a87f0.dmp
14:02:32     INFO -  mozcrash Saved app info as Z:\task_1527081175\build\blobber_upload_dir\030899e8-eb55-4230-b7e9-43577b6a87f0.extra
14:02:32  WARNING -  PROCESS-CRASH | dom/file/tests/test_fileapi_slice_realFile_1.html | application crashed [@ MOZ_CrashOOL]
14:02:32     INFO -  Crash dump filename: c:\users\task_1527081175\appdata\local\temp\tmphcoeg3.mozrunner\minidumps\030899e8-eb55-4230-b7e9-43577b6a87f0.dmp
14:02:32     INFO -  Operating system: Windows NT
14:02:32     INFO -                    10.0.15063
14:02:32     INFO -  CPU: amd64
14:02:32     INFO -       family 6 model 63 stepping 2
14:02:32     INFO -       8 CPUs
14:02:32     INFO -  GPU: UNKNOWN
14:02:32     INFO -  Crash reason:  EXCEPTION_BREAKPOINT
14:02:32     INFO -  Crash address: 0x7fff0ca92214
14:02:32     INFO -  Process uptime: 28 seconds
14:02:32     INFO -  Thread 63 (crashed)
14:02:32     INFO -   0  mozglue.dll!MOZ_CrashOOL [Assertions.cpp:9f4d7874551d9104d13a2459256492506fa2b765 : 33 + 0x0]
14:02:32     INFO -      rax = 0x0000000000000000   rdx = 0x0000000000000000
14:02:32     INFO -      rcx = 0x00000000ffffffff   rbx = 0x00007ffef8dfb048
14:02:32     INFO -      rsi = 0x0000000000000001   rdi = 0x000000000000002e
14:02:32     INFO -      rbp = 0x000000a5729ff039   rsp = 0x000000a5729fee90
14:02:32     INFO -       r8 = 0x000000a5729fd768    r9 = 0x0000000000000000
14:02:32     INFO -      r10 = 0x0000000000000000   r11 = 0x000000a5729fed10
14:02:32     INFO -      r12 = 0x0000000000000000   r13 = 0x0000000000000000
14:02:32     INFO -      r14 = 0x0000023afcaf6f00   r15 = 0x0000023aff9352b0
14:02:32     INFO -      rip = 0x00007fff0ca92214
14:02:32     INFO -      Found by: given as instruction pointer in context
14:02:32     INFO -   1  xul.dll!nsAutoOwningThread::AssertCurrentThreadOwnsMe(char const *) [nsISupportsImpl.cpp:9f4d7874551d9104d13a2459256492506fa2b765 : 46 + 0x15]
14:02:32     INFO -      rbx = 0x00007ffef8dfb048   rbp = 0x000000a5729ff039
14:02:32     INFO -      rsp = 0x000000a5729feec0   r12 = 0x0000000000000000
14:02:32     INFO -      r13 = 0x0000000000000000   r14 = 0x0000023afcaf6f00
14:02:32     INFO -      r15 = 0x0000023aff9352b0   rip = 0x00007ffef3c6c54f
14:02:32     INFO -      Found by: call frame info
14:02:32     INFO -   2  xul.dll!mozilla::net::HttpChannelParent::Release() [HttpChannelParent.cpp:9f4d7874551d9104d13a2459256492506fa2b765 : 285 + 0x23]
14:02:32     INFO -      rbx = 0x00007ffef8dfb048   rbp = 0x000000a5729ff039
14:02:32     INFO -      rsp = 0x000000a5729feef0   r12 = 0x0000000000000000
14:02:32     INFO -      r13 = 0x0000000000000000   r14 = 0x0000023afcaf6f00
14:02:32     INFO -      r15 = 0x0000023aff9352b0   rip = 0x00007ffef413a1bf
14:02:32     INFO -      Found by: call frame info
14:02:32     INFO -   3  xul.dll!void std::_Func_impl_no_alloc<<lambda_3d72daabeec4e540a55137ada0758901>,void,__int64>::_Delete_this(bool) [functional:9f4d7874551d9104d13a2459256492506fa2b765 : 448 + 0x1d]
14:02:32     INFO -      rbx = 0x00007ffef8dfb048   rbp = 0x000000a5729ff039
14:02:32     INFO -      rsp = 0x000000a5729fef20   r12 = 0x0000000000000000
14:02:32     INFO -      r13 = 0x0000000000000000   r14 = 0x0000023afcaf6f00
14:02:32     INFO -      r15 = 0x0000023aff9352b0   rip = 0x00007ffef414ad11
14:02:32     INFO -      Found by: call frame info
14:02:32     INFO -   4  xul.dll!mozilla::`anonymous namespace'::AvailableEvent::`scalar deleting destructor'(unsigned int) + 0x52
14:02:32     INFO -      rbx = 0x00007ffef8dfb048   rbp = 0x000000a5729ff039
14:02:32     INFO -      rsp = 0x000000a5729fef50   r12 = 0x0000000000000000
14:02:32     INFO -      r13 = 0x0000000000000000   r14 = 0x0000023afcaf6f00
14:02:32     INFO -      r15 = 0x0000023aff9352b0   rip = 0x00007ffef3cb38de
14:02:32     INFO -      Found by: call frame info
14:02:32     INFO -   5  xul.dll!mozilla::Runnable::Release() [nsThreadUtils.cpp:9f4d7874551d9104d13a2459256492506fa2b765 : 50 + 0x4d]
14:02:32     INFO -      rbx = 0x00007ffef8dfb048   rbp = 0x000000a5729ff039
14:02:32     INFO -      rsp = 0x000000a5729fef80   r12 = 0x0000000000000000
14:02:32     INFO -      r13 = 0x0000000000000000   r14 = 0x0000023afcaf6f00
14:02:32     INFO -      r15 = 0x0000023aff9352b0   rip = 0x00007ffef3d1aea9
14:02:32     INFO -      Found by: call frame info
14:02:32     INFO -   6  xul.dll!nsThreadPool::Run() [nsThreadPool.cpp:9f4d7874551d9104d13a2459256492506fa2b765 : 231 + 0x21]
14:02:32     INFO -      rbx = 0x00007ffef8dfb048   rbp = 0x000000a5729ff039
14:02:32     INFO -      rsp = 0x000000a5729fefb0   r12 = 0x0000000000000000
14:02:32     INFO -      r13 = 0x0000000000000000   r14 = 0x0000023afcaf6f00
14:02:32     INFO -      r15 = 0x0000023aff9352b0   rip = 0x00007ffef3d1b652
14:02:32     INFO -      Found by: call frame info
14:02:32     INFO -   7  xul.dll!nsThread::ProcessNextEvent(bool,bool *) [nsThread.cpp:9f4d7874551d9104d13a2459256492506fa2b765 : 1090 + 0x14]
14:02:32     INFO -      rbx = 0x00007ffef8dfb048   rbp = 0x000000a5729ff039
14:02:32     INFO -      rsp = 0x000000a5729ff0a0   r12 = 0x0000000000000000
14:02:32     INFO -      r13 = 0x0000000000000000   r14 = 0x0000023afcaf6f00
14:02:32     INFO -      r15 = 0x0000023aff9352b0   rip = 0x00007ffef3d0c365
14:02:32     INFO -      Found by: call frame info
14:02:32     INFO -   8  xul.dll!NS_ProcessNextEvent(nsIThread *,bool) [nsThreadUtils.cpp:9f4d7874551d9104d13a2459256492506fa2b765 : 519 + 0x11]
14:02:32     INFO -      rbx = 0x00007ffef8dfb048   rbp = 0x000000a5729ff039
14:02:32     INFO -      rsp = 0x000000a5729ff800   r12 = 0x0000000000000000
14:02:32     INFO -      r13 = 0x0000000000000000   r14 = 0x0000023afcaf6f00
14:02:32     INFO -      r15 = 0x0000023aff9352b0   rip = 0x00007ffef3d1a1fb
14:02:32     INFO -      Found by: call frame info
14:02:32     INFO -   9  xul.dll!mozilla::ipc::MessagePumpForNonMainThreads::Run(base::MessagePump::Delegate *) [MessagePump.cpp:9f4d7874551d9104d13a2459256492506fa2b765 : 364 + 0xa]
14:02:32     INFO -      rbx = 0x00007ffef8dfb048   rbp = 0x000000a5729ff039
14:02:32     INFO -      rsp = 0x000000a5729ff840   r12 = 0x0000000000000000
14:02:32     INFO -      r13 = 0x0000000000000000   r14 = 0x0000023afcaf6f00
14:02:32     INFO -      r15 = 0x0000023aff9352b0   rip = 0x00007ffef4283f92
14:02:32     INFO -      Found by: call frame info
14:02:32     INFO -  10  xul.dll!MessageLoop::RunHandler() [message_loop.cc:9f4d7874551d9104d13a2459256492506fa2b765 : 319 + 0x5]
14:02:32     INFO -      rbx = 0x00007ffef8dfb048   rbp = 0x000000a5729ff039
14:02:32     INFO -      rsp = 0x000000a5729ff880   r12 = 0x0000000000000000
14:02:32     INFO -      r13 = 0x0000000000000000   r14 = 0x0000023afcaf6f00
14:02:32     INFO -      r15 = 0x0000023aff9352b0   rip = 0x00007ffef4247a02
14:02:32     INFO -      Found by: call frame info
14:02:32     INFO -  11  xul.dll!MessageLoop::Run() [message_loop.cc:9f4d7874551d9104d13a2459256492506fa2b765 : 299 + 0x8]
14:02:32     INFO -      rbx = 0x00007ffef8dfb048   rbp = 0x000000a5729ff039
14:02:32     INFO -      rsp = 0x000000a5729ff8b0   r12 = 0x0000000000000000
14:02:32     INFO -      r13 = 0x0000000000000000   r14 = 0x0000023afcaf6f00
14:02:32     INFO -      r15 = 0x0000023aff9352b0   rip = 0x00007ffef42475e2
14:02:32     INFO -      Found by: call frame info
14:02:32     INFO -  12  xul.dll!nsThread::ThreadFunc(void *) [nsThread.cpp:9f4d7874551d9104d13a2459256492506fa2b765 : 425 + 0x11]
14:02:32     INFO -      rbx = 0x00007ffef8dfb048   rbp = 0x000000a5729ff039
14:02:32     INFO -      rsp = 0x000000a5729ff900   r12 = 0x0000000000000000
14:02:32     INFO -      r13 = 0x0000000000000000   r14 = 0x0000023afcaf6f00
14:02:32     INFO -      r15 = 0x0000023aff9352b0   rip = 0x00007ffef3d1374a
14:02:32     INFO -      Found by: call frame info
14:02:32     INFO -  13  nss3.dll!PR_NativeRunThread [pruthr.c:9f4d7874551d9104d13a2459256492506fa2b765 : 397 + 0x7]
14:02:32     INFO -      rbx = 0x00007ffef8dfb048   rbp = 0x000000a5729ff039
14:02:32     INFO -      rsp = 0x000000a5729ff950   r12 = 0x0000000000000000
14:02:32     INFO -      r13 = 0x0000000000000000   r14 = 0x0000023afcaf6f00
14:02:32     INFO -      r15 = 0x0000023aff9352b0   rip = 0x00007fff09c77aea
14:02:32     INFO -      Found by: call frame info
14:02:32     INFO -  14  nss3.dll!static unsigned int pr_root(void *) [w95thred.c:9f4d7874551d9104d13a2459256492506fa2b765 : 137 + 0x6]
14:02:32     INFO -      rbx = 0x00007ffef8dfb048   rbp = 0x000000a5729ff039
14:02:32     INFO -      rsp = 0x000000a5729ff980   r12 = 0x0000000000000000
14:02:32     INFO -      r13 = 0x0000000000000000   r14 = 0x0000023afcaf6f00
14:02:32     INFO -      r15 = 0x0000023aff9352b0   rip = 0x00007fff09c6924a
14:02:32     INFO -      Found by: call frame info
14:02:32     INFO -  15  ucrtbase.dll!RtlGetFullPathName_Ustr + 0x949
14:02:32     INFO -      rbx = 0x00007ffef8dfb048   rbp = 0x000000a5729ff039
14:02:32     INFO -      rsp = 0x000000a5729ff9b0   r12 = 0x0000000000000000
14:02:32     INFO -      r13 = 0x0000000000000000   r14 = 0x0000023afcaf6f00
14:02:32     INFO -      r15 = 0x0000023aff9352b0   rip = 0x00007fff1af30369
14:02:32     INFO -      Found by: call frame info

Comment 1

[Intermittent Failures Robot]

19 failures in 109 pushes (0.174 failures/push) were associated with this bug yesterday.    

Repository breakdown:
* try: 13
* mozilla-central: 3
* autoland: 2
* mozilla-inbound: 1

Platform breakdown:
* windows10-64: 11
* windows7-32: 6
* windows10-64-qr: 1
* linux32: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1463927&startday=2018-05-24&endday=2018-05-24&tree=trunk

Comment 3

[Intermittent Failures Robot]

35 failures in 632 pushes (0.055 failures/push) were associated with this bug in the last 7 days.   

** This failure happened more than 30 times this week! Resolving this bug is a high priority. **

** Try to resolve this bug as soon as possible. If unresolved for 2 weeks, the affected test(s) may be disabled. **  

Repository breakdown:
* try: 15
* autoland: 10
* mozilla-inbound: 6
* mozilla-central: 4

Platform breakdown:
* windows10-64: 22
* windows7-32: 9
* windows10-64-qr: 2
* linux32: 2

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1463927&startday=2018-05-21&endday=2018-05-27&tree=trunk

Comment 4

[Intermittent Failures Robot]

19 failures in 655 pushes (0.029 failures/push) were associated with this bug in the last 7 days.    

Repository breakdown:
* autoland: 13
* mozilla-inbound: 4
* try: 1
* mozilla-central: 1

Platform breakdown:
* windows10-64: 15
* windows7-32: 3
* osx-10-10: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1463927&startday=2018-05-28&endday=2018-06-03&tree=trunk

Comment 5

[Firefox Bug Husbandry Bot]

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