Firefox stops after 2 minutes of play

[codewright2000]

I've noticed this with 2 servers, including the reference player demo site with the latest dash.js library release (2.00). The latest release of Firefox (44.0.2) stops playback after 2 minutes. If this has been already reported, then please forgive me. I could not find it.

[dsparacio]

@codewright2000 Thanks for the report. Do you have an MPD that exhibits this behaviour that I can use to test? Also thanks for Firefox version, what OS?

[codewright2000]

The OS is Windows 10. The MPD on the reference player is http://dash.edgesuite.net/dash264/TestCases/1a/sony/SNE_DASH_SD_CASE1A_REVISED.mpd. I have been running more tests and get differing results. I had a case where it continued for well beyond 2 minutes. I had a case where it stopped at 8 seconds. Below is the console output where it stopped at 2:04. This is the area I seem to experience the problem the most.

Using //@ to indicate sourceMappingURL pragmas is deprecated. Use //# instead jquery-1.10.2.min.js:1:0
SyntaxError: missing ; before statement jquery.flot.js:1947:18
mutating the [[Prototype]] of an object will cause your code to run very slowly; instead create the object with the correct initial [[Prototype]] value using Object.create dash.all.debug.js:3090:415
Use of getPreventDefault() is deprecated. Use defaultPrevented instead. jquery-1.10.2.min.js:5:17117
Empty string passed to getElementById(). jquery-1.10.2.min.js:4:1404
unclosed token index.html:1:1
Empty string passed to getElementById(). jquery-1.10.2.min.js:4:1404
unclosed token index.html:1:1
Empty string passed to getElementById(). jquery-1.10.2.min.js:4:1404
[260195] BaseURL: 533.1626666666667 / 597 dash.all.debug.js:2722:13
[260283] loaded audio:MediaSegment:533.1626666666667 (206, 0ms, 86ms) dash.all.debug.js:2722:13
[260298] Buffered Range: 63.018666 - 124.202666 dash.all.debug.js:2722:13
[260298] Buffered Range: 242.346666 - 536.192 dash.all.debug.js:2722:13
[260298] Getting the request for audio time : 536.192 dash.all.debug.js:2722:13
[260298] Index for audio time 536.192 is 176 dash.all.debug.js:2722:13
[260298] BaseURL: 533.1626666666667 / 597 dash.all.debug.js:2722:13
[260299] Getting the next request at index: 177 dash.all.debug.js:2722:13
[260299] BaseURL: 536.192 / 597 dash.all.debug.js:2722:13
[260358] loaded audio:MediaSegment:536.192 (206, 0ms, 57ms) dash.all.debug.js:2722:13
[260375] Buffered Range: 63.018666 - 124.202666 dash.all.debug.js:2722:13
[260375] Buffered Range: 242.346666 - 539.221333 dash.all.debug.js:2722:13
[260376] Getting the request for audio time : 539.2213333333333 dash.all.debug.js:2722:13
[260376] Index for audio time 539.2213333333333 is 177 dash.all.debug.js:2722:13
[260376] BaseURL: 536.192 / 597 dash.all.debug.js:2722:13
[260376] Getting the next request at index: 178 dash.all.debug.js:2722:13
[260376] BaseURL: 539.2213333333333 / 597 dash.all.debug.js:2722:13
[260451] loaded audio:MediaSegment:539.2213333333333 (206, 0ms, 72ms) dash.all.debug.js:2722:13
[260470] Buffered Range: 63.018666 - 124.202666 dash.all.debug.js:2722:13
[260470] Buffered Range: 242.346666 - 542.250666 dash.all.debug.js:2722:13
[260470] Getting the request for audio time : 542.2506666666666 dash.all.debug.js:2722:13
[260471] Index for audio time 542.2506666666666 is 178 dash.all.debug.js:2722:13
[260471] BaseURL: 539.2213333333333 / 597 dash.all.debug.js:2722:13
[260471] Getting the next request at index: 179 dash.all.debug.js:2722:13
[260471] BaseURL: 542.2506666666667 / 597 dash.all.debug.js:2722:13
[260540] loaded audio:MediaSegment:542.2506666666667 (206, 0ms, 66ms) dash.all.debug.js:2722:13
[260566] Buffered Range: 63.018666 - 124.202666 dash.all.debug.js:2722:13
[260566] Buffered Range: 242.346666 - 545.28 dash.all.debug.js:2722:13
[260567] Getting the request for audio time : 545.28 dash.all.debug.js:2722:13
[260567] Index for audio time 545.28 is 179 dash.all.debug.js:2722:13
[260567] BaseURL: 542.2506666666667 / 597 dash.all.debug.js:2722:13
[260567] Getting the next request at index: 180 dash.all.debug.js:2722:13
[260567] BaseURL: 545.28 / 597 dash.all.debug.js:2722:13
[260638] loaded audio:MediaSegment:545.28 (206, 0ms, 68ms) dash.all.debug.js:2722:13
[260657] Buffered Range: 63.018666 - 124.202666 dash.all.debug.js:2722:13
[260657] Buffered Range: 242.346666 - 548.309333 dash.all.debug.js:2722:13
[260658] Getting the request for audio time : 548.3093333333333 dash.all.debug.js:2722:13
[260658] Index for audio time 548.3093333333333 is 180 dash.all.debug.js:2722:13
[260658] BaseURL: 545.28 / 597 dash.all.debug.js:2722:13
[260658] Getting the next request at index: 181 dash.all.debug.js:2722:13
[260658] BaseURL: 548.3093333333334 / 597 dash.all.debug.js:2722:13
[260726] loaded audio:MediaSegment:548.3093333333334 (206, 0ms, 66ms) dash.all.debug.js:2722:13
[260743] Buffered Range: 63.018666 - 124.202666 dash.all.debug.js:2722:13
[260743] Buffered Range: 242.346666 - 551.338666 dash.all.debug.js:2722:13
[260743] Getting the request for audio time : 551.3386666666667 dash.all.debug.js:2722:13
[260743] Index for audio time 551.3386666666667 is 181 dash.all.debug.js:2722:13
[260743] BaseURL: 548.3093333333334 / 597 dash.all.debug.js:2722:13
[260743] Getting the next request at index: 182 dash.all.debug.js:2722:13
[260744] BaseURL: 551.3386666666667 / 597 dash.all.debug.js:2722:13
[260803] loaded audio:MediaSegment:551.3386666666667 (206, 0ms, 57ms) dash.all.debug.js:2722:13
[260821] Buffered Range: 63.018666 - 124.202666 dash.all.debug.js:2722:13
[260821] Buffered Range: 242.346666 - 554.368 dash.all.debug.js:2722:13
[260822] Prior to making a request for time, NextFragmentRequestRule is aligning index handler's currentTime with bufferedRange.end. 554.3679999999999 was changed to 554.368 dash.all.debug.js:2722:13
[260822] Getting the request for audio time : 554.368 dash.all.debug.js:2722:13
[260822] Index for audio time 554.368 is 182 dash.all.debug.js:2722:13
[260822] BaseURL: 551.3386666666667 / 597 dash.all.debug.js:2722:13
[260822] Getting the next request at index: 183 dash.all.debug.js:2722:13
[260822] BaseURL: 554.368 / 597 dash.all.debug.js:2722:13
[260878] loaded audio:MediaSegment:554.368 (206, 0ms, 53ms) dash.all.debug.js:2722:13
[260893] Buffered Range: 63.018666 - 124.202666 dash.all.debug.js:2722:13
[260893] Buffered Range: 242.346666 - 557.397333 dash.all.debug.js:2722:13
[260894] Getting the request for audio time : 557.3973333333333 dash.all.debug.js:2722:13
[260894] Index for audio time 557.3973333333333 is 183 dash.all.debug.js:2722:13
[260894] BaseURL: 554.368 / 597 dash.all.debug.js:2722:13
[260894] Getting the next request at index: 184 dash.all.debug.js:2722:13
[260894] BaseURL: 557.3973333333333 / 597 dash.all.debug.js:2722:13
[260974] loaded audio:MediaSegment:557.3973333333333 (206, 0ms, 78ms) dash.all.debug.js:2722:13
[261001] Buffered Range: 63.018666 - 124.202666 dash.all.debug.js:2722:13
[261001] Buffered Range: 242.346666 - 560.426666 dash.all.debug.js:2722:13
[261001] Getting the request for audio time : 560.4266666666666 dash.all.debug.js:2722:13
[261002] Index for audio time 560.4266666666666 is 184 dash.all.debug.js:2722:13
[261002] BaseURL: 557.3973333333333 / 597 dash.all.debug.js:2722:13
[261002] Getting the next request at index: 185 dash.all.debug.js:2722:13
[261002] BaseURL: 560.4266666666666 / 597 dash.all.debug.js:2722:13
[261073] loaded audio:MediaSegment:560.4266666666666 (206, 0ms, 69ms) dash.all.debug.js:2722:13
[261091] Buffered Range: 63.018666 - 124.202666 dash.all.debug.js:2722:13
[261091] Buffered Range: 242.346666 - 563.456 dash.all.debug.js:2722:13
[261092] Prior to making a request for time, NextFragmentRequestRule is aligning index handler's currentTime with bufferedRange.end. 563.4559999999999 was changed to 563.456 dash.all.debug.js:2722:13
[261092] Getting the request for audio time : 563.456 dash.all.debug.js:2722:13
[261092] Index for audio time 563.456 is 185 dash.all.debug.js:2722:13
[261092] BaseURL: 560.4266666666666 / 597 dash.all.debug.js:2722:13
[261092] Getting the next request at index: 186 dash.all.debug.js:2722:13
[261092] BaseURL: 563.456 / 597 dash.all.debug.js:2722:13
[261166] loaded audio:MediaSegment:563.456 (206, 0ms, 71ms) dash.all.debug.js:2722:13
[261185] Buffered Range: 63.018666 - 124.202666 dash.all.debug.js:2722:13
[261185] Buffered Range: 242.346666 - 566.485333 dash.all.debug.js:2722:13
[261186] Getting the request for audio time : 566.4853333333333 dash.all.debug.js:2722:13
[261186] Index for audio time 566.4853333333333 is 186 dash.all.debug.js:2722:13
[261186] BaseURL: 563.456 / 597 dash.all.debug.js:2722:13
[261186] Getting the next request at index: 187 dash.all.debug.js:2722:13
[261186] BaseURL: 566.4853333333333 / 597 dash.all.debug.js:2722:13
[261241] loaded audio:MediaSegment:566.4853333333333 (206, 0ms, 53ms) dash.all.debug.js:2722:13
[261256] Buffered Range: 63.018666 - 124.202666 dash.all.debug.js:2722:13
[261257] Buffered Range: 242.346666 - 569.514666 dash.all.debug.js:2722:13
[261257] Getting the request for audio time : 569.5146666666666 dash.all.debug.js:2722:13
[261257] Index for audio time 569.5146666666666 is 187 dash.all.debug.js:2722:13
[261257] BaseURL: 566.4853333333333 / 597 dash.all.debug.js:2722:13
[261257] Getting the next request at index: 188 dash.all.debug.js:2722:13
[261258] BaseURL: 569.5146666666667 / 597 dash.all.debug.js:2722:13
[261348] loaded audio:MediaSegment:569.5146666666667 (206, 0ms, 88ms) dash.all.debug.js:2722:13
[261372] Buffered Range: 63.018666 - 124.202666 dash.all.debug.js:2722:13
[261372] Buffered Range: 242.346666 - 572.544 dash.all.debug.js:2722:13
[261373] Getting the request for audio time : 572.544 dash.all.debug.js:2722:13
[261373] Index for audio time 572.544 is 188 dash.all.debug.js:2722:13
[261373] BaseURL: 569.5146666666667 / 597 dash.all.debug.js:2722:13
[261373] Getting the next request at index: 189 dash.all.debug.js:2722:13
[261373] BaseURL: 572.544 / 597 dash.all.debug.js:2722:13
[261430] loaded audio:MediaSegment:572.544 (206, 0ms, 55ms) dash.all.debug.js:2722:13
[261445] Buffered Range: 63.018666 - 124.202666 dash.all.debug.js:2722:13
[261445] Buffered Range: 242.346666 - 575.573333 dash.all.debug.js:2722:13
[261446] Getting the request for audio time : 575.5733333333333 dash.all.debug.js:2722:13
[261446] Index for audio time 575.5733333333333 is 189 dash.all.debug.js:2722:13
[261446] BaseURL: 572.544 / 597 dash.all.debug.js:2722:13
[261446] Getting the next request at index: 190 dash.all.debug.js:2722:13
[261446] BaseURL: 575.5733333333334 / 597 dash.all.debug.js:2722:13
[261546] loaded audio:MediaSegment:575.5733333333334 (206, 0ms, 98ms) dash.all.debug.js:2722:13
[261562] Buffered Range: 63.018666 - 124.202666 dash.all.debug.js:2722:13
[261562] Buffered Range: 242.346666 - 578.602666 dash.all.debug.js:2722:13
[261562] Getting the request for audio time : 578.6026666666667 dash.all.debug.js:2722:13
[261562] Index for audio time 578.6026666666667 is 190 dash.all.debug.js:2722:13
[261562] BaseURL: 575.5733333333334 / 597 dash.all.debug.js:2722:13
[261562] Getting the next request at index: 191 dash.all.debug.js:2722:13
[261562] BaseURL: 578.6026666666667 / 597 dash.all.debug.js:2722:13
[261634] loaded audio:MediaSegment:578.6026666666667 (206, 0ms, 69ms) dash.all.debug.js:2722:13
[261651] Buffered Range: 63.018666 - 124.202666 dash.all.debug.js:2722:13
[261651] Buffered Range: 242.346666 - 581.632 dash.all.debug.js:2722:13
[261652] Getting the request for audio time : 581.632 dash.all.debug.js:2722:13
[261652] Index for audio time 581.632 is 191 dash.all.debug.js:2722:13
[261652] BaseURL: 578.6026666666667 / 597 dash.all.debug.js:2722:13
[261652] Getting the next request at index: 192 dash.all.debug.js:2722:13
[261652] BaseURL: 581.632 / 597 dash.all.debug.js:2722:13
[261726] loaded audio:MediaSegment:581.632 (206, 0ms, 71ms) dash.all.debug.js:2722:13
[261753] Buffered Range: 63.018666 - 124.202666 dash.all.debug.js:2722:13
[261753] Buffered Range: 242.346666 - 584.661333 dash.all.debug.js:2722:13
[261753] Getting the request for audio time : 584.6613333333332 dash.all.debug.js:2722:13
[261753] Index for audio time 584.6613333333332 is 192 dash.all.debug.js:2722:13
[261753] BaseURL: 581.632 / 597 dash.all.debug.js:2722:13
[261753] Getting the next request at index: 193 dash.all.debug.js:2722:13
[261753] BaseURL: 584.6613333333333 / 597 dash.all.debug.js:2722:13
[261820] loaded audio:MediaSegment:584.6613333333333 (206, 0ms, 64ms) dash.all.debug.js:2722:13
[261837] Buffered Range: 63.018666 - 124.202666 dash.all.debug.js:2722:13
[261838] Buffered Range: 242.346666 - 587.690666 dash.all.debug.js:2722:13
[261838] Getting the request for audio time : 587.6906666666666 dash.all.debug.js:2722:13
[261838] Index for audio time 587.6906666666666 is 193 dash.all.debug.js:2722:13
[261838] BaseURL: 584.6613333333333 / 597 dash.all.debug.js:2722:13
[261838] Getting the next request at index: 194 dash.all.debug.js:2722:13
[261838] BaseURL: 587.6906666666666 / 597 dash.all.debug.js:2722:13
[261906] loaded audio:MediaSegment:587.6906666666666 (206, 0ms, 65ms) dash.all.debug.js:2722:13
[261924] Buffered Range: 63.018666 - 124.202666 dash.all.debug.js:2722:13
[261924] Buffered Range: 242.346666 - 590.72 dash.all.debug.js:2722:13
[261925] Prior to making a request for time, NextFragmentRequestRule is aligning index handler's currentTime with bufferedRange.end. 590.7199999999999 was changed to 590.72 dash.all.debug.js:2722:13
[261925] Getting the request for audio time : 590.72 dash.all.debug.js:2722:13
[261925] Index for audio time 590.72 is 194 dash.all.debug.js:2722:13
[261925] BaseURL: 587.6906666666666 / 597 dash.all.debug.js:2722:13
[261925] Getting the next request at index: 195 dash.all.debug.js:2722:13
[261925] BaseURL: 590.72 / 597 dash.all.debug.js:2722:13
[261996] loaded audio:MediaSegment:590.72 (206, 0ms, 68ms) dash.all.debug.js:2722:13
[262011] Buffered Range: 63.018666 - 124.202666 dash.all.debug.js:2722:13
[262011] Buffered Range: 242.346666 - 593.749333 dash.all.debug.js:2722:13
[262011] Getting the request for audio time : 593.7493333333333 dash.all.debug.js:2722:13
[262012] Index for audio time 593.7493333333333 is 195 dash.all.debug.js:2722:13
[262012] BaseURL: 590.72 / 597 dash.all.debug.js:2722:13
[262012] Getting the next request at index: 196 dash.all.debug.js:2722:13
[262012] BaseURL: 593.7493333333333 / 597 dash.all.debug.js:2722:13
[262075] loaded audio:MediaSegment:593.7493333333333 (206, 0ms, 61ms) dash.all.debug.js:2722:13
[262093] Buffered Range: 63.018666 - 124.202666 dash.all.debug.js:2722:13
[262093] Buffered Range: 242.346666 - 596.778666 dash.all.debug.js:2722:13
[262094] Getting the request for audio time : 596.7786666666666 dash.all.debug.js:2722:13
[262094] Index for audio time 596.7786666666666 is 196 dash.all.debug.js:2722:13
[262094] BaseURL: 593.7493333333333 / 597 dash.all.debug.js:2722:13
[262094] Getting the next request at index: 197 dash.all.debug.js:2722:13
[262094] BaseURL: 596.7786666666667 / 597 dash.all.debug.js:2722:13
[262153] loaded audio:MediaSegment:596.7786666666667 (206, 0ms, 56ms) dash.all.debug.js:2722:13
[262176] Buffered Range: 63.018666 - 124.202666 dash.all.debug.js:2722:13
[262176] Buffered Range: 242.346666 - 597.034666 dash.all.debug.js:2722:13
[262177] Getting the request for audio time : 597.0346666666667 dash.all.debug.js:2722:13
[262177] Index for audio time 597.0346666666667 is 196 dash.all.debug.js:2722:13
[262177] BaseURL: 593.7493333333333 / 597 dash.all.debug.js:2722:13
[262177] Getting the next request at index: 198 dash.all.debug.js:2722:13
[262177] Signal complete. dash.all.debug.js:2722:13
[262186] Stream is complete dash.all.debug.js:2722:13
[263901] Getting the request for video time : 148.94045833333334 dash.all.debug.js:2722:13
[263902] Index for video time 148.94045833333334 is 29 dash.all.debug.js:2722:13
[263902] BaseURL: 143.97716666666668 / 597 dash.all.debug.js:2722:13
[263902] Getting the next request at index: 30 dash.all.debug.js:2722:13
[263902] BaseURL: 148.94045833333334 / 597 dash.all.debug.js:2722:13
[264095] loaded video:MediaSegment:148.94045833333334 (206, 128ms, 58ms) dash.all.debug.js:2722:13
[264131] Buffered Range: 64.5645 - 153.90375 dash.all.debug.js:2722:13
[268135] Getting the request for video time : 153.90375 dash.all.debug.js:2722:13
[268135] Index for video time 153.90375 is 30 dash.all.debug.js:2722:13
[268135] BaseURL: 148.94045833333334 / 597 dash.all.debug.js:2722:13
[268135] Getting the next request at index: 31 dash.all.debug.js:2722:13
[268136] BaseURL: 153.90375 / 597 dash.all.debug.js:2722:13
[268325] loaded video:MediaSegment:153.90375 (206, 88ms, 99ms) dash.all.debug.js:2722:13
[268354] Buffered Range: 64.5645 - 158.867041 dash.all.debug.js:2722:13
[268832] try to prune buffer dash.all.debug.js:2722:13
[268832] pruning buffer: 29.5955 seconds. dash.all.debug.js:2722:13
[268832] try to prune buffer dash.all.debug.js:2722:13
[268832] pruning buffer: 31.141333999999993 seconds. dash.all.debug.js:2722:13
[268838] Schedule controller starting for video dash.all.debug.js:2722:13
[298996] try to prune buffer dash.all.debug.js:2722:13
[298997] try to prune buffer dash.all.debug.js:2722:13
[298998] pruning buffer: 0.12266699999999275 seconds. dash.all.debug.js:2722:13
[328990] try to prune buffer dash.all.debug.js:2722:13
[328990] pruning buffer: 0.12266699999999275 seconds. dash.all.debug.js:2722:13
[358990] try to prune buffer dash.all.debug.js:2722:13
[358991] pruning buffer: 0.12266699999999275 seconds. dash.all.debug.js:2722:13
[388996] try to prune buffer dash.all.debug.js:2722:13
[388997] try to prune buffer dash.all.debug.js:2722:13
[388997] pruning buffer: 0.12266699999999275 seconds. dash.all.debug.js:2722:13
[418990] try to prune buffer dash.all.debug.js:2722:13
[418991] try to prune buffer dash.all.debug.js:2722:13
[418991] pruning buffer: 0.12266699999999275 seconds. dash.all.debug.js:2722:13
[448990] try to prune buffer dash.all.debug.js:2722:13
[448991] pruning buffer: 0.12266699999999275 seconds. dash.all.debug.js:2722:13

[dreamsproductions]

I think this might relate to the issue we reported #1050
v2.0 is still freezing after varying amounts of time on smart TVs. We're at BVE London streaming seminar this week and I've been testing v2.0 on some different smart TVs around the exhibition.
I do not believe this is a manifest issue, as the manifests play ok with Shaka and Bitdash players.

[dsparacio]

Thanks for the report. Ill dig in today and see what I can tell. Have you played with the buffer pruning API to see if it makes a difference. you can set how often it prunes and how much it prunes. API is on mediaplayer. Ill test this as well when I dig in.

[codewright2000]

Sorry for the delayed response....crazy day.... I have not tried changing any settings. I will experiment with some of them tonight.

[dsparacio]

Not sure if it is related to 1050 or not. Keep in mind. Was able to reproduce stop at 157 on FF win VM.

[dsparacio]

@codewright2000 just tested http://dash.edgesuite.net/dash264/TestCases/1a/sony/SNE_DASH_SD_CASE1A_REVISED.mpd
on FF on win 10 and other browsers and it seems to be very solid now. Can you confirm with nightly build of 2.1 here
http://mediapm.edgesuite.net/dash/private/support-player/nightly/index.html

I am not making any changes to default settings and playback seems very solid. I am on latest FF 45