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

HLS interstitials freezes while playing #6765

Open
5 tasks done
acris5 opened this issue Oct 10, 2024 · 4 comments
Open
5 tasks done

HLS interstitials freezes while playing #6765

acris5 opened this issue Oct 10, 2024 · 4 comments
Labels
Bug Confirmed Stream Issue
Milestone
1.6.0

Comments

@acris5
Copy link

acris5 commented Oct 10, 2024

What version of Hls.js are you using?

c8ee777

What browser (including version) are you using?

Chrome

What OS (including version) are you using?

Ubuntu 24.04

Test stream

No response

Configuration

USE_INTERSTITALS=true INTERSTITALS=true npm run build

Additional player setup steps

 cat video.m3u8 
#EXTM3U
#EXT-X-VERSION:6
## Generated with https://github.com/shaka-project/shaka-packager version 1d478935ae-release
#EXT-X-TARGETDURATION:3
#EXT-X-MEDIA-SEQUENCE:898
#EXT-X-PROGRAM-DATE-TIME:2024-10-08T15:46:59.807Z
#EXT-X-MAP:URI="video_init.mp4"
#EXTINF:2.000,
video_899.m4s
#EXTINF:2.000,
video_900.m4s
#EXTINF:2.000,
video_901.m4s
#EXT-X-DATERANGE:ID="id3",CLASS="com.apple.hls.interstitial",START-DATE="2024-10-08T15:47:06.391Z",DURATION=215.000,X-RESUME-OFFSET=215,X-ASSET-URI="http://127.0.0.1/advert/index.m3u8",X-TIMELINE-OCCUPIES="RANGE"
#EXTINF:2.000,
video_902.m4s
#EXTINF:2.000,
video_903.m4s
#EXTINF:2.000,
video_904.m4s
#EXTINF:2.000,
video_905.m4s

Checklist

Steps to reproduce

  1. I generate Live Hls playlist
  2. Insert Ext-x-datetime tag when scte35 comes

Expected behaviour

Interstitial loads and plays while duration of Ext-x-datetime, then video playback resumes

What actually happened?

Interstitial loads and plays first 5 seconds then I see grey screen only, no video resume
In browser log "interstitials-controller.ts:1427 [warn] > [interstitials]: Interstitial "1" removed while playing"

Console output

[1-1-2633930073] [log] > [stream-controller]: PARSED->IDLE
base-stream-controller.ts:896 [1-1-2633930073] [log] > [stream-controller]: Loading main sn: 91 of level 0 (frag:[182.165-184.165]) cc: 0 [0-97], target: 182.165
base-stream-controller.ts:1983 [1-1-2633930073] [log] > [stream-controller]: IDLE->FRAG_LOADING
base-stream-controller.ts:517 [1-1-2633930073] [log] > [stream-controller]: Loaded main sn: 91 of level 0
base-stream-controller.ts:1983 [1-1-2633930073] [log] > [stream-controller]: FRAG_LOADING->PARSING
transmuxer-interface.ts:394 [1-1-2633930073] [log] > [transmuxer.ts]: Flushed main sn: 91 of level 0
base-stream-controller.ts:1983 [1-1-2633930073] [log] > [stream-controller]: PARSING->PARSED
base-stream-controller.ts:1945 [1-1-2633930073] [log] > [stream-controller]: Parsed main sn: 91 of level 0 (frag:[182.165-184.226])
base-stream-controller.ts:703 [1-1-2633930073] [log] > [stream-controller]: Buffered main sn: 91 of level 0 (frag:[182.165-184.226] > buffer:[0.226-184.149])
base-stream-controller.ts:1983 [1-1-2633930073] [log] > [stream-controller]: PARSED->IDLE
base-stream-controller.ts:896 [1-1-2633930073] [log] > [stream-controller]: Loading main sn: 92 of level 0 (frag:[184.149-186.149]) cc: 0 [0-97], target: 184.149
base-stream-controller.ts:1983 [1-1-2633930073] [log] > [stream-controller]: IDLE->FRAG_LOADING
base-stream-controller.ts:517 [1-1-2633930073] [log] > [stream-controller]: Loaded main sn: 92 of level 0
base-stream-controller.ts:1983 [1-1-2633930073] [log] > [stream-controller]: FRAG_LOADING->PARSING
transmuxer-interface.ts:394 [1-1-2633930073] [log] > [transmuxer.ts]: Flushed main sn: 92 of level 0
base-stream-controller.ts:1983 [1-1-2633930073] [log] > [stream-controller]: PARSING->PARSED
base-stream-controller.ts:1945 [1-1-2633930073] [log] > [stream-controller]: Parsed main sn: 92 of level 0 (frag:[184.149-186.226])
base-stream-controller.ts:703 [1-1-2633930073] [log] > [stream-controller]: Buffered main sn: 92 of level 0 (frag:[184.149-186.226] > buffer:[0.226-186.155])
base-stream-controller.ts:1983 [1-1-2633930073] [log] > [stream-controller]: PARSED->IDLE
base-stream-controller.ts:896 [1-1-2633930073] [log] > [stream-controller]: Loading main sn: 93 of level 0 (frag:[186.155-188.155]) cc: 0 [0-97], target: 186.155
base-stream-controller.ts:1983 [1-1-2633930073] [log] > [stream-controller]: IDLE->FRAG_LOADING
base-stream-controller.ts:517 [1-1-2633930073] [log] > [stream-controller]: Loaded main sn: 93 of level 0
base-stream-controller.ts:1983 [1-1-2633930073] [log] > [stream-controller]: FRAG_LOADING->PARSING
transmuxer-interface.ts:394 [1-1-2633930073] [log] > [transmuxer.ts]: Flushed main sn: 93 of level 0
base-stream-controller.ts:1983 [1-1-2633930073] [log] > [stream-controller]: PARSING->PARSED
base-stream-controller.ts:1945 [1-1-2633930073] [log] > [stream-controller]: Parsed main sn: 93 of level 0 (frag:[186.155-188.226])
base-stream-controller.ts:703 [1-1-2633930073] [log] > [stream-controller]: Buffered main sn: 93 of level 0 (frag:[186.155-188.226] > buffer:[0.226-188.160])
base-stream-controller.ts:1983 [1-1-2633930073] [log] > [stream-controller]: PARSED->IDLE
base-stream-controller.ts:896 [1-1-2633930073] [log] > [stream-controller]: Loading main sn: 94 of level 0 (frag:[188.160-190.160]) cc: 0 [0-97], target: 188.16
base-stream-controller.ts:1983 [1-1-2633930073] [log] > [stream-controller]: IDLE->FRAG_LOADING
base-stream-controller.ts:517 [1-1-2633930073] [log] > [stream-controller]: Loaded main sn: 94 of level 0
base-stream-controller.ts:1983 [1-1-2633930073] [log] > [stream-controller]: FRAG_LOADING->PARSING
transmuxer-interface.ts:394 [1-1-2633930073] [log] > [transmuxer.ts]: Flushed main sn: 94 of level 0
base-stream-controller.ts:1983 [1-1-2633930073] [log] > [stream-controller]: PARSING->PARSED
base-stream-controller.ts:1945 [1-1-2633930073] [log] > [stream-controller]: Parsed main sn: 94 of level 0 (frag:[188.160-190.226])
base-stream-controller.ts:703 [1-1-2633930073] [log] > [stream-controller]: Buffered main sn: 94 of level 0 (frag:[188.160-190.226] > buffer:[0.226-190.165])
base-stream-controller.ts:1983 [1-1-2633930073] [log] > [stream-controller]: PARSED->IDLE
base-stream-controller.ts:896 [1-1-2633930073] [log] > [stream-controller]: Loading main sn: 95 of level 0 (frag:[190.165-192.165]) cc: 0 [0-97], target: 190.165
base-stream-controller.ts:1983 [1-1-2633930073] [log] > [stream-controller]: IDLE->FRAG_LOADING
base-stream-controller.ts:517 [1-1-2633930073] [log] > [stream-controller]: Loaded main sn: 95 of level 0
base-stream-controller.ts:1983 [1-1-2633930073] [log] > [stream-controller]: FRAG_LOADING->PARSING
transmuxer-interface.ts:394 [1-1-2633930073] [log] > [transmuxer.ts]: Flushed main sn: 95 of level 0
base-stream-controller.ts:1983 [1-1-2633930073] [log] > [stream-controller]: PARSING->PARSED
base-stream-controller.ts:1945 [1-1-2633930073] [log] > [stream-controller]: Parsed main sn: 95 of level 0 (frag:[190.165-192.226])
base-stream-controller.ts:703 [1-1-2633930073] [log] > [stream-controller]: Buffered main sn: 95 of level 0 (frag:[190.165-192.226] > buffer:[0.226-192.149])
base-stream-controller.ts:1983 [1-1-2633930073] [log] > [stream-controller]: PARSED->IDLE
base-stream-controller.ts:896 [1-1-2633930073] [log] > [stream-controller]: Loading main sn: 96 of level 0 (frag:[192.149-194.149]) cc: 0 [0-97], target: 192.149
base-stream-controller.ts:1983 [1-1-2633930073] [log] > [stream-controller]: IDLE->FRAG_LOADING
base-stream-controller.ts:517 [1-1-2633930073] [log] > [stream-controller]: Loaded main sn: 96 of level 0
base-stream-controller.ts:1983 [1-1-2633930073] [log] > [stream-controller]: FRAG_LOADING->PARSING
transmuxer-interface.ts:394 [1-1-2633930073] [log] > [transmuxer.ts]: Flushed main sn: 96 of level 0
base-stream-controller.ts:1983 [1-1-2633930073] [log] > [stream-controller]: PARSING->PARSED
base-stream-controller.ts:1945 [1-1-2633930073] [log] > [stream-controller]: Parsed main sn: 96 of level 0 (frag:[192.149-194.226])
base-stream-controller.ts:703 [1-1-2633930073] [log] > [stream-controller]: Buffered main sn: 96 of level 0 (frag:[192.149-194.226] > buffer:[0.226-194.155])
base-stream-controller.ts:1983 [1-1-2633930073] [log] > [stream-controller]: PARSED->IDLE
base-stream-controller.ts:896 [1-1-2633930073] [log] > [stream-controller]: Loading main sn: 97 of level 0 (frag:[194.155-194.715]) cc: 0 [0-97], target: 194.155
base-stream-controller.ts:1983 [1-1-2633930073] [log] > [stream-controller]: IDLE->FRAG_LOADING
base-stream-controller.ts:517 [1-1-2633930073] [log] > [stream-controller]: Loaded main sn: 97 of level 0
base-stream-controller.ts:1983 [1-1-2633930073] [log] > [stream-controller]: FRAG_LOADING->PARSING
transmuxer-interface.ts:394 [1-1-2633930073] [log] > [transmuxer.ts]: Flushed main sn: 97 of level 0
interstitials-controller.ts:1934 [log] > [interstitials]: Interstitial asset "1-1-2633930073" duration change 194.72533333333334 > 195.43466666666666
interstitials-controller.ts:1413 [log] > [interstitials]: Interstitial events (1): ["1" 104.00-234.00]
Schedule: [primary: 0.00-104.00],["1": 104.00-234.00],[primary: 234.00-Infinity]
scte35_3.html:97 Thu Oct 10 2024 16:31:24 GMT+0300 (Moscow Standard Time) HLS Intersticial Events INTERSTITIALS_UPDATED hlsInterstitialsUpdated 
Data {events: Array(1), schedule: Array(3), durations: {…}, removedIds: Array(0)}
base-stream-controller.ts:1983 [1-1-2633930073] [log] > [stream-controller]: PARSING->PARSED
base-stream-controller.ts:1945 [1-1-2633930073] [log] > [stream-controller]: Parsed main sn: 97 of level 0 (frag:[194.155-195.435])
base-stream-controller.ts:703 [1-1-2633930073] [log] > [stream-controller]: Buffered main sn: 97 of level 0 (frag:[194.155-195.435] > buffer:[0.226-194.786])
base-stream-controller.ts:1983 [1-1-2633930073] [log] > [stream-controller]: PARSED->IDLE
buffer-controller.ts:1033 [1-1-2633930073] [log] > [buffer-controller]: video buffer reached EOS
buffer-controller.ts:1033 [1-1-2633930073] [log] > [buffer-controller]: audio buffer reached EOS
buffer-controller.ts:1045 [1-1-2633930073] [log] > [buffer-controller]: Queueing EOS
base-stream-controller.ts:1983 [1-1-2633930073] [log] > [stream-controller]: IDLE->ENDED
buffer-controller.ts:1065 [1-1-2633930073] [log] > [buffer-controller]: Calling mediaSource.endOfStream()
interstitials-controller.ts:1969 [log] > [interstitials]: buffered to end of asset HlsAssetPlayer: ["1-1-2633930073" 104.00-299.43] 80db8c9a-d3ed-42ad-b28d-6275dee25641 
interstitials-controller.ts:1637 [log] > [interstitials]: buffered to boundary [primary: 234.00-Infinity] (193.77 remaining)
hls.ts:571 [1-1-2633930073] [log] > pause buffering
hls.ts:522 [log] > startLoad(122.59760009765625)
hls.ts:556 [1-1-2633930073] [log] > resume buffering
level-controller.ts:647 [log] > [level-controller]: Loading level index 0 with http://127.0.0.1:8081/test_app/bitrate_1/video.m3u8
base-stream-controller.ts:1983 [log] > [stream-controller]: STOPPED->IDLE
audio-track-controller.ts:422 [log] > [audio-track-controller]: loading audio-track playlist 0 "stream_0" lang:ru group:default-audio-group
base-stream-controller.ts:1983 [log] > [audio-stream-controller]: STOPPED->WAITING_TRACK
base-stream-controller.ts:1983 [log] > [audio-stream-controller]: WAITING_TRACK->WAITING_INIT_PTS
base-stream-controller.ts:1983 [log] > [subtitle-stream-controller]: STOPPED->IDLE
buffer-controller.ts:1510 [1-1-2633930073] [log] > [buffer-controller]: Media source ended
base-playlist-controller.ts:163 [log] > [level-controller]: live playlist 0 REFRESHED 65--1
base-playlist-controller.ts:290 [log] > [level-controller]: reload live playlist 0 in 1995 ms
stream-controller.ts:664 [log] > [stream-controller]: Level 0 loaded [59,65][part-65--1], cc [0, 0] duration:14
audio-track-controller.ts:100 [log] > [audio-track-controller]: Audio track 0 "stream_0" lang:ru group:default-audio-group loaded [60-66]
base-playlist-controller.ts:163 [log] > [audio-track-controller]: live playlist 0 REFRESHED 66--1
base-playlist-controller.ts:290 [log] > [audio-track-controller]: reload live playlist 0 in 1979 ms
audio-stream-controller.ts:533 [log] > [audio-stream-controller]: Audio track 0 loaded [60,66][part-66--1],duration:13.994333333333344
base-stream-controller.ts:1983 [log] > [audio-stream-controller]: WAITING_INIT_PTS->IDLE
audio-track-controller.ts:422 [log] > [audio-track-controller]: loading audio-track playlist 0 "stream_0" lang:ru group:default-audio-group
audio-track-controller.ts:100 [log] > [audio-track-controller]: Audio track 0 "stream_0" lang:ru group:default-audio-group loaded [60-66]
base-playlist-controller.ts:163 [log] > [audio-track-controller]: live playlist 0 MISSED
base-playlist-controller.ts:290 [log] > [audio-track-controller]: reload live playlist 0 in 1496 ms
audio-stream-controller.ts:533 [log] > [audio-stream-controller]: Audio track 0 loaded [60,66][part-66--1],duration:13.994333333333344
level-controller.ts:647 [log] > [level-controller]: Loading level index 0 with http://127.0.0.1:8081/test_app/bitrate_1/video.m3u8
base-playlist-controller.ts:163 [log] > [level-controller]: live playlist 0 MISSED
base-playlist-controller.ts:290 [log] > [level-controller]: reload live playlist 0 in 1496 ms
stream-controller.ts:664 [log] > [stream-controller]: Level 0 loaded [59,65][part-65--1], cc [0, 0] duration:14
audio-track-controller.ts:422 [log] > [audio-track-controller]: loading audio-track playlist 0 "stream_0" lang:ru group:default-audio-group
audio-track-controller.ts:100 [log] > [audio-track-controller]: Audio track 0 "stream_0" lang:ru group:default-audio-group loaded [61-68]
base-playlist-controller.ts:163 [log] > [audio-track-controller]: live playlist 0 REFRESHED 68--1
base-playlist-controller.ts:290 [log] > [audio-track-controller]: reload live playlist 0 in 2995 ms
audio-stream-controller.ts:533 [log] > [audio-stream-controller]: Audio track 0 loaded [61,68][part-68--1],duration:15.998999999998915
level-controller.ts:647 [log] > [level-controller]: Loading level index 0 with http://127.0.0.1:8081/test_app/bitrate_1/video.m3u8
base-playlist-controller.ts:163 [log] > [level-controller]: live playlist 0 REFRESHED 66--1
base-playlist-controller.ts:290 [log] > [level-controller]: reload live playlist 0 in 1995 ms
stream-controller.ts:664 [log] > [stream-controller]: Level 0 loaded [60,66][part-66--1], cc [0, 0] duration:14
interstitials-controller.ts:1413 [log] > [interstitials]: Interstitial events (1): ["1" 103.40-233.40]
Schedule: [primary: 0.00-103.40],["1": 103.40-233.40],[primary: 233.40-Infinity]
scte35_3.html:97 Thu Oct 10 2024 16:31:27 GMT+0300 (Moscow Standard Time) HLS Intersticial Events INTERSTITIALS_UPDATED hlsInterstitialsUpdated 
Data {events: Array(1), schedule: Array(3), durations: {…}, removedIds: Array(0)}
scte35_3.html:184 Event received:  {event: Event, type: 'urn:scte:scte35:2014:xml+bin'}
level-controller.ts:647 [log] > [level-controller]: Loading level index 0 with http://127.0.0.1:8081/test_app/bitrate_1/video.m3u8
base-playlist-controller.ts:163 [log] > [level-controller]: live playlist 0 REFRESHED 67--1
base-playlist-controller.ts:290 [log] > [level-controller]: reload live playlist 0 in 2996 ms
stream-controller.ts:664 [log] > [stream-controller]: Level 0 loaded [61,67][part-67--1], cc [0, 0] duration:14
interstitials-controller.ts:1413 [log] > [interstitials]: Interstitial events (1): ["1" 104.04-234.04]
Schedule: [primary: 0.00-104.04],["1": 104.04-234.04],[primary: 234.04-Infinity]
scte35_3.html:97 Thu Oct 10 2024 16:31:29 GMT+0300 (Moscow Standard Time) HLS Intersticial Events INTERSTITIALS_UPDATED hlsInterstitialsUpdated 
Data {events: Array(1), schedule: Array(3), durations: {…}, removedIds: Array(0)}
audio-track-controller.ts:422 [log] > [audio-track-controller]: loading audio-track playlist 0 "stream_0" lang:ru group:default-audio-group
audio-track-controller.ts:100 [log] > [audio-track-controller]: Audio track 0 "stream_0" lang:ru group:default-audio-group loaded [63-69]
base-playlist-controller.ts:163 [log] > [audio-track-controller]: live playlist 0 REFRESHED 69--1
base-playlist-controller.ts:290 [log] > [audio-track-controller]: reload live playlist 0 in 2995 ms
audio-stream-controller.ts:533 [log] > [audio-stream-controller]: Audio track 0 loaded [63,69][part-69--1],duration:14.01466666666775
level-controller.ts:647 [log] > [level-controller]: Loading level index 0 with http://127.0.0.1:8081/test_app/bitrate_1/video.m3u8
base-playlist-controller.ts:163 [log] > [level-controller]: live playlist 0 REFRESHED 69--1
base-playlist-controller.ts:290 [log] > [level-controller]: reload live playlist 0 in 2995 ms
stream-controller.ts:664 [log] > [stream-controller]: Level 0 loaded [63,69][part-69--1], cc [0, 0] duration:14
interstitials-controller.ts:1413 [log] > [interstitials]: Interstitial events (1): ["1" 103.96-233.96]
Schedule: [primary: 0.00-103.96],["1": 103.96-233.96],[primary: 233.96-Infinity]
scte35_3.html:97 Thu Oct 10 2024 16:31:32 GMT+0300 (Moscow Standard Time) HLS Intersticial Events INTERSTITIALS_UPDATED hlsInterstitialsUpdated 
Data {events: Array(1), schedule: Array(3), durations: {…}, removedIds: Array(0)}
audio-track-controller.ts:422 [log] > [audio-track-controller]: loading audio-track playlist 0 "stream_0" lang:ru group:default-audio-group
audio-track-controller.ts:100 [log] > [audio-track-controller]: Audio track 0 "stream_0" lang:ru group:default-audio-group loaded [64-71]
base-playlist-controller.ts:163 [log] > [audio-track-controller]: live playlist 0 REFRESHED 71--1
base-playlist-controller.ts:290 [log] > [audio-track-controller]: reload live playlist 0 in 2991 ms
audio-stream-controller.ts:533 [log] > [audio-stream-controller]: Audio track 0 loaded [64,71][part-71--1],duration:15.998333333333335
level-controller.ts:647 [log] > [level-controller]: Loading level index 0 with http://127.0.0.1:8081/test_app/bitrate_1/video.m3u8
base-playlist-controller.ts:163 [log] > [level-controller]: live playlist 0 REFRESHED 70--1
base-playlist-controller.ts:290 [log] > [level-controller]: reload live playlist 0 in 2994 ms
stream-controller.ts:664 [log] > [stream-controller]: Level 0 loaded [64,70][part-70--1], cc [0, 0] duration:14
interstitials-controller.ts:1413 [log] > [interstitials]: Interstitial events (0): 
Schedule: [primary: 0.00-Infinity]
interstitials-controller.ts:1421 [log] > [interstitials]: Removed events 1
interstitials-controller.ts:1427 [warn] > [interstitials]: Interstitial "1" removed while playing
InterstitialsController._this.onScheduleUpdate @ interstitials-controller.ts:1427
updateSchedule @ interstitials-schedule.ts:296
parseInterstitialDateRanges @ interstitials-schedule.ts:272
onLevelUpdated @ interstitials-controller.ts:1272
emit @ index.js:203
emit @ hls.ts:356
trigger @ hls.ts:364
onLevelLoaded @ stream-controller.ts:704
emit @ index.js:203
emit @ hls.ts:356
trigger @ hls.ts:364
handlePlaylistLoaded @ playlist-loader.ts:681
handleTrackOrLevelPlaylist @ playlist-loader.ts:503
onSuccess @ playlist-loader.ts:318
readystatechange @ xhr-loader.ts:238
XMLHttpRequest.send (async)
openAndSendXhr @ xhr-loader.ts:165
loadInternal @ xhr-loader.ts:124
load @ xhr-loader.ts:82
load @ playlist-loader.ts:351
onLevelLoading @ playlist-loader.ts:165
emit @ index.js:203
emit @ hls.ts:356
trigger @ hls.ts:364
loadPlaylist @ level-controller.ts:661
(anonymous) @ base-playlist-controller.ts:312
setTimeout (async)
playlistLoaded @ base-playlist-controller.ts:311
onLevelLoaded @ level-controller.ts:622
emit @ index.js:203
emit @ hls.ts:356
trigger @ hls.ts:364
handlePlaylistLoaded @ playlist-loader.ts:681
handleTrackOrLevelPlaylist @ playlist-loader.ts:503
onSuccess @ playlist-loader.ts:318
readystatechange @ xhr-loader.ts:238
XMLHttpRequest.send (async)
openAndSendXhr @ xhr-loader.ts:165
loadInternal @ xhr-loader.ts:124
load @ xhr-loader.ts:82
load @ playlist-loader.ts:351
onLevelLoading @ playlist-loader.ts:165
emit @ index.js:203
emit @ hls.ts:356
trigger @ hls.ts:364
loadPlaylist @ level-controller.ts:661
(anonymous) @ base-playlist-controller.ts:312
setTimeout (async)
playlistLoaded @ base-playlist-controller.ts:311
onLevelLoaded @ level-controller.ts:622
emit @ index.js:203
emit @ hls.ts:356
trigger @ hls.ts:364
handlePlaylistLoaded @ playlist-loader.ts:681
handleTrackOrLevelPlaylist @ playlist-loader.ts:503
onSuccess @ playlist-loader.ts:318
readystatechange @ xhr-loader.ts:238
XMLHttpRequest.send (async)
openAndSendXhr @ xhr-loader.ts:165
loadInternal @ xhr-loader.ts:124
load @ xhr-loader.ts:82
load @ playlist-loader.ts:351
onLevelLoading @ playlist-loader.ts:165
emit @ index.js:203
emit @ hls.ts:356
trigger @ hls.ts:364
loadPlaylist @ level-controller.ts:661
(anonymous) @ base-playlist-controller.ts:312
setTimeout (async)
playlistLoaded @ base-playlist-controller.ts:311
onLevelLoaded @ level-controller.ts:622
emit @ index.js:203
emit @ hls.ts:356
trigger @ hls.ts:364
handlePlaylistLoaded @ playlist-loader.ts:681
handleTrackOrLevelPlaylist @ playlist-loader.ts:503
onSuccess @ playlist-loader.ts:318
readystatechange @ xhr-loader.ts:238
XMLHttpRequest.send (async)
openAndSendXhr @ xhr-loader.ts:165
loadInternal @ xhr-loader.ts:124
load @ xhr-loader.ts:82
load @ playlist-loader.ts:351
onLevelLoading @ playlist-loader.ts:165
emit @ index.js:203
emit @ hls.ts:356
trigger @ hls.ts:364
loadPlaylist @ level-controller.ts:661
(anonymous) @ base-playlist-controller.ts:312
setTimeout (async)
playlistLoaded @ base-playlist-controller.ts:311
onLevelLoaded @ level-controller.ts:622
emit @ index.js:203
emit @ hls.ts:356
trigger @ hls.ts:364
handlePlaylistLoaded @ playlist-loader.ts:681
handleTrackOrLevelPlaylist @ playlist-loader.ts:503
onSuccess @ playlist-loader.ts:318
readystatechange @ xhr-loader.ts:238
XMLHttpRequest.send (async)
openAndSendXhr @ xhr-loader.ts:165
loadInternal @ xhr-loader.ts:124
load @ xhr-loader.ts:82
load @ playlist-loader.ts:351
onLevelLoading @ playlist-loader.ts:165
emit @ index.js:203
emit @ hls.ts:356
trigger @ hls.ts:364
loadPlaylist @ level-controller.ts:661
(anonymous) @ base-playlist-controller.ts:312
setTimeout (async)
playlistLoaded @ base-playlist-controller.ts:311
onLevelLoaded @ level-controller.ts:622
emit @ index.js:203
emit @ hls.ts:356
trigger @ hls.ts:364
handlePlaylistLoaded @ playlist-loader.ts:681
handleTrackOrLevelPlaylist @ playlist-loader.ts:503
onSuccess @ playlist-loader.ts:318
readystatechange @ xhr-loader.ts:238
XMLHttpRequest.send (async)
openAndSendXhr @ xhr-loader.ts:165
loadInternal @ xhr-loader.ts:124
load @ xhr-loader.ts:82
load @ playlist-loader.ts:351
onLevelLoading @ playlist-loader.ts:165
emit @ index.js:203
emit @ hls.ts:356
trigger @ hls.ts:364
loadPlaylist @ level-controller.ts:661
startLoad @ base-playlist-controller.ts:52
(anonymous) @ hls.ts:530
startLoad @ hls.ts:529
preloadPrimary @ interstitials-controller.ts:1694
bufferedToItem @ interstitials-controller.ts:1682
bufferedToEnd @ interstitials-controller.ts:1992
emit @ index.js:182
emit @ hls.ts:356
trigger @ hls.ts:364
(anonymous) @ buffer-controller.ts:1069
(anonymous) @ buffer-controller.ts:1668
Promise.then (async)
blockBuffers @ buffer-controller.ts:1663
blockUntilOpen @ buffer-controller.ts:1621
onBufferEos @ buffer-controller.ts:1046
emit @ index.js:182
emit @ hls.ts:356
trigger @ hls.ts:364
doTickIdle @ stream-controller.ts:265
doTick @ stream-controller.ts:215
tick @ task-loop.ts:109
onFragBuffered @ stream-controller.ts:972
emit @ index.js:203
emit @ hls.ts:356
trigger @ hls.ts:364
onUnblocked @ buffer-controller.ts:992
(anonymous) @ buffer-controller.ts:1668
Promise.then (async)
blockBuffers @ buffer-controller.ts:1663
onFragParsed @ buffer-controller.ts:1006
emit @ index.js:182
emit @ hls.ts:356
trigger @ hls.ts:364
updateLevelTiming @ base-stream-controller.ts:1950
_handleTransmuxerFlush @ base-stream-controller.ts:1034
TransmuxerInterface.onWorkerMessage @ transmuxer-interface.ts:387
Show 17 more frames
Show less
interstitials-controller.ts:2071 [log] > [interstitials]: clearAssetPlayer "1-1-2633930073" toSegment: undefined
hls.ts:403 [1-1-2633930073] [log] > destroy
hls.ts:448 [1-1-2633930073] [log] > detachMedia
buffer-controller.ts:456 [1-1-2633930073] [log] > [buffer-controller]: media source detaching
base-stream-controller.ts:1983 [1-1-2633930073] [log] > [stream-controller]: ENDED->STOPPED
5e7062fc-8583-43c9-a687-1e57e6c16ec2:545 [log] > Debug logs enabled for "main" in hls.js version undefined
base-stream-controller.ts:1983 [1-1-2633930073] [log] > [subtitle-stream-controller]: IDLE->STOPPED
scte35_3.html:97 Thu Oct 10 2024 16:31:35 GMT+0300 (Moscow Standard Time) HLS Intersticial Events INTERSTITIALS_UPDATED hlsInterstitialsUpdated 
Data {events: Array(0), schedule: Array(1), durations: {…}, removedIds: Array(1)}
audio-track-controller.ts:422 [log] > [audio-track-controller]: loading audio-track playlist 0 "stream_0" lang:ru group:default-audio-group
audio-track-controller.ts:100 [log] > [audio-track-controller]: Audio track 0 "stream_0" lang:ru group:default-audio-group loaded [65-72]
base-playlist-controller.ts:163 [log] > [audio-track-controller]: live playlist 0 REFRESHED 72--1
base-playlist-controller.ts:290 [log] > [audio-track-controller]: reload live playlist 0 in 2995 ms
audio-stream-controller.ts:533 [log] > [audio-stream-controller]: Audio track 0 loaded [65,72][part-72--1],duration:15.99799999999999
level-controller.ts:647 [log] > [level-controller]: Loading level index 0 with http://127.0.0.1:8081/test_app/bitrate_1/video.m3u8
base-playlist-controller.ts:163 [log] > [level-controller]: live playlist 0 REFRESHED 72--1
base-playlist-controller.ts:290 [log] > [level-controller]: reload live playlist 0 in 2996 ms
stream-controller.ts:664 [log] > [stream-controller]: Level 0 loaded [66,72][part-72--1], cc [0, 0] duration:14
audio-track-controller.ts:422 [log] > [audio-track-controller]: loading audio-track playlist 0 "stream_0" lang:ru group:default-audio-group
audio-track-controller.ts:100 [log] > [audio-track-controller]: Audio track 0 "stream_0" lang:ru group:default-audio-group loaded [68-74]
base-playlist-controller.ts:163 [log] > [audio-track-controller]: live playlist 0 REFRESHED 74--1
base-playlist-controller.ts:290 [log] > [audio-track-controller]: reload live playlist 0 in 2996 ms
audio-stream-controller.ts:533 [log] > [audio-stream-controller]: Audio track 0 loaded [68,74][part-74--1],duration:13.9

Chrome media internals output

No response
@acris5 acris5 added Bug Needs Triage If there is a suspected stream issue, apply this label to triage if it is something we should fix. labels Oct 10, 2024
@robwalch
Copy link
Collaborator 

interstitials-controller.ts:1421 [log] > [interstitials]: Removed events 1
interstitials-controller.ts:1427 [warn] > [interstitials]: Interstitial "1" removed while playing

It looks like the date range was removed from the live playlist while playback (active schedule index) was in the event. We haven't confirmed what the expected behavior is here. I think the expectation should be that the the interstitial is exited immediately and throwing playback back to primary at the nearest available resumption offset (you've defined one that might not exist until the interstitial is played through completely).

This one will be a little tricky to replicate, but we can inject dateranges in the parser over live to replicate what's in the logs. Any additional debugging on your end would help. The first thing we might want to try is calling clearInterstitial if it's not already and then call setSchedulePosition with the resolved schedule position here

hls.js/src/controller/interstitials-controller.ts

Lines 1419 to 1425 in d25ad97

if (
this.isInterstitial(playingItem) &&
removedIds.includes(playingItem.event.identifier)
) {
this.warn(
`Interstitial "${playingItem.event.identifier}" removed while playing`,
);

@robwalch robwalch added Stream Issue and removed Needs Triage If there is a suspected stream issue, apply this label to triage if it is something we should fix. labels Oct 17, 2024
@robwalch
Copy link
Collaborator

robwalch commented Oct 17, 2024 •
edited
Loading

From the HLS side, try keeping the date range in the playlist until the START-DATE + DURATION is less than the first PDT in the playlist (plus some number of target-durations for tardy clients).

@acris5
Copy link
Author

acris5 commented Oct 18, 2024

You are absolutely right, the thing was that EXT-X-DATERANGE went out of sliding window of playlist. I added it to header and it now works. Also I found that if advert playlist duration is more that tag's duration value then playback also is stalled. I think it will be better to continue main video in all cases.

@acris5
Copy link
Author

acris5 commented Oct 18, 2024

Now I use both EXT-X-DATERANGE and EXT-CUE tags, it perfectly works with hls.js and safari implementation https://github.com/Eyevinn/hls-interstitials-web-demo/tree/master

#EXTM3U
#EXT-X-VERSION:6
## Generated with https://github.com/shaka-project/shaka-packager version 24c9a404bc-release
#EXT-X-TARGETDURATION:3
#EXT-X-MEDIA-SEQUENCE:553
#EXT-X-PROGRAM-DATE-TIME:2024-10-16T16:23:26.032Z
#EXT-X-MAP:URI="video_init.mp4"
#EXT-X-DATERANGE:ID="1",CLASS="com.apple.hls.interstitial",START-DATE="2024-10-16T16:22:54.540Z",DURATION=110.000,X-RESUME-OFFSET=0.000,X-ASSET-URI="advert/index.m3u8",X-TIMELINE-OCCUPIES="RANGE"
#EXTINF:2.000,
video_554.m4s
#EXT-X-CUE-CONT:27.200/110.000
#EXTINF:2.000,
video_555.m4s
#EXT-X-CUE-CONT:29.200/110.000
#EXTINF:2.000,
video_556.m4s
#EXT-X-CUE-CONT:31.200/110.000
#EXTINF:2.000,
video_557.m4s
#EXT-X-CUE-CONT:33.200/110.000
#EXTINF:2.000,
video_558.m4s
#EXT-X-CUE-CONT:35.200/110.000
#EXTINF:2.000,
video_559.m4s
#EXT-X-CUE-CONT:37.200/110.000
#EXTINF:2.000,
video_560.m4s
#EXT-X-CUE-CONT:39.200/110.000
#EXTINF:2.000,
video_561.m4s
#EXT-X-CUE-CONT:41.200/110.000
#EXTINF:2.000,
video_562.m4s
#EXT-X-CUE-CONT:43.200/110.000
#EXTINF:2.000,
video_563.m4s
#EXT-X-CUE-CONT:45.200/110.000
#EXTINF:2.000,
video_564.m4s
#EXT-X-CUE-CONT:47.200/110.000
#EXTINF:2.000,
video_565.m4s
#EXT-X-CUE-CONT:49.200/110.000
#EXTINF:2.000,
video_566.m4s
#EXT-X-CUE-CONT:51.200/110.000
#EXTINF:2.000,
video_567.m4s
#EXT-X-CUE-CONT:53.200/110.000
#EXTINF:2.000,
video_568.m4s
#EXT-X-CUE-CONT:55.200/110.000
#EXTINF:2.000,
video_569.m4s

@robwalch robwalch added this to the 1.6.0 milestone Oct 21, 2024
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 Stream Issue
Projects
None yet
Milestone
1.6.0
Development

No branches or pull requests
2 participants
@robwalch @acris5