Closed Bug 1597536 Opened 5 years ago Closed 2 years ago

Intermittent PROCESS-CRASH | Main app process exited normally | application crashed [@ js::WeakMap<js::HeapPtr<JSObject *>,js::HeapPtr<JS::Value> >::markEntry(js::GCMarker *,js::HeapPtr<JSObject *> &,js::HeapPtr<JS::Value> &)]

Categories

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

defect

Tracking

()

RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: crash, intermittent-failure)

Crash Data

Filed by: rgurzau [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=276795702&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/IKEoQpVxQJyLwWr29x0j9A/runs/0/artifacts/public/logs/live_backing.log


[task 2019-11-18T18:07:40.315Z] 18:07:40 INFO - TEST-START | dom/media/test/test_invalid_reject_play.html
[task 2019-11-18T18:07:40.398Z] 18:07:40 INFO - GECKO(5816) | [Child 3820, MediaDecoderStateMachine #1] WARNING: Decoder=13779b0 Decode error: NS_ERROR_DOM_MEDIA_FATAL_ERR (0x806e0005) - RefPtr<MediaDataDecoder::DecodePromise> __thiscall mozilla::OpusDataDecoder::ProcessDecode(mozilla::MediaRawData *): Discard padding larger than packet: file z:/task_1574091729/build/src/dom/media/MediaDecoderStateMachine.cpp, line 3328
[task 2019-11-18T18:07:40.413Z] 18:07:40 INFO - GECKO(5816) | [Child 3820, MediaDecoderStateMachine #1] WARNING: Decoder=13779b0 Decode error: NS_ERROR_DOM_MEDIA_FATAL_ERR (0x806e0005) - RefPtr<MediaDataDecoder::DecodePromise> __thiscall mozilla::OpusDataDecoder::ProcessDecode(mozilla::MediaRawData *): Discard padding larger than packet: file z:/task_1574091729/build/src/dom/media/MediaDecoderStateMachine.cpp, line 3328
[task 2019-11-18T18:07:40.416Z] 18:07:40 INFO - GECKO(5816) | [Child 3820, MediaDecoderStateMachine #1] WARNING: Decoder=1377b40 Decode error: NS_ERROR_DOM_MEDIA_FATAL_ERR (0x806e0005) - RefPtr<MediaDataDecoder::DecodePromise> __thiscall mozilla::OpusDataDecoder::ProcessDecode(mozilla::MediaRawData *): Discard padding larger than packet: file z:/task_1574091729/build/src/dom/media/MediaDecoderStateMachine.cpp, line 3328
[task 2019-11-18T18:07:40.416Z] 18:07:40 INFO - GECKO(5816) | [Child 3820, MediaDecoderStateMachine #1] WARNING: Decoder=1377b40 Decode error: NS_ERROR_DOM_MEDIA_FATAL_ERR (0x806e0005) - RefPtr<MediaDataDecoder::DecodePromise> __thiscall mozilla::OpusDataDecoder::ProcessDecode(mozilla::MediaRawData *): Discard padding larger than packet: file z:/task_1574091729/build/src/dom/media/MediaDecoderStateMachine.cpp, line 3328
[task 2019-11-18T18:07:40.443Z] 18:07:40 INFO - GECKO(5816) | [Child 3820, MediaDecoderStateMachine #1] WARNING: Decoder=1377b40 Decode error: NS_ERROR_DOM_MEDIA_FATAL_ERR (0x806e0005) - RefPtr<MediaDataDecoder::DecodePromise> __thiscall mozilla::OpusDataDecoder::ProcessDecode(mozilla::MediaRawData *): Discard padding larger than packet: file z:/task_1574091729/build/src/dom/media/MediaDecoderStateMachine.cpp, line 3328
[task 2019-11-18T18:07:40.443Z] 18:07:40 INFO - GECKO(5816) | [Child 3820, MediaDecoderStateMachine #1] WARNING: Decoder=1377cd0 Decode error: NS_ERROR_DOM_MEDIA_FATAL_ERR (0x806e0005) - RefPtr<MediaDataDecoder::DecodePromise> __thiscall mozilla::OpusDataDecoder::ProcessDecode(mozilla::MediaRawData *): Discard padding larger than packet: file z:/task_1574091729/build/src/dom/media/MediaDecoderStateMachine.cpp, line 3328
[task 2019-11-18T18:07:40.446Z] 18:07:40 INFO - GECKO(5816) | [
[task 2019-11-18T18:07:40.446Z] 18:07:40 INFO - GECKO(5816) | Child 3
[task 2019-11-18T18:07:40.446Z] 18:07:40 INFO - GECKO(5816) | 820, MediaDecoderStateMachine #1] WARNING: Decoder=1377cd0 D
[task 2019-11-18T18:07:40.446Z] 18:07:40 INFO - GECKO(5816) | ecode error: NS_ERROR_DOM_MEDIA_FATAL_ERR (0x806e0005) - RefPtr<MediaDataDecoder::DecodePromise> __thiscall mozilla::OpusDataDecoder::ProcessDecode(mozilla::MediaRawData *): Discard padding larger than packet: file z:/task_1574091729/build/src/dom/media/MediaDecoderStateMachine.cpp, line 3328
[task 2019-11-18T18:07:40.461Z] 18:07:40 INFO - GECKO(5816) | [Child 3820, MediaDecoderStateMachine #1] WARNING: Decoder=1377cd0 Decode error: NS_ERROR_DOM_MEDIA_FATAL_ERR (0x806e0005) - RefPtr<MediaDataDecoder::DecodePromise> __thiscall mozilla::OpusDataDecoder::ProcessDecode(mozilla::MediaRawData *): Discard padding larger than packet: file z:/task_1574091729/build/src/dom/media/MediaDecoderStateMachine.cpp, line 3328
[task 2019-11-18T18:07:40.468Z] 18:07:40 INFO - GECKO(5816) | [Child 3820, MediaDecoderStateMachine #1] WARNING: Decoder=1377e60 Decode error: NS_ERROR_DOM_MEDIA_FATAL_ERR (0x806e0005) - RefPtr<MediaDataDecoder::DecodePromise> __thiscall mozilla::OpusDataDecoder::ProcessDecode(mozilla::MediaRawData *): Discard padding on interstitial packet: file z:/task_1574091729/build/src/dom/media/MediaDecoderStateMachine.cpp, line 3328
[task 2019-11-18T18:07:40.468Z] 18:07:40 INFO - GECKO(5816) | [Child 3820, MediaDecoderStateMachine #1] WARNING
[task 2019-11-18T18:07:40.468Z] 18:07:40 INFO - GECKO(5816) | : Decoder=13779b0 Decode error: NS_ERROR_DOM_MEDIA_FATAL_ERR (0x806e0005
[task 2019-11-18T18:07:40.468Z] 18:07:40 INFO - GECKO(5816) | ) - RefPtr<MediaDataDecoder::DecodePromi
[task 2019-11-18T18:07:40.471Z] 18:07:40 INFO - GECKO(5816) | se> __thiscall mozilla::OpusDataDecoder::ProcessDecode(mozilla::MediaRawData *): Discard padding larger than packet: file z:/task_1574091729/build/src/dom/media/MediaDecoderStateMachine.cpp, line 3328
[task 2019-11-18T18:07:40.471Z] 18:07:40 INFO - GECKO(5816) | [Child 3820, MediaDecoderStateMachine #1] WARNING: Decoder=1377e60 Decode error: NS_ERROR_DOM_MEDIA_FATAL_ERR (0x806e0005) - RefPtr<MediaDataDecoder::DecodePromise> __thiscall mozilla::OpusDataDecoder::ProcessDecode(mozilla::MediaRawData *): Discard padding on interstitial packet: file z:/task_1574091729/build/src/dom/media/MediaDecoderStateMachine.cpp, line 3328
[task 2019-11-18T18:07:40.490Z] 18:07:40 INFO - GECKO(5816) | [Child 3820, MediaDecoderStateMachine #1] WARNING: Decoder=1377e60 Decode error: NS_ERROR_DOM_MEDIA_FATAL_ERR (0x806e0005) - RefPtr<MediaDataDecoder::DecodePromise> __thiscall mozilla::OpusDataDecoder::ProcessDecode(mozilla::MediaRawData *): Discard padding on interstitial packet: file z:/task_1574091729/build/src/dom/media/MediaDecoderStateMachine.cpp, line 3328
[task 2019-11-18T18:07:40.497Z] 18:07:40 INFO - GECKO(5816) | [Child 3820, MediaDecoderStateMachine #1] WARNING: Decoder=1377b40 Decode error: NS_ERROR_DOM_MEDIA_FATAL_ERR (0x806e0005) - RefPtr<MediaDataDecoder::DecodePromise> __thiscall mozilla::OpusDataDecoder::ProcessDecode(mozilla::MediaRawData *): Discard padding larger than packet: file z:/task_1574091729/build/src/dom/media/MediaDecoderStateMachine.cpp, line 3328
[task 2019-11-18T18:07:40.604Z] 18:07:40 INFO - GECKO(5816) | [Child 3820, MediaDecoderStateMachine #1] WARNING: Decoder=1377b40 Decode error: NS_ERROR_DOM_MEDIA_FATAL_ERR (0x806e0005) - RefPtr<MediaDataDecoder::DecodePromise> __thiscall mozilla::OpusDataDecoder::ProcessDecode(mozilla::MediaRawData *): Discard padding larger than packet: file z:/task_1574091729/build/src/dom/media/MediaDecoderStateMachine.cpp, line 3328
[task 2019-11-18T18:07:40.644Z] 18:07:40 ERROR - GECKO(5816) | [Child 3820, MediaDecoderStateMachA content process crashed and MOZ_CRASHREPORTER_SHUTDOWN is set, shutting down
[task 2019-11-18T18:07:40.751Z] 18:07:40 INFO - GECKO(5816) | [Parent 5992, Gecko_IOThread] WARNING: file z:/task_1574091729/build/src/ipc/chromium/src/base/process_util_win.cc, line 160
[task 2019-11-18T18:07:40.950Z] 18:07:40 INFO - GECKO(5816) | 1574100460939 Marionette TRACE Received observer notification xpcom-will-shutdown
[task 2019-11-18T18:07:40.950Z] 18:07:40 INFO - GECKO(5816) | 1574100460939 Marionette INFO Stopped listening on port 2828
[task 2019-11-18T18:07:40.950Z] 18:07:40 INFO - GECKO(5816) | 1574100460939 Marionette DEBUG Remote service is inactive
[task 2019-11-18T18:07:41.129Z] 18:07:41 INFO - TEST-INFO | Main app process: exit 0
[task 2019-11-18T18:07:41.129Z] 18:07:41 INFO - runtests.py | Application ran for: 0:05:08.128000
[task 2019-11-18T18:07:41.129Z] 18:07:41 INFO - zombiecheck | Reading PID log: c:\users\task_1574099084\appdata\local\temp\tmpncpcihpidlog
[task 2019-11-18T18:07:41.129Z] 18:07:41 INFO - ==> process 5992 launched child process 3240 ("Z:\task_1574099084\build\application\firefox\firefox.exe" -contentproc --channel="5992.0.1541932342\352280949" -childID 1 -isForBrowser -prefsHandle 1600 -prefMapHandle 1588 -prefsLen 1 -prefMapSize 223574 -parentBuildID 20191118153125 -greomni "Z:\task_1574099084\build\application\firefox\omni.ja" -appomni "Z:\task_1574099084\build\application\firefox\browser\omni.ja" -appdir "Z:\task_1574099084\build\application\firefox\browser" - 5992 "\.\pipe\gecko-crash-server-pipe.5992" 2168 tab)
[task 2019-11-18T18:07:41.130Z] 18:07:41 INFO - ==> process 5992 launched child process 5824 ("Z:\task_1574099084\build\application\firefox\firefox.exe" -contentproc --channel="5992.6.616216331\1877091668" -childID 2 -isForBrowser -prefsHandle 2364 -prefMapHandle 1912 -prefsLen 463 -prefMapSize 223574 -parentBuildID 20191118153125 -greomni "Z:\task_1574099084\build\application\firefox\omni.ja" -appomni "Z:\task_1574099084\build\application\firefox\browser\omni.ja" -appdir "Z:\task_1574099084\build\application\firefox\browser" - 5992 "\.\pipe\gecko-crash-server-pipe.5992" 2376 tab)
[task 2019-11-18T18:07:41.130Z] 18:07:41 INFO - ==> process 5992 launched child process 3820 ("Z:\task_1574099084\build\application\firefox\firefox.exe" -contentproc --channel="5992.12.2037773341\1727035396" -childID 3 -isForBrowser -prefsHandle 2588 -prefMapHandle 2584 -prefsLen 524 -prefMapSize 223574 -parentBuildID 20191118153125 -greomni "Z:\task_1574099084\build\application\firefox\omni.ja" -appomni "Z:\task_1574099084\build\application\firefox\browser\omni.ja" -appdir "Z:\task_1574099084\build\application\firefox\browser" - 5992 "\.\pipe\gecko-crash-server-pipe.5992" 2600 tab)
[task 2019-11-18T18:07:41.130Z] 18:07:41 INFO - zombiecheck | Checking for orphan process with PID: 3240
[task 2019-11-18T18:07:41.131Z] 18:07:41 INFO - zombiecheck | Checking for orphan process with PID: 5824
[task 2019-11-18T18:07:41.131Z] 18:07:41 INFO - zombiecheck | Checking for orphan process with PID: 3820
[task 2019-11-18T18:07:41.131Z] 18:07:41 INFO - mozcrash Downloading symbols from: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/ZnVCe4PTQ4WEtXgsReJvQw/artifacts/public/build/target.crashreporter-symbols.zip
[task 2019-11-18T18:07:45.792Z] 18:07:45 INFO - mozcrash Copy/paste: Z:\task_1574099084\build\win32-minidump_stackwalk.exe c:\users\task_1574099084\appdata\local\temp\tmppubsx1.mozrunner\minidumps\66bdfc9b-2d25-47a8-9e12-a66feb3de8c3.dmp c:\users\task_1574099084\appdata\local\temp\tmp_2ubdy
[task 2019-11-18T18:07:59.707Z] 18:07:59 INFO - mozcrash Saved minidump as Z:\task_1574099084\build\blobber_upload_dir\66bdfc9b-2d25-47a8-9e12-a66feb3de8c3.dmp
[task 2019-11-18T18:07:59.710Z] 18:07:59 INFO - mozcrash Saved app info as Z:\task_1574099084\build\blobber_upload_dir\66bdfc9b-2d25-47a8-9e12-a66feb3de8c3.extra
[task 2019-11-18T18:07:59.808Z] 18:07:59 INFO - PROCESS-CRASH | Main app process exited normally | application crashed [@ js::WeakMap<js::HeapPtr<JSObject *>,js::HeapPtr<JS::Value> >::markEntry(js::GCMarker *,js::HeapPtr<JSObject *> &,js::HeapPtr<JS::Value> &)]
[task 2019-11-18T18:07:59.808Z] 18:07:59 INFO - Crash dump filename: c:\users\task_1574099084\appdata\local\temp\tmppubsx1.mozrunner\minidumps\66bdfc9b-2d25-47a8-9e12-a66feb3de8c3.dmp
[task 2019-11-18T18:07:59.808Z] 18:07:59 INFO - Operating system: Windows NT
[task 2019-11-18T18:07:59.808Z] 18:07:59 INFO - 6.1.7601 Service Pack 1
[task 2019-11-18T18:07:59.808Z] 18:07:59 INFO - CPU: x86
[task 2019-11-18T18:07:59.808Z] 18:07:59 INFO - GenuineIntel family 6 model 63 stepping 2
[task 2019-11-18T18:07:59.808Z] 18:07:59 INFO - 8 CPUs
[task 2019-11-18T18:07:59.808Z] 18:07:59 INFO -
[task 2019-11-18T18:07:59.808Z] 18:07:59 INFO - GPU: UNKNOWN
[task 2019-11-18T18:07:59.808Z] 18:07:59 INFO -
[task 2019-11-18T18:07:59.808Z] 18:07:59 INFO - Crash reason: EXCEPTION_ACCESS_VIOLATION_READ
[task 2019-11-18T18:07:59.808Z] 18:07:59 INFO - Crash address: 0xffffc
[task 2019-11-18T18:07:59.808Z] 18:07:59 INFO - Assertion: Unknown assertion type 0x00000000
[task 2019-11-18T18:07:59.808Z] 18:07:59 INFO - Process uptime: 306 seconds
[task 2019-11-18T18:07:59.808Z] 18:07:59 INFO -
[task 2019-11-18T18:07:59.808Z] 18:07:59 INFO - Thread 0 (crashed)
[task 2019-11-18T18:07:59.808Z] 18:07:59 INFO - 0 xul.dll!js::WeakMap<js::HeapPtr<JSObject *>,js::HeapPtr<JS::Value> >::markEntry(js::GCMarker *,js::HeapPtr<JSObject *> &,js::HeapPtr<JS::Value> &) [WeakMap-inl.h:a52581ac4a9dd02c9ae085f428db1e1579a60513 : 131 + 0x1f]
[task 2019-11-18T18:07:59.808Z] 18:07:59 INFO - eip = 0x5affac35 esp = 0x0025ec60 ebp = 0x0025ec7c ebx = 0x1d773118
[task 2019-11-18T18:07:59.808Z] 18:07:59 INFO - esi = 0x00000000 edi = 0x08be77c0 eax = 0x00000000 ecx = 0x0838bac0
[task 2019-11-18T18:07:59.808Z] 18:07:59 INFO - edx = 0x06d26000 efl = 0x00010246
[task 2019-11-18T18:07:59.808Z] 18:07:59 INFO - Found by: given as instruction pointer in context
[task 2019-11-18T18:07:59.808Z] 18:07:59 INFO - 1 xul.dll!js::WeakMap<js::HeapPtr<JSObject *>,js::HeapPtr<JS::Value> >::markKey(js::GCMarker *,js::gc::Cell *,js::gc::Cell *) [WeakMap-inl.h:a52581ac4a9dd02c9ae085f428db1e1579a60513 : 120 + 0xa]
[task 2019-11-18T18:07:59.808Z] 18:07:59 INFO - eip = 0x5aff97bf esp = 0x0025ec84 ebp = 0x0025ecd4 ebx = 0x1d773118
[task 2019-11-18T18:07:59.809Z] 18:07:59 INFO - esi = 0x00000000 edi = 0x08be77c0
[task 2019-11-18T18:07:59.809Z] 18:07:59 INFO - Found by: call frame info
[task 2019-11-18T18:07:59.809Z] 18:07:59 INFO - 2 xul.dll!void js::GCMarker::processMarkStackTop(class js::SliceBudget & const) [Marking.cpp:a52581ac4a9dd02c9ae085f428db1e1579a60513 : 1861 + 0x1c]
[task 2019-11-18T18:07:59.809Z] 18:07:59 INFO - eip = 0x5b078c7e esp = 0x0025ecdc ebp = 0x0025ed5c ebx = 0x00000003
[task 2019-11-18T18:07:59.809Z] 18:07:59 INFO - esi = 0x083089d4 edi = 0x088f9a40
[task 2019-11-18T18:07:59.809Z] 18:07:59 INFO - Found by: call frame info
[task 2019-11-18T18:07:59.809Z] 18:07:59 INFO - 3 xul.dll!js::GCMarker::markUntilBudgetExhausted(js::SliceBudget &) [Marking.cpp:a52581ac4a9dd02c9ae085f428db1e1579a60513 : 1662 + 0x9]
[task 2019-11-18T18:07:59.809Z] 18:07:59 INFO - eip = 0x5b077c4e esp = 0x0025ed64 ebp = 0x0025ed94 ebx = 0x06d275e0
[task 2019-11-18T18:07:59.809Z] 18:07:59 INFO - esi = 0x06d275d0 edi = 0x0025eda0
[task 2019-11-18T18:07:59.809Z] 18:07:59 INFO - Found by: call frame info
[task 2019-11-18T18:07:59.809Z] 18:07:59 INFO - 4 xul.dll!void js::gc::GCRuntime::drainMarkStack() [GC.cpp:a52581ac4a9dd02c9ae085f428db1e1579a60513 : 5678 + 0x6]
[task 2019-11-18T18:07:59.809Z] 18:07:59 INFO - eip = 0x5b05c3fd esp = 0x0025ed9c ebp = 0x0025ede4 ebx = 0x0025ee88
[task 2019-11-18T18:07:59.809Z] 18:07:59 INFO - esi = 0x06d275d0 edi = 0x06d262b8
[task 2019-11-18T18:07:59.809Z] 18:07:59 INFO - Found by: call frame info
[task 2019-11-18T18:07:59.809Z] 18:07:59 INFO - 5 xul.dll!static void js::gc::GCRuntime::markWeakReferences<js::gc::SweepGroupZonesIter>(js::gcstats::PhaseKind) [GC.cpp:a52581ac4a9dd02c9ae085f428db1e1579a60513 : 0 + 0x7]
[task 2019-11-18T18:07:59.809Z] 18:07:59 INFO - eip = 0x5b05c31e esp = 0x0025edec ebp = 0x0025ee08
[task 2019-11-18T18:07:59.809Z] 18:07:59 INFO - Found by: call frame info
[task 2019-11-18T18:07:59.809Z] 18:07:59 INFO - 6 xul.dll!js::gc::IncrementalProgress js::gc::GCRuntime::endMarkingSweepGroup(class JSFreeOp *, class js::SliceBudget & const) [GC.cpp:a52581ac4a9dd02c9ae085f428db1e1579a60513 : 5073 + 0xc]
[task 2019-11-18T18:07:59.809Z] 18:07:59 INFO - eip = 0x5b05d59f esp = 0x0025ee10 ebp = 0x0025ee28 ebx = 0x0025ee88
[task 2019-11-18T18:07:59.809Z] 18:07:59 INFO - esi = 0x06d262b8 edi = 0x06d262e8
[task 2019-11-18T18:07:59.809Z] 18:07:59 INFO - Found by: call frame info
[task 2019-11-18T18:07:59.809Z] 18:07:59 INFO - 7 xul.dll!js::gc::IncrementalProgress sweepaction::SweepActionCall::run(struct js::gc::SweepAction::Args & const) [GC.cpp:a52581ac4a9dd02c9ae085f428db1e1579a60513 : 6072 + 0x10]
[task 2019-11-18T18:07:59.809Z] 18:07:59 INFO - eip = 0x5b06e11f esp = 0x0025ee30 ebp = 0x0025ee48 ebx = 0x0025ee88
[task 2019-11-18T18:07:59.809Z] 18:07:59 INFO - esi = 0x06d262b8 edi = 0x5b05d530
[task 2019-11-18T18:07:59.809Z] 18:07:59 INFO - Found by: call frame info
[task 2019-11-18T18:07:59.809Z] 18:07:59 INFO - 8 xul.dll!js::gc::IncrementalProgress sweepaction::SweepActionSequence::run(struct js::gc::SweepAction::Args & const) [GC.cpp:a52581ac4a9dd02c9ae085f428db1e1579a60513 : 6142 + 0x6]
[task 2019-11-18T18:07:59.810Z] 18:07:59 INFO - eip = 0x5b071d18 esp = 0x0025ee50 ebp = 0x0025ee64 ebx = 0x0025ee88
[task 2019-11-18T18:07:59.810Z] 18:07:59 INFO - esi = 0x06d031c0 edi = 0x06d031e4
[task 2019-11-18T18:07:59.810Z] 18:07:59 INFO - Found by: call frame info

The priority flag is not set for this bug.
:drno, could you have a look please?

For more information, please visit auto_nag documentation.

Flags: needinfo?(drno)
Priority: -- → P5
Severity: normal → S4
Flags: needinfo?(drno)
Status: NEW → RESOLVED
Closed: 2 years ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.