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

Some DASH/Widevine streams are looping on a Vestel MB130 #1295

Open
peijkelhardt opened this issue Sep 28, 2023 · 3 comments
Open

Some DASH/Widevine streams are looping on a Vestel MB130 #1295

peijkelhardt opened this issue Sep 28, 2023 · 3 comments

Comments

@peijkelhardt
Copy link

Some of our Replay streams keep playing the same loop for about 5 seconds on specific Vestel devices (MB130). When checking the debug logs, I see a combination of trying to seek to un-freeze player & current discontinuity encountered video logs.

We are using the 3.30 version with the following config:

player = new RxPlayer({
    maxBufferAhead: 30,
    wantedBufferAhead: 20,
    maxBufferBehind: 5,
    initialVideoBitrate: 2500000,
    initialAudioBitrate: 64000
});

player.loadVideo({
    transport: "dash",
    url: "https://{redacted}GlobalManifest.mpd",
    keySystems: [{
        type: "widevine",
        ...
    }],
    autoPlay: true,
    startAt: undefined,
    enableFastSwitching: false,
    onCodecSwitch: "reload",
    textTrackMode: "html",
    textTrackElement: document.createElement("div"),
});

Collection of logs:

API: Calling loadvideo https://[redacted].mpd
Locking `contentLock` to clean-up the current content.
DRM: Clearing-up DRM session.
DRM: Nothing to clear. Returning right away. No state = true
API: current media element state tick event init position 0 seeking false internalSeek null rebuffering false freezing false ended false paused true playbackRate 1 readyState 0
API: playerStateChange event LOADING
API: DRM session cleaned-up with success!
Unlocking `contentLock`. Next content can begin.
Init: Creating ContentDecryptor
DRM: Starting ContentDecryptor logic.
DRM: Searching for compatible MediaKeySystemAccess
DRM: Request keysystem access com.widevine.alpha,1 of 1
DRM: Found compatible keysystem com.widevine.alpha 1
DRM: Calling createMediaKeys on the MediaKeySystemAccess
DASH: WASM MPD Parser not initialized. Running JS one.
DASH Parser: merging "switchable" AdaptationSets 5 4
MF: Manifest parsed in 267.2999999999811ms
DRM: MediaKeys created with success
Init: Creating MediaSource
Init: Attaching MediaSource URL to the media element blob:https://[redacted]
DRM: Attaching current MediaKeys
DRM: Attaching MediaKeys to the media element
DRM: MediaKeys attached with success
DRM: Setting server certificate on the MediaKeys
Init: Calculating initial time
Init: starting at the minimum available position: 0.017291666666666667
Init: Initial time calculated: 0.017291666666666667
Init: Resume playback speed 1
Init: Updating duration 3640.32
Stream: Creating new Stream for video 0
Stream: Updating video adaptation A: 4 P: 0
Init: Duration has to be updated. 3640.32
SB: Adding native SegmentBuffer with codec video/mp4;codecs="avc1.640029"
Stream: Creating new Stream for audio 0
Stream: Updating audio adaptation A: 1 P: 0
Init: Duration has to be updated. 3640.32
SB: Adding native SegmentBuffer with codec audio/mp4;codecs="mp4a.40.2"
Stream: Creating new Stream for text 0
Stream: Set no text Adaptation. P: 0
Stream: Creating new Stream for image 0
Stream: Updating image adaptation A: 6 P: 0
SB: Creating a new image SegmentBuffer
ISB: Creating ImageSegmentBuffer
ABR: Creating new BandwidthEstimator for  video
ABR: Steps for buffer based chooser. bufferLevel: 0, bitrate: 443600 ,bufferLevel: 6.802896272800928, bitrate: 648000 ,bufferLevel: 11.170013884595148, bitrate: 1066800 ,bufferLevel: 15.37290154586124, bitrate: 1444800 ,bufferLevel: 19.79331093099003, bitrate: 2671200 ,bufferLevel: 24.658858230343256, bitrate: 3590400 ,bufferLevel: 27.554458168122714, bitrate: 4714400
ABR: enter starvation mode.
ABR: Choosing representation with bandwidth estimation. 1444800 video=1444800
Stream: changing representation video video=1444800 1444800
Stream: Media segments now need to be requested. Starting queue. video 11
SF: Beginning request video P: 1 A: 4 R: video=1444800 S: 0-1.92
SF: Beginning request video P: 1 A: 4 R: video=1444800 S: init
ABR: Creating new BandwidthEstimator for  audio
Stream: changing representation audio audio_112034_cze=112000 112000
Stream: Media segments now need to be requested. Starting queue. audio 11
SF: Beginning request audio P: 1 A: 1 R: audio_112034_cze=112000 S: 0.017291666666666667-1.92
SF: Beginning request audio P: 1 A: 1 R: audio_112034_cze=112000 S: init
ABR: Creating new BandwidthEstimator for  image
Stream: changing representation image thumbnail 10000
Stream: Media segments now need to be requested. Starting queue. image 2
SF: Beginning request image P: 1 A: 6 R: thumbnail S: 0-12
SF: Beginning request image P: 1 A: 6 R: thumbnail S: init
Stream: no more init segment to request. Cancelling queue. image
SF: Segment request cancelled image P: 1 A: 6 R: thumbnail S: init
SF: Segment request ended with success image P: 1 A: 6 R: thumbnail S: init
SF: Segment request ended with success video P: 1 A: 4 R: video=1444800 S: 0-1.92
AVSB: receiving order to push data to the SourceBuffer video 
AVSB: pushing segment video 
Stream: no more init segment to request. Cancelling queue. video
SF: Segment request cancelled video P: 1 A: 4 R: video=1444800 S: init
ABR: New last stable representation 1444800
AVSB: receiving order to push data to the SourceBuffer video video P: 1 A: 4 R: video=1444800 S: 0-1.92
AVSB: receiving order for validating end of segment video video P: 1 A: 4 R: video=1444800 S: 0-1.92
SF: Segment request ended with success video P: 1 A: 4 R: video=1444800 S: init
AVSB: receiving order for validating end of segment video video P: 1 A: 4 R: video=1444800 S: init
SF: Segment request ended with success audio P: 1 A: 1 R: audio_112034_cze=112000 S: 0.017291666666666667-1.92
AVSB: receiving order to push data to the SourceBuffer audio 
AVSB: pushing segment audio 
Stream: no more init segment to request. Cancelling queue. audio
SF: Segment request cancelled audio P: 1 A: 1 R: audio_112034_cze=112000 S: init
ABR: New last stable representation 112000
AVSB: receiving order to push data to the SourceBuffer audio audio P: 1 A: 1 R: audio_112034_cze=112000 S: 0.017291666666666667-1.92
AVSB: receiving order for validating end of segment audio audio P: 1 A: 1 R: audio_112034_cze=112000 S: 0.017291666666666667-1.92
SF: Segment request ended with success audio P: 1 A: 1 R: audio_112034_cze=112000 S: init
AVSB: receiving order for validating end of segment audio audio P: 1 A: 1 R: audio_112034_cze=112000 S: init
API: current media element state tick event timeupdate position 0 seeking false internalSeek null rebuffering false freezing false ended false paused true playbackRate 1 readyState 0
API: current playback timeline:

^0 
timeupdate
ABR: Choosing representation with bandwidth estimation. 3590400 video=3590400
Stream: new video bitrate estimate 5207860.132970233
Stream: slow Representation switch video
SF: Segment request failed image P: 1 A: 6 R: thumbnail S: 0-12
SF: Beginning request video P: 1 A: 4 R: video=1444800 S: 1.92-1.92
SF: Beginning request audio P: 1 A: 1 R: audio_112034_cze=112000 S: 1.9372916666666666-1.92
SB: Aborting SegmentBuffer image
ISB: disposing image SegmentBuffer
API: Sending warning: t: OtherError (PIPELINE_PARSE_ERROR) Error: Invalid BIF file(…)
DRM: mediaKeys.setServerCertificate returned an error DOMException: Internal CDM error.
API: Sending warning: t: EncryptedMediaError (LICENSE_SERVER_CERTIFICATE_ERROR) InvalidAccessError: Internal CDM error.(…)
DRM: Creating a new temporary session
DRM-LSS: Add MediaKeySession temporary
DRM: Binding session events 
Compat: Calling generateRequest on the MediaKeySession
Compat: Trying to move CENC PSSH from init data at the end of it.
DRM: Creating a new temporary session
DRM-LSS: Add MediaKeySession temporary
DRM: Binding session events 
Compat: Calling generateRequest on the MediaKeySession
Compat: Trying to move CENC PSSH from init data at the end of it.
AVSB: receiving order to push data to the SourceBuffer audio audio P: 1 A: 1 R: audio_112034_cze=112000 S: 1.9372916666666666-1.92
SF: Segment request ended with success audio P: 1 A: 1 R: audio_112034_cze=112000 S: 1.9372916666666666-1.92
AVSB: receiving order for validating end of segment audio audio P: 1 A: 1 R: audio_112034_cze=112000 S: 1.9372916666666666-1.92
SF: Beginning request audio P: 1 A: 1 R: audio_112034_cze=112000 S: 3.857291666666667-1.92
AVSB: receiving order to push data to the SourceBuffer video video P: 1 A: 4 R: video=1444800 S: 1.92-1.92
SF: Segment request ended with success video P: 1 A: 4 R: video=1444800 S: 1.92-1.92
AVSB: receiving order for validating end of segment video video P: 1 A: 4 R: video=1444800 S: 1.92-1.92
Stream: No request left, terminate video
Stream: changing representation video video=3590400 3590400
DRM: Init data already processed. Skipping it.
Stream: Media segments now need to be requested. Starting queue. video 9
SF: Beginning request video P: 1 A: 4 R: video=3590400 S: 3.84-1.92
SF: Beginning request video P: 1 A: 4 R: video=3590400 S: init
API: current media element state tick event loadedmetadata position 0 seeking false internalSeek null rebuffering false freezing false ended false paused true playbackRate 1 readyState 1
API: current playback timeline:

^0 
loadedmetadata
ABR: Choosing representation with bandwidth estimation. 3590400 video=3590400
Stream: new video bitrate estimate 5586518.961699452
Init: Set initial time 0.017291666666666667
Init: Can begin to play content
AVSB: updating timestampOffset video 0 -1695883099.2083333
AVSB: pushing segment video video P: 1 A: 4 R: video=1444800 S: 0-1.92
ABR: Choosing representation with bandwidth estimation. 3590400 video=3590400
AVSB: updating timestampOffset audio 0 -1695883099.2083333
AVSB: pushing segment audio audio P: 1 A: 1 R: audio_112034_cze=112000 S: 0.017291666666666667-1.92
AVSB: receiving order to push data to the SourceBuffer audio audio P: 1 A: 1 R: audio_112034_cze=112000 S: 3.857291666666667-1.92
SF: Segment request ended with success audio P: 1 A: 1 R: audio_112034_cze=112000 S: 3.857291666666667-1.92
AVSB: receiving order for validating end of segment audio audio P: 1 A: 1 R: audio_112034_cze=112000 S: 3.857291666666667-1.92
AVSB: receiving order to push data to the SourceBuffer video 
Stream: no more init segment to request. Cancelling queue. video
SF: Segment request cancelled video P: 1 A: 4 R: video=3590400 S: init
SF: Segment request ended with success video P: 1 A: 4 R: video=3590400 S: init
AVSB: receiving order for validating end of segment video video P: 1 A: 4 R: video=3590400 S: init
API: current media element state tick event internal-seeking position 0.017291666666666667 seeking true internalSeek 0.017291666666666667 rebuffering true freezing false ended false paused false playbackRate 1 readyState 1
API: current playback timeline:

^0.017291666666666667 
seeking
ABR: Choosing representation with bandwidth estimation. 3590400 video=3590400
Init: Pause playback to build buffer
API: current media element state tick event play position 0.017291666666666667 seeking true internalSeek 0.017291666666666667 rebuffering true freezing false ended false paused false playbackRate 0 readyState 1
API: current playback timeline:

^0.017291666666666667 
play
ABR: Choosing representation with bandwidth estimation. 3590400 video=3590400
AVSB: receiving order to push data to the SourceBuffer video video P: 1 A: 4 R: video=3590400 S: 3.84-1.92
SF: Segment request ended with success video P: 1 A: 4 R: video=3590400 S: 3.84-1.92
AVSB: receiving order for validating end of segment video video P: 1 A: 4 R: video=3590400 S: 3.84-1.92
ABR: New last stable representation 3590400
SF: Beginning request audio P: 1 A: 1 R: audio_112034_cze=112000 S: 5.777291666666667-1.92
SF: Beginning request video P: 1 A: 4 R: video=3590400 S: 5.76-1.92
DRM: Received message event, type license-request ksid083D81CE
DRM: Calling `getLicense` license-request
DRM: Received message event, type license-request ksidA41F22AD
DRM: Calling `getLicense` license-request
API: current media element state tick event ratechange position 0.017291666666666667 seeking true internalSeek 0.017291666666666667 rebuffering true freezing false ended false paused false playbackRate 0 readyState 1
API: current playback timeline:

^0.017291666666666667 
ratechange
ABR: Choosing representation with bandwidth estimation. 3590400 video=3590400
Stream: new video bitrate estimate 6298552.723160051
SI: first segment pushed video 0 1.92
AVSB: Acknowledging complete segment video P: 1 A: 4 R: video=1444800 S: 0-1.92
SI: found true buffered start video 0.08 0
SI: found true buffered end video 2 1.92
AVSB: Acknowledging complete segment video P: 1 A: 4 R: video=1444800 S: init
AVSB: pushing segment video video P: 1 A: 4 R: video=1444800 S: 1.92-1.92
ABR: Choosing representation with bandwidth estimation. 3590400 video=3590400
SI: first segment pushed audio 0.017291666666666667 1.9372916666666666
AVSB: Acknowledging complete segment audio P: 1 A: 1 R: audio_112034_cze=112000 S: 0.017291666666666667-1.92
SI: found true buffered start audio 0.017292 0.017291666666666667
SI: found true buffered end audio 1.937291 1.9372916666666666
AVSB: Acknowledging complete segment audio P: 1 A: 1 R: audio_112034_cze=112000 S: init
AVSB: pushing segment audio audio P: 1 A: 1 R: audio_112034_cze=112000 S: 1.9372916666666666-1.92
AVSB: receiving order to push data to the SourceBuffer audio audio P: 1 A: 1 R: audio_112034_cze=112000 S: 5.777291666666667-1.92
SF: Segment request ended with success audio P: 1 A: 1 R: audio_112034_cze=112000 S: 5.777291666666667-1.92
AVSB: receiving order for validating end of segment audio audio P: 1 A: 1 R: audio_112034_cze=112000 S: 5.777291666666667-1.92
SF: Beginning request audio P: 1 A: 1 R: audio_112034_cze=112000 S: 7.697291666666667-1.92
DRM: Updating MediaKeySession with message
DRM: Updating MediaKeySession with message
AVSB: receiving order to push data to the SourceBuffer video video P: 1 A: 4 R: video=3590400 S: 5.76-1.92
SF: Segment request ended with success video P: 1 A: 4 R: video=3590400 S: 5.76-1.92
AVSB: receiving order for validating end of segment video video P: 1 A: 4 R: video=3590400 S: 5.76-1.92
SF: Beginning request video P: 1 A: 4 R: video=3590400 S: 7.68-1.92
SI: Pushing segment strictly after previous one. audio 1.9372916666666666 1.937291
AVSB: Acknowledging complete segment audio P: 1 A: 1 R: audio_112034_cze=112000 S: 1.9372916666666666-1.92
SI: found true buffered end audio 3.857291 3.857291666666667
SI: current audio inventory timeline:
0.02|A|3.86
[A] P: 1 || R: audio_112034_cze=112000(112000)
AVSB: pushing segment audio audio P: 1 A: 1 R: audio_112034_cze=112000 S: 3.857291666666667-1.92
AVSB: receiving order to push data to the SourceBuffer audio audio P: 1 A: 1 R: audio_112034_cze=112000 S: 7.697291666666667-1.92
SF: Segment request ended with success audio P: 1 A: 1 R: audio_112034_cze=112000 S: 7.697291666666667-1.92
AVSB: receiving order for validating end of segment audio audio P: 1 A: 1 R: audio_112034_cze=112000 S: 7.697291666666667-1.92
SI: Segment pushed updates end of previous one video 1.92 3.84 0.08 2
AVSB: Acknowledging complete segment video P: 1 A: 4 R: video=1444800 S: 1.92-1.92
SI: calculating buffered end of contiguous segment video 1.92 1.92
SI: found true buffered end video 3.92 3.84
SI: current video inventory timeline:
0.08|A|3.92
[A] P: 1 || R: video=1444800(1444800)
AVSB: updating timestampOffset video -1695883099.2083333 0
AVSB: pushing segment video 
ABR: Choosing representation with bandwidth estimation. 4714400 video=4714400
Stream: new video bitrate estimate 6803148.62078111
Stream: slow Representation switch video
SI: current video inventory timeline:
0.08|A|3.92
[A] P: 1 || R: video=1444800(1444800)
2DRM: MediaKeySession update succeeded.
API: current media element state tick event timeupdate position 0.017291666666666667 seeking true internalSeek 0.017291666666666667 rebuffering true freezing false ended false paused false playbackRate 0 readyState 1
API: current playback timeline:
0.08|==3.78==|3.86
         ^0.017291666666666667 
timeupdate
ABR: Choosing representation with bandwidth estimation. 4714400 video=4714400
Stream: slow Representation switch video
SI: current video inventory timeline:
0.08|A|3.92
[A] P: 1 || R: video=1444800(1444800)
SI: current video inventory timeline:
0.08|A|3.92
[A] P: 1 || R: video=1444800(1444800)
SI: current audio inventory timeline:
0.02|A|3.86
[A] P: 1 || R: audio_112034_cze=112000(112000)
RS: current discontinuity encountered audio 0.017292
Init: discontinuity found 0.017291666666666667 0.033958666666666665
SA: skippable discontinuity found in the stream 0.017291666666666667 0.034958666666666666
API: Sending warning: t: MediaError (DISCONTINUITY_ENCOUNTERED) A discontinuity has been encountered at position 0.017291666666666667, seeked at position 0.034958666666666666(…)
AVSB: receiving order to push data to the SourceBuffer video video P: 1 A: 4 R: video=3590400 S: 7.68-1.92
SF: Segment request ended with success video P: 1 A: 4 R: video=3590400 S: 7.68-1.92
AVSB: receiving order for validating end of segment video video P: 1 A: 4 R: video=3590400 S: 7.68-1.92
SI: current video inventory timeline:
0.08|A|3.92
[A] P: 1 || R: video=1444800(1444800)
Stream: No request left, terminate video
Stream: changing representation video video=4714400 4714400
SI: current video inventory timeline:
0.08|A|3.92
[A] P: 1 || R: video=1444800(1444800)
Stream: Media segments now need to be requested. Starting queue. video 6
SF: Beginning request audio P: 1 A: 1 R: audio_112034_cze=112000 S: 9.617291666666667-1.92
SF: Beginning request video P: 1 A: 4 R: video=4714400 S: 9.6-1.92
SF: Beginning request video P: 1 A: 4 R: video=4714400 S: init
DRM: Creating a new temporary session
DRM-LSS: Add MediaKeySession temporary
DRM: Binding session events 
Compat: Calling generateRequest on the MediaKeySession
Compat: Trying to move CENC PSSH from init data at the end of it.
DRM: keystatuseschange event received ksid083D81CE
DRM: key status update (27301361c45644edabc4a78b0ac507b5): usable
DRM: key status update (688557aa135644edabc4a78b0ac507b5): usable
DRM: key status update (6f741361c45644edabc4a78b0ac507b5): usable
DRM: key status update (7c785792c45644edabc4a78b0ac507b5): usable
Decipherability changed for "audio_112034_cze=112000" (112000) undefined
Decipherability changed for "audio_112035_qaa=112000" (112000) undefined
Decipherability changed for "video=443600" (443600) undefined
Decipherability changed for "video=648000" (648000) undefined
Decipherability changed for "video=1066800" (1066800) undefined
Decipherability changed for "video=1444800" (1444800) undefined
Decipherability changed for "video=2671200" (2671200) undefined
Decipherability changed for "video=3590400" (3590400) undefined
Decipherability changed for "video=4714400" (4714400) undefined
DRM: keystatuseschange event received ksidA41F22AD
DRM: key status update (27301361c45644edabc4a78b0ac507b5): usable
DRM: key status update (688557aa135644edabc4a78b0ac507b5): usable
DRM: key status update (6f741361c45644edabc4a78b0ac507b5): usable
DRM: key status update (7c785792c45644edabc4a78b0ac507b5): usable
API: current media element state tick event internal-seeking position 0.034958666666666666 seeking true internalSeek 0.034958666666666666 rebuffering true freezing false ended false paused false playbackRate 0 readyState 1
API: current playback timeline:
0.08|==3.78==|3.86
         ^0.034958666666666666 
seeking
ABR: Choosing representation with bandwidth estimation. 3590400 video=3590400
Stream: new video bitrate estimate 6188195.60147634
Stream: urgent Representation switch video
SI: current video inventory timeline:
0.08|A|3.92
[A] P: 1 || R: video=1444800(1444800)
Stream: Urgent switch, terminate now. video
Stream: no more media segment to request. Cancelling queue. video
SF: Segment request cancelled video P: 1 A: 4 R: video=4714400 S: 9.6-1.92
SI: current video inventory timeline:
0.08|A|3.92
[A] P: 1 || R: video=1444800(1444800)
Stream: Urgent switch, terminate now. video
Stream: no more init segment to request. Cancelling queue. video
SF: Segment request cancelled video P: 1 A: 4 R: video=4714400 S: init
Stream: changing representation video video=3590400 3590400
DRM: Init data already processed. Skipping it.
SI: current video inventory timeline:
0.08|A|3.92
[A] P: 1 || R: video=1444800(1444800)
Stream: Media segments now need to be requested. Starting queue. video 6
SF: Beginning request video P: 1 A: 4 R: video=3590400 S: 9.6-1.92
SF: Found wanted segment in cache video P: 1 A: 4 R: video=3590400 S: init
AVSB: receiving order to push data to the SourceBuffer video 
SI: current video inventory timeline:
0.08|A|3.92
[A] P: 1 || R: video=1444800(1444800)
GC: cleaning range from SegmentBuffer 1695883103.065625 1695883104.985624
AVSB: receiving order to remove data from the SourceBuffer audio 1695883103.065625 1695883104.985624
RS: current discontinuity encountered audio 0.017292
Init: discontinuity encountered inferior to the threshold 0.034958666666666666 0.09666666666666666 0.2
API: Sending warning: t: MediaError (DISCONTINUITY_ENCOUNTERED) A discontinuity has been encountered at position 0.034958666666666666, seeked at position 0.09666666666666666(…)
SF: Segment request aborted video P: 1 A: 4 R: video=4714400 S: 9.6-1.92
SF: Segment request aborted video P: 1 A: 4 R: video=4714400 S: init
SI: Pushing segment strictly after previous one. audio 3.857291666666667 3.857291
AVSB: Acknowledging complete segment audio P: 1 A: 1 R: audio_112034_cze=112000 S: 3.857291666666667-1.92
SI: 1 segments GCed. audio
SI: buffered range not known after sync. Skipping history. 3.857291666666667 5.777291666666667
AVSB: pushing segment audio audio P: 1 A: 1 R: audio_112034_cze=112000 S: 5.777291666666667-1.92
AVSB: Acknowledging complete segment video P: 1 A: 4 R: video=3590400 S: init
AVSB: updating timestampOffset video 0 -1695883099.2083333
AVSB: pushing segment video video P: 1 A: 4 R: video=3590400 S: 3.84-1.92
ABR: Choosing representation with bandwidth estimation. 3590400 video=3590400
AVSB: receiving order to push data to the SourceBuffer audio audio P: 1 A: 1 R: audio_112034_cze=112000 S: 9.617291666666667-1.92
SF: Segment request ended with success audio P: 1 A: 1 R: audio_112034_cze=112000 S: 9.617291666666667-1.92
AVSB: receiving order for validating end of segment audio audio P: 1 A: 1 R: audio_112034_cze=112000 S: 9.617291666666667-1.92
SF: Beginning request audio P: 1 A: 1 R: audio_112034_cze=112000 S: 11.537291666666667-1.92
API: current media element state tick event internal-seeking position 0 seeking false internalSeek null rebuffering true freezing false ended false paused false playbackRate 0 readyState 4
API: current playback timeline:
0.08|==3.78==|3.86
         ^0 
seeking
API: Sending warning: t: MediaError (MEDIA_TIME_BEFORE_MANIFEST) The current position is behind the earliest time announced in the Manifest.(…)
ABR: Choosing representation with bandwidth estimation. 3590400 video=3590400
SI: current video inventory timeline:
0.08|A|3.92
[A] P: 1 || R: video=1444800(1444800)
RS: current discontinuity encountered video 0.08
GC: cleaning range from SegmentBuffer 1695883103.065625 1695883104.985624
AVSB: receiving order to remove data from the SourceBuffer audio 1695883103.065625 1695883104.985624
RS: current discontinuity encountered audio 0.017292
Stream: Next media segment changed, cancelling previous audio
SF: Segment request cancelled audio P: 1 A: 1 R: audio_112034_cze=112000 S: 11.537291666666667-1.92
SF: Beginning request audio P: 1 A: 1 R: audio_112034_cze=112000 S: 3.857291666666667-1.92
SF: Segment request cancelled video P: 1 A: 4 R: video=3590400 S: 9.6-1.92
Stream: segment request interrupted temporarly. 1017529865285 9.6
Init: discontinuity found 0 0.033958666666666665
Init: discontinuity found 0 0.09666666666666666
SA: skippable discontinuity found in the stream 0 0.09766666666666667
API: Sending warning: t: MediaError (DISCONTINUITY_ENCOUNTERED) A discontinuity has been encountered at position 0, seeked at position 0.09766666666666667(…)
SF: Segment request aborted audio P: 1 A: 1 R: audio_112034_cze=112000 S: 11.537291666666667-1.92
SF: Segment request aborted video P: 1 A: 4 R: video=3590400 S: 9.6-1.92
API: current media element state tick event seeked position 0.09766666666666667 seeking true internalSeek null rebuffering false freezing false ended false paused false playbackRate 0 readyState 4
API: current playback timeline:
0.08|==3.78==|3.86
         ^0.09766666666666667 
seeked
ABR: Choosing representation with bandwidth estimation. 3590400 video=3590400
SI: current video inventory timeline:
0.08|A|3.92
[A] P: 1 || R: video=1444800(1444800)
RS: current discontinuity encountered video 0.08
GC: cleaning range from SegmentBuffer 1695883103.065625 1695883104.985624
AVSB: receiving order to remove data from the SourceBuffer audio 1695883103.065625 1695883104.985624
RS: current discontinuity encountered audio 0.017292
Init: Resume playback speed 1
API: current media element state tick event canplay position 0.09766666666666667 seeking true internalSeek null rebuffering false freezing false ended false paused false playbackRate 1 readyState 4
API: current playback timeline:
0.08|==3.78==|3.86
         ^0.09766666666666667 
canplay
ABR: Choosing representation with bandwidth estimation. 3590400 video=3590400
SI: current video inventory timeline:
0.08|A|3.92
[A] P: 1 || R: video=1444800(1444800)
RS: current discontinuity encountered video 0.08
GC: cleaning range from SegmentBuffer 1695883103.065625 1695883104.985624
AVSB: receiving order to remove data from the SourceBuffer audio 1695883103.065625 1695883104.985624
RS: current discontinuity encountered audio 0.017292
API: playerStateChange event LOADED
API: playerStateChange event PLAYING
AVSB: receiving order to push data to the SourceBuffer audio audio P: 1 A: 1 R: audio_112034_cze=112000 S: 3.857291666666667-1.92
SF: Segment request ended with success audio P: 1 A: 1 R: audio_112034_cze=112000 S: 3.857291666666667-1.92
AVSB: receiving order for validating end of segment audio audio P: 1 A: 1 R: audio_112034_cze=112000 S: 3.857291666666667-1.92
SF: Beginning request video P: 1 A: 4 R: video=3590400 S: 9.6-1.92
SF: Beginning request audio P: 1 A: 1 R: audio_112034_cze=112000 S: 11.537291666666667-1.92
API: current media element state tick event internal-seeking position 0.09766666666666667 seeking true internalSeek 0.09766666666666667 rebuffering false freezing false ended false paused false playbackRate 1 readyState 4
API: current playback timeline:
0.08|==3.78==|3.86
         ^0.09766666666666667 
seeking
ABR: Choosing representation with bandwidth estimation. 3590400 video=3590400
SI: current video inventory timeline:
0.08|A|3.92
[A] P: 1 || R: video=1444800(1444800)
RS: current discontinuity encountered video 0.08
GC: cleaning range from SegmentBuffer 1695883103.065625 1695883104.985624
AVSB: receiving order to remove data from the SourceBuffer audio 1695883103.065625 1695883104.985624
RS: current discontinuity encountered audio 0.017292
API: current media element state tick event ratechange position 0.09766666666666667 seeking true internalSeek 0.09766666666666667 rebuffering false freezing false ended false paused false playbackRate 1 readyState 4
API: current playback timeline:
0.08|==3.78==|3.86
         ^0.09766666666666667 
ratechange
ABR: Choosing representation with bandwidth estimation. 3590400 video=3590400
SI: current video inventory timeline:
0.08|A|3.92
[A] P: 1 || R: video=1444800(1444800)
RS: current discontinuity encountered video 0.08
GC: cleaning range from SegmentBuffer 1695883103.065625 1695883104.985624
AVSB: receiving order to remove data from the SourceBuffer audio 1695883103.065625 1695883104.985624
RS: current discontinuity encountered audio 0.017292
DRM: Received message event, type license-request ksid62FA2693
DRM: Calling `getLicense` license-request
AVSB: receiving order to push data to the SourceBuffer audio audio P: 1 A: 1 R: audio_112034_cze=112000 S: 11.537291666666667-1.92
SF: Segment request ended with success audio P: 1 A: 1 R: audio_112034_cze=112000 S: 11.537291666666667-1.92
AVSB: receiving order for validating end of segment audio audio P: 1 A: 1 R: audio_112034_cze=112000 S: 11.537291666666667-1.92
SI: Segment pushed updates end of previous one video 3.84 5.76 1.92 3.92
AVSB: Acknowledging complete segment video P: 1 A: 4 R: video=3590400 S: 3.84-1.92
SI: calculating buffered end of contiguous segment video 3.84 3.84
SI: found true buffered end video 5.84 5.76
SI: current video inventory timeline:
0.08|A|3.84 ~ 3.84|B|5.84
[A] P: 1 || R: video=1444800(1444800)
[B] P: 1 || R: video=3590400(3590400)
AVSB: pushing segment video video P: 1 A: 4 R: video=3590400 S: 5.76-1.92
ABR: Choosing representation with bandwidth estimation. 3590400 video=3590400
SI: Pushing segment strictly after previous one. audio 5.777291666666667 3.857291
AVSB: Acknowledging complete segment audio P: 1 A: 1 R: audio_112034_cze=112000 S: 5.777291666666667-1.92
SI: found true buffered start audio 5.777292 5.777291666666667
SI: found true buffered end audio 7.697291 7.697291666666667
AVSB: pushing segment audio audio P: 1 A: 1 R: audio_112034_cze=112000 S: 7.697291666666667-1.92
API: current media element state tick event seeked position 0 seeking false internalSeek null rebuffering true freezing false ended false paused false playbackRate 1 readyState 2
API: current playback timeline:
0.08|==3.78==|3.86 ~1.92~ 5.78|==0.06==|5.84
         ^0 
seeked
API: Sending warning: t: MediaError (MEDIA_TIME_BEFORE_MANIFEST) The current position is behind the earliest time announced in the Manifest.(…)
ABR: Choosing representation with bandwidth estimation. 3590400 video=3590400
SI: current video inventory timeline:
0.08|A|3.84 ~ 3.84|B|5.84
[A] P: 1 || R: video=1444800(1444800)
[B] P: 1 || R: video=3590400(3590400)
RS: current discontinuity encountered video 0.08
GC: cleaning range from SegmentBuffer 1695883103.065625 1695883104.985624
AVSB: receiving order to remove data from the SourceBuffer audio 1695883103.065625 1695883104.985624
RS: current discontinuity encountered audio 0.017292
Init: Pause playback to build buffer
Init: discontinuity found 0 0.033958666666666665
Init: discontinuity found 0 0.09666666666666666
SA: skippable discontinuity found in the stream 0 0.09766666666666667
API: Sending warning: t: MediaError (DISCONTINUITY_ENCOUNTERED) A discontinuity has been encountered at position 0, seeked at position 0.09766666666666667(…)
API: current media element state tick event ratechange position 0.09766666666666667 seeking true internalSeek null rebuffering true freezing false ended false paused false playbackRate 0 readyState 2
API: current playback timeline:
0.08|==3.78==|3.86 ~1.92~ 5.78|==0.06==|5.84
         ^0.09766666666666667 
ratechange
ABR: Choosing representation with bandwidth estimation. 3590400 video=3590400
SI: current video inventory timeline:
0.08|A|3.84 ~ 3.84|B|5.84
[A] P: 1 || R: video=1444800(1444800)
[B] P: 1 || R: video=3590400(3590400)
RS: current discontinuity encountered video 0.08
GC: cleaning range from SegmentBuffer 1695883103.065625 1695883104.985624
AVSB: receiving order to remove data from the SourceBuffer audio 1695883103.065625 1695883104.985624
RS: current discontinuity encountered audio 0.017292
API: playerStateChange event SEEKING
API: current media element state tick event internal-seeking position 0.09766666666666667 seeking true internalSeek 0.09766666666666667 rebuffering false freezing false ended false paused false playbackRate 0 readyState 2
API: current playback timeline:
0.08|==3.78==|3.86 ~1.92~ 5.78|==0.06==|5.84
         ^0.09766666666666667 
seeking
ABR: Choosing representation with bandwidth estimation. 3590400 video=3590400
SI: current video inventory timeline:
0.08|A|3.84 ~ 3.84|B|5.84
[A] P: 1 || R: video=1444800(1444800)
[B] P: 1 || R: video=3590400(3590400)
RS: current discontinuity encountered video 0.08
GC: cleaning range from SegmentBuffer 1695883103.065625 1695883104.985624
AVSB: receiving order to remove data from the SourceBuffer audio 1695883103.065625 1695883104.985624
RS: current discontinuity encountered audio 0.017292
Init: Resume playback speed 1
API: playerStateChange event PLAYING
AVSB: receiving order to push data to the SourceBuffer video video P: 1 A: 4 R: video=3590400 S: 9.6-1.92
SF: Segment request ended with success video P: 1 A: 4 R: video=3590400 S: 9.6-1.92
AVSB: receiving order for validating end of segment video video P: 1 A: 4 R: video=3590400 S: 9.6-1.92
SF: Beginning request video P: 1 A: 4 R: video=3590400 S: 11.52-1.92
API: current media element state tick event ratechange position 0.09766666666666667 seeking true internalSeek 0.09766666666666667 rebuffering false freezing false ended false paused false playbackRate 1 readyState 2
API: current playback timeline:
0.08|==3.78==|3.86 ~1.92~ 5.78|==1.98==|7.76
         ^0.09766666666666667 
ratechange
ABR: exit starvation mode.
ABR: Choosing representation with bandwidth estimation. 4714400 video=4714400
Stream: new video bitrate estimate 6728942.760434266
Stream: urgent Representation switch video
SI: current video inventory timeline:
0.08|A|3.84 ~ 3.84|B|5.84
[A] P: 1 || R: video=1444800(1444800)
[B] P: 1 || R: video=3590400(3590400)
RS: current discontinuity encountered video 0.08
Stream: Urgent switch, terminate now. video
Stream: no more media segment to request. Cancelling queue. video
SF: Beginning request audio P: 1 A: 1 R: audio_112034_cze=112000 S: 13.457291666666666-1.92
SF: Segment request cancelled video P: 1 A: 4 R: video=3590400 S: 11.52-1.92
SI: current video inventory timeline:
0.08|A|3.84 ~ 3.84|B|5.84
[A] P: 1 || R: video=1444800(1444800)
[B] P: 1 || R: video=3590400(3590400)
RS: current discontinuity encountered video 0.08
Stream: Urgent switch, terminate now. video
Stream: changing representation video video=4714400 4714400
DRM: Init data already processed. Skipping it.
SI: current video inventory timeline:
0.08|A|3.84 ~ 3.84|B|5.84
[A] P: 1 || R: video=1444800(1444800)
[B] P: 1 || R: video=3590400(3590400)
RS: current discontinuity encountered video 0.08
Stream: Media segments now need to be requested. Starting queue. video 5
SF: Beginning request video P: 1 A: 4 R: video=4714400 S: 11.52-1.92
SF: Beginning request video P: 1 A: 4 R: video=4714400 S: init
GC: cleaning range from SegmentBuffer 1695883103.065625 1695883104.985624
AVSB: receiving order to remove data from the SourceBuffer audio 1695883103.065625 1695883104.985624
RS: current discontinuity encountered audio 0.017292
SF: Segment request aborted video P: 1 A: 4 R: video=3590400 S: 11.52-1.92
SI: Segment pushed updates end of previous one video 5.76 7.68 3.84 5.84
AVSB: Acknowledging complete segment video P: 1 A: 4 R: video=3590400 S: 5.76-1.92
SI: calculating buffered end of contiguous segment video 5.76 5.76
SI: found true buffered end video 7.76 7.68
SI: current video inventory timeline:
0.08|A|3.84 ~ 3.84|B|7.76
[A] P: 1 || R: video=1444800(1444800)
[B] P: 1 || R: video=3590400(3590400)
AVSB: pushing segment video video P: 1 A: 4 R: video=3590400 S: 7.68-1.92
ABR: Choosing representation with bandwidth estimation. 4714400 video=4714400
SI: Pushing segment strictly after previous one. audio 7.697291666666667 7.697291
AVSB: Acknowledging complete segment audio P: 1 A: 1 R: audio_112034_cze=112000 S: 7.697291666666667-1.92
SI: found true buffered end audio 9.617291 9.617291666666667
AVSB: removing data from SourceBuffer audio 1695883103.065625 1695883104.985624
DRM: Updating MediaKeySession with message
AVSB: receiving order to push data to the SourceBuffer audio audio P: 1 A: 1 R: audio_112034_cze=112000 S: 13.457291666666666-1.92
SF: Segment request ended with success audio P: 1 A: 1 R: audio_112034_cze=112000 S: 13.457291666666666-1.92
AVSB: receiving order for validating end of segment audio audio P: 1 A: 1 R: audio_112034_cze=112000 S: 13.457291666666666-1.92
AVSB: receiving order to push data to the SourceBuffer video 
SI: current video inventory timeline:
0.08|A|3.84 ~ 3.84|B|7.76
[A] P: 1 || R: video=1444800(1444800)
[B] P: 1 || R: video=3590400(3590400)
RS: current discontinuity encountered video 0.08
Stream: no more init segment to request. Cancelling queue. video
SF: Segment request cancelled video P: 1 A: 4 R: video=4714400 S: init
SF: Segment request ended with success video P: 1 A: 4 R: video=4714400 S: init
AVSB: receiving order for validating end of segment video video P: 1 A: 4 R: video=4714400 S: init
API: current media element state tick event timeupdate position 0.09766666666666667 seeking true internalSeek 0.09766666666666667 rebuffering false freezing true ended false paused false playbackRate 1 readyState 2
API: current playback timeline:
0.08|==3.78==|3.86 ~1.92~ 5.78|==1.98==|7.76
         ^0.09766666666666667 
timeupdate
ABR: Choosing representation with bandwidth estimation. 4714400 video=4714400
SI: current video inventory timeline:
0.08|A|3.84 ~ 3.84|B|7.76
[A] P: 1 || R: video=1444800(1444800)
[B] P: 1 || R: video=3590400(3590400)
GC: cleaning range from SegmentBuffer 1695883103.065625 1695883104.985624
AVSB: receiving order to remove data from the SourceBuffer audio 1695883103.065625 1695883104.985624
RS: current discontinuity encountered audio 0.017292
SI: current audio inventory timeline:
0.02|A|3.86 ~ 5.78|A|9.62
[A] P: 1 || R: audio_112034_cze=112000(112000)
AVSB: pushing segment audio audio P: 1 A: 1 R: audio_112034_cze=112000 S: 9.617291666666667-1.92
SI: Segment pushed updates end of previous one video 7.68 9.6 5.76 7.76
AVSB: Acknowledging complete segment video P: 1 A: 4 R: video=3590400 S: 7.68-1.92
SI: calculating buffered end of contiguous segment video 7.68 7.68
SI: found true buffered end video 9.68 9.6
SI: current video inventory timeline:
0.08|A|3.84 ~ 3.84|B|9.68
[A] P: 1 || R: video=1444800(1444800)
[B] P: 1 || R: video=3590400(3590400)
AVSB: updating timestampOffset video -1695883099.2083333 0
AVSB: updating timestampOffset video 0 -1695883099.2083333
AVSB: pushing segment video video P: 1 A: 4 R: video=3590400 S: 9.6-1.92
ABR: Choosing representation with bandwidth estimation. 4714400 video=4714400
ABR: Choosing representation with bandwidth estimation. 4714400 video=4714400
AVSB: receiving order to push data to the SourceBuffer video video P: 1 A: 4 R: video=4714400 S: 11.52-1.92
SF: Segment request ended with success video P: 1 A: 4 R: video=4714400 S: 11.52-1.92
AVSB: receiving order for validating end of segment video video P: 1 A: 4 R: video=4714400 S: 11.52-1.92
ABR: New last stable representation 4714400
SF: Beginning request audio P: 1 A: 1 R: audio_112034_cze=112000 S: 15.377291666666666-1.92
SF: Beginning request video P: 1 A: 4 R: video=4714400 S: 13.44-1.92
DRM: MediaKeySession update succeeded.
DRM: keystatuseschange event received ksid62FA2693
DRM: key status update (27301361c45644edabc4a78b0ac507b5): usable
DRM: key status update (688557aa135644edabc4a78b0ac507b5): usable
DRM: key status update (6f741361c45644edabc4a78b0ac507b5): usable
DRM: key status update (7c785792c45644edabc4a78b0ac507b5): usable
AVSB: receiving order to push data to the SourceBuffer audio audio P: 1 A: 1 R: audio_112034_cze=112000 S: 15.377291666666666-1.92
SF: Segment request ended with success audio P: 1 A: 1 R: audio_112034_cze=112000 S: 15.377291666666666-1.92
AVSB: receiving order for validating end of segment audio audio P: 1 A: 1 R: audio_112034_cze=112000 S: 15.377291666666666-1.92
SF: Beginning request audio P: 1 A: 1 R: audio_112034_cze=112000 S: 17.297291666666666-1.92
SI: Pushing segment strictly after previous one. audio 9.617291666666667 9.617291
AVSB: Acknowledging complete segment audio P: 1 A: 1 R: audio_112034_cze=112000 S: 9.617291666666667-1.92
SI: found true buffered end audio 11.537291 11.537291666666667
SI: current audio inventory timeline:
0.02|A|3.86 ~ 5.78|A|11.54
[A] P: 1 || R: audio_112034_cze=112000(112000)
AVSB: removing data from SourceBuffer audio 1695883103.065625 1695883104.985624
API: current media element state tick event timeupdate position 0.09766666666666667 seeking true internalSeek 0.09766666666666667 rebuffering false freezing true ended false paused false playbackRate 1 readyState 2
API: current playback timeline:
0.08|==3.78==|3.86 ~1.92~ 5.78|==3.90==|9.68
         ^0.09766666666666667 
timeupdate
ABR: Choosing representation with bandwidth estimation. 4714400 video=4714400
Stream: new video bitrate estimate 7727513.294053169
SI: current video inventory timeline:
0.08|A|3.84 ~ 3.84|B|9.68
[A] P: 1 || R: video=1444800(1444800)
[B] P: 1 || R: video=3590400(3590400)
SI: current audio inventory timeline:
0.02|A|3.86 ~ 5.78|A|11.54
[A] P: 1 || R: audio_112034_cze=112000(112000)
RS: current discontinuity encountered audio 0.017292
API: playerStateChange event BUFFERING
AVSB: receiving order to push data to the SourceBuffer audio audio P: 1 A: 1 R: audio_112034_cze=112000 S: 17.297291666666666-1.92
SF: Segment request ended with success audio P: 1 A: 1 R: audio_112034_cze=112000 S: 17.297291666666666-1.92
AVSB: receiving order for validating end of segment audio audio P: 1 A: 1 R: audio_112034_cze=112000 S: 17.297291666666666-1.92
SI: Segment pushed updates end of previous one video 9.6 11.52 7.68 9.68
AVSB: Acknowledging complete segment video P: 1 A: 4 R: video=3590400 S: 9.6-1.92
SI: calculating buffered end of contiguous segment video 9.6 9.6
SI: found true buffered end video 11.6 11.52
SI: current video inventory timeline:
0.08|A|3.84 ~ 3.84|B|11.60
[A] P: 1 || R: video=1444800(1444800)
[B] P: 1 || R: video=3590400(3590400)
AVSB: updating timestampOffset video -1695883099.2083333 0
AVSB: pushing segment video 
ABR: Choosing representation with bandwidth estimation. 4714400 video=4714400
SI: current audio inventory timeline:
0.02|A|3.86 ~ 5.78|A|11.54
[A] P: 1 || R: audio_112034_cze=112000(112000)
AVSB: removing data from SourceBuffer audio 1695883103.065625 1695883104.985624
AVSB: receiving order to push data to the SourceBuffer video video P: 1 A: 4 R: video=4714400 S: 13.44-1.92
SF: Segment request ended with success video P: 1 A: 4 R: video=4714400 S: 13.44-1.92
AVSB: receiving order for validating end of segment video video P: 1 A: 4 R: video=4714400 S: 13.44-1.92
SF: Beginning request video P: 1 A: 4 R: video=4714400 S: 15.36-1.92
AVSB: Acknowledging complete segment video P: 1 A: 4 R: video=4714400 S: init
AVSB: updating timestampOffset video 0 -1695883099.2083333
AVSB: pushing segment video video P: 1 A: 4 R: video=4714400 S: 11.52-1.92
ABR: Choosing representation with bandwidth estimation. 4714400 video=4714400
Stream: new video bitrate estimate 8469951.599717205
SI: current audio inventory timeline:
0.02|A|3.86 ~ 5.78|A|11.54
[A] P: 1 || R: audio_112034_cze=112000(112000)
AVSB: removing data from SourceBuffer audio 1695883103.065625 1695883104.985624
AVSB: receiving order to push data to the SourceBuffer video video P: 1 A: 4 R: video=4714400 S: 15.36-1.92
SF: Segment request ended with success video P: 1 A: 4 R: video=4714400 S: 15.36-1.92
AVSB: receiving order for validating end of segment video video P: 1 A: 4 R: video=4714400 S: 15.36-1.92
SF: Beginning request video P: 1 A: 4 R: video=4714400 S: 17.28-1.92
SI: current audio inventory timeline:
0.02|A|3.86 ~ 5.78|A|11.54
[A] P: 1 || R: audio_112034_cze=112000(112000)
AVSB: pushing segment audio audio P: 1 A: 1 R: audio_112034_cze=112000 S: 3.857291666666667-1.92
SI: Segment pushed updates end of previous one video 11.52 13.44 9.6 11.6
AVSB: Acknowledging complete segment video P: 1 A: 4 R: video=4714400 S: 11.52-1.92
SI: calculating buffered end of contiguous segment video 11.52 11.52
SI: found true buffered end video 13.52 13.44
SI: current video inventory timeline:
0.08|A|3.84 ~ 3.84|B|11.52 ~ 11.52|C|13.52
[A] P: 1 || R: video=1444800(1444800)
[B] P: 1 || R: video=3590400(3590400)
[C] P: 1 || R: video=4714400(4714400)
AVSB: pushing segment video video P: 1 A: 4 R: video=4714400 S: 13.44-1.92
ABR: Choosing representation with bandwidth estimation. 4714400 video=4714400
Stream: new video bitrate estimate 9068758.185891788
API: current media element state tick event timeupdate position 0.09766666666666667 seeking true internalSeek 0.09766666666666667 rebuffering false freezing true ended false paused false playbackRate 1 readyState 2
API: current playback timeline:
0.08|==11.46==|11.54
          ^0.09766666666666667 
timeupdate
ABR: Choosing representation with bandwidth estimation. 4714400 video=4714400
SI: current video inventory timeline:
0.08|A|3.84 ~ 3.84|B|11.52 ~ 11.52|C|13.52
[A] P: 1 || R: video=1444800(1444800)
[B] P: 1 || R: video=3590400(3590400)
[C] P: 1 || R: video=4714400(4714400)
RS: current discontinuity encountered video 0.08
SI: current audio inventory timeline:
0.02|A|11.54
[A] P: 1 || R: audio_112034_cze=112000(112000)
RS: current discontinuity encountered audio 0.017292
SI: Pushing segment strictly after previous one. audio 3.857291666666667 3.857291
AVSB: Acknowledging complete segment audio P: 1 A: 1 R: audio_112034_cze=112000 S: 3.857291666666667-1.92
SI: calculating buffered end of contiguous segment audio 5.777292 5.777291666666667
SI: current audio inventory timeline:
0.02|A|11.54
[A] P: 1 || R: audio_112034_cze=112000(112000)
AVSB: removing data from SourceBuffer audio 1695883103.065625 1695883104.985624
SI: Segment pushed updates end of previous one video 13.44 15.36 11.52 13.52
AVSB: Acknowledging complete segment video P: 1 A: 4 R: video=4714400 S: 13.44-1.92
SI: calculating buffered end of contiguous segment video 13.44 13.44
SI: found true buffered end video 15.44 15.36
SI: current video inventory timeline:
0.08|A|3.84 ~ 3.84|B|11.52 ~ 11.52|C|15.44
[A] P: 1 || R: video=1444800(1444800)
[B] P: 1 || R: video=3590400(3590400)
[C] P: 1 || R: video=4714400(4714400)
AVSB: pushing segment video video P: 1 A: 4 R: video=4714400 S: 15.36-1.92
ABR: Choosing representation with bandwidth estimation. 4714400 video=4714400
AVSB: receiving order to push data to the SourceBuffer video video P: 1 A: 4 R: video=4714400 S: 17.28-1.92
SF: Segment request ended with success video P: 1 A: 4 R: video=4714400 S: 17.28-1.92
AVSB: receiving order for validating end of segment video video P: 1 A: 4 R: video=4714400 S: 17.28-1.92
SF: Beginning request audio P: 1 A: 1 R: audio_112034_cze=112000 S: 19.217291666666668-1.92
SF: Beginning request video P: 1 A: 4 R: video=4714400 S: 19.2-1.92
AVSB: receiving order to push data to the SourceBuffer audio audio P: 1 A: 1 R: audio_112034_cze=112000 S: 19.217291666666668-1.92
SF: Segment request ended with success audio P: 1 A: 1 R: audio_112034_cze=112000 S: 19.217291666666668-1.92
AVSB: receiving order for validating end of segment audio audio P: 1 A: 1 R: audio_112034_cze=112000 S: 19.217291666666668-1.92
SI: current audio inventory timeline:
0.02|A|11.54
[A] P: 1 || R: audio_112034_cze=112000(112000)
RS: current discontinuity encountered audio 0.017292
SI: current audio inventory timeline:
0.02|A|11.54
[A] P: 1 || R: audio_112034_cze=112000(112000)
AVSB: removing data from SourceBuffer audio 1695883103.065625 1695883104.985624
SI: Segment pushed updates end of previous one video 15.36 17.28 13.44 15.44
AVSB: Acknowledging complete segment video P: 1 A: 4 R: video=4714400 S: 15.36-1.92
SI: calculating buffered end of contiguous segment video 15.36 15.36
SI: found true buffered end video 17.36 17.28
SI: current video inventory timeline:
0.08|A|3.84 ~ 3.84|B|11.52 ~ 11.52|C|17.36
[A] P: 1 || R: video=1444800(1444800)
[B] P: 1 || R: video=3590400(3590400)
[C] P: 1 || R: video=4714400(4714400)
AVSB: pushing segment video video P: 1 A: 4 R: video=4714400 S: 17.28-1.92
ABR: Choosing representation with bandwidth estimation. 4714400 video=4714400
Stream: new video bitrate estimate 9727866.162761051
API: current media element state tick event timeupdate position 0.09766666666666667 seeking true internalSeek 0.09766666666666667 rebuffering false freezing true ended false paused false playbackRate 1 readyState 2
API: current playback timeline:
0.08|==11.46==|11.54
          ^0.09766666666666667 
timeupdate
ABR: Choosing representation with bandwidth estimation. 4714400 video=4714400
SI: current video inventory timeline:
0.08|A|3.84 ~ 3.84|B|11.52 ~ 11.52|C|17.36
[A] P: 1 || R: video=1444800(1444800)
[B] P: 1 || R: video=3590400(3590400)
[C] P: 1 || R: video=4714400(4714400)
RS: current discontinuity encountered video 0.08
SI: current audio inventory timeline:
0.02|A|11.54
[A] P: 1 || R: audio_112034_cze=112000(112000)
RS: current discontinuity encountered audio 0.017292
SI: current audio inventory timeline:
0.02|A|11.54
[A] P: 1 || R: audio_112034_cze=112000(112000)
AVSB: pushing segment audio audio P: 1 A: 1 R: audio_112034_cze=112000 S: 11.537291666666667-1.92
SI: Segment pushed updates end of previous one video 17.28 19.200000000000003 15.36 17.36
AVSB: Acknowledging complete segment video P: 1 A: 4 R: video=4714400 S: 17.28-1.92
SI: calculating buffered end of contiguous segment video 17.28 17.28
SI: found true buffered end video 19.28 19.200000000000003
SI: current video inventory timeline:
0.08|A|3.84 ~ 3.84|B|11.52 ~ 11.52|C|19.28
[A] P: 1 || R: video=1444800(1444800)
[B] P: 1 || R: video=3590400(3590400)
[C] P: 1 || R: video=4714400(4714400)
ABR: Choosing representation with bandwidth estimation. 4714400 video=4714400
AVSB: receiving order to push data to the SourceBuffer video video P: 1 A: 4 R: video=4714400 S: 19.2-1.92
AVSB: pushing segment video video P: 1 A: 4 R: video=4714400 S: 19.2-1.92
SF: Segment request ended with success video P: 1 A: 4 R: video=4714400 S: 19.2-1.92
AVSB: receiving order for validating end of segment video video P: 1 A: 4 R: video=4714400 S: 19.2-1.92
SI: current video inventory timeline:
0.08|A|3.84 ~ 3.84|B|11.52 ~ 11.52|C|19.28
[A] P: 1 || R: video=1444800(1444800)
[B] P: 1 || R: video=3590400(3590400)
[C] P: 1 || R: video=4714400(4714400)
RS: current discontinuity encountered video 0.08
SI: Pushing segment strictly after previous one. audio 11.537291666666667 11.537291
AVSB: Acknowledging complete segment audio P: 1 A: 1 R: audio_112034_cze=112000 S: 11.537291666666667-1.92
SI: found true buffered end audio 13.457291 13.457291666666666
SI: current audio inventory timeline:
0.02|A|13.46
[A] P: 1 || R: audio_112034_cze=112000(112000)
AVSB: removing data from SourceBuffer audio 1695883103.065625 1695883104.985624
SI: Segment pushed updates end of previous one video 19.2 21.119999999999997 17.28 19.28
AVSB: Acknowledging complete segment video P: 1 A: 4 R: video=4714400 S: 19.2-1.92
SI: calculating buffered end of contiguous segment video 19.2 19.2
SI: found true buffered end video 21.2 21.119999999999997
SI: current video inventory timeline:
0.08|A|3.84 ~ 3.84|B|11.52 ~ 11.52|C|21.20
[A] P: 1 || R: video=1444800(1444800)
[B] P: 1 || R: video=3590400(3590400)
[C] P: 1 || R: video=4714400(4714400)
ABR: Choosing representation with bandwidth estimation. 4714400 video=4714400
Stream: new video bitrate estimate 10110946.327150334
SI: current audio inventory timeline:
0.02|A|13.46
[A] P: 1 || R: audio_112034_cze=112000(112000)
AVSB: removing data from SourceBuffer audio 1695883103.065625 1695883104.985624
SI: current audio inventory timeline:
0.02|A|13.46
[A] P: 1 || R: audio_112034_cze=112000(112000)
AVSB: removing data from SourceBuffer audio 1695883103.065625 1695883104.985624
SI: current audio inventory timeline:
0.02|A|13.46
[A] P: 1 || R: audio_112034_cze=112000(112000)
AVSB: removing data from SourceBuffer audio 1695883103.065625 1695883104.985624
SI: current audio inventory timeline:
0.02|A|13.46
[A] P: 1 || R: audio_112034_cze=112000(112000)
AVSB: pushing segment audio audio P: 1 A: 1 R: audio_112034_cze=112000 S: 13.457291666666666-1.92
SI: Pushing segment strictly after previous one. audio 13.457291666666666 13.457291
AVSB: Acknowledging complete segment audio P: 1 A: 1 R: audio_112034_cze=112000 S: 13.457291666666666-1.92
SI: found true buffered end audio 15.377291 15.377291666666666
SI: current audio inventory timeline:
0.02|A|15.38
[A] P: 1 || R: audio_112034_cze=112000(112000)
AVSB: removing data from SourceBuffer audio 1695883103.065625 1695883104.985624
SI: current audio inventory timeline:
0.02|A|15.38
[A] P: 1 || R: audio_112034_cze=112000(112000)
AVSB: pushing segment audio audio P: 1 A: 1 R: audio_112034_cze=112000 S: 15.377291666666666-1.92
SI: Pushing segment strictly after previous one. audio 15.377291666666666 15.377291
AVSB: Acknowledging complete segment audio P: 1 A: 1 R: audio_112034_cze=112000 S: 15.377291666666666-1.92
SI: found true buffered end audio 17.297291 17.297291666666666
SI: current audio inventory timeline:
0.02|A|17.30
[A] P: 1 || R: audio_112034_cze=112000(112000)
AVSB: pushing segment audio audio P: 1 A: 1 R: audio_112034_cze=112000 S: 17.297291666666666-1.92
SI: Pushing segment strictly after previous one. audio 17.297291666666666 17.297291
AVSB: Acknowledging complete segment audio P: 1 A: 1 R: audio_112034_cze=112000 S: 17.297291666666666-1.92
SI: found true buffered end audio 19.217291 19.217291666666668
SI: current audio inventory timeline:
0.02|A|19.22
[A] P: 1 || R: audio_112034_cze=112000(112000)
AVSB: pushing segment audio audio P: 1 A: 1 R: audio_112034_cze=112000 S: 19.217291666666668-1.92
SI: Pushing segment strictly after previous one. audio 19.217291666666668 19.217291
AVSB: Acknowledging complete segment audio P: 1 A: 1 R: audio_112034_cze=112000 S: 19.217291666666668-1.92
SI: found true buffered end audio 21.137291 21.13729166666667
SI: current audio inventory timeline:
0.02|A|21.14
[A] P: 1 || R: audio_112034_cze=112000(112000)
API: current media element state tick event timeupdate position 0.09766666666666667 seeking true internalSeek 0.09766666666666667 rebuffering false freezing true ended false paused false playbackRate 1 readyState 2
API: current playback timeline:
0.08|==21.06==|21.14
          ^0.09766666666666667 
timeupdate
ABR: Choosing representation with bandwidth estimation. 4714400 video=4714400
SI: current video inventory timeline:
0.08|A|3.84 ~ 3.84|B|11.52 ~ 11.52|C|21.20
[A] P: 1 || R: video=1444800(1444800)
[B] P: 1 || R: video=3590400(3590400)
[C] P: 1 || R: video=4714400(4714400)
RS: current discontinuity encountered video 0.08
SI: current audio inventory timeline:
0.02|A|21.14
[A] P: 1 || R: audio_112034_cze=112000(112000)
RS: current discontinuity encountered audio 0.017292
API: current media element state tick event timeupdate position 0.09766666666666667 seeking true internalSeek 0.09766666666666667 rebuffering false freezing true ended false paused false playbackRate 1 readyState 2
API: current playback timeline:
0.08|==21.06==|21.14
          ^0.09766666666666667 
timeupdate
ABR: Choosing representation with bandwidth estimation. 4714400 video=4714400
SI: current video inventory timeline:
0.08|A|3.84 ~ 3.84|B|11.52 ~ 11.52|C|21.20
[A] P: 1 || R: video=1444800(1444800)
[B] P: 1 || R: video=3590400(3590400)
[C] P: 1 || R: video=4714400(4714400)
RS: current discontinuity encountered video 0.08
SI: current audio inventory timeline:
0.02|A|21.14
[A] P: 1 || R: audio_112034_cze=112000(112000)
RS: current discontinuity encountered audio 0.017292
API: current media element state tick event timeupdate position 0.09766666666666667 seeking true internalSeek 0.09766666666666667 rebuffering false freezing true ended false paused false playbackRate 1 readyState 2
API: current playback timeline:
0.08|==21.06==|21.14
          ^0.09766666666666667 
timeupdate
ABR: Choosing representation with bandwidth estimation. 4714400 video=4714400
SI: current video inventory timeline:
0.08|A|3.84 ~ 3.84|B|11.52 ~ 11.52|C|21.20
[A] P: 1 || R: video=1444800(1444800)
[B] P: 1 || R: video=3590400(3590400)
[C] P: 1 || R: video=4714400(4714400)
RS: current discontinuity encountered video 0.08
SI: current audio inventory timeline:
0.02|A|21.14
[A] P: 1 || R: audio_112034_cze=112000(112000)
RS: current discontinuity encountered audio 0.017292
API: current media element state tick event timeupdate position 0.09766666666666667 seeking true internalSeek 0.09766666666666667 rebuffering false freezing true ended false paused false playbackRate 1 readyState 2
API: current playback timeline:
0.08|==21.06==|21.14
          ^0.09766666666666667 
timeupdate
ABR: Choosing representation with bandwidth estimation. 4714400 video=4714400
SI: current video inventory timeline:
0.08|A|3.84 ~ 3.84|B|11.52 ~ 11.52|C|21.20
[A] P: 1 || R: video=1444800(1444800)
[B] P: 1 || R: video=3590400(3590400)
[C] P: 1 || R: video=4714400(4714400)
RS: current discontinuity encountered video 0.08
SI: current audio inventory timeline:
0.02|A|21.14
[A] P: 1 || R: audio_112034_cze=112000(112000)
RS: current discontinuity encountered audio 0.017292
Init: trying to seek to un-freeze player
API: current media element state tick event internal-seeking position 0.09866666666666667 seeking true internalSeek 0.09866666666666667 rebuffering false freezing false ended false paused false playbackRate 1 readyState 2
API: current playback timeline:
0.08|==21.06==|21.14
          ^0.09866666666666667 
seeking
ABR: Choosing representation with bandwidth estimation. 4714400 video=4714400
SI: current video inventory timeline:
0.08|A|3.84 ~ 3.84|B|11.52 ~ 11.52|C|21.20
[A] P: 1 || R: video=1444800(1444800)
[B] P: 1 || R: video=3590400(3590400)
[C] P: 1 || R: video=4714400(4714400)
RS: current discontinuity encountered video 0.08
SI: current audio inventory timeline:
0.02|A|21.14
[A] P: 1 || R: audio_112034_cze=112000(112000)
RS: current discontinuity encountered audio 0.017292
API: playerStateChange event PLAYING
API: current media element state tick event timeupdate position 0.09866666666666667 seeking true internalSeek 0.09866666666666667 rebuffering false freezing true ended false paused false playbackRate 1 readyState 2
API: current playback timeline:
0.08|==21.06==|21.14
          ^0.09866666666666667 
timeupdate
ABR: Choosing representation with bandwidth estimation. 4714400 video=4714400
SI: current video inventory timeline:
0.08|A|3.84 ~ 3.84|B|11.52 ~ 11.52|C|21.20
[A] P: 1 || R: video=1444800(1444800)
[B] P: 1 || R: video=3590400(3590400)
[C] P: 1 || R: video=4714400(4714400)
RS: current discontinuity encountered video 0.08
SI: current audio inventory timeline:
0.02|A|21.14
[A] P: 1 || R: audio_112034_cze=112000(112000)
RS: current discontinuity encountered audio 0.017292
SB: Aborting SegmentBuffer audio
SB: Aborting SegmentBuffer video
2Init: Removing SourceBuffer from mediaSource
Init: Revoking previous URL
Locking `contentLock` to clean-up the current content.
DRM: Clearing-up DRM session.
DRM: closing all current sessions.
DRM-LSS: Closing all current MediaKeySessions 3
DRM: Trying to close a MediaKeySession ksidA41F22AD
DRM: Trying to close a MediaKeySession ksid62FA2693
DRM: Trying to close a MediaKeySession ksid083D81CE
API: playerStateChange event STOPPED
3DRM: Succeeded to close MediaKeySession
API: DRM session cleaned-up with success!
Unlocking `contentLock`. Next content can begin.
@peaBerberian
Copy link
Collaborator

Hi,

Interesting, it seems we have what we call "freezing" situations on those TV.

Basically we have the data in the buffer but it doesn't play. The RxPlayer regularly perform small seeks to flush the buffers (basically emptying them and re-filling them) to force playback to re-start but it doesn't seem to have an effect.

One more curious thing is that the readyState seems to be stuck at the rare (for us) 2 value, so it just have data "for the current frame" (https://developer.mozilla.org/en-US/docs/Web/API/HTMLMediaElement/readyState), I don't know in which situation this would be possible but it may not be actually the true state of media here.

You wrote that the content does play in a loop? With sound?

Maybe the issue is that media does play but the reported media status is completely wrong. We would thus re-seek close to 0 (which we think is the current position) after enough time has passed without playback in the RxPlayer's point of view.

@peijkelhardt
Copy link
Author

You wrote that the content does play in a loop? With sound?

Yes, with sound. It's played in a loop as well.
I also see discontinuities for both video & audio in the logs.

The weird thing is that this appears to occur only on this specific model for Vestel. We have tested MB181, MB211 & MB230 for example, which don't show this issue.

@peaBerberian
Copy link
Collaborator

OK thanks, so I'm relatively confident that this model has a lower-level issue where it's not reporting the correct media information like the currentTime and the readyState. Those are very central for the RxPlayer to work.

currentTime cannot be easily replaced (even by simulating it by assuming an 1x playback when there's buffer - as there are many things which may break this assumption).

The best would probably to report the issue to them.

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Assignees
No one assigned
Labels
None yet
Projects
None yet
Milestone
No milestone
Development

No branches or pull requests
2 participants
@peaBerberian @peijkelhardt