Closed Bug 1590927 Opened 6 years ago Closed 4 years ago

Intermittent dom/media/test/crashtests/encrypted-track-without-tenc.mp4 | assertion count 1 is more than expected 0 assertions

Categories

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

Product:
Core
Component:
Audio/Video: Playback
Type:
defect

Tracking

()

Status:
RESOLVED WORKSFORME

People

(Reporter: intermittent-bug-filer, Unassigned)

References

Details

(Keywords: intermittent-failure)

Filed by: aiakab [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=272638874&repo=autoland
Full log: https://queue.taskcluster.net/v1/task/b9SggCyaSB-ovRRrU8p3JA/runs/0/artifacts/public/logs/live_backing.log
Reftest URL: https://hg.mozilla.org/mozilla-central/raw-file/tip/layout/tools/reftest/reftest-analyzer.xhtml#logurl=https://queue.taskcluster.net/v1/task/b9SggCyaSB-ovRRrU8p3JA/runs/0/artifacts/public/logs/live_backing.log&only_show_unexpected=1

[task 2019-10-23T20:01:53.200Z] 20:01:53 INFO - REFTEST TEST-START | http://10.0.2.2:8854/tests/dom/media/test/crashtests/encrypted-track-without-tenc.mp4
[task 2019-10-23T20:01:53.200Z] 20:01:53 INFO - REFTEST TEST-LOAD | http://10.0.2.2:8854/tests/dom/media/test/crashtests/encrypted-track-without-tenc.mp4 | 661 / 3800 (17%)
[task 2019-10-23T20:01:53.201Z] 20:01:53 INFO - REFTEST TEST-PASS | http://10.0.2.2:8854/tests/dom/media/test/crashtests/encrypted-track-without-tenc.mp4 | (LOAD ONLY)
[task 2019-10-23T20:01:53.201Z] 20:01:53 INFO - REFTEST TEST-END | http://10.0.2.2:8854/tests/dom/media/test/crashtests/encrypted-track-without-tenc.mp4
[task 2019-10-23T20:01:53.201Z] 20:01:53 WARNING - REFTEST TEST-UNEXPECTED-FAIL | http://10.0.2.2:8854/tests/dom/media/test/crashtests/encrypted-track-without-tenc.mp4 | assertion count 1 is more than expected 0 assertions
[task 2019-10-23T20:01:53.202Z] 20:01:53 INFO - REFTEST TEST-START | http://10.0.2.2:8854/tests/dom/media/test/crashtests/encrypted-track-with-sample-missing-cenc-aux.mp4
[task 2019-10-23T20:01:53.202Z] 20:01:53 INFO - REFTEST TEST-LOAD | http://10.0.2.2:8854/tests/dom/media/test/crashtests/encrypted-track-with-sample-missing-cenc-aux.mp4 | 662 / 3800 (17%)

Logcats show the assertion is

11-28 13:43:20.080  2868  2883 I Gecko   : [Child 2868, Main Thread] ###!!! ASSERTION: Overwriting an existing document channel!: '(loadFlags & nsIChannel::LOAD_REPLACE) || !(mDocumentRequest.get())', file /builds/worker/workspace/build/src/uriloader/base/nsDocLoader.cpp, line 432

https://searchfox.org/mozilla-central/rev/04d8e7629354bab9e6a285183e763410860c5006/uriloader/base/nsDocLoader.cpp#430-432

There are 20 total failures in the last 7 days.

  • debug: android-hw-p2-8-0-android-aarch64-qr and android-em-7-0-x86_64-qr

Recent failure log: https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=293124901&repo=autoland&lineNumber=4603

Flags: needinfo?(gbrown)

The assertion remains the same as it was several months ago (comment 7).

This logcat seems typical:
https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=293110000&repo=autoland&lineNumber=4140
https://firefoxci.taskcluster-artifacts.net/VRLgnH5aTh2tpA1Z3aKaEA/0/public/test_info//logcat-emulator-5554.log

03-13 21:40:30.057  2808  2823 I Gecko   : REFTEST TEST-LOAD | http://10.0.2.2:8854/tests/dom/media/test/crashtests/encrypted-track-without-tenc.mp4 | 665 / 3776 (17%)https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=293110000&repo=autoland&lineNumber=4140
03-13 21:40:30.057  2808  2823 I Gecko   : 
03-13 21:40:30.057  2808  2823 I Gecko   : {"action":"log","time":1584135630057,"thread":null,"pid":null,"source":"reftest","level":"DEBUG","message":"START http://10.0.2.2:8854/tests/dom/media/test/crashtests/encrypted-track-without-tenc.mp4"}
03-13 21:40:30.150  2808  2882 E eglCodecCommon: glUtilsParamSize: unknow param 0x00008caa
03-13 21:40:30.235  2841  2858 W Web Content: [JavaScript Warning: "Media resource http://10.0.2.2:8854/tests/dom/media/test/crashtests/encrypted-track-without-tenc.mp4 could not be decoded." {file: "http://10.0.2.2:8854/tests/dom/media/test/crashtests/encrypted-track-without-tenc.mp4" line: 0}]
03-13 21:40:30.236  2841  2858 I Gecko   : [Child 2841, Main Thread] ###!!! ASSERTION: Overwriting an existing document channel!: '(loadFlags & nsIChannel::LOAD_REPLACE) || !(mDocumentRequest.get())', file /builds/worker/checkouts/gecko/uriloader/base/nsDocLoader.cpp, line 428

This test was added by bug 1533215. It has been failing intermittently on Android for many months now.

:bryce - Can you have a look?

Flags: needinfo?(gbrown) → needinfo?(bvandyk)
See Also: → 1533215

I notice that several of the tests which run before this test are either skipped on Android or allow assertions. I wonder if an earlier test is causing the assertion and we are "chasing" it across tests??

https://searchfox.org/mozilla-central/rev/d69ec052bed8700af7a283e37b60b4af22734930/dom/media/test/crashtests/crashtests.list#113-123

load oscillator-ended-2.html
skip-if(Android&&AndroidVersion=='22') load video-replay-after-audio-end.html # bug 1315125, bug 1358876
# This needs to run at the end to avoid leaking busted state into other tests.
skip-if(Android) load 691096-1.html # Bug 1365451
load 1236639.html
test-pref(media.navigator.permission.disabled,true) test-pref(media.devices.insecure.enabled,true) test-pref(media.getusermedia.insecure.enabled,true) load 1388372.html
load 1494073.html
skip-if(Android) load 1526044.html # Bug 1528391
skip-if(Android&&AndroidVersion<21) load encrypted-track-with-bad-sample-description-index.mp4 # Bug 1533211, unkip after bug 1550912
load encrypted-track-without-tenc.mp4 # Bug 1533215
asserts-if(Android,0-1) load encrypted-track-with-sample-missing-cenc-aux.mp4 # Bug 1533625, bug 1588967

(In reply to Geoff Brown [:gbrown] from comment #25)

I notice that several of the tests which run before this test are either skipped on Android or allow assertions. I wonder if an earlier test is causing the assertion and we are "chasing" it across tests??

https://searchfox.org/mozilla-central/rev/d69ec052bed8700af7a283e37b60b4af22734930/dom/media/test/crashtests/crashtests.list#113-123

load oscillator-ended-2.html
skip-if(Android&&AndroidVersion=='22') load video-replay-after-audio-end.html # bug 1315125, bug 1358876
# This needs to run at the end to avoid leaking busted state into other tests.
skip-if(Android) load 691096-1.html # Bug 1365451
load 1236639.html
test-pref(media.navigator.permission.disabled,true) test-pref(media.devices.insecure.enabled,true) test-pref(media.getusermedia.insecure.enabled,true) load 1388372.html
load 1494073.html
skip-if(Android) load 1526044.html # Bug 1528391
skip-if(Android&&AndroidVersion<21) load encrypted-track-with-bad-sample-description-index.mp4 # Bug 1533211, unkip after bug 1550912
load encrypted-track-without-tenc.mp4 # Bug 1533215
asserts-if(Android,0-1) load encrypted-track-with-sample-missing-cenc-aux.mp4 # Bug 1533625, bug 1588967

The crashtest is a encrypted media file. It looks like it can't be decoded, but I'm surprised it leads to us hitting this assert, which seems unrelated to media. It would make sense to me that the crash could be coming from somewhere else.

Ehsan, do you know the code related to the assert? Do you know when we'd expect to see this assert hit?

Flags: needinfo?(bvandyk) → needinfo?(ehsan)

In the last 7 days there have been 21 occurrences on android (7 and 8) debug.

Recent failure: https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=297142029&repo=autoland&lineNumber=4142

Status: NEW → RESOLVED
Closed: 4 years ago
Flags: needinfo?(ehsan.akhgari)
Resolution: --- → WORKSFORME
You need to log in before you can comment on or make changes to this bug.