1482580 - Intermittent PROCESS-CRASH | Main app process exited normally | application crashed [@ xul.dll + 0x2ea0401] | After test_mediarecorder_fires_start_event_once_when_erroring.html failures

Status: RESOLVED INCOMPLETE

(Core :: Audio/Video: Playback, defect, P5)

Description

[Treeherder Bug Filer]

Filed by: btara [at] mozilla.com

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

https://queue.taskcluster.net/v1/task/JehBAn5qR8G-AfzyLk827Q/runs/0/artifacts/public/logs/live_backing.log

23:31:57     INFO - TEST-START | dom/media/test/test_mediarecorder_fires_start_event_once_when_erroring.html
23:31:57     INFO - GECKO(2412) | MEMORY STAT | vsize 1558MB | vsizeMaxContiguous 131079152MB | residentFast 134MB | heapAllocated 17MB
23:31:57     INFO - Buffered messages logged at 23:31:57
23:31:57     INFO - TEST-PASS | dom/media/test/test_mediarecorder_fires_start_event_once_when_erroring.html | One start event should be fired by the recorder 
23:31:57     INFO - TEST-PASS | dom/media/test/test_mediarecorder_fires_start_event_once_when_erroring.html | One start event should have been fired by the recorder 
23:31:57     INFO - Buffered messages finished
23:31:57     INFO - TEST-UNEXPECTED-ERROR | dom/media/test/test_mediarecorder_fires_start_event_once_when_erroring.html | unexpected-crash-dump-found - This test left crash dumps behind, but we weren't expecting it to!
23:31:57     INFO - Found unexpected crash dump file C:\Users\task_1533943039\AppData\Local\Temp\tmpxvo58g.mozrunner\minidumps\e78b37aa-63cf-49a8-89a6-6d8d9603887f.dmp.
23:31:57     INFO - TEST-UNEXPECTED-CRASH | dom/media/test/test_mediarecorder_fires_start_event_once_when_erroring.html | Finished in 72ms
23:31:57     INFO - {u'runtime': 72}
23:31:57     INFO - TEST-INFO took 72ms
23:31:57     INFO - TEST-START | dom/media/test/test_mediarecorder_getencodeddata.html
23:31:57     INFO - GECKO(2412) | ###!!! [Parent][MessageChannel] Error: (msgtype=0x170089,name=PBrowser::Msg_UpdateNativeWindowHandle) Channel error: cannot send/recv
23:31:57     INFO - GECKO(2412) | ###!!! [Parent][MessageChannel] Error: (msgtype=0x17007C,name=PBrowser::Msg_Destroy) Channel error: cannot send/recv
23:31:57     INFO - GECKO(2412) | A content process crashed and MOZ_CRASHREPORTER_SHUTDOWN is set, shutting down
23:31:58     INFO - GECKO(2412) | [Parent 2412, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346
23:31:58     INFO - GECKO(2412) | [Parent 2412, Gecko_IOThread] WARNING: file z:/build/build/src/ipc/chromium/src/base/process_util_win.cc, line 188
23:31:58     INFO - GECKO(2412) | [Child 892, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346
23:31:58     INFO - GECKO(2412) | [Parent 2412, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346
23:31:58     INFO - GECKO(2412) | [Child 7516, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346
23:31:58     INFO - GECKO(2412) | [Child 7516, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346
23:31:58     INFO - GECKO(2412) | [Parent 2412, Gecko_IOThread] WARNING: file z:/build/build/src/ipc/chromium/src/base/process_util_win.cc, line 188
23:31:58     INFO - GECKO(2412) | 1533943918223	Marionette	DEBUG	Received observer notification xpcom-will-shutdown
23:31:58     INFO - GECKO(2412) | 1533943918223	Marionette	INFO	Stopped listening on port 2828
23:31:58     INFO - GECKO(2412) | 1533943918223	Marionette	DEBUG	Remote service is inactive
23:31:58     INFO - TEST-INFO | Main app process: exit 0
23:31:58     INFO - runtests.py | Application ran for: 0:05:30.176000
23:31:58     INFO - zombiecheck | Reading PID log: c:\users\task_1533943039\appdata\local\temp\tmppagh16pidlog
23:31:58     INFO - ==> process 2412 launched child process 7516 ("Z:\task_1533943039\build\application\firefox\firefox.exe" -contentproc --channel="2412.0.1069930508\1421504404" -childID 1 -isForBrowser -prefsHandle 2204 -prefMapHandle 2464 -prefsLen 1 -prefMapSize 183601 -schedulerPrefs 0001,2 -parentBuildID 20180810215321 -greomni "Z:\task_1533943039\build\application\firefox\omni.ja" -appomni "Z:\task_1533943039\build\application\firefox\browser\omni.ja" -appdir "Z:\task_1533943039\build\application\firefox\browser" - 2412 "\\.\pipe\gecko-crash-server-pipe.2412" 2288 tab)
23:31:58     INFO - ==> process 2412 launched child process 6712 ("Z:\task_1533943039\build\application\firefox\firefox.exe" -contentproc --channel="2412.6.972157721\301704443" -childID 2 -isForBrowser -prefsHandle 2540 -prefMapHandle 1756 -prefsLen 41 -prefMapSize 183601 -schedulerPrefs 0001,2 -parentBuildID 20180810215321 -greomni "Z:\task_1533943039\build\application\firefox\omni.ja" -appomni "Z:\task_1533943039\build\application\firefox\browser\omni.ja" -appdir "Z:\task_1533943039\build\application\firefox\browser" - 2412 "\\.\pipe\gecko-crash-server-pipe.2412" 2128 tab)
23:31:58     INFO - ==> process 2412 launched child process 892 ("Z:\task_1533943039\build\application\firefox\firefox.exe" -contentproc --channel="2412.12.253796776\1793284799" -childID 3 -isForBrowser -prefsHandle 3148 -prefMapHandle 2488 -prefsLen 1340 -prefMapSize 183601 -schedulerPrefs 0001,2 -parentBuildID 20180810215321 -greomni "Z:\task_1533943039\build\application\firefox\omni.ja" -appomni "Z:\task_1533943039\build\application\firefox\browser\omni.ja" -appdir "Z:\task_1533943039\build\application\firefox\browser" - 2412 "\\.\pipe\gecko-crash-server-pipe.2412" 3160 tab)
23:31:58     INFO - zombiecheck | Checking for orphan process with PID: 6712
23:31:58     INFO - zombiecheck | Checking for orphan process with PID: 892
23:31:58     INFO - zombiecheck | Checking for orphan process with PID: 7516
23:31:58     INFO - mozcrash Downloading symbols from: https://queue.taskcluster.net/v1/task/IDlXrCpgQy-GHMBv8V9R8Q/artifacts/public/build/target.crashreporter-symbols.zip
23:32:08     INFO - mozcrash Copy/paste: Z:\task_1533943039\build\win32-minidump_stackwalk.exe c:\users\task_1533943039\appdata\local\temp\tmpxvo58g.mozrunner\minidumps\e78b37aa-63cf-49a8-89a6-6d8d9603887f.dmp c:\users\task_1533943039\appdata\local\temp\tmp3yquak
23:32:10     INFO - mozcrash Saved minidump as Z:\task_1533943039\build\blobber_upload_dir\e78b37aa-63cf-49a8-89a6-6d8d9603887f.dmp
23:32:10     INFO - mozcrash Saved app info as Z:\task_1533943039\build\blobber_upload_dir\e78b37aa-63cf-49a8-89a6-6d8d9603887f.extra
23:32:10     INFO - PROCESS-CRASH | Main app process exited normally | application crashed [@ xul.dll + 0x2ea0401]
23:32:10     INFO - Crash dump filename: c:\users\task_1533943039\appdata\local\temp\tmpxvo58g.mozrunner\minidumps\e78b37aa-63cf-49a8-89a6-6d8d9603887f.dmp
23:32:10     INFO - Operating system: Windows NT
23:32:10     INFO -                   10.0.15063 
23:32:10     INFO - CPU: amd64
23:32:10     INFO -      family 6 model 63 stepping 2
23:32:10     INFO -      8 CPUs
23:32:10     INFO - 
23:32:10     INFO - GPU: UNKNOWN
23:32:10     INFO - 
23:32:10     INFO - Crash reason:  EXCEPTION_BREAKPOINT
23:32:10     INFO - Crash address: 0x7ffae7040401
23:32:10     INFO - Assertion: Unknown assertion type 0x00000000
23:32:10     INFO - Process uptime: 328 seconds
23:32:10     INFO - 
23:32:10     INFO - Thread 47 (crashed)
23:32:10     INFO -  0  xul.dll + 0x2ea0401
23:32:10     INFO -     rax = 0x00007ffae8ece20a   rdx = 0x0000000000000001
23:32:10     INFO -     rcx = 0x00007ffb0b0039b0   rbx = 0x1f7d4b25f7e0e9ed
23:32:10     INFO -     rsi = 0x00000256f32fe000   rdi = 0x00000256f31fef20
23:32:10     INFO -     rbp = 0x08766c07b5250d25   rsp = 0x00000088897bebe0
23:32:10     INFO -      r8 = 0x0000000000000000    r9 = 0x0000000000000000
23:32:10     INFO -     r10 = 0x0000000000000002   r11 = 0x00000256edd97980
23:32:10     INFO -     r12 = 0x00000256f2466800   r13 = 0x00000256f3106d60
23:32:10     INFO -     r14 = 0x00000256eddae228   r15 = 0x00000256f32f9180
23:32:10     INFO -     rip = 0x00007ffae7040401
23:32:10     INFO -     Found by: given as instruction pointer in context
23:32:10     INFO -  1  mozglue.dll!static void * Allocator<MozJemallocBase>::malloc(unsigned __int64) [malloc_decls.h:babb78f979b24ef5a6cbed39d6d4e9e2239814da : 37 + 0x18]
23:32:10     INFO -     rbp = 0x08766c07b5250d25   rsp = 0x00000088897bec20
23:32:10     INFO -     rip = 0x00007ffb0afde14d
23:32:10     INFO -     Found by: stack scanning
23:32:10     INFO -  2  0x256f3151eb0
23:32:10     INFO -     rbp = 0x08766c07b5250d25   rsp = 0x00000088897bec60
23:32:10     INFO -     rip = 0x00000256f3151eb0
23:32:10     INFO -     Found by: call frame info
23:32:10     INFO - 
23:32:10     INFO - Thread 0
23:32:10     INFO -  0  ntdll.dll!NtAlpcSendWaitReceivePort + 0x14
23:32:10     INFO -     rax = 0x00000256e83e7708   rdx = 0x00007ffb0bce0000
23:32:10     INFO -     rcx = 0x0000000000000001   rbx = 0x00000256ed74a510
23:32:10     INFO -     rsi = 0x00000256f5b3cca0   rdi = 0x00000256f263b320
23:32:10     INFO -     rbp = 0x00000256f263b448   rsp = 0x0000008824bfc118
23:32:10     INFO -      r8 = 0x0000000000000000    r9 = 0x0000008824bfcb18
23:32:10     INFO -     r10 = 0x000000000000db40   r11 = 0x00000000e7ffffff
23:32:10     INFO -     r12 = 0x0000000000000000   r13 = 0x0000000000000000
23:32:10     INFO -     r14 = 0x0000000000000000   r15 = 0x00007ffb156244b4
23:32:10     INFO -     rip = 0x00007ffb164064c4
23:32:10     INFO -     Found by: given as instruction pointer in context
23:32:10     INFO -  1  rpcrt4.dll!TppAlpcpExecuteCallback + 0x192
23:32:10     INFO -     rbx = 0x00000256ed74a510   rbp = 0x00000256f263b448
23:32:10     INFO -     rsp = 0x0000008824bfc120   r12 = 0x0000000000000000
23:32:10     INFO -     r13 = 0x0000000000000000   r14 = 0x0000000000000000
23:32:10     INFO -     r15 = 0x00007ffb156244b4   rip = 0x00007ffb15572bf2
23:32:10     INFO -     Found by: call frame info
23:32:10     INFO -  2  rpcrt4.dll!RtlQueryProcessLockInformation + 0x171
23:32:10     INFO -     rbx = 0x00000256ed74a510   rbp = 0x00000256f263b448
23:32:10     INFO -     rsp = 0x0000008824bfc1d0   r12 = 0x0000000000000000
23:32:10     INFO -     r13 = 0x0000000000000000   r14 = 0x0000000000000000
23:32:10     INFO -     r15 = 0x00007ffb156244b4   rip = 0x00007ffb15609b61
23:32:10     INFO -     Found by: call frame info
23:32:10     INFO -  3  0x256f2637410
23:32:10     INFO -     rbx = 0x00000256ed74a510   rbp = 0x00000256f263b448
23:32:10     INFO -     rsp = 0x0000008824bfc270   r12 = 0x0000000000000000
23:32:10     INFO -     r13 = 0x0000000000000000   r14 = 0x0000000000000000
23:32:10     INFO -     r15 = 0x00007ffb156244b4   rip = 0x00000256f2637410
23:32:10     INFO -     Found by: call frame info
23:32:10     INFO -  4  AudioSes.dll!output_l + 0x334
23:32:10     INFO -     rbp = 0x00000256f263b448   rsp = 0x0000008824bfc280
23:32:10     INFO -     rip = 0x00007ffb0c1bc820
23:32:10     INFO -     Found by: stack scanning
23:32:10     INFO -  5  0x256f263b320
23:32:10     INFO -     rbp = 0x00000256f263b448   rsp = 0x0000008824bfc540
23:32:10     INFO -     rip = 0x00000256f263b320

Comment 1

[Intermittent Failures Robot]

1 failures in 2579 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:
* mozilla-inbound: 1

Platform breakdown:
* windows10-64: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1482580&startday=2018-08-06&endday=2018-08-12&tree=all

Comment 2

[Firefox Bug Husbandry Bot]

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