Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

Sign up for GitHub

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Jump to bottom

BufferStalledError from the demo #4826

Closed
5 tasks done
ROBERT-MCDOWELL opened this issue Aug 4, 2022 · 9 comments
Closed
5 tasks done

BufferStalledError from the demo #4826

ROBERT-MCDOWELL opened this issue Aug 4, 2022 · 9 comments
Labels
Bug Confirmed Bug report confirmed or reproduced. gap-controller
Milestone
1.6.0

Comments

@ROBERT-MCDOWELL
Copy link

What version of Hls.js are you using?

LAST

What browser (including version) are you using?

All browsers

What OS (including version) are you using?

All OS

Test stream

https://hls-js-bbdf933d-da48-407d-aaf3-68cc4ee058e7.netlify.app/demo/?src=https%3A%2F%2Ftest-streams.mux.dev%2Fx36xhzz%2Fx36xhzz.m3u8&demoConfig=eyJlbmFibGVTdHJlYW1pbmciOnRydWUsImF1dG9SZWNvdmVyRXJyb3IiOnRydWUsInN0b3BPblN0YWxsIjpmYWxzZSwiZHVtcGZNUDQiOmZhbHNlLCJsZXZlbENhcHBpbmciOi0xLCJsaW1pdE1ldHJpY3MiOi0xfQ==

Configuration

{
  "debug": true,
  "enableWorker": true,
  "lowLatencyMode": true,
  "backBufferLength": 90
}

Additional player setup steps

No response

Checklist

Steps to reproduce

Play demo without changes

....

Expected behaviour

buffer must be filled better

What actually happened?

I tested the demo with the first video on the list and the rest untouched and I get bufferStalledError on all browsers last vevrsion
on a 100Mb/s line where I everyday watch multiple simultaneous HD streaming from YT
any clue why I get so many buffer error?

Console output

shls.js:4125 [log] > [stream-controller]: FRAG_LOADING->PARSING
4610d57f-1499-4a37-a180-8835f9262d5e:948 [log] > [transmuxer.ts]: Flushed fragment 8 of level 4
hls.js:4125 [log] > [stream-controller]: PARSING->PARSED
hls.js:3369 [log] > [stream-controller]: Buffered main sn: 8 of level 4 [0.023,90.001]
hls.js:4125 [log] > [stream-controller]: PARSED->IDLE
hls.js:3438 [log] > [stream-controller]: Loading fragment 9 cc: 0 of [0-63] level: 4, target: 90.001
hls.js:4125 [log] > [stream-controller]: IDLE->FRAG_LOADING
hls.js:3255 [log] > [stream-controller]: Loaded fragment 9 of level 4
4610d57f-1499-4a37-a180-8835f9262d5e:948 [log] > [transmuxer.ts]: Flushed fragment 9 of level 4
hls.js:4125 [log] > [stream-controller]: FRAG_LOADING->PARSING
hls.js:4125 [log] > [stream-controller]: PARSING->PARSED
hls.js:3369 [log] > [stream-controller]: Buffered main sn: 9 of level 4 [0.023,100.008]
hls.js:4125 [log] > [stream-controller]: PARSED->IDLE
hls.js:7631 [warn] > Playback stalling at @12.996104 due to low buffer ({"len":87.012239,"start":0.023222,"end":100.008343})
_reportStall @ hls.js:7631
poll @ hls.js:7559
checkBuffer @ hls.js:10425
onTickEnd @ hls.js:9757
doTick @ hls.js:9751
tick @ hls.js:22845
setInterval (async)
setInterval @ hls.js:22800
startLoad @ hls.js:9660
(anonymous) @ hls.js:17716
startLoad @ hls.js:17715
onManifestLoaded @ hls.js:8611
emit @ hls.js:310
emit @ hls.js:17604
trigger @ hls.js:17609
handleMasterPlaylist @ hls.js:20678
loadsuccess @ hls.js:20602
readystatechange @ hls.js:28762
XMLHttpRequest.send (async)
loadInternal @ hls.js:28696
load @ hls.js:28637
load @ hls.js:20575
onManifestLoading @ hls.js:20428
emit @ hls.js:310
emit @ hls.js:17604
trigger @ hls.js:17609
loadSource @ hls.js:17696
loadSelectedStream @ hls-demo.js:1828
applyConfigEditorValue @ hls-demo.js:3032
onclick @ ?src=https%3A%2F%2Ftest-streams.mux.dev%2Fx36xhzz%2Fx36xhzz.m3u8&demoConfig=eyJlbmFibGVTdHJlYW1pbmciOnRydWUsImF1dG9SZWNvdmVyRXJyb3IiOnRydWUsInN0b3BPblN0YWxsIjpmYWxzZSwiZHVtcGZNUDQiOmZhbHNlLCJsZXZlbENhcHBpbmciOi0xLCJsaW1pdE1ldHJpY3MiOi0xfQ==:140
hls.js:8230 [warn] > [playback-rate-controller]: Stall detected, adjusting target latency
onError @ hls.js:8230
emit @ hls.js:310
emit @ hls.js:17604
trigger @ hls.js:17609
_reportStall @ hls.js:7632
poll @ hls.js:7559
checkBuffer @ hls.js:10425
onTickEnd @ hls.js:9757
doTick @ hls.js:9751
tick @ hls.js:22845
setInterval (async)
setInterval @ hls.js:22800
startLoad @ hls.js:9660
(anonymous) @ hls.js:17716
startLoad @ hls.js:17715
onManifestLoaded @ hls.js:8611
emit @ hls.js:310
emit @ hls.js:17604
trigger @ hls.js:17609
handleMasterPlaylist @ hls.js:20678
loadsuccess @ hls.js:20602
readystatechange @ hls.js:28762
XMLHttpRequest.send (async)
loadInternal @ hls.js:28696
load @ hls.js:28637
load @ hls.js:20575
onManifestLoading @ hls.js:20428
emit @ hls.js:310
emit @ hls.js:17604
trigger @ hls.js:17609
loadSource @ hls.js:17696
loadSelectedStream @ hls-demo.js:1828
applyConfigEditorValue @ hls-demo.js:3032
onclick @ ?src=https%3A%2F%2Ftest-streams.mux.dev%2Fx36xhzz%2Fx36xhzz.m3u8&demoConfig=eyJlbmFibGVTdHJlYW1pbmciOnRydWUsImF1dG9SZWNvdmVyRXJyb3IiOnRydWUsInN0b3BPblN0YWxsIjpmYWxzZSwiZHVtcGZNUDQiOmZhbHNlLCJsZXZlbENhcHBpbmciOi0xLCJsaW1pdE1ldHJpY3MiOi0xfQ==:140
hls-demo.js:2148 Error event: {type: 'mediaError', details: 'bufferStalledError', fatal: false, buffer: 87.012239}
(anonymous) @ hls-demo.js:2148
emit @ hls.js:310
emit @ hls.js:17604
trigger @ hls.js:17609
_reportStall @ hls.js:7632
poll @ hls.js:7559
checkBuffer @ hls.js:10425
onTickEnd @ hls.js:9757
doTick @ hls.js:9751
tick @ hls.js:22845
setInterval (async)
setInterval @ hls.js:22800
startLoad @ hls.js:9660
(anonymous) @ hls.js:17716
startLoad @ hls.js:17715
onManifestLoaded @ hls.js:8611
emit @ hls.js:310
emit @ hls.js:17604
trigger @ hls.js:17609
handleMasterPlaylist @ hls.js:20678
loadsuccess @ hls.js:20602
readystatechange @ hls.js:28762
XMLHttpRequest.send (async)
loadInternal @ hls.js:28696
load @ hls.js:28637
load @ hls.js:20575
onManifestLoading @ hls.js:20428
emit @ hls.js:310
emit @ hls.js:17604
trigger @ hls.js:17609
loadSource @ hls.js:17696
loadSelectedStream @ hls-demo.js:1828
applyConfigEditorValue @ hls-demo.js:3032
onclick @ ?src=https%3A%2F%2Ftest-streams.mux.dev%2Fx36xhzz%2Fx36xhzz.m3u8&demoConfig=eyJlbmFibGVTdHJlYW1pbmciOnRydWUsImF1dG9SZWNvdmVyRXJyb3IiOnRydWUsInN0b3BPblN0YWxsIjpmYWxzZSwiZHVtcGZNUDQiOmZhbHNlLCJsZXZlbENhcHBpbmciOi0xLCJsaW1pdE1ldHJpY3MiOi0xfQ==:140
hls.js:7607 [warn] > Trying to nudge playhead over buffer-hole
_tryFixBufferStall @ hls.js:7607
poll @ hls.js:7568
checkBuffer @ hls.js:10425
onTickEnd @ hls.js:9757
doTick @ hls.js:9751
tick @ hls.js:22845
setInterval (async)
setInterval @ hls.js:22800
startLoad @ hls.js:9660
(anonymous) @ hls.js:17716
startLoad @ hls.js:17715
onManifestLoaded @ hls.js:8611
emit @ hls.js:310
emit @ hls.js:17604
trigger @ hls.js:17609
handleMasterPlaylist @ hls.js:20678
loadsuccess @ hls.js:20602
readystatechange @ hls.js:28762
XMLHttpRequest.send (async)
loadInternal @ hls.js:28696
load @ hls.js:28637
load @ hls.js:20575
onManifestLoading @ hls.js:20428
emit @ hls.js:310
emit @ hls.js:17604
trigger @ hls.js:17609
loadSource @ hls.js:17696
loadSelectedStream @ hls-demo.js:1828
applyConfigEditorValue @ hls-demo.js:3032
onclick @ ?src=https%3A%2F%2Ftest-streams.mux.dev%2Fx36xhzz%2Fx36xhzz.m3u8&demoConfig=eyJlbmFibGVTdHJlYW1pbmciOnRydWUsImF1dG9SZWNvdmVyRXJyb3IiOnRydWUsInN0b3BPblN0YWxsIjpmYWxzZSwiZHVtcGZNUDQiOmZhbHNlLCJsZXZlbENhcHBpbmciOi0xLCJsaW1pdE1ldHJpY3MiOi0xfQ==:140
hls.js:7711 [warn] > Nudging 'currentTime' from 12.996104 to 13.096104
_tryNudgeBuffer @ hls.js:7711
_tryFixBufferStall @ hls.js:7613
poll @ hls.js:7568
checkBuffer @ hls.js:10425
onTickEnd @ hls.js:9757
doTick @ hls.js:9751
tick @ hls.js:22845
setInterval (async)
setInterval @ hls.js:22800
startLoad @ hls.js:9660
(anonymous) @ hls.js:17716
startLoad @ hls.js:17715
onManifestLoaded @ hls.js:8611
emit @ hls.js:310
emit @ hls.js:17604
trigger @ hls.js:17609
handleMasterPlaylist @ hls.js:20678
loadsuccess @ hls.js:20602
readystatechange @ hls.js:28762
XMLHttpRequest.send (async)
loadInternal @ hls.js:28696
load @ hls.js:28637
load @ hls.js:20575
onManifestLoading @ hls.js:20428
emit @ hls.js:310
emit @ hls.js:17604
trigger @ hls.js:17609
loadSource @ hls.js:17696
loadSelectedStream @ hls-demo.js:1828
applyConfigEditorValue @ hls-demo.js:3032
onclick @ ?src=https%3A%2F%2Ftest-streams.mux.dev%2Fx36xhzz%2Fx36xhzz.m3u8&demoConfig=eyJlbmFibGVTdHJlYW1pbmciOnRydWUsImF1dG9SZWNvdmVyRXJyb3IiOnRydWUsInN0b3BPblN0YWxsIjpmYWxzZSwiZHVtcGZNUDQiOmZhbHNlLCJsZXZlbENhcHBpbmciOi0xLCJsaW1pdE1ldHJpY3MiOi0xfQ==:140
hls-demo.js:2148 Error event: {type: 'mediaError', details: 'bufferNudgeOnStall', fatal: false}
(anonymous) @ hls-demo.js:2148
emit @ hls.js:310
emit @ hls.js:17604
trigger @ hls.js:17609
_tryNudgeBuffer @ hls.js:7713
_tryFixBufferStall @ hls.js:7613
poll @ hls.js:7568
checkBuffer @ hls.js:10425
onTickEnd @ hls.js:9757
doTick @ hls.js:9751
tick @ hls.js:22845
setInterval (async)
setInterval @ hls.js:22800
startLoad @ hls.js:9660
(anonymous) @ hls.js:17716
startLoad @ hls.js:17715
onManifestLoaded @ hls.js:8611
emit @ hls.js:310
emit @ hls.js:17604
trigger @ hls.js:17609
handleMasterPlaylist @ hls.js:20678
loadsuccess @ hls.js:20602
readystatechange @ hls.js:28762
XMLHttpRequest.send (async)
loadInternal @ hls.js:28696
load @ hls.js:28637
load @ hls.js:20575
onManifestLoading @ hls.js:20428
emit @ hls.js:310
emit @ hls.js:17604
trigger @ hls.js:17609
loadSource @ hls.js:17696
loadSelectedStream @ hls-demo.js:1828
applyConfigEditorValue @ hls-demo.js:3032
onclick @ ?src=https%3A%2F%2Ftest-streams.mux.dev%2Fx36xhzz%2Fx36xhzz.m3u8&demoConfig=eyJlbmFibGVTdHJlYW1pbmciOnRydWUsImF1dG9SZWNvdmVyRXJyb3IiOnRydWUsInN0b3BPblN0YWxsIjpmYWxzZSwiZHVtcGZNUDQiOmZhbHNlLCJsZXZlbENhcHBpbmciOi0xLCJsaW1pdE1ldHJpY3MiOi0xfQ==:140
hls.js:3130 [log] > [stream-controller]: media seeking to 13.096, state: IDLE
hls.js:3130 [log] > [audio-stream-controller]: media seeking to 13.096, state: STOPPED
hls.js:3130 [log] > [subtitle-stream-controller]: media seeking to 13.096, state: IDLE
hls.js:10062 [log] > [stream-controller]: Media seeked to 13.096
hls.js:7483 [warn] > playback not stuck anymore @13.113104, after 50ms
poll @ hls.js:7483
checkBuffer @ hls.js:10425
onTickEnd @ hls.js:9757
doTick @ hls.js:9751
tick @ hls.js:22845
setInterval (async)
setInterval @ hls.js:22800
startLoad @ hls.js:9660
(anonymous) @ hls.js:17716
startLoad @ hls.js:17715
onManifestLoaded @ hls.js:8611
emit @ hls.js:310
emit @ hls.js:17604
trigger @ hls.js:17609
handleMasterPlaylist @ hls.js:20678
loadsuccess @ hls.js:20602
readystatechange @ hls.js:28762
XMLHttpRequest.send (async)
loadInternal @ hls.js:28696
load @ hls.js:28637
load @ hls.js:20575
onManifestLoading @ hls.js:20428
emit @ hls.js:310
emit @ hls.js:17604
trigger @ hls.js:17609
loadSource @ hls.js:17696
loadSelectedStream @ hls-demo.js:1828
applyConfigEditorValue @ hls-demo.js:3032
onclick @ ?src=https%3A%2F%2Ftest-streams.mux.dev%2Fx36xhzz%2Fx36xhzz.m3u8&demoConfig=eyJlbmFibGVTdHJlYW1pbmciOnRydWUsImF1dG9SZWNvdmVyRXJyb3IiOnRydWUsInN0b3BPblN0YWxsIjpmYWxzZSwiZHVtcGZNUDQiOmZhbHNlLCJsZXZlbENhcHBpbmciOi0xLCJsaW1pdE1ldHJpY3MiOi0xfQ==:140

Chrome media internals output

No response
@ROBERT-MCDOWELL ROBERT-MCDOWELL added Bug Needs Triage If there is a suspected stream issue, apply this label to triage if it is something we should fix. labels Aug 4, 2022
@robwalch
Copy link
Collaborator

robwalch commented Aug 4, 2022

According to the logs the buffer is provisioned. The stall is likely reported because the player is observing that currentTime has not changed for more than a certain threshold.

@ROBERT-MCDOWELL
Copy link
Author

ok so does it mean the player is too much redundant?

@ROBERT-MCDOWELL ROBERT-MCDOWELL mentioned this issue Aug 4, 2022
Closed
@robwalch robwalch mentioned this issue Aug 30, 2022
Closed
@robwalch
Copy link
Collaborator

robwalch commented Jan 5, 2023 

hls.js:7631 [warn] > Playback stalling at @12.996104 due to low buffer ({"len":87.012239,"start":0.023222,"end":100.008343})
_reportStall @ hls.js:7631
poll @ hls.js:7559
checkBuffer @ hls.js:10425

This means that the stall event was fired at 12.9s of playback with 87 seconds of buffer available by checkBuffer. It would only do this if the browser you are running failed to update currentTime between multiple checks for a certain period. Yes it is too redundant and should rely more prominently on waiting and canplay events and only fallback to currentTime observation when these events do not fire or the playback is not advancing on or near a buffer gap.

@robwalch robwalch added Confirmed Bug report confirmed or reproduced. and removed Needs Triage If there is a suspected stream issue, apply this label to triage if it is something we should fix. labels Jan 5, 2023
@robwalch
Copy link
Collaborator

robwalch commented Jan 5, 2023

I've observed similar behavior in Chrome 107-108. It might be related to JavaScript throttling or some other internal issue. Restarting Chrome clears things up.

@ROBERT-MCDOWELL
Copy link
Author

ROBERT-MCDOWELL commented Jan 6, 2023 •
edited
Loading

@robwalch
thanks for the clarification. weird it's also happening on last chrome versions.... did not know there is such kind of throttle for javascript.... is it also possible that the GPU / CPU busy for something else has not enough speed/time to interpret the jS?

@robwalch
Copy link
Collaborator

robwalch commented Jan 6, 2023

it's possible

@robwalch
Copy link
Collaborator

robwalch commented Jan 6, 2023

Related to #4884

@ROBERT-MCDOWELL
Copy link
Author

do you think a compromise can be done from the code? I close the issue?

@robwalch robwalch added this to the 1.6.0 milestone Jan 28, 2025
@robwalch
Copy link
Collaborator

Marking as resolved by #6941.

@github-project-automation github-project-automation bot moved this from To do to Done in HLS.js Performance Jan 28, 2025
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Assignees
No one assigned
Labels
Bug Confirmed Bug report confirmed or reproduced. gap-controller
Projects
HLS.js Release Planning and Backlog
Archived in project
Milestone
1.6.0
Development

No branches or pull requests
2 participants
@robwalch @ROBERT-MCDOWELL