search

video-dev / hls.js

HLS.js is a JavaScript library that plays HLS in browsers with support for MSE.
https://hlsjs.video-dev.org/demo
Other
14.42k stars 2.55k forks source link

Live LLHLS stream intermittently fails to start playing #3473

Closed crazytoad closed 3 years ago

crazytoad commented 3 years ago

What version of Hls.js are you using?

v1.0.0-beta.4

What browser and OS are you using?

Chrome on macOS

Test stream:

I can provide a test stream over Slack

Checklist

Steps to reproduce

Using the v1.0.0-beta.4 demo page, https://hls-js-0bd03ae0-14e6-4a69-a198-a0fa2cc343ea.netlify.app/demo/, my live stream fails to start playing maybe 1 out of 15 times or so. When it fails, it appears to get stuck in the audio stream controller and never recovers.

Expected behavior

Stream starts playing

Actual behavior

Stream never starts

Console output

[log] > loadSource:https://localhost/live/ngrp:test.f4v_cmaf/playlist_sfm4s.m3u8
12:12:27.922 stream-controller.ts:538 [log] > [stream-controller]: Trigger BUFFER_RESET
12:12:27.922 hls.ts:340 [log] > attachMedia
12:12:27.946 buffer-controller.ts:697 [log] > [buffer-controller]: Media source opened
12:12:27.947 base-stream-controller.ts:1095 [log] > [subtitle-stream-controller]: STOPPED->IDLE
12:12:27.950 level-controller.ts:165 [log] > [level-controller]: manifest loaded, 3 level(s) found, first bitrate: 3000000
12:12:27.950 buffer-controller.ts:127 [log] > 2 bufferCodec event(s) expected
12:12:27.951 hls.ts:382 [log] > startLoad(-1)
12:12:27.951 level-controller.ts:233 [log] > [level-controller]: switching to level 2 from -1
12:12:27.951 audio-track-controller.ts:130 [log] > [audio-track-controller]: Updating audio tracks, 1 track(s) found in "Audio" group-id
12:12:27.952 audio-track-controller.ts:186 [log] > [audio-track-controller]: Now switching to audio-track index 0
12:12:27.952 base-stream-controller.ts:1095 [log] > [audio-stream-controller]: STOPPED->IDLE
12:12:27.952 base-stream-controller.ts:1095 [log] > [audio-stream-controller]: IDLE->WAITING_TRACK
12:12:27.953 level-controller.ts:506 [log] > [level-controller]: Attempt loading level index 2 with URL-id 0 https://localhost/live/ngrp:test.f4v_cmaf/cl_w3492934_b3000000_vo_sfm4s_ZX8tdXM=.m3u8
12:12:27.954 base-stream-controller.ts:1095 [log] > [stream-controller]: STOPPED->IDLE
12:12:27.954 audio-track-controller.ts:246 [log] > [audio-track-controller]: loading audio-track playlist for id: 0
12:12:27.954 base-stream-controller.ts:1095 [log] > [audio-stream-controller]: WAITING_TRACK->STOPPED
12:12:27.954 base-stream-controller.ts:1095 [log] > [audio-stream-controller]: STOPPED->WAITING_TRACK
12:12:27.954 base-stream-controller.ts:1095 [log] > [subtitle-stream-controller]: IDLE->STOPPED
12:12:27.955 base-stream-controller.ts:1095 [log] > [subtitle-stream-controller]: STOPPED->IDLE
12:12:27.983 xhr-loader.ts:244 Refused to get unsafe header "age"
getResponseHeader @ xhr-loader.ts:244
12:12:27.984 audio-track-controller.ts:83 [log] > [audio-track-controller]: audioTrack 0 loaded [145-149]
12:12:27.984 audio-track-controller.ts:246 [log] > [audio-track-controller]: loading audio-track playlist for id: 0
12:12:27.984 audio-stream-controller.ts:433 [log] > [audio-stream-controller]: Track 0 loaded [145,149],duration:19.970000000000002
12:12:27.984 base-stream-controller.ts:972 [log] > [audio-stream-controller]: Live playlist - first load, unknown sliding
12:12:27.984 base-stream-controller.ts:1009 [log] > [audio-stream-controller]: Configure startPosition to 0
12:12:27.984 base-stream-controller.ts:1095 [log] > [audio-stream-controller]: WAITING_TRACK->IDLE
12:12:27.984 base-stream-controller.ts:535 [log] > [audio-stream-controller]: Loading fragment initSegment cc: 0 track: 0, target: 0
12:12:27.984 base-stream-controller.ts:1095 [log] > [audio-stream-controller]: IDLE->FRAG_LOADING
12:12:27.985 level-controller.ts:506 [log] > [level-controller]: Attempt loading level index 2 at sn 149 part 4 with URL-id 0 https://localhost/live/ngrp:test.f4v_cmaf/cl_w3492934_b3000000_vo_sfm4s_ZX8tdXM=.m3u8?_HLS_msn=149&_HLS_part=4&_HLS_skip=YES
12:12:27.985 base-stream-controller.ts:1095 [log] > [stream-controller]: IDLE->WAITING_LEVEL
12:12:27.985 stream-controller.ts:602 [log] > [stream-controller]: Level 2 loaded [145,149], cc [0, 0] duration:20
12:12:27.985 base-stream-controller.ts:972 [log] > [stream-controller]: Live playlist - first load, unknown sliding
12:12:27.985 buffer-controller.ts:602 [log] > [buffer-controller]: Updating Media Source duration to 20.000
12:12:27.985 base-stream-controller.ts:1095 [log] > [stream-controller]: WAITING_LEVEL->IDLE
12:12:27.985 base-stream-controller.ts:1009 [log] > [stream-controller]: Configure startPosition to 16.997284912109375
12:12:27.986 base-stream-controller.ts:535 [log] > [stream-controller]: Loading fragment initSegment cc: 0 level: 2, target: 0
12:12:27.986 base-stream-controller.ts:1095 [log] > [stream-controller]: IDLE->FRAG_LOADING
12:12:28.003 base-stream-controller.ts:1095 [log] > [stream-controller]: FRAG_LOADING->IDLE
12:12:28.003 base-stream-controller.ts:449 [log] > [stream-controller]: Buffered main sn: initSegment of level 2 
12:12:28.003 base-stream-controller.ts:786 [log] > [stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:28.003 base-stream-controller.ts:502 [log] > [stream-controller]: Loading part sn: 149 p: 0 cc: 0 of playlist [145-149] parts [0-12-15] level: 2, target: 16.997
12:12:28.003 base-stream-controller.ts:1095 [log] > [stream-controller]: IDLE->FRAG_LOADING
12:12:28.004 base-stream-controller.ts:1095 [log] > [audio-stream-controller]: FRAG_LOADING->IDLE
12:12:28.004 base-stream-controller.ts:449 [log] > [audio-stream-controller]: Buffered audio sn: initSegment of track 0 
12:12:28.004 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:28.004 audio-stream-controller.ts:852 [log] > [audio-stream-controller]: Waiting for video PTS in continuity counter 0 of live stream before loading audio fragment 145 of level 0
12:12:28.004 base-stream-controller.ts:1095 [log] > [audio-stream-controller]: IDLE->WAITING_INIT_PTS
12:12:28.004 base-stream-controller.ts:1095 [log] > [audio-stream-controller]: WAITING_INIT_PTS->IDLE
12:12:28.007 audio-track-controller.ts:83 [log] > [audio-track-controller]: audioTrack 0 loaded [146-149]
12:12:28.007 base-playlist-controller.ts:119 [log] > [audio-track-controller]: live playlist 0 REFRESHED 150-0
12:12:28.008 audio-track-controller.ts:246 [log] > [audio-track-controller]: loading audio-track playlist for id: 0
12:12:28.008 audio-stream-controller.ts:433 [log] > [audio-stream-controller]: Track 0 loaded [146,149],duration:16.96
12:12:28.008 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:28.010 base-playlist-controller.ts:119 [log] > [level-controller]: live playlist 2 REFRESHED 150-0
12:12:28.010 level-controller.ts:506 [log] > [level-controller]: Attempt loading level index 2 at sn 150 part 1 with URL-id 0 https://localhost/live/ngrp:test.f4v_cmaf/cl_w3492934_b3000000_vo_sfm4s_ZX8tdXM=.m3u8?_HLS_msn=150&_HLS_part=1&_HLS_skip=YES
12:12:28.010 stream-controller.ts:602 [log] > [stream-controller]: Level 2 loaded [146,149], cc [0, 0] duration:17
12:12:28.010 buffer-controller.ts:602 [log] > [buffer-controller]: Updating Media Source duration to 21.000
12:12:28.048 transmuxer-interface.ts:66 [log] > demuxing in webworker
12:12:28.051 transmuxer-interface.ts:186 [log] > [transmuxer-interface, main]: Starting new transmux session for sn: 149 p: 0 level: 2 id: 1
        discontinuity: true
        trackSwitch: true
        contiguous: false
        accurateTimeOffset: false
        timeOffset: 16
12:12:28.055 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:28.063 4a063449-89e0-4bd0-b570-08aa358dc79d:603 [log] >
12:12:28.065 base-stream-controller.ts:1095 [log] > [stream-controller]: FRAG_LOADING->PARSING
12:12:28.065 stream-controller.ts:1277 [log] > [stream-controller]: Init video buffer, container:video/mp4, codecs[level/parsed]=[avc1.4d4c1f/avc1.4d4c1f]
12:12:28.066 audio-stream-controller.ts:115 [log] > [audio-stream-controller]: InitPTS for cc: 0 found from main: 576
12:12:28.127 4a063449-89e0-4bd0-b570-08aa358dc79d:946 [log] > [transmuxer.ts]: Flushed fragment 149 p: 3 of level 2
12:12:28.127 base-stream-controller.ts:1095 [log] > [stream-controller]: PARSING->PARSED
12:12:28.155 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:28.255 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:28.355 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:28.455 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:28.555 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:28.655 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:28.755 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:28.855 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:28.955 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:29.010 audio-track-controller.ts:83 [log] > [audio-track-controller]: audioTrack 0 loaded [146-149]
12:12:29.010 base-playlist-controller.ts:119 [log] > [audio-track-controller]: live playlist 0 REFRESHED 150-1
12:12:29.010 audio-track-controller.ts:246 [log] > [audio-track-controller]: loading audio-track playlist for id: 0
12:12:29.011 audio-stream-controller.ts:433 [log] > [audio-stream-controller]: Track 0 loaded [146,149],duration:17.945
12:12:29.011 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:29.012 base-playlist-controller.ts:119 [log] > [level-controller]: live playlist 2 REFRESHED 150-1
12:12:29.012 level-controller.ts:506 [log] > [level-controller]: Attempt loading level index 2 at sn 150 part 2 with URL-id 0 https://localhost/live/ngrp:test.f4v_cmaf/cl_w3492934_b3000000_vo_sfm4s_ZX8tdXM=.m3u8?_HLS_msn=150&_HLS_part=2&_HLS_skip=YES
12:12:29.013 stream-controller.ts:602 [log] > [stream-controller]: Level 2 loaded [146,149], cc [0, 0] duration:18
12:12:29.013 buffer-controller.ts:602 [log] > [buffer-controller]: Updating Media Source duration to 22.000
12:12:29.055 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:29.155 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:29.255 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:29.355 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:29.455 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:29.555 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:29.655 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:29.755 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:29.855 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:29.955 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:30.011 audio-track-controller.ts:83 [log] > [audio-track-controller]: audioTrack 0 loaded [146-149]
12:12:30.011 base-playlist-controller.ts:119 [log] > [audio-track-controller]: live playlist 0 REFRESHED 150-2
12:12:30.011 audio-track-controller.ts:246 [log] > [audio-track-controller]: loading audio-track playlist for id: 0
12:12:30.011 audio-stream-controller.ts:433 [log] > [audio-stream-controller]: Track 0 loaded [146,149],duration:18.926000000000002
12:12:30.011 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:30.013 base-playlist-controller.ts:119 [log] > [level-controller]: live playlist 2 REFRESHED 150-2
12:12:30.013 level-controller.ts:506 [log] > [level-controller]: Attempt loading level index 2 at sn 150 part 3 with URL-id 0 https://localhost/live/ngrp:test.f4v_cmaf/cl_w3492934_b3000000_vo_sfm4s_ZX8tdXM=.m3u8?_HLS_msn=150&_HLS_part=3&_HLS_skip=YES
12:12:30.014 stream-controller.ts:602 [log] > [stream-controller]: Level 2 loaded [146,149], cc [0, 0] duration:19
12:12:30.014 buffer-controller.ts:602 [log] > [buffer-controller]: Updating Media Source duration to 23.000
12:12:30.055 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:30.155 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:30.255 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:30.355 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:30.455 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:30.555 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:30.655 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:30.755 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:30.855 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:30.916 audio-track-controller.ts:83 [log] > [audio-track-controller]: audioTrack 0 loaded [146-149]
12:12:30.916 base-playlist-controller.ts:119 [log] > [audio-track-controller]: live playlist 0 REFRESHED 150-3
12:12:30.916 audio-track-controller.ts:246 [log] > [audio-track-controller]: loading audio-track playlist for id: 0
12:12:30.916 audio-stream-controller.ts:433 [log] > [audio-stream-controller]: Track 0 loaded [146,149],duration:19.927000000000003
12:12:30.916 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:30.918 base-playlist-controller.ts:119 [log] > [level-controller]: live playlist 2 REFRESHED 150-3
12:12:30.918 level-controller.ts:506 [log] > [level-controller]: Attempt loading level index 2 at sn 150 part 4 with URL-id 0 https://localhost/live/ngrp:test.f4v_cmaf/cl_w3492934_b3000000_vo_sfm4s_ZX8tdXM=.m3u8?_HLS_msn=150&_HLS_part=4&_HLS_skip=YES
12:12:30.918 stream-controller.ts:602 [log] > [stream-controller]: Level 2 loaded [146,149], cc [0, 0] duration:20
12:12:30.918 buffer-controller.ts:602 [log] > [buffer-controller]: Updating Media Source duration to 24.000
12:12:30.955 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:31.055 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:31.155 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:31.255 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:31.355 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:31.455 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:31.555 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:31.655 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:31.755 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:31.855 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:31.955 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:32.016 audio-track-controller.ts:83 [log] > [audio-track-controller]: audioTrack 0 loaded [147-150]
12:12:32.016 base-playlist-controller.ts:119 [log] > [audio-track-controller]: live playlist 0 REFRESHED 151-0
12:12:32.016 audio-track-controller.ts:246 [log] > [audio-track-controller]: loading audio-track playlist for id: 0
12:12:32.016 audio-stream-controller.ts:433 [log] > [audio-stream-controller]: Track 0 loaded [147,150],duration:16.970000000000002
12:12:32.016 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:32.022 base-playlist-controller.ts:119 [log] > [level-controller]: live playlist 2 REFRESHED 151-0
12:12:32.022 level-controller.ts:506 [log] > [level-controller]: Attempt loading level index 2 at sn 151 part 1 with URL-id 0 https://localhost/live/ngrp:test.f4v_cmaf/cl_w3492934_b3000000_vo_sfm4s_ZX8tdXM=.m3u8?_HLS_msn=151&_HLS_part=1&_HLS_skip=YES
12:12:32.022 stream-controller.ts:602 [log] > [stream-controller]: Level 2 loaded [147,150], cc [0, 0] duration:17
12:12:32.022 buffer-controller.ts:602 [log] > [buffer-controller]: Updating Media Source duration to 25.000
12:12:32.055 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:32.155 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:32.255 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:32.355 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:32.455 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:32.555 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:32.655 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:32.755 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:32.855 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:32.955 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:33.020 audio-track-controller.ts:83 [log] > [audio-track-controller]: audioTrack 0 loaded [147-150]
12:12:33.020 base-playlist-controller.ts:119 [log] > [audio-track-controller]: live playlist 0 REFRESHED 151-1
12:12:33.020 audio-track-controller.ts:246 [log] > [audio-track-controller]: loading audio-track playlist for id: 0
12:12:33.020 audio-stream-controller.ts:433 [log] > [audio-stream-controller]: Track 0 loaded [147,150],duration:17.966
12:12:33.020 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:33.022 base-playlist-controller.ts:119 [log] > [level-controller]: live playlist 2 REFRESHED 151-1
12:12:33.023 level-controller.ts:506 [log] > [level-controller]: Attempt loading level index 2 at sn 151 part 2 with URL-id 0 https://localhost/live/ngrp:test.f4v_cmaf/cl_w3492934_b3000000_vo_sfm4s_ZX8tdXM=.m3u8?_HLS_msn=151&_HLS_part=2&_HLS_skip=YES
12:12:33.023 stream-controller.ts:602 [log] > [stream-controller]: Level 2 loaded [147,150], cc [0, 0] duration:18
12:12:33.023 buffer-controller.ts:602 [log] > [buffer-controller]: Updating Media Source duration to 26.000
12:12:33.055 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:33.155 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:33.255 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:33.356 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:33.455 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:33.555 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:33.655 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:33.755 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:33.855 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:33.940 audio-track-controller.ts:83 [log] > [audio-track-controller]: audioTrack 0 loaded [147-150]
12:12:33.940 base-playlist-controller.ts:119 [log] > [audio-track-controller]: live playlist 0 REFRESHED 151-2
12:12:33.940 audio-track-controller.ts:246 [log] > [audio-track-controller]: loading audio-track playlist for id: 0
12:12:33.941 audio-stream-controller.ts:433 [log] > [audio-stream-controller]: Track 0 loaded [147,150],duration:18.959
12:12:33.942 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:33.945 base-playlist-controller.ts:119 [log] > [level-controller]: live playlist 2 REFRESHED 151-2
12:12:33.945 level-controller.ts:506 [log] > [level-controller]: Attempt loading level index 2 at sn 151 part 3 with URL-id 0 https://localhost/live/ngrp:test.f4v_cmaf/cl_w3492934_b3000000_vo_sfm4s_ZX8tdXM=.m3u8?_HLS_msn=151&_HLS_part=3&_HLS_skip=YES
12:12:33.946 stream-controller.ts:602 [log] > [stream-controller]: Level 2 loaded [147,150], cc [0, 0] duration:19
12:12:33.946 buffer-controller.ts:602 [log] > [buffer-controller]: Updating Media Source duration to 27.000
12:12:33.983 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:34.055 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:34.155 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:34.255 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:34.355 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:34.455 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:34.555 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:34.655 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:34.755 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:34.855 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:34.955 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:35.028 audio-track-controller.ts:83 [log] > [audio-track-controller]: audioTrack 0 loaded [147-150]
12:12:35.028 base-playlist-controller.ts:119 [log] > [audio-track-controller]: live playlist 0 REFRESHED 151-3
12:12:35.029 audio-track-controller.ts:246 [log] > [audio-track-controller]: loading audio-track playlist for id: 0
12:12:35.029 audio-stream-controller.ts:433 [log] > [audio-stream-controller]: Track 0 loaded [147,150],duration:19.948999999999998
12:12:35.029 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:35.032 base-playlist-controller.ts:119 [log] > [level-controller]: live playlist 2 REFRESHED 151-3
12:12:35.032 level-controller.ts:506 [log] > [level-controller]: Attempt loading level index 2 at sn 151 part 4 with URL-id 0 https://localhost/live/ngrp:test.f4v_cmaf/cl_w3492934_b3000000_vo_sfm4s_ZX8tdXM=.m3u8?_HLS_msn=151&_HLS_part=4&_HLS_skip=YES
12:12:35.032 stream-controller.ts:602 [log] > [stream-controller]: Level 2 loaded [147,150], cc [0, 0] duration:20
12:12:35.033 buffer-controller.ts:602 [log] > [buffer-controller]: Updating Media Source duration to 28.000
12:12:35.055 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:35.155 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:35.255 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:35.355 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:35.456 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:35.555 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:35.655 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:35.755 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:35.855 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:35.930 audio-track-controller.ts:83 [log] > [audio-track-controller]: audioTrack 0 loaded [148-151]
12:12:35.930 base-playlist-controller.ts:119 [log] > [audio-track-controller]: live playlist 0 REFRESHED 152-0
12:12:35.930 audio-track-controller.ts:246 [log] > [audio-track-controller]: loading audio-track playlist for id: 0
12:12:35.931 audio-stream-controller.ts:433 [log] > [audio-stream-controller]: Track 0 loaded [148,151],duration:16.96
12:12:35.932 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:35.935 base-playlist-controller.ts:119 [log] > [level-controller]: live playlist 2 REFRESHED 152-0
12:12:35.936 level-controller.ts:506 [log] > [level-controller]: Attempt loading level index 2 at sn 152 part 1 with URL-id 0 https://localhost/live/ngrp:test.f4v_cmaf/cl_w3492934_b3000000_vo_sfm4s_ZX8tdXM=.m3u8?_HLS_msn=152&_HLS_part=1&_HLS_skip=YES
12:12:35.936 stream-controller.ts:602 [log] > [stream-controller]: Level 2 loaded [148,151], cc [0, 0] duration:17
12:12:35.937 buffer-controller.ts:602 [log] > [buffer-controller]: Updating Media Source duration to 29.000
12:12:35.955 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:36.055 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:36.156 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:36.255 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:36.362 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:36.455 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:36.555 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:36.655 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:36.755 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:36.855 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:36.932 audio-track-controller.ts:83 [log] > [audio-track-controller]: audioTrack 0 loaded [148-151]
12:12:36.932 base-playlist-controller.ts:119 [log] > [audio-track-controller]: live playlist 0 REFRESHED 152-1
12:12:36.933 audio-track-controller.ts:246 [log] > [audio-track-controller]: loading audio-track playlist for id: 0
12:12:36.933 audio-stream-controller.ts:433 [log] > [audio-stream-controller]: Track 0 loaded [148,151],duration:17.945
12:12:36.934 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:36.937 base-playlist-controller.ts:119 [log] > [level-controller]: live playlist 2 REFRESHED 152-1
12:12:36.938 level-controller.ts:506 [log] > [level-controller]: Attempt loading level index 2 at sn 152 part 2 with URL-id 0 https://localhost/live/ngrp:test.f4v_cmaf/cl_w3492934_b3000000_vo_sfm4s_ZX8tdXM=.m3u8?_HLS_msn=152&_HLS_part=2&_HLS_skip=YES
12:12:36.939 stream-controller.ts:602 [log] > [stream-controller]: Level 2 loaded [148,151], cc [0, 0] duration:18
12:12:36.939 buffer-controller.ts:602 [log] > [buffer-controller]: Updating Media Source duration to 30.000
12:12:36.955 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:37.055 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:37.155 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:37.255 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:37.355 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:37.488 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:37.586 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:37.655 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:37.757 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:37.882 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:37.956 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:38.036 base-playlist-controller.ts:119 [log] > [level-controller]: live playlist 2 REFRESHED 152-2
12:12:38.037 level-controller.ts:506 [log] > [level-controller]: Attempt loading level index 2 at sn 152 part 3 with URL-id 0 https://localhost/live/ngrp:test.f4v_cmaf/cl_w3492934_b3000000_vo_sfm4s_ZX8tdXM=.m3u8?_HLS_msn=152&_HLS_part=3&_HLS_skip=YES
12:12:38.038 stream-controller.ts:602 [log] > [stream-controller]: Level 2 loaded [148,151], cc [0, 0] duration:19
12:12:38.038 buffer-controller.ts:602 [log] > [buffer-controller]: Updating Media Source duration to 31.000
12:12:38.043 audio-track-controller.ts:83 [log] > [audio-track-controller]: audioTrack 0 loaded [148-151]
12:12:38.043 base-playlist-controller.ts:119 [log] > [audio-track-controller]: live playlist 0 REFRESHED 152-2
12:12:38.043 audio-track-controller.ts:246 [log] > [audio-track-controller]: loading audio-track playlist for id: 0
12:12:38.044 audio-stream-controller.ts:433 [log] > [audio-stream-controller]: Track 0 loaded [148,151],duration:18.926000000000002
12:12:38.044 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:38.069 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:38.155 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:38.255 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:38.356 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:38.456 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:38.555 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:38.655 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:38.755 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:38.855 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:38.955 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:39.039 audio-track-controller.ts:83 [log] > [audio-track-controller]: audioTrack 0 loaded [148-151]
12:12:39.039 base-playlist-controller.ts:119 [log] > [audio-track-controller]: live playlist 0 REFRESHED 152-3
12:12:39.039 audio-track-controller.ts:246 [log] > [audio-track-controller]: loading audio-track playlist for id: 0
12:12:39.040 audio-stream-controller.ts:433 [log] > [audio-stream-controller]: Track 0 loaded [148,151],duration:19.927000000000003
12:12:39.040 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:39.049 base-playlist-controller.ts:119 [log] > [level-controller]: live playlist 2 REFRESHED 152-3
12:12:39.049 level-controller.ts:506 [log] > [level-controller]: Attempt loading level index 2 at sn 152 part 4 with URL-id 0 https://localhost/live/ngrp:test.f4v_cmaf/cl_w3492934_b3000000_vo_sfm4s_ZX8tdXM=.m3u8?_HLS_msn=152&_HLS_part=4&_HLS_skip=YES
12:12:39.050 stream-controller.ts:602 [log] > [stream-controller]: Level 2 loaded [148,151], cc [0, 0] duration:20
12:12:39.050 buffer-controller.ts:602 [log] > [buffer-controller]: Updating Media Source duration to 32.000
12:12:39.055 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:39.155 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:39.255 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:39.355 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:39.455 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:39.555 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:39.655 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:39.755 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:39.855 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:39.945 audio-track-controller.ts:83 [log] > [audio-track-controller]: audioTrack 0 loaded [149-152]
12:12:39.946 base-playlist-controller.ts:119 [log] > [audio-track-controller]: live playlist 0 REFRESHED 153-0
12:12:39.946 audio-track-controller.ts:246 [log] > [audio-track-controller]: loading audio-track playlist for id: 0
12:12:39.947 audio-stream-controller.ts:433 [log] > [audio-stream-controller]: Track 0 loaded [149,152],duration:16.97
12:12:39.947 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:39.951 base-playlist-controller.ts:119 [log] > [level-controller]: live playlist 2 REFRESHED 153-0
12:12:39.951 level-controller.ts:506 [log] > [level-controller]: Attempt loading level index 2 at sn 153 part 1 with URL-id 0 https://localhost/live/ngrp:test.f4v_cmaf/cl_w3492934_b3000000_vo_sfm4s_ZX8tdXM=.m3u8?_HLS_msn=153&_HLS_part=1&_HLS_skip=YES
12:12:39.952 stream-controller.ts:602 [log] > [stream-controller]: Level 2 loaded [149,152], cc [0, 0] duration:17
12:12:39.952 buffer-controller.ts:602 [log] > [buffer-controller]: Updating Media Source duration to 33.000
12:12:39.957 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:40.056 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:40.155 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:40.255 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:40.356 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:40.455 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:40.555 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:40.655 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:40.755 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:40.856 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:40.955 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:41.048 audio-track-controller.ts:83 [log] > [audio-track-controller]: audioTrack 0 loaded [149-152]
12:12:41.048 base-playlist-controller.ts:119 [log] > [audio-track-controller]: live playlist 0 REFRESHED 153-1
12:12:41.048 audio-track-controller.ts:246 [log] > [audio-track-controller]: loading audio-track playlist for id: 0
12:12:41.049 audio-stream-controller.ts:433 [log] > [audio-stream-controller]: Track 0 loaded [149,152],duration:17.966
12:12:41.049 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:41.052 base-playlist-controller.ts:119 [log] > [level-controller]: live playlist 2 REFRESHED 153-1
12:12:41.052 level-controller.ts:506 [log] > [level-controller]: Attempt loading level index 2 at sn 153 part 2 with URL-id 0 https://localhost/live/ngrp:test.f4v_cmaf/cl_w3492934_b3000000_vo_sfm4s_ZX8tdXM=.m3u8?_HLS_msn=153&_HLS_part=2&_HLS_skip=YES
12:12:41.053 stream-controller.ts:602 [log] > [stream-controller]: Level 2 loaded [149,152], cc [0, 0] duration:18
12:12:41.053 buffer-controller.ts:602 [log] > [buffer-controller]: Updating Media Source duration to 34.000
12:12:41.056 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:41.155 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:41.255 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:41.356 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:41.455 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:41.555 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:41.655 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:41.755 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:41.855 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:41.949 audio-track-controller.ts:83 [log] > [audio-track-controller]: audioTrack 0 loaded [149-152]
12:12:41.950 base-playlist-controller.ts:119 [log] > [audio-track-controller]: live playlist 0 REFRESHED 153-2
12:12:41.950 audio-track-controller.ts:246 [log] > [audio-track-controller]: loading audio-track playlist for id: 0
12:12:41.950 audio-stream-controller.ts:433 [log] > [audio-stream-controller]: Track 0 loaded [149,152],duration:18.959000000000003
12:12:41.951 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:41.954 base-playlist-controller.ts:119 [log] > [level-controller]: live playlist 2 REFRESHED 153-2
12:12:41.954 level-controller.ts:506 [log] > [level-controller]: Attempt loading level index 2 at sn 153 part 3 with URL-id 0 https://localhost/live/ngrp:test.f4v_cmaf/cl_w3492934_b3000000_vo_sfm4s_ZX8tdXM=.m3u8?_HLS_msn=153&_HLS_part=3&_HLS_skip=YES
12:12:41.955 stream-controller.ts:602 [log] > [stream-controller]: Level 2 loaded [149,152], cc [0, 0] duration:19
12:12:41.955 buffer-controller.ts:602 [log] > [buffer-controller]: Updating Media Source duration to 35.000
12:12:41.958 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:42.055 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:42.155 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:42.256 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:42.355 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:42.455 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:42.555 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:42.656 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:42.755 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:42.855 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:42.952 audio-track-controller.ts:83 [log] > [audio-track-controller]: audioTrack 0 loaded [149-152]
12:12:42.953 base-playlist-controller.ts:119 [log] > [audio-track-controller]: live playlist 0 REFRESHED 153-3
12:12:42.953 audio-track-controller.ts:246 [log] > [audio-track-controller]: loading audio-track playlist for id: 0
12:12:42.953 audio-stream-controller.ts:433 [log] > [audio-stream-controller]: Track 0 loaded [149,152],duration:19.949000000000005
12:12:42.954 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:42.958 base-playlist-controller.ts:119 [log] > [level-controller]: live playlist 2 REFRESHED 153-3
12:12:42.958 level-controller.ts:506 [log] > [level-controller]: Attempt loading level index 2 at sn 153 part 4 with URL-id 0 https://localhost/live/ngrp:test.f4v_cmaf/cl_w3492934_b3000000_vo_sfm4s_ZX8tdXM=.m3u8?_HLS_msn=153&_HLS_part=4&_HLS_skip=YES
12:12:42.959 stream-controller.ts:602 [log] > [stream-controller]: Level 2 loaded [149,152], cc [0, 0] duration:20
12:12:42.959 buffer-controller.ts:602 [log] > [buffer-controller]: Updating Media Source duration to 36.000
12:12:42.964 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:43.055 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:43.155 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:43.255 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:43.355 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:43.455 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:43.555 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:43.655 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:43.755 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
12:12:43.855 base-stream-controller.ts:786 [log] > [audio-stream-controller]: Live playlist, switching playlist, load frag with same PDT: null
crazytoad commented 3 years ago

Some information about the stream: It's CMAF with 3 video quality levels and a single audio track.

Example playlist:

#EXTM3U
#EXT-X-VERSION:5
#EXT-X-MEDIA:TYPE=AUDIO,GROUP-ID="Audio",NAME="240p",URI="cl_w3492934_b161311_ao_sfm4s_ZX8tdXM=.m3u8"
#EXT-X-STREAM-INF:BANDWIDTH=3000000,NAME="720p 3.0mbps",LANGUAGE="en-us",CODECS="avc1.4d4c1f,mp4a.40.2",RESOLUTION=1080x720,AUDIO="Audio"
cl_w3492934_b3000000_vo_sfm4s_ZX8tdXM=.m3u8
#EXT-X-STREAM-INF:BANDWIDTH=1000000,NAME="360p 1.0mbps",LANGUAGE="en-us",CODECS="avc1.4d4c1e,mp4a.40.2",RESOLUTION=540x360,AUDIO="Audio"
cl_w3492934_b1000000_vo_sfm4s_ZX8tdXM=.m3u8
#EXT-X-STREAM-INF:BANDWIDTH=500000,NAME="240p 500kbps",LANGUAGE="en-us",CODECS="avc1.4d4c15,mp4a.40.2",RESOLUTION=320x240,AUDIO="Audio"
cl_w3492934_b500000_vo_sfm4s_ZX8tdXM=.m3u8

When the problem happens, the demo timeline and stats don't show any buffering - the duration and latency just keep climbing:

Buffered: 
Seekable: [0, 67] 
Played: 
Dropped frames: 0
Corrupted frames: 0
Bandwidth Estimate: 500000.000
Live Stats:
  Max Latency: Infinity
  Target Latency: 3.003
  Latency: 67.00218994140624
  Edge Stall: 0
  Playback rate: 1.00
robwalch commented 3 years ago

Example playlist: That's the manifest. The playlist has a lot of issues:

#EXTM3U
#EXT-X-VERSION:6
#EXT-X-SERVER-CONTROL:CAN-BLOCK-RELOAD=YES,PART-HOLD-BACK=3.0,CAN-SKIP-UNTIL=6.0
#EXT-X-INDEPENDENT-SEGMENTS
#EXT-X-TARGETDURATION:1
#EXT-X-MEDIA-SEQUENCE:14853
#EXT-X-PART-INF:PART-TARGET=1.0
#EXT-X-RENDITION-REPORT:URI="chunklist_w1785578074_b1400000_vo_sfm4s_t64NzIwcCAzLjZtYnBzIixMQU5HVUFHRT0iZW4tdXM=.m3u8?_HLS_msn=14857&_HLS_part=1&_HLS_skip=YES&nc=0.3943549291741461",LAST-MSN=14858,LAST-PART=0
#EXT-X-RENDITION-REPORT:URI="chunklist_w1785578074_b750000_vo_sfm4s_t64NzIwcCAzLjZtYnBzIixMQU5HVUFHRT0iZW4tdXM=.m3u8?_HLS_msn=14857&_HLS_part=1&_HLS_skip=YES&nc=0.3943549291741461",LAST-MSN=14858,LAST-PART=0
#EXT-X-MAP:URI="header_w1785578074_b3600000_vo_sfm4s_t64NzIwcCAzLjZtYnBzIixMQU5HVUFHRT0iZW4tdXM=_m3u8.cmfv?nc=0.3943549291741461"
#EXT-X-PROGRAM-DATE-TIME:2021-02-20T05:12:49.108+00:00
#EXTINF:1.0,
media_w1785578074_b3600000_vo_sfm4s_t64NzIwcCAzLjZtYnBzIixMQU5HVUFHRT0iZW4tdXM=_14853_m3u8.cmfv?nc=0.3943549291741461
#EXT-X-PROGRAM-DATE-TIME:2021-02-20T05:12:49.860+00:00
#EXTINF:1.0,
media_w1785578074_b3600000_vo_sfm4s_t64NzIwcCAzLjZtYnBzIixMQU5HVUFHRT0iZW4tdXM=_14854_m3u8.cmfv?nc=0.3943549291741461
#EXT-X-PROGRAM-DATE-TIME:2021-02-20T05:12:50.860+00:00
#EXT-X-PART:DURATION=1.0,URI="media_w1785578074_b3600000_vo_sfm4s_t64NzIwcCAzLjZtYnBzIixMQU5HVUFHRT0iZW4tdXM=_14855.0_m3u8.cmfv?nc=0.3943549291741461",INDEPENDENT=YES
#EXTINF:1.0,
media_w1785578074_b3600000_vo_sfm4s_t64NzIwcCAzLjZtYnBzIixMQU5HVUFHRT0iZW4tdXM=_14855_m3u8.cmfv?nc=0.3943549291741461
#EXT-X-PROGRAM-DATE-TIME:2021-02-20T05:12:51.860+00:00
#EXT-X-PART:DURATION=1.0,URI="media_w1785578074_b3600000_vo_sfm4s_t64NzIwcCAzLjZtYnBzIixMQU5HVUFHRT0iZW4tdXM=_14856.0_m3u8.cmfv?nc=0.3943549291741461",INDEPENDENT=YES
#EXTINF:1.0,
media_w1785578074_b3600000_vo_sfm4s_t64NzIwcCAzLjZtYnBzIixMQU5HVUFHRT0iZW4tdXM=_14856_m3u8.cmfv?nc=0.3943549291741461
#EXT-X-PROGRAM-DATE-TIME:2021-02-20T05:12:52.862+00:00
#EXT-X-PART:DURATION=1.0,URI="media_w1785578074_b3600000_vo_sfm4s_t64NzIwcCAzLjZtYnBzIixMQU5HVUFHRT0iZW4tdXM=_14857.0_m3u8.cmfv?nc=0.3943549291741461",INDEPENDENT=YES
#EXTINF:1.0,
media_w1785578074_b3600000_vo_sfm4s_t64NzIwcCAzLjZtYnBzIixMQU5HVUFHRT0iZW4tdXM=_14857_m3u8.cmfv?nc=0.3943549291741461
#EXT-X-PROGRAM-DATE-TIME:2021-02-20T05:12:53.863+00:00
#EXT-X-PART:DURATION=1.0,URI="media_w1785578074_b3600000_vo_sfm4s_t64NzIwcCAzLjZtYnBzIixMQU5HVUFHRT0iZW4tdXM=_14858.0_m3u8.cmfv?nc=0.3943549291741461",INDEPENDENT=YES
#EXTINF:1.0,
media_w1785578074_b3600000_vo_sfm4s_t64NzIwcCAzLjZtYnBzIixMQU5HVUFHRT0iZW4tdXM=_14858_m3u8.cmfv?nc=0.3943549291741461
#EXT-X-PRELOAD-HINT:TYPE=PART,URI="media_w1785578074_b3600000_vo_sfm4s_t64NzIwcCAzLjZtYnBzIixMQU5HVUFHRT0iZW4tdXM=_14859.0_m3u8.cmfv?nc=0.3943549291741461"

Only one part per segment (except for the last one), so the non-LL segments are ahead of the LL parts. The PDT value differences do match the segment durations (they should all be one segment apart).

In the logs above, I've spotted some issues with hls.js attempting to find the next fragment based on PDT, and PDT not being set on a fragment even though it's present on others. That I can address, but once that is taken care of the stream then fails to start 100% of the time because of the issues listed above causing hls.js to request the last part. The stream updates the playlist, removing the part and responds with a 410 error. At that point the player cannot recover.

robwalch commented 3 years ago

If you filter the console logs to just show lines with startPosition you'll see the audio and video start out of sync:

[audio-stream-controller]: Configure startPosition to 0
[stream-controller]: Configure startPosition to 16.997284912109375

This happens when the audio track playlist loads before the main level, and so the start position is set before the two playlists are aligned. Just added a fix to #3504:

https://github.com/video-dev/hls.js/pull/3504/commits/2ec0822bac13da4bf653e563a351407bbd7403a2#diff-8e7ff0d6df9bf1a1d598f2b008dd556c321c40be15b0942de44f4ff997b870a8R434