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

Repeated requests for initialization segment for live playlists #4450

Closed
5 tasks done
erkreutzer opened this issue Dec 2, 2021 · 0 comments
Closed
5 tasks done

Repeated requests for initialization segment for live playlists #4450

erkreutzer opened this issue Dec 2, 2021 · 0 comments
Labels
Bug
Milestone
1.1.2

Comments

@erkreutzer
Copy link
Contributor

What version of Hls.js are you using?

v1.1.1

What browser (including version) are you using?

Chrome Version 96.0.4664.55 (Official Build) (x86_64)

What OS (including version) are you using?

macOS Monterey version 12.0.1

Test stream

https://hls-js.netlify.app/demo/?src=https%3A%2F%2Fstream.mux.com%2Fv69RSHhFelSm4701snP22dYz2jICy4E4FUyk02rW4gxRM.m3u8&demoConfig=eyJlbmFibGVTdHJlYW1pbmciOnRydWUsImF1dG9SZWNvdmVyRXJyb3IiOnRydWUsInN0b3BPblN0YWxsIjpmYWxzZSwiZHVtcGZNUDQiOmZhbHNlLCJsZXZlbENhcHBpbmciOi0xLCJsaW1pdE1ldHJpY3MiOi0xfQ==

Configuration

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

Additional player setup steps

Note, this happens for the ll-hls demo stream, but I also have a non-public live HLS stream that does not use ll-hls which shows the same issue. The playlists for this private stream look like:

#EXTM3U
#EXT-X-VERSION:7
#EXT-X-TARGETDURATION:5
#EXT-X-MEDIA-SEQUENCE:38887
#EXT-X-MAP:URI="init1638325408.mp4"
#EXTINF:4.000000
#EXT-X-PROGRAM-DATE-TIME:2021-12-02T21:35:53Z
camera1638480953.m4s
#EXTINF:4.000000
#EXT-X-PROGRAM-DATE-TIME:2021-12-02T21:35:57Z
camera1638480957.m4s

Checklist

Steps to reproduce

  1. Load the video stream and open the network tab
  2. Look for the fragment initialization segment url in the EXT-X-MAP
  3. Search filter the network tab to only show requests for this resource

Expected behaviour

The expected behaviour is that the initialization segment would only be requested if a new initialization segment is found. Any references to previous initialization segments should use previously downloaded segments instead of re-downloading the same initialization segment.

What actually happened?

The same initialization segment is requested over and over again. You can see this also by looking for the log Loading fragment initSegment in the console output.

I believe the cause of the repeated requests relates to the changes to support multiple EXT-X-MAP tags ( #3859 ). Specifically the issue seems to be how LevelDetails are merged inside the level-helper. The chain of updating fragments to maintain references to the same initSegment Fragment works for Fragments that overlap between the old and new details, but for fragments which only appear in the new LevelDetails the initSegment is not updated. If requests for the non-overlapping fragments are made before another level merge occurs, the stream-controller will re-request the same initSegment.

A unit test I've used to test the functionality is:

    it('sets initSegments on non-overlapping segments', function () {
      const oldPlaylistString = `#EXTM3U
#EXT-X-VERSION:7
#EXT-X-TARGETDURATION:1
#EXT-X-MEDIA-SEQUENCE:27081
#EXT-X-MAP:URI="init1638284459.mp4"
#EXTINF:1.000000
#EXT-X-PROGRAM-DATE-TIME:2021-11-30T22:32:19Z
camera27078.m4s
#EXTINF:1.000000
#EXT-X-PROGRAM-DATE-TIME:2021-11-30T22:32:20Z
camera27079.m4s
#EXTINF:1.000000
#EXT-X-PROGRAM-DATE-TIME:2021-11-30T22:32:21Z
camera27080.m4s`;
      const newPlaylistString = `#EXTM3U
#EXT-X-VERSION:7
#EXT-X-TARGETDURATION:1
#EXT-X-MEDIA-SEQUENCE:27082
#EXT-X-MAP:URI="init1638284459.mp4"
#EXTINF:1.000000
#EXT-X-PROGRAM-DATE-TIME:2021-11-30T22:32:20Z
camera27079.m4s
#EXTINF:1.000000
#EXT-X-PROGRAM-DATE-TIME:2021-11-30T22:32:21Z
camera27080.m4s
#EXTINF:1.000000
#EXT-X-PROGRAM-DATE-TIME:2021-11-30T22:32:22Z
camera27081.m4s`;

      const oldPlaylist = M3U8Parser.parseLevelPlaylist(
        oldPlaylistString,
        'http://test.com/livevideo.m3u8',
        0,
        PlaylistLevelType.MAIN,
        0
      );
      const newPlaylist = M3U8Parser.parseLevelPlaylist(
        newPlaylistString,
        'http://test.com/livevideo.m3u8',
        0,
        PlaylistLevelType.MAIN,
        0
      );

      mergeDetails(oldPlaylist, newPlaylist);

      const oldInitSegment = oldPlaylist.fragments[0].initSegment;
      const actual = newPlaylist.fragments.map((f) => f.initSegment);
      expect(actual[0]).to.equal(oldInitSegment);
      expect(actual[1]).to.equal(oldInitSegment);
      expect(actual[2]).to.equal(oldInitSegment);
    });

Console output

hls-demo.js:1803 Using Hls.js config: Object
16:20:36.832 hls.js:25560 [log] >
16:20:36.844 hls.js:17343 [log] > stopLoad
16:20:36.844 hls.js:17306 [log] > loadSource:https://test-streams.mux.dev/x36xhzz/x36xhzz.m3u8
16:20:36.846 hls.js:9757 [log] > [stream-controller]: Trigger BUFFER_RESET
16:20:36.846 hls.js:17277 [log] > attachMedia
16:20:36.864 hls.js:8315 [log] > [level-controller]: manifest loaded, 5 level(s) found, first bitrate: 2149280
16:20:36.865 hls.js:4280 [log] > 1 bufferCodec event(s) expected
16:20:36.871 hls.js:17332 [log] > startLoad(-1)
16:20:36.871 hls.js:8673 [log] > [level-controller]: switching to level 3 from -1
16:20:36.871 hls.js:8565 [log] > [level-controller]: Attempt loading level index 3 with URL-id 0 https://test-streams.mux.dev/x36xhzz/url_0/193039199_mp4_h264_aac_hd_7.m3u8
16:20:36.872 hls.js:4107 [log] > [stream-controller]: STOPPED->IDLE
16:20:36.873 hls.js:4107 [log] > [subtitle-stream-controller]: STOPPED->IDLE
16:20:36.879 hls.js:9820 [log] > [stream-controller]: Level 3 loaded [0,63], cc [0, 0] duration:634.584
16:20:37.857 hls.js:4183 [log] > [buffer-controller]: Media source opened
16:20:37.858 hls.js:4771 [log] > [buffer-controller]: Updating Media Source duration to 634.584
16:20:37.872 hls.js:3401 [log] > [stream-controller]: Loading fragment 0 cc: 0 of [0-63] level: 3, target: 0
16:20:37.872 hls.js:4107 [log] > [stream-controller]: IDLE->FRAG_LOADING
16:20:38.068 hls.js:14695 [log] > demuxing in webworker
16:20:38.076 hls.js:14792 [log] > [transmuxer-interface, main]: Starting new transmux session for sn: 0 p: -1 level: 3 id: 1
        discontinuity: true
        trackSwitch: true
        contiguous: false
        accurateTimeOffset: true
        timeOffset: 0
16:20:38.076 hls.js:3212 [log] > [stream-controller]: Loaded fragment 0 of level 3
16:20:38.110 blob:https://hls-js-dev.netlify.app/8f07580c-6277-40ce-9ff3-2e8f0c4e6c3b:603 [log] >
16:20:38.111 blob:https://hls-js-dev.netlify.app/8f07580c-6277-40ce-9ff3-2e8f0c4e6c3b:2699 [log] > [mp4-remuxer]: ISGenerated flag reset
16:20:38.111 blob:https://hls-js-dev.netlify.app/8f07580c-6277-40ce-9ff3-2e8f0c4e6c3b:2688 [log] > [mp4-remuxer]: initPTS & initDTS reset
16:20:38.116 blob:https://hls-js-dev.netlify.app/8f07580c-6277-40ce-9ff3-2e8f0c4e6c3b:7540 [log] > manifest codec:mp4a.40.2, ADTS type:2, samplingIndex:4
16:20:38.116 blob:https://hls-js-dev.netlify.app/8f07580c-6277-40ce-9ff3-2e8f0c4e6c3b:7702 [log] > parsed codec:mp4a.40.5, rate:44100, channels:2
16:20:38.144 blob:https://hls-js-dev.netlify.app/8f07580c-6277-40ce-9ff3-2e8f0c4e6c3b:937 [log] > [transmuxer.ts]: Flushed fragment 0 of level 3
16:20:38.144 hls.js:4107 [log] > [stream-controller]: FRAG_LOADING->PARSING
16:20:38.145 hls.js:10402 [log] > [stream-controller]: Init audio buffer, container:audio/mp4, codecs[selected/level/parsed]=[mp4a.40.2/mp4a.40.2/mp4a.40.5]
16:20:38.145 hls.js:10408 [log] > [stream-controller]: Init video buffer, container:video/mp4, codecs[level/parsed]=[avc1.64001f/avc1.64001f]
16:20:38.145 hls.js:4842 [log] > [buffer-controller]: creating sourceBuffer(audio/mp4;codecs=mp4a.40.2)
16:20:38.145 hls.js:4842 [log] > [buffer-controller]: creating sourceBuffer(video/mp4;codecs=avc1.64001f)
16:20:38.161 hls.js:1648 [log] > [audio-stream-controller]: InitPTS for cc: 0 found from main: 900909
16:20:38.178 hls.js:4107 [log] > [stream-controller]: PARSING->PARSED
16:20:38.246 hls.js:3332 [log] > [stream-controller]: Buffered main sn: 0 of level 3 [0.023,10.008]
16:20:38.246 hls.js:4107 [log] > [stream-controller]: PARSED->IDLE
16:20:38.246 hls.js:8673 [log] > [level-controller]: switching to level 4 from 3
16:20:38.247 hls.js:8565 [log] > [level-controller]: Attempt loading level index 4 with URL-id 0 https://test-streams.mux.dev/x36xhzz/url_8/193039199_mp4_h264_aac_fhd_7.m3u8
16:20:38.248 hls.js:4107 [log] > [stream-controller]: IDLE->WAITING_LEVEL
16:20:38.503 hls.js:9820 [log] > [stream-controller]: Level 4 loaded [0,63], cc [0, 0] duration:634.567
16:20:38.505 hls.js:4107 [log] > [stream-controller]: WAITING_LEVEL->IDLE
16:20:38.505 hls.js:3401 [log] > [stream-controller]: Loading fragment 1 cc: 0 of [0-63] level: 4, target: 10.008
16:20:38.505 hls.js:4107 [log] > [stream-controller]: IDLE->FRAG_LOADING
16:20:38.552 hls.js:14792 [log] > [transmuxer-interface, main]: Starting new transmux session for sn: 1 p: -1 level: 4 id: 1
        discontinuity: false
        trackSwitch: true
        contiguous: false
        accurateTimeOffset: true
        timeOffset: 10
16:20:38.552 hls.js:3212 [log] > [stream-controller]: Loaded fragment 1 of level 4
16:20:38.552 8f07580c-6277-40ce-9…3-2e8f0c4e6c3b:2699 [log] > [mp4-remuxer]: ISGenerated flag reset
16:20:38.552 8f07580c-6277-40ce-9…3-2e8f0c4e6c3b:2693 [log] > [mp4-remuxer]: reset next timestamp
16:20:38.552 8f07580c-6277-40ce-9…3-2e8f0c4e6c3b:2699 [log] > [mp4-remuxer]: ISGenerated flag reset
16:20:38.552 8f07580c-6277-40ce-9…3-2e8f0c4e6c3b:2688 [log] > [mp4-remuxer]: initPTS & initDTS reset
16:20:38.568 8f07580c-6277-40ce-9…3-2e8f0c4e6c3b:7540 [log] > manifest codec:mp4a.40.2, ADTS type:2, samplingIndex:4
16:20:38.568 8f07580c-6277-40ce-9…3-2e8f0c4e6c3b:7702 [log] > parsed codec:mp4a.40.5, rate:44100, channels:2
16:20:38.662 8f07580c-6277-40ce-9ff3-2e8f0c4e6c3b:937 [log] > [transmuxer.ts]: Flushed fragment 1 of level 4
16:20:38.662 hls.js:4107 [log] > [stream-controller]: FRAG_LOADING->PARSING
16:20:38.662 hls.js:10402 [log] > [stream-controller]: Init audio buffer, container:audio/mp4, codecs[selected/level/parsed]=[mp4a.40.2/mp4a.40.2/mp4a.40.5]
16:20:38.662 hls.js:10408 [log] > [stream-controller]: Init video buffer, container:video/mp4, codecs[level/parsed]=[avc1.640028/avc1.640028]
16:20:38.716 hls.js:4107 [log] > [stream-controller]: PARSING->PARSED
16:20:38.854 hls.js:3332 [log] > [stream-controller]: Buffered main sn: 1 of level 4 [0.023,19.992]
16:20:38.854 hls.js:4107 [log] > [stream-controller]: PARSED->IDLE
16:20:38.854 hls.js:3401 [log] > [stream-controller]: Loading fragment 2 cc: 0 of [0-63] level: 4, target: 19.992
16:20:38.854 hls.js:4107 [log] > [stream-controller]: IDLE->FRAG_LOADING
16:20:38.877 hls.js:3212 [log] > [stream-controller]: Loaded fragment 2 of level 4
16:20:38.944 8f07580c-6277-40ce-9ff3-2e8f0c4e6c3b:937 [log] > [transmuxer.ts]: Flushed fragment 2 of level 4
16:20:38.944 hls.js:4107 [log] > [stream-controller]: FRAG_LOADING->PARSING
16:20:38.978 hls.js:4107 [log] > [stream-controller]: PARSING->PARSED
16:20:39.037 hls.js:3332 [log] > [stream-controller]: Buffered main sn: 2 of level 4 [0.023,30.000]
16:20:39.037 hls.js:4107 [log] > [stream-controller]: PARSED->IDLE
16:20:39.038 hls.js:3401 [log] > [stream-controller]: Loading fragment 3 cc: 0 of [0-63] level: 4, target: 30
16:20:39.038 hls.js:4107 [log] > [stream-controller]: IDLE->FRAG_LOADING
16:20:39.307 hls.js:3212 [log] > [stream-controller]: Loaded fragment 3 of level 4
16:20:39.416 8f07580c-6277-40ce-9ff3-2e8f0c4e6c3b:937 [log] > [transmuxer.ts]: Flushed fragment 3 of level 4
16:20:39.416 hls.js:4107 [log] > [stream-controller]: FRAG_LOADING->PARSING
16:20:39.462 hls.js:4107 [log] > [stream-controller]: PARSING->PARSED
16:20:39.518 hls.js:3332 [log] > [stream-controller]: Buffered main sn: 3 of level 4 [0.023,40.008]
16:20:39.518 hls.js:4107 [log] > [stream-controller]: PARSED->IDLE
16:20:39.518 hls.js:3401 [log] > [stream-controller]: Loading fragment 4 cc: 0 of [0-63] level: 4, target: 40.008
16:20:39.518 hls.js:4107 [log] > [stream-controller]: IDLE->FRAG_LOADING
16:20:39.552 hls.js:3212 [log] > [stream-controller]: Loaded fragment 4 of level 4
16:20:39.596 8f07580c-6277-40ce-9ff3-2e8f0c4e6c3b:937 [log] > [transmuxer.ts]: Flushed fragment 4 of level 4
16:20:39.597 hls.js:4107 [log] > [stream-controller]: FRAG_LOADING->PARSING
16:20:39.620 hls.js:4107 [log] > [stream-controller]: PARSING->PARSED
16:20:39.681 hls.js:3332 [log] > [stream-controller]: Buffered main sn: 4 of level 4 [0.023,49.993]
16:20:39.681 hls.js:4107 [log] > [stream-controller]: PARSED->IDLE
16:20:39.681 hls.js:3401 [log] > [stream-controller]: Loading fragment 5 cc: 0 of [0-63] level: 4, target: 49.993
16:20:39.681 hls.js:4107 [log] > [stream-controller]: IDLE->FRAG_LOADING
16:20:39.702 hls.js:3212 [log] > [stream-controller]: Loaded fragment 5 of level 4
16:20:39.756 8f07580c-6277-40ce-9ff3-2e8f0c4e6c3b:937 [log] > [transmuxer.ts]: Flushed fragment 5 of level 4
16:20:39.756 hls.js:4107 [log] > [stream-controller]: FRAG_LOADING->PARSING
16:20:39.789 hls.js:4107 [log] > [stream-controller]: PARSING->PARSED
16:20:39.854 hls.js:3332 [log] > [stream-controller]: Buffered main sn: 5 of level 4 [0.023,60.000]
16:20:39.854 hls.js:4107 [log] > [stream-controller]: PARSED->IDLE
16:20:39.854 hls.js:3401 [log] > [stream-controller]: Loading fragment 6 cc: 0 of [0-63] level: 4, target: 60
16:20:39.855 hls.js:4107 [log] > [stream-controller]: IDLE->FRAG_LOADING
16:20:39.870 hls.js:3212 [log] > [stream-controller]: Loaded fragment 6 of level 4
16:20:39.896 8f07580c-6277-40ce-9ff3-2e8f0c4e6c3b:937 [log] > [transmuxer.ts]: Flushed fragment 6 of level 4
16:20:39.896 hls.js:4107 [log] > [stream-controller]: FRAG_LOADING->PARSING
16:20:39.920 hls.js:4107 [log] > [stream-controller]: PARSING->PARSED
16:20:39.979 hls.js:3332 [log] > [stream-controller]: Buffered main sn: 6 of level 4 [0.023,70.008]
16:20:39.979 hls.js:4107 [log] > [stream-controller]: PARSED->IDLE
16:20:39.979 hls.js:3401 [log] > [stream-controller]: Loading fragment 7 cc: 0 of [0-63] level: 4, target: 70.008
16:20:39.979 hls.js:4107 [log] > [stream-controller]: IDLE->FRAG_LOADING
16:20:39.996 hls.js:3212 [log] > [stream-controller]: Loaded fragment 7 of level 4
16:20:40.036 8f07580c-6277-40ce-9ff3-2e8f0c4e6c3b:937 [log] > [transmuxer.ts]: Flushed fragment 7 of level 4
16:20:40.036 hls.js:4107 [log] > [stream-controller]: FRAG_LOADING->PARSING
16:20:40.068 hls.js:4107 [log] > [stream-controller]: PARSING->PARSED
16:20:40.142 hls.js:3332 [log] > [stream-controller]: Buffered main sn: 7 of level 4 [0.023,79.946]
16:20:40.142 hls.js:4107 [log] > [stream-controller]: PARSED->IDLE
16:20:46.073 hls.js:17255 [log] > destroy
16:20:46.073 hls.js:17289 [log] > detachMedia
16:20:46.073 hls.js:4305 [log] > [buffer-controller]: media source detaching
16:20:46.080 hls.js:4107 [log] > [stream-controller]: IDLE->STOPPED
16:20:46.080 hls.js:4107 [log] > [subtitle-stream-controller]: IDLE->STOPPED
16:20:46.083 hls-demo.js:1803 Using Hls.js config: {debug: true, enableWorker: true, lowLatencyMode: true, backBufferLength: 90}
16:20:46.084 hls.js:25560 [log] >
16:20:46.093 hls.js:17343 [log] > stopLoad
16:20:46.093 hls.js:17306 [log] > loadSource:https://stream.mux.com/v69RSHhFelSm4701snP22dYz2jICy4E4FUyk02rW4gxRM.m3u8
16:20:46.096 hls.js:9757 [log] > [stream-controller]: Trigger BUFFER_RESET
16:20:46.098 hls.js:17277 [log] > attachMedia
16:20:46.132 hls.js:4183 [log] > [buffer-controller]: Media source opened
16:20:46.132 hls.js:4107 [log] > [subtitle-stream-controller]: STOPPED->IDLE
16:20:46.357 hls.js:8315 [log] > [level-controller]: manifest loaded, 5 level(s) found, first bitrate: 2587410
16:20:46.357 hls.js:4280 [log] > 2 bufferCodec event(s) expected
16:20:46.360 hls.js:17332 [log] > startLoad(-1)
16:20:46.361 hls.js:8673 [log] > [level-controller]: switching to level 3 from -1
16:20:46.361 hls.js:2490 [log] > [audio-track-controller]: Updating audio tracks, 1 track(s) found in "audio-hi-0" group-id
16:20:46.364 hls.js:2519 [log] > [audio-track-controller]: Now switching to audio-track index 0
16:20:46.364 hls.js:4107 [log] > [audio-stream-controller]: STOPPED->IDLE
16:20:46.365 hls.js:4107 [log] > [audio-stream-controller]: IDLE->WAITING_TRACK
16:20:46.367 hls.js:8565 [log] > [level-controller]: Attempt loading level index 3 with URL-id 0 https://manifest-gce-us-east1-production.fastly.mux.com/PttkmSZklh3dWg7UYIedDln01f8JkwIzPOi01mYLjGlHxNXRZ5gqHF6k7yTqPFibgdfIRRByDKlrAvGmvVv3YlrdO5tGnWFJNjhm2wTL6NEns/rendition.m3u8?cdn=fastly&expires=1639083600&live=1&skid=default&signature=NjFiMjZlNTBfMDVhMDdmMzIwODE2YjE4NjQ0MmU3NWMwODRiZjIwM2I0YjIwNWE3ZDhmZDU2Yjk1MDQzYjllMDJhZTA1OWM1Yg==
16:20:46.368 hls.js:4107 [log] > [stream-controller]: STOPPED->IDLE
16:20:46.368 hls.js:2597 [log] > [audio-track-controller]: loading audio-track playlist for id: 0
16:20:46.369 hls.js:4107 [log] > [audio-stream-controller]: WAITING_TRACK->STOPPED
16:20:46.369 hls.js:4107 [log] > [audio-stream-controller]: STOPPED->WAITING_TRACK
16:20:46.369 hls.js:4107 [log] > [subtitle-stream-controller]: IDLE->STOPPED
16:20:46.370 hls.js:4107 [log] > [subtitle-stream-controller]: STOPPED->IDLE
16:20:46.395 hls.js:8565 [log] > [level-controller]: Attempt loading level index 3 at sn 16813 part 0 with URL-id 0 https://manifest-gce-us-east1-production.fastly.mux.com/PttkmSZklh3dWg7UYIedDln01f8JkwIzPOi01mYLjGlHxNXRZ5gqHF6k7yTqPFibgdfIRRByDKlrAvGmvVv3YlrdO5tGnWFJNjhm2wTL6NEns/rendition.m3u8?cdn=fastly&expires=1639083600&live=1&skid=default&signature=NjFiMjZlNTBfMDVhMDdmMzIwODE2YjE4NjQ0MmU3NWMwODRiZjIwM2I0YjIwNWE3ZDhmZDU2Yjk1MDQzYjllMDJhZTA1OWM1Yg%3D%3D&_HLS_msn=16813&_HLS_part=0
16:20:46.396 hls.js:4107 [log] > [stream-controller]: IDLE->WAITING_LEVEL
16:20:46.396 hls.js:9820 [log] > [stream-controller]: Level 3 loaded [16803,16812], cc [0, 0] duration:20
16:20:46.396 hls.js:3876 [log] > [stream-controller]: Live playlist sliding: 0.00 start-sn: na->16803 prev-sn: na fragments: 10
16:20:46.396 hls.js:4771 [log] > [buffer-controller]: Updating Media Source duration to 20.000
16:20:46.397 hls.js:4107 [log] > [stream-controller]: WAITING_LEVEL->IDLE
16:20:46.398 hls.js:3401 [log] > [stream-controller]: Loading fragment initSegment cc: 0 level: 3, target: 0
16:20:46.398 hls.js:4107 [log] > [stream-controller]: IDLE->FRAG_LOADING
16:20:46.401 hls.js:2449 [log] > [audio-track-controller]: audioTrack 0 loaded [16803-16812]
16:20:46.402 hls.js:2597 [log] > [audio-track-controller]: loading audio-track playlist for id: 0
16:20:46.403 hls.js:1970 [log] > [audio-stream-controller]: Track 0 loaded [16803,16812],duration:20
16:20:46.403 hls.js:4107 [log] > [audio-stream-controller]: WAITING_TRACK->IDLE
16:20:46.403 hls.js:3401 [log] > [audio-stream-controller]: Loading fragment initSegment cc: 0 track: 0, target: 0
16:20:46.403 hls.js:4107 [log] > [audio-stream-controller]: IDLE->FRAG_LOADING
16:20:46.412 hls.js:4107 [log] > [stream-controller]: FRAG_LOADING->IDLE
16:20:46.413 hls.js:3332 [log] > [stream-controller]: Buffered main sn: initSegment of level 3 
16:20:46.413 hls.js:3383 [log] > [stream-controller]: Loading part sn: 16812 p: 0 cc: 0 of playlist [16803-16812] parts [0-4-5] level: 3, target: 18
16:20:46.413 hls.js:4107 [log] > [stream-controller]: IDLE->FRAG_LOADING
16:20:46.417 hls.js:4107 [log] > [audio-stream-controller]: FRAG_LOADING->IDLE
16:20:46.417 hls.js:3332 [log] > [audio-stream-controller]: Buffered audio sn: initSegment of track 0 
16:20:46.417 hls.js:2315 [log] > [audio-stream-controller]: Waiting for video PTS in continuity counter 0 of live stream before loading audio fragment 16812 of level 0
16:20:46.417 hls.js:4107 [log] > [audio-stream-controller]: IDLE->WAITING_INIT_PTS
16:20:46.418 hls.js:4107 [log] > [audio-stream-controller]: WAITING_INIT_PTS->IDLE
16:20:46.435 hls.js:14695 [log] > demuxing in webworker
16:20:46.439 hls.js:14792 [log] > [transmuxer-interface, main]: Starting new transmux session for sn: 16812 p: 0 level: 3 id: 1
        discontinuity: true
        trackSwitch: true
        contiguous: false
        accurateTimeOffset: false
        timeOffset: 18
16:20:46.458 adf8e0f3-3409-462a-9b71-55772a2d4bcb:603 [log] >
16:20:46.460 hls.js:4107 [log] > [stream-controller]: FRAG_LOADING->PARSING
16:20:46.460 hls.js:10408 [log] > [stream-controller]: Init video buffer, container:video/mp4, codecs[level/parsed]=[avc1.640020/avc1.640020]
16:20:46.470 hls.js:1648 [log] > [audio-stream-controller]: InitPTS for cc: 0 found from main: 33605.96666666667
16:20:46.476 hls.js:3383 [log] > [audio-stream-controller]: Loading part sn: 16812 p: 0 cc: 0 of playlist [16803-16812] parts [0-4-5] track: 0, target: 18
16:20:46.476 hls.js:4107 [log] > [audio-stream-controller]: IDLE->FRAG_LOADING
16:20:46.479 adf8e0f3-3409-462a-9b71-55772a2d4bcb:937 [log] > [transmuxer.ts]: Flushed fragment 16812 p: 1 of level 3
16:20:46.485 hls.js:4107 [log] > [stream-controller]: PARSING->PARSED
16:20:46.493 hls.js:14695 [log] > demuxing in webworker
16:20:46.496 hls.js:14792 [log] > [transmuxer-interface, audio]: Starting new transmux session for sn: 16812 p: 0 level: 0 id: 1
        discontinuity: true
        trackSwitch: true
        contiguous: false
        accurateTimeOffset: false
        timeOffset: 18
16:20:46.566 982c0d21-bdd3-497b-a636-2464ca32c081:603 [log] >
16:20:46.568 hls.js:4107 [log] > [audio-stream-controller]: FRAG_LOADING->PARSING
16:20:46.568 982c0d21-bdd3-497b-a636-2464ca32c081:937 [log] > [transmuxer.ts]: Flushed fragment 16812 p: 1 of level 0
16:20:46.568 hls.js:2331 [log] > [audio-stream-controller]: Switching audio track : flushing all audio
16:20:46.571 hls.js:2286 [log] > [audio-stream-controller]: Init audio buffer, container:audio/mp4, codecs[parsed]=[mp4a.40.2]
16:20:46.572 hls.js:4842 [log] > [buffer-controller]: creating sourceBuffer(video/mp4;codecs=avc1.640020)
16:20:46.572 hls.js:4842 [log] > [buffer-controller]: creating sourceBuffer(audio/mp4;codecs=mp4a.40.2)
16:20:46.572 hls.js:9997 [log] > [stream-controller]: Alternate track found, use video.buffered to schedule main fragment loading
16:20:46.573 hls.js:4929 [log] > [buffer-controller]: Removing [0,20] from the audio SourceBuffer
16:20:46.595 hls.js:4107 [log] > [audio-stream-controller]: PARSING->PARSED
16:20:46.676 hls.js:10165 [log] > adjusting start position by 0.1988659511718751 to match buffer start
16:20:46.676 hls.js:10170 [log] > [stream-controller]: seek to target start position 18.033666 from current time 0
16:20:46.678 hls.js:3087 [log] > [stream-controller]: media seeking to 18.034, state: PARSED
16:20:46.678 hls.js:3087 [log] > [audio-stream-controller]: media seeking to 18.034, state: PARSED
16:20:46.678 hls.js:3087 [log] > [subtitle-stream-controller]: media seeking to 18.034, state: IDLE
16:20:46.692 hls.js:3332 [log] > [audio-stream-controller]: Buffered audio sn: 16812 part: 1 of track 0 [18.034,20.039]
16:20:46.692 hls.js:4107 [log] > [audio-stream-controller]: PARSED->IDLE
16:20:46.711 hls.js:3332 [log] > [stream-controller]: Buffered main sn: 16812 part: 1 of level 3 [18.033,20.033]
16:20:46.711 hls.js:4107 [log] > [stream-controller]: PARSED->IDLE
16:20:46.711 hls.js:8673 [log] > [level-controller]: switching to level 4 from 3
16:20:46.712 hls.js:8565 [log] > [level-controller]: Attempt loading level index 4 with URL-id 0 https://manifest-gce-us-east1-production.fastly.mux.com/atcotL004WmaEG7W5nSKY9eVek7oY46A6FeDR8bx5pNh6BHwWP53OJzrYEtS6IbGCouQRRVzReBA4u4wL2dkQrIKB9nx900TPb/rendition.m3u8?cdn=fastly&expires=1639083600&live=1&skid=default&signature=NjFiMjZlNTBfNTFlNDA1NGE2NTQ5NGVhOTIwNzVjZmI5MWI3ZmY2YWQyZTNhOTUyODMzMDA4ZWU4OWYyNzkxOTAzYzY0NTA4OQ==
16:20:46.712 hls.js:19925 [log] > [playlist-loader]: aborting previous loader for type: level
16:20:46.713 hls.js:4107 [log] > [stream-controller]: IDLE->WAITING_LEVEL
16:20:46.811 hls.js:9748 [log] > [stream-controller]: Media seeked to 18.034
16:20:47.040 hls.js:8565 [log] > [level-controller]: Attempt loading level index 4 at sn 16813 part 1 with URL-id 0 https://manifest-gce-us-east1-production.fastly.mux.com/atcotL004WmaEG7W5nSKY9eVek7oY46A6FeDR8bx5pNh6BHwWP53OJzrYEtS6IbGCouQRRVzReBA4u4wL2dkQrIKB9nx900TPb/rendition.m3u8?cdn=fastly&expires=1639083600&live=1&skid=default&signature=NjFiMjZlNTBfNTFlNDA1NGE2NTQ5NGVhOTIwNzVjZmI5MWI3ZmY2YWQyZTNhOTUyODMzMDA4ZWU4OWYyNzkxOTAzYzY0NTA4OQ%3D%3D&_HLS_msn=16813&_HLS_part=1
16:20:47.042 hls.js:9820 [log] > [stream-controller]: Level 4 loaded [16803,16812], cc [0, 0] duration:21
16:20:47.042 hls.js:3876 [log] > [stream-controller]: Live playlist sliding: 0.00 start-sn: na->16803 prev-sn: 16812 fragments: 10
16:20:47.043 hls.js:4107 [log] > [stream-controller]: WAITING_LEVEL->IDLE
16:20:47.044 hls.js:3401 [log] > [stream-controller]: Loading fragment initSegment cc: 0 level: 4, target: 0
16:20:47.044 hls.js:4107 [log] > [stream-controller]: IDLE->FRAG_LOADING
16:20:47.047 hls.js:4771 [log] > [buffer-controller]: Updating Media Source duration to 21.000
16:20:47.049 hls.js:2449 [log] > [audio-track-controller]: audioTrack 0 loaded [16803-16812]
16:20:47.050 hls.js:2744 [log] > [audio-track-controller]: live playlist 0 REFRESHED 16813-0
16:20:47.051 hls.js:2597 [log] > [audio-track-controller]: loading audio-track playlist for id: 0
16:20:47.052 hls.js:1970 [log] > [audio-stream-controller]: Track 0 loaded [16803,16812],duration:21
16:20:47.052 hls.js:3383 [log] > [audio-stream-controller]: Loading part sn: 16813 p: 0 cc: 0 of playlist [16803-16812] parts [0-6-6] track: 0, target: 20.039
16:20:47.052 hls.js:4107 [log] > [audio-stream-controller]: IDLE->FRAG_LOADING
16:20:47.060 hls.js:4107 [log] > [stream-controller]: FRAG_LOADING->IDLE
16:20:47.060 hls.js:3332 [log] > [stream-controller]: Buffered main sn: initSegment of level 4 [18.033,20.033]
16:20:47.060 hls.js:3383 [log] > [stream-controller]: Loading part sn: 16813 p: 0 cc: 0 of playlist [16803-16812] parts [0-6-6] level: 4, target: 20.033
16:20:47.060 hls.js:4107 [log] > [stream-controller]: IDLE->FRAG_LOADING
16:20:47.074 hls.js:4107 [log] > [audio-stream-controller]: FRAG_LOADING->PARSING
16:20:47.075 982c0d21-bdd3-497b-a636-2464ca32c081:937 [log] > [transmuxer.ts]: Flushed fragment 16813 p: 0 of level 0
16:20:47.083 hls.js:4107 [log] > [audio-stream-controller]: PARSING->PARSED
16:20:47.096 hls.js:3332 [log] > [audio-stream-controller]: Buffered audio sn: 16813 part: 0 of track 0 [18.034,21.042]
16:20:47.097 hls.js:4107 [log] > [audio-stream-controller]: PARSED->IDLE
16:20:47.103 hls.js:14792 [log] > [transmuxer-interface, main]: Starting new transmux session for sn: 16813 p: 0 level: 4 id: 1
        discontinuity: false
        trackSwitch: true
        contiguous: false
        accurateTimeOffset: false
        timeOffset: 20
16:20:47.104 adf8e0f3-3409-462a-9b71-55772a2d4bcb:937 [log] > [transmuxer.ts]: Flushed fragment 16813 p: 0 of level 4
16:20:47.105 hls.js:4107 [log] > [stream-controller]: FRAG_LOADING->PARSING
16:20:47.105 hls.js:10408 [log] > [stream-controller]: Init video buffer, container:video/mp4, codecs[level/parsed]=[avc1.64002a/avc1.64002a]
16:20:47.123 hls.js:4107 [log] > [stream-controller]: PARSING->PARSED
16:20:47.159 hls.js:3332 [log] > [stream-controller]: Buffered main sn: 16813 part: 0 of level 4 [18.033,21.133]
16:20:47.159 hls.js:4107 [log] > [stream-controller]: PARSED->IDLE
16:20:48.039 hls.js:2744 [log] > [level-controller]: live playlist 4 REFRESHED 16813-1
16:20:48.040 hls.js:8565 [log] > [level-controller]: Attempt loading level index 4 at sn 16814 part 0 with URL-id 0 https://manifest-gce-us-east1-production.fastly.mux.com/atcotL004WmaEG7W5nSKY9eVek7oY46A6FeDR8bx5pNh6BHwWP53OJzrYEtS6IbGCouQRRVzReBA4u4wL2dkQrIKB9nx900TPb/rendition.m3u8?cdn=fastly&expires=1639083600&live=1&skid=default&signature=NjFiMjZlNTBfNTFlNDA1NGE2NTQ5NGVhOTIwNzVjZmI5MWI3ZmY2YWQyZTNhOTUyODMzMDA4ZWU4OWYyNzkxOTAzYzY0NTA4OQ%3D%3D&_HLS_msn=16814&_HLS_part=0
16:20:48.041 hls.js:9820 [log] > [stream-controller]: Level 4 loaded [16804,16813], cc [0, 0] duration:20
16:20:48.043 hls.js:3383 [log] > [stream-controller]: Loading part sn: 16813 p: 1 cc: 0 of playlist [16804-16813] parts [0-5-5] level: 4, target: 21.133
16:20:48.043 hls.js:4107 [log] > [stream-controller]: IDLE->FRAG_LOADING
16:20:48.046 hls.js:4771 [log] > [buffer-controller]: Updating Media Source duration to 22.000
16:20:48.048 hls.js:2449 [log] > [audio-track-controller]: audioTrack 0 loaded [16804-16813]
16:20:48.048 hls.js:2744 [log] > [audio-track-controller]: live playlist 0 REFRESHED 16813-1
16:20:48.048 hls.js:2597 [log] > [audio-track-controller]: loading audio-track playlist for id: 0
16:20:48.049 hls.js:1970 [log] > [audio-stream-controller]: Track 0 loaded [16804,16813],duration:20
16:20:48.049 hls.js:3383 [log] > [audio-stream-controller]: Loading part sn: 16813 p: 1 cc: 0 of playlist [16804-16813] parts [0-5-5] track: 0, target: 21.042
16:20:48.049 hls.js:4107 [log] > [audio-stream-controller]: IDLE->FRAG_LOADING
16:20:48.075 982c0d21-bdd3-497b-a636-2464ca32c081:937 [log] > [transmuxer.ts]: Flushed fragment 16813 p: 1 of level 0
16:20:48.075 hls.js:4107 [log] > [audio-stream-controller]: FRAG_LOADING->PARSING
16:20:48.088 hls.js:4107 [log] > [audio-stream-controller]: PARSING->PARSED
16:20:48.127 hls.js:3332 [log] > [audio-stream-controller]: Buffered audio sn: 16813 part: 1 of track 0 [18.034,22.044]
16:20:48.127 hls.js:4107 [log] > [audio-stream-controller]: PARSED->IDLE
16:20:48.127 hls.js:3401 [log] > [audio-stream-controller]: Loading fragment initSegment cc: 0 track: 0, target: 0
16:20:48.127 hls.js:4107 [log] > [audio-stream-controller]: IDLE->FRAG_LOADING
16:20:48.143 adf8e0f3-3409-462a-9b71-55772a2d4bcb:937 [log] > [transmuxer.ts]: Flushed fragment 16813 p: 1 of level 4
16:20:48.144 hls.js:4107 [log] > [stream-controller]: FRAG_LOADING->PARSING
16:20:48.156 hls.js:4107 [log] > [stream-controller]: PARSING->PARSED
16:20:48.180 hls.js:3332 [log] > [stream-controller]: Buffered main sn: 16813 part: 1 of level 4 [18.033,22.033]
16:20:48.180 hls.js:4107 [log] > [stream-controller]: PARSED->IDLE
16:20:48.181 hls.js:3401 [log] > [stream-controller]: Loading fragment initSegment cc: 0 level: 4, target: 0
16:20:48.181 hls.js:4107 [log] > [stream-controller]: IDLE->FRAG_LOADING
16:20:48.403 hls.js:4107 [log] > [audio-stream-controller]: FRAG_LOADING->IDLE
16:20:48.403 hls.js:3332 [log] > [audio-stream-controller]: Buffered audio sn: initSegment of track 0 [18.034,22.044]
16:20:48.413 hls.js:4107 [log] > [stream-controller]: FRAG_LOADING->IDLE
16:20:48.413 hls.js:3332 [log] > [stream-controller]: Buffered main sn: initSegment of level 4 [18.033,22.033]
16:20:49.038 hls.js:2449 [log] > [audio-track-controller]: audioTrack 0 loaded [16804-16813]
16:20:49.039 hls.js:2744 [log] > [audio-track-controller]: live playlist 0 REFRESHED 16814-0
16:20:49.039 hls.js:2597 [log] > [audio-track-controller]: loading audio-track playlist for id: 0
16:20:49.040 hls.js:1970 [log] > [audio-stream-controller]: Track 0 loaded [16804,16813],duration:21
16:20:49.040 hls.js:3383 [log] > [audio-stream-controller]: Loading part sn: 16814 p: 0 cc: 0 of playlist [16804-16813] parts [0-6-6] track: 0, target: 22.044
16:20:49.041 hls.js:4107 [log] > [audio-stream-controller]: IDLE->FRAG_LOADING
16:20:49.079 hls.js:2744 [log] > [level-controller]: live playlist 4 REFRESHED 16814-0
16:20:49.079 hls.js:8565 [log] > [level-controller]: Attempt loading level index 4 at sn 16814 part 1 with URL-id 0 https://manifest-gce-us-east1-production.fastly.mux.com/atcotL004WmaEG7W5nSKY9eVek7oY46A6FeDR8bx5pNh6BHwWP53OJzrYEtS6IbGCouQRRVzReBA4u4wL2dkQrIKB9nx900TPb/rendition.m3u8?cdn=fastly&expires=1639083600&live=1&skid=default&signature=NjFiMjZlNTBfNTFlNDA1NGE2NTQ5NGVhOTIwNzVjZmI5MWI3ZmY2YWQyZTNhOTUyODMzMDA4ZWU4OWYyNzkxOTAzYzY0NTA4OQ%3D%3D&_HLS_msn=16814&_HLS_part=1
16:20:49.081 hls.js:9820 [log] > [stream-controller]: Level 4 loaded [16804,16813], cc [0, 0] duration:21
16:20:49.082 hls.js:3383 [log] > [stream-controller]: Loading part sn: 16814 p: 0 cc: 0 of playlist [16804-16813] parts [0-6-6] level: 4, target: 22.033
16:20:49.082 hls.js:4107 [log] > [stream-controller]: IDLE->FRAG_LOADING
16:20:49.086 hls.js:4771 [log] > [buffer-controller]: Updating Media Source duration to 23.000
16:20:49.089 982c0d21-bdd3-497b-a636-2464ca32c081:937 [log] > [transmuxer.ts]: Flushed fragment 16814 p: 0 of level 0
16:20:49.089 hls.js:4107 [log] > [audio-stream-controller]: FRAG_LOADING->PARSING
16:20:49.102 hls.js:4107 [log] > [audio-stream-controller]: PARSING->PARSED
16:20:49.132 hls.js:3332 [log] > [audio-stream-controller]: Buffered audio sn: 16814 part: 0 of track 0 [18.034,23.047]
16:20:49.132 hls.js:4107 [log] > [audio-stream-controller]: PARSED->IDLE
16:20:49.170 adf8e0f3-3409-462a-9b71-55772a2d4bcb:937 [log] > [transmuxer.ts]: Flushed fragment 16814 p: 0 of level 4
16:20:49.171 hls.js:4107 [log] > [stream-controller]: FRAG_LOADING->PARSING
16:20:49.179 hls.js:4107 [log] > [stream-controller]: PARSING->PARSED
16:20:49.198 hls.js:3332 [log] > [stream-controller]: Buffered main sn: 16814 part: 0 of level 4 [18.033,23.133]
16:20:49.198 hls.js:4107 [log] > [stream-controller]: PARSED->IDLE
16:20:50.039 hls.js:2449 [log] > [audio-track-controller]: audioTrack 0 loaded [16805-16814]
16:20:50.039 hls.js:2744 [log] > [audio-track-controller]: live playlist 0 REFRESHED 16814-1
16:20:50.039 hls.js:2597 [log] > [audio-track-controller]: loading audio-track playlist for id: 0
16:20:50.040 hls.js:1970 [log] > [audio-stream-controller]: Track 0 loaded [16805,16814],duration:20
16:20:50.040 hls.js:3383 [log] > [audio-stream-controller]: Loading part sn: 16814 p: 1 cc: 0 of playlist [16805-16814] parts [0-5-5] track: 0, target: 23.047
16:20:50.040 hls.js:4107 [log] > [audio-stream-controller]: IDLE->FRAG_LOADING
16:20:50.066 982c0d21-bdd3-497b-a636-2464ca32c081:937 [log] > [transmuxer.ts]: Flushed fragment 16814 p: 1 of level 0
16:20:50.068 hls.js:4107 [log] > [audio-stream-controller]: FRAG_LOADING->PARSING
16:20:50.079 hls.js:4107 [log] > [audio-stream-controller]: PARSING->PARSED
16:20:50.087 hls.js:2744 [log] > [level-controller]: live playlist 4 REFRESHED 16814-1
16:20:50.088 hls.js:8565 [log] > [level-controller]: Attempt loading level index 4 at sn 16815 part 0 with URL-id 0 https://manifest-gce-us-east1-production.fastly.mux.com/atcotL004WmaEG7W5nSKY9eVek7oY46A6FeDR8bx5pNh6BHwWP53OJzrYEtS6IbGCouQRRVzReBA4u4wL2dkQrIKB9nx900TPb/rendition.m3u8?cdn=fastly&expires=1639083600&live=1&skid=default&signature=NjFiMjZlNTBfNTFlNDA1NGE2NTQ5NGVhOTIwNzVjZmI5MWI3ZmY2YWQyZTNhOTUyODMzMDA4ZWU4OWYyNzkxOTAzYzY0NTA4OQ%3D%3D&_HLS_msn=16815&_HLS_part=0
16:20:50.090 hls.js:9820 [log] > [stream-controller]: Level 4 loaded [16805,16814], cc [0, 0] duration:20
16:20:50.091 hls.js:3383 [log] > [stream-controller]: Loading part sn: 16814 p: 1 cc: 0 of playlist [16805-16814] parts [0-5-5] level: 4, target: 23.133
16:20:50.091 hls.js:4107 [log] > [stream-controller]: IDLE->FRAG_LOADING
16:20:50.129 hls.js:3332 [log] > [audio-stream-controller]: Buffered audio sn: 16814 part: 1 of track 0 [18.034,24.050]
16:20:50.129 hls.js:4107 [log] > [audio-stream-controller]: PARSED->IDLE
16:20:50.129 hls.js:3401 [log] > [audio-stream-controller]: Loading fragment initSegment cc: 0 track: 0, target: 0
16:20:50.129 hls.js:4107 [log] > [audio-stream-controller]: IDLE->FRAG_LOADING
16:20:50.145 hls.js:4107 [log] > [audio-stream-controller]: FRAG_LOADING->IDLE
16:20:50.145 hls.js:3332 [log] > [audio-stream-controller]: Buffered audio sn: initSegment of track 0 [18.034,24.050]
16:20:50.157 adf8e0f3-3409-462a-9b71-55772a2d4bcb:937 [log] > [transmuxer.ts]: Flushed fragment 16814 p: 1 of level 4
16:20:50.158 hls.js:4107 [log] > [stream-controller]: FRAG_LOADING->PARSING
16:20:50.168 hls.js:4107 [log] > [stream-controller]: PARSING->PARSED
16:20:50.201 hls.js:3332 [log] > [stream-controller]: Buffered main sn: 16814 part: 1 of level 4 [18.033,24.033]
16:20:50.201 hls.js:4107 [log] > [stream-controller]: PARSED->IDLE
16:20:50.201 hls.js:3401 [log] > [stream-controller]: Loading fragment initSegment cc: 0 level: 4, target: 0
16:20:50.201 hls.js:4107 [log] > [stream-controller]: IDLE->FRAG_LOADING
16:20:50.216 hls.js:4107 [log] > [stream-controller]: FRAG_LOADING->IDLE
16:20:50.216 hls.js:3332 [log] > [stream-controller]: Buffered main sn: initSegment of level 4 [18.033,24.033]
16:20:51.037 hls.js:2744 [log] > [level-controller]: live playlist 4 REFRESHED 16815-0
16:20:51.038 hls.js:8565 [log] > [level-controller]: Attempt loading level index 4 at sn 16815 part 1 with URL-id 0 https://manifest-gce-us-east1-production.fastly.mux.com/atcotL004WmaEG7W5nSKY9eVek7oY46A6FeDR8bx5pNh6BHwWP53OJzrYEtS6IbGCouQRRVzReBA4u4wL2dkQrIKB9nx900TPb/rendition.m3u8?cdn=fastly&expires=1639083600&live=1&skid=default&signature=NjFiMjZlNTBfNTFlNDA1NGE2NTQ5NGVhOTIwNzVjZmI5MWI3ZmY2YWQyZTNhOTUyODMzMDA4ZWU4OWYyNzkxOTAzYzY0NTA4OQ%3D%3D&_HLS_msn=16815&_HLS_part=1
16:20:51.040 hls.js:9820 [log] > [stream-controller]: Level 4 loaded [16805,16814], cc [0, 0] duration:21
16:20:51.041 hls.js:3383 [log] > [stream-controller]: Loading part sn: 16815 p: 0 cc: 0 of playlist [16805-16814] parts [0-6-6] level: 4, target: 24.033
16:20:51.041 hls.js:4107 [log] > [stream-controller]: IDLE->FRAG_LOADING
16:20:51.046 hls.js:4771 [log] > [buffer-controller]: Updating Media Source duration to 25.000
16:20:51.063 hls.js:2449 [log] > [audio-track-controller]: audioTrack 0 loaded [16805-16814]
16:20:51.067 hls.js:2744 [log] > [audio-track-controller]: live playlist 0 REFRESHED 16815-0
16:20:51.068 hls.js:2597 [log] > [audio-track-controller]: loading audio-track playlist for id: 0
16:20:51.070 hls.js:1970 [log] > [audio-stream-controller]: Track 0 loaded [16805,16814],duration:21
16:20:51.070 hls.js:3383 [log] > [audio-stream-controller]: Loading part sn: 16815 p: 0 cc: 0 of playlist [16805-16814] parts [0-6-6] track: 0, target: 24.05
16:20:51.070 hls.js:4107 [log] > [audio-stream-controller]: IDLE->FRAG_LOADING
16:20:51.098 982c0d21-bdd3-497b-a636-2464ca32c081:937 [log] > [transmuxer.ts]: Flushed fragment 16815 p: 0 of level 0
16:20:51.110 hls.js:4107 [log] > [audio-stream-controller]: FRAG_LOADING->PARSING
16:20:51.119 hls.js:4107 [log] > [audio-stream-controller]: PARSING->PARSED
16:20:51.153 hls.js:3332 [log] > [audio-stream-controller]: Buffered audio sn: 16815 part: 0 of track 0 [18.034,25.052]
16:20:51.153 hls.js:4107 [log] > [audio-stream-controller]: PARSED->IDLE
16:20:51.181 adf8e0f3-3409-462a-9b71-55772a2d4bcb:937 [log] > [transmuxer.ts]: Flushed fragment 16815 p: 0 of level 4
16:20:51.192 hls.js:4107 [log] > [stream-controller]: FRAG_LOADING->PARSING
16:20:51.200 hls.js:4107 [log] > [stream-controller]: PARSING->PARSED
16:20:51.223 hls.js:3332 [log] > [stream-controller]: Buffered main sn: 16815 part: 0 of level 4 [18.033,25.133]
16:20:51.223 hls.js:4107 [log] > [stream-controller]: PARSED->IDLE
16:20:52.044 hls.js:2744 [log] > [level-controller]: live playlist 4 REFRESHED 16815-1
16:20:52.044 hls.js:8565 [log] > [level-controller]: Attempt loading level index 4 at sn 16816 part 0 with URL-id 0 https://manifest-gce-us-east1-production.fastly.mux.com/atcotL004WmaEG7W5nSKY9eVek7oY46A6FeDR8bx5pNh6BHwWP53OJzrYEtS6IbGCouQRRVzReBA4u4wL2dkQrIKB9nx900TPb/rendition.m3u8?cdn=fastly&expires=1639083600&live=1&skid=default&signature=NjFiMjZlNTBfNTFlNDA1NGE2NTQ5NGVhOTIwNzVjZmI5MWI3ZmY2YWQyZTNhOTUyODMzMDA4ZWU4OWYyNzkxOTAzYzY0NTA4OQ%3D%3D&_HLS_msn=16816&_HLS_part=0
16:20:52.046 hls.js:9820 [log] > [stream-controller]: Level 4 loaded [16806,16815], cc [0, 0] duration:20
16:20:52.047 hls.js:3383 [log] > [stream-controller]: Loading part sn: 16815 p: 1 cc: 0 of playlist [16806-16815] parts [0-5-5] level: 4, target: 25.133
16:20:52.047 hls.js:4107 [log] > [stream-controller]: IDLE->FRAG_LOADING
16:20:52.051 hls.js:4771 [log] > [buffer-controller]: Updating Media Source duration to 26.000
16:20:52.067 hls.js:2449 [log] > [audio-track-controller]: audioTrack 0 loaded [16806-16815]
16:20:52.067 hls.js:2744 [log] > [audio-track-controller]: live playlist 0 REFRESHED 16815-1
16:20:52.068 hls.js:2597 [log] > [audio-track-controller]: loading audio-track playlist for id: 0
16:20:52.069 hls.js:1970 [log] > [audio-stream-controller]: Track 0 loaded [16806,16815],duration:20
16:20:52.069 hls.js:3383 [log] > [audio-stream-controller]: Loading part sn: 16815 p: 1 cc: 0 of playlist [16806-16815] parts [0-5-5] track: 0, target: 25.052
16:20:52.069 hls.js:4107 [log] > [audio-stream-controller]: IDLE->FRAG_LOADING
16:20:52.093 982c0d21-bdd3-497b-a636-2464ca32c081:937 [log] > [transmuxer.ts]: Flushed fragment 16815 p: 1 of level 0
16:20:52.094 hls.js:4107 [log] > [audio-stream-controller]: FRAG_LOADING->PARSING
16:20:52.103 hls.js:4107 [log] > [audio-stream-controller]: PARSING->PARSED
16:20:52.135 hls.js:3332 [log] > [audio-stream-controller]: Buffered audio sn: 16815 part: 1 of track 0 [18.034,26.034]
16:20:52.135 hls.js:4107 [log] > [audio-stream-controller]: PARSED->IDLE
16:20:52.136 hls.js:3401 [log] > [audio-stream-controller]: Loading fragment initSegment cc: 0 track: 0, target: 0
16:20:52.136 hls.js:4107 [log] > [audio-stream-controller]: IDLE->FRAG_LOADING
16:20:52.154 adf8e0f3-3409-462a-9b71-55772a2d4bcb:937 [log] > [transmuxer.ts]: Flushed fragment 16815 p: 1 of level 4
16:20:52.156 hls.js:4107 [log] > [audio-stream-controller]: FRAG_LOADING->IDLE
16:20:52.156 hls.js:3332 [log] > [audio-stream-controller]: Buffered audio sn: initSegment of track 0 [18.034,26.034]
16:20:52.161 hls.js:4107 [log] > [stream-controller]: FRAG_LOADING->PARSING
16:20:52.173 hls.js:4107 [log] > [stream-controller]: PARSING->PARSED
16:20:52.191 hls.js:3332 [log] > [stream-controller]: Buffered main sn: 16815 part: 1 of level 4 [18.033,26.033]
16:20:52.191 hls.js:4107 [log] > [stream-controller]: PARSED->IDLE
16:20:52.191 hls.js:3401 [log] > [stream-controller]: Loading fragment initSegment cc: 0 level: 4, target: 0
16:20:52.191 hls.js:4107 [log] > [stream-controller]: IDLE->FRAG_LOADING
16:20:52.212 hls.js:4107 [log] > [stream-controller]: FRAG_LOADING->IDLE
16:20:52.212 hls.js:3332 [log] > [stream-controller]: Buffered main sn: initSegment of level 4 [18.033,26.033]
16:20:53.048 hls.js:2449 [log] > [audio-track-controller]: audioTrack 0 loaded [16806-16815]
16:20:53.048 hls.js:2744 [log] > [audio-track-controller]: live playlist 0 REFRESHED 16816-0
16:20:53.048 hls.js:2597 [log] > [audio-track-controller]: loading audio-track playlist for id: 0
16:20:53.050 hls.js:1970 [log] > [audio-stream-controller]: Track 0 loaded [16806,16815],duration:21
16:20:53.050 hls.js:3383 [log] > [audio-stream-controller]: Loading part sn: 16816 p: 0 cc: 0 of playlist [16806-16815] parts [0-6-6] track: 0, target: 26.034
16:20:53.050 hls.js:4107 [log] > [audio-stream-controller]: IDLE->FRAG_LOADING
16:20:53.059 hls.js:2744 [log] > [level-controller]: live playlist 4 REFRESHED 16816-0
16:20:53.060 hls.js:8565 [log] > [level-controller]: Attempt loading level index 4 at sn 16816 part 1 with URL-id 0 https://manifest-gce-us-east1-production.fastly.mux.com/atcotL004WmaEG7W5nSKY9eVek7oY46A6FeDR8bx5pNh6BHwWP53OJzrYEtS6IbGCouQRRVzReBA4u4wL2dkQrIKB9nx900TPb/rendition.m3u8?cdn=fastly&expires=1639083600&live=1&skid=default&signature=NjFiMjZlNTBfNTFlNDA1NGE2NTQ5NGVhOTIwNzVjZmI5MWI3ZmY2YWQyZTNhOTUyODMzMDA4ZWU4OWYyNzkxOTAzYzY0NTA4OQ%3D%3D&_HLS_msn=16816&_HLS_part=1
16:20:53.063 hls.js:9820 [log] > [stream-controller]: Level 4 loaded [16806,16815], cc [0, 0] duration:21
16:20:53.064 hls.js:3383 [log] > [stream-controller]: Loading part sn: 16816 p: 0 cc: 0 of playlist [16806-16815] parts [0-6-6] level: 4, target: 26.033
16:20:53.064 hls.js:4107 [log] > [stream-controller]: IDLE->FRAG_LOADING
16:20:53.069 hls.js:4771 [log] > [buffer-controller]: Updating Media Source duration to 27.000
16:20:53.088 982c0d21-bdd3-497b-a636-2464ca32c081:937 [log] > [transmuxer.ts]: Flushed fragment 16816 p: 0 of level 0
16:20:53.091 hls.js:4107 [log] > [audio-stream-controller]: FRAG_LOADING->PARSING
16:20:53.101 hls.js:4107 [log] > [audio-stream-controller]: PARSING->PARSED
16:20:53.130 hls.js:3332 [log] > [audio-stream-controller]: Buffered audio sn: 16816 part: 0 of track 0 [18.034,27.036]
16:20:53.130 hls.js:4107 [log] > [audio-stream-controller]: PARSED->IDLE
16:20:53.141 adf8e0f3-3409-462a-9b71-55772a2d4bcb:937 [log] > [transmuxer.ts]: Flushed fragment 16816 p: 0 of level 4
16:20:53.142 hls.js:4107 [log] > [stream-controller]: FRAG_LOADING->PARSING
16:20:53.153 hls.js:4107 [log] > [stream-controller]: PARSING->PARSED
16:20:53.195 hls.js:3332 [log] > [stream-controller]: Buffered main sn: 16816 part: 0 of level 4 [18.033,27.133]
16:20:53.195 hls.js:4107 [log] > [stream-controller]: PARSED->IDLE
16:20:54.041 hls.js:2449 [log] > [audio-track-controller]: audioTrack 0 loaded [16807-16816]
16:20:54.041 hls.js:2744 [log] > [audio-track-controller]: live playlist 0 REFRESHED 16816-1
16:20:54.042 hls.js:2597 [log] > [audio-track-controller]: loading audio-track playlist for id: 0
16:20:54.043 hls.js:1970 [log] > [audio-stream-controller]: Track 0 loaded [16807,16816],duration:20
16:20:54.044 hls.js:3383 [log] > [audio-stream-controller]: Loading part sn: 16816 p: 1 cc: 0 of playlist [16807-16816] parts [0-5-5] track: 0, target: 27.036
16:20:54.044 hls.js:4107 [log] > [audio-stream-controller]: IDLE->FRAG_LOADING
16:20:54.068 hls.js:2744 [log] > [level-controller]: live playlist 4 REFRESHED 16816-1
16:20:54.069 hls.js:8565 [log] > [level-controller]: Attempt loading level index 4 at sn 16817 part 0 with URL-id 0 https://manifest-gce-us-east1-production.fastly.mux.com/atcotL004WmaEG7W5nSKY9eVek7oY46A6FeDR8bx5pNh6BHwWP53OJzrYEtS6IbGCouQRRVzReBA4u4wL2dkQrIKB9nx900TPb/rendition.m3u8?cdn=fastly&expires=1639083600&live=1&skid=default&signature=NjFiMjZlNTBfNTFlNDA1NGE2NTQ5NGVhOTIwNzVjZmI5MWI3ZmY2YWQyZTNhOTUyODMzMDA4ZWU4OWYyNzkxOTAzYzY0NTA4OQ%3D%3D&_HLS_msn=16817&_HLS_part=0
16:20:54.071 hls.js:9820 [log] > [stream-controller]: Level 4 loaded [16807,16816], cc [0, 0] duration:20
16:20:54.073 hls.js:3383 [log] > [stream-controller]: Loading part sn: 16816 p: 1 cc: 0 of playlist [16807-16816] parts [0-5-5] level: 4, target: 27.133
16:20:54.073 hls.js:4107 [log] > [stream-controller]: IDLE->FRAG_LOADING
16:20:54.079 hls.js:4771 [log] > [buffer-controller]: Updating Media Source duration to 28.000
16:20:54.086 982c0d21-bdd3-497b-a636-2464ca32c081:937 [log] > [transmuxer.ts]: Flushed fragment 16816 p: 1 of level 0
16:20:54.098 hls.js:4107 [log] > [audio-stream-controller]: FRAG_LOADING->PARSING
16:20:54.108 hls.js:4107 [log] > [audio-stream-controller]: PARSING->PARSED
16:20:54.142 hls.js:3332 [log] > [audio-stream-controller]: Buffered audio sn: 16816 part: 1 of track 0 [18.034,28.039]
16:20:54.143 hls.js:4107 [log] > [audio-stream-controller]: PARSED->IDLE
16:20:54.143 hls.js:3401 [log] > [audio-stream-controller]: Loading fragment initSegment cc: 0 track: 0, target: 0
16:20:54.143 hls.js:4107 [log] > [audio-stream-controller]: IDLE->FRAG_LOADING
16:20:54.168 adf8e0f3-3409-462a-9b71-55772a2d4bcb:937 [log] > [transmuxer.ts]: Flushed fragment 16816 p: 1 of level 4
16:20:54.168 hls.js:4107 [log] > [audio-stream-controller]: FRAG_LOADING->IDLE
16:20:54.168 hls.js:3332 [log] > [audio-stream-controller]: Buffered audio sn: initSegment of track 0 [18.034,28.039]
16:20:54.175 hls.js:4107 [log] > [stream-controller]: FRAG_LOADING->PARSING
16:20:54.183 hls.js:4107 [log] > [stream-controller]: PARSING->PARSED
16:20:54.197 hls.js:3332 [log] > [stream-controller]: Buffered main sn: 16816 part: 1 of level 4 [18.033,28.033]
16:20:54.197 hls.js:4107 [log] > [stream-controller]: PARSED->IDLE
16:20:54.197 hls.js:3401 [log] > [stream-controller]: Loading fragment initSegment cc: 0 level: 4, target: 0
16:20:54.197 hls.js:4107 [log] > [stream-controller]: IDLE->FRAG_LOADING
16:20:54.212 hls.js:4107 [log] > [stream-controller]: FRAG_LOADING->IDLE
16:20:54.212 hls.js:3332 [log] > [stream-controller]: Buffered main sn: initSegment

Chrome media internals output

No response
@erkreutzer erkreutzer added Bug Needs Triage If there is a suspected stream issue, apply this label to triage if it is something we should fix. labels Dec 2, 2021
@robwalch robwalch removed the Needs Triage If there is a suspected stream issue, apply this label to triage if it is something we should fix. label Dec 3, 2021
erkreutzer added a commit to erkreutzer/hls.js that referenced this issue Dec 3, 2021
@erkreutzer
Fix repeated requests for initSegment
58e0134 
Fixes video-dev#4450
For live video, merging playlists did not updated non-overlapping
fragments. Now, the level-helper will update any non-overlapping
fragmentHint or fragments to reference existing initSegment
fragments.
@erkreutzer erkreutzer mentioned this issue Dec 3, 2021
Merged
3 tasks
@robwalch robwalch added this to the 1.1.2 milestone Dec 6, 2021
littlespex pushed a commit to cbsinteractive/hls.js that referenced this issue Dec 9, 2022
@joeyparrish
chore: Clean up unused AES-128 failure paths (video-dev#4450)
91003d3 
Now that we support AES-128, some older AES-128 code paths in the HLS
parser have become obsolete.  This cleans up those unneeded code paths
and adds a test to ensure that the correct failure handling is used
when AES-128 cannot be supported by the platform.
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Assignees
No one assigned
Labels
Bug
Projects
None yet
Milestone
1.1.2
Development

No branches or pull requests
2 participants
@robwalch @erkreutzer