Freeze on content-to-ad transition

Open TomaszKowalik opened this issue 9 months ago • 5 comments

Hi We are testing an ad replacement feature where our third-party library performs on-the-fly transcoding for each ad and inserts it into the manifest. As a result, each ad becomes a separate Period in DASH, meaning ad blocks are composed of multiple individual Periods. We've noticed that on some devices, rxPlayer struggles with the transition from content to the first Period of the ad block. It often freezes for some time - sometimes it eventually transitions and proceeds through the ad Periods correctly, but other times it appears to freeze indefinitely. Interestingly, seeking into the ad block does not cause the freeze, so the issue seems specific to the transition into the first ad (Period). What’s also notable is that this doesn’t happen for every ad block, but when it does, the behavior is consistent for that particular block. I gathered logs for such "endleesly freezed" block from WebOS 4: its 4.3.0 Rx version

API: Calling loadvideo 
API: Initializing MediaSource mode in the main thread
XHR: Sending 
Locking `contentLock` to clean-up the current content.
DRM: Clearing-up DRM session.
API: current media element state tick event init position 0 seeking 0 internalSeek false rebuffering false freezing false ended false paused true playbackRate 1 readyState 0 pendingPosition undefined
API: playerStateChange event LOADING
DRM: closing all current sessions.
DRM-LSS: Closing all current MediaKeySessions 0
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 cached compatible keySystem
DRM: Calling createMediaKeys on the MediaKeySystemAccess
DRM: MediaKeys created with success
DRM: Disabling old MediaKeys
DRM: Disposing of the current MediaKeys
DRM-LSS: Closing all current MediaKeySessions 0
Compat: MediaKeys updated with success
DRM: Waiting for attachment.
Init: Creating MediaSource
MTCI: Attaching MediaSource URL to the media element
Init: MediaSource opened
DRM: disabling MediaKeys attachment lock. Ready for content
DRM: Attaching current MediaKeys
DRM: Attaching MediaKeys to the media element
Compat: MediaKeys updated with success
DRM: MediaKeys attached with success
DRM: Setting server certificate on the MediaKeys
API: current media element state tick event timeupdate position 0 seeking 0 internalSeek false rebuffering false freezing false ended false paused true playbackRate 1 readyState 0 pendingPosition null
API: current playback timeline:
^0 
timeupdate
DASH: WASM MPD Parser not initialized. Running JS one.
MF: Manifest parsed in 135.63499999977648ms
Init: Calculating initial time
Init: using startAt.minimumPosition
Init: Initial time calculated: 761
HTD: Creating HTMLTextDisplayer
API: current media element state tick event manual position 0 seeking 0 internalSeek false rebuffering false freezing false ended false paused true playbackRate 1 readyState 0 pendingPosition 761
API: current playback timeline:
^0 
manual
Init: Resume playback speed 1
Init: Updating duration 1892.158568318685
Stream: Creating new Stream for video 0
TS: Adding Track Reference video 1_1748329378.000000000
Stream: Creating new Stream for audio 0
TS: Adding Track Reference audio 1_1748329378.000000000
Stream: Creating new Stream for text 0
TS: Adding Track Reference text 1_1748329378.000000000
Stream: Updating audio adaptation A: 1 P: 0
SB: Adding native SegmentSink with codec audio/mp4;codecs="mp4a.40.2"
AVSB: calling `mediaSource.addSourceBuffer` audio/mp4;codecs="mp4a.40.2"
Stream: Updating video adaptation A: 3 P: 0
SB: Adding native SegmentSink with codec video/mp4;codecs="avc1.640029"
AVSB: calling `mediaSource.addSourceBuffer` video/mp4;codecs="avc1.640029"
Stream: Set no text Adaptation. P: 0
Stream: full "empty" AdaptationStream text
Stream: Creating new Stream for text 762.060291667
TS: Adding Track Reference text 1_1748330140.009000000
ABR: Creating new BandwidthEstimator for  audio
Stream: changing representation audio audio_112034_cze=112000 112000
Stream: Creating RepresentationStream audio 112000
SQ: Media segments now need to be requested. Starting queue. audio 2
SF: Beginning request audio P: 1_1748329378.000000000 A: 1 R: audio_112034_cze=112000 S: 758.9973333333334-1.92
XHR: Sending GET 
SF: Beginning request audio P: 1_1748329378.000000000 A: 1 R: audio_112034_cze=112000 S: init
XHR: Sending GET 
ABR: Creating new BandwidthEstimator for  video
ABR: Steps for buffer based chooser. bufferLevel: 4, bitrate: 443600 ,bufferLevel: 10.802896272800929, bitrate: 648000 ,bufferLevel: 15.170013884595148, bitrate: 1066800 ,bufferLevel: 19.37290154586124, bitrate: 1444800 ,bufferLevel: 23.79331093099003, bitrate: 2671200 ,bufferLevel: 28.658858230343256, bitrate: 3590400 ,bufferLevel: 31.554458168122714, bitrate: 4714400
ABR: enter starvation mode.
ABR: Choosing representation with bandwidth estimation. 4714400 video=4714400
ABR: Steps for buffer based chooser. bufferLevel: 4, bitrate: 443600 ,bufferLevel: 10.802896272800929, bitrate: 648000 ,bufferLevel: 15.170013884595148, bitrate: 1066800 ,bufferLevel: 19.37290154586124, bitrate: 1444800 ,bufferLevel: 23.79331093099003, bitrate: 2671200 ,bufferLevel: 28.658858230343256, bitrate: 3590400 ,bufferLevel: 31.554458168122714, bitrate: 4714400
ABR: Choosing representation with bandwidth estimation. 4714400 video=4714400
Stream: changing representation video video=4714400 4714400
Stream: Creating RepresentationStream video 4714400
SQ: Media segments now need to be requested. Starting queue. video 2
SF: Beginning request video P: 1_1748329378.000000000 A: 3 R: video=4714400 S: 759-1.92
XHR: Sending GET 
SF: Beginning request video P: 1_1748329378.000000000 A: 3 R: video=4714400 S: init
XHR: Sending GET 
SF: Segment request ended with success audio P: 1_1748329378.000000000 A: 1 R: audio_112034_cze=112000 S: 758.9973333333334-1.92
AVSB: receiving order to push data to the SourceBuffer audio audio P: 1_1748329378.000000000 A: 1 R: audio_112034_cze=112000 S: init
AVSB: pushing segment audio audio P: 1_1748329378.000000000 A: 1 R: audio_112034_cze=112000 S: init
SBI: receiving order to push data to the SourceBuffer audio
SBI: pushing segment audio
SQ: no more init segment to request. Cancelling queue. audio
SF: Segment request cancelled audio P: 1_1748329378.000000000 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_1748329378.000000000 A: 1 R: audio_112034_cze=112000 S: 758.9973333333334-1.92
AVSB: pushing segment audio audio P: 1_1748329378.000000000 A: 1 R: audio_112034_cze=112000 S: 758.9973333333334-1.92
SBI: receiving order to push data to the SourceBuffer audio
SF: Beginning request audio P: 1_1748329378.000000000 A: 1 R: audio_112034_cze=112000 S: 760.9173333333333-1.1306666666666667
XHR: Sending GET 
SF: Segment request ended with success audio P: 1_1748329378.000000000 A: 1 R: audio_112034_cze=112000 S: init
AVSB: receiving order to push data to the SourceBuffer video video P: 1_1748329378.000000000 A: 3 R: video=4714400 S: init
AVSB: pushing segment video video P: 1_1748329378.000000000 A: 3 R: video=4714400 S: init
SBI: receiving order to push data to the SourceBuffer video
SBI: pushing segment video
SQ: no more init segment to request. Cancelling queue. video
SF: Segment request cancelled video P: 1_1748329378.000000000 A: 3 R: video=4714400 S: init
SF: Segment request ended with success video P: 1_1748329378.000000000 A: 3 R: video=4714400 S: init
API: current media element state tick event timeupdate position 0 seeking 0 internalSeek false rebuffering false freezing false ended false paused true playbackRate 1 readyState 0 pendingPosition 761
API: current playback timeline:

^0 
timeupdate
2SI: synchronizing unknown buffered ranges: 
Stream: full "empty" AdaptationStream text
ABR: Choosing representation with bandwidth estimation. 4714400 video=4714400
AVSB: receiving order to push data to the SourceBuffer audio audio P: 1_1748329378.000000000 A: 1 R: audio_112034_cze=112000 S: 760.9173333333333-1.1306666666666667
AVSB: pushing segment audio audio P: 1_1748329378.000000000 A: 1 R: audio_112034_cze=112000 S: 760.9173333333333-1.1306666666666667
SBI: receiving order to push data to the SourceBuffer audio
SF: Segment request ended with success audio P: 1_1748329378.000000000 A: 1 R: audio_112034_cze=112000 S: 760.9173333333333-1.1306666666666667
Stream: Creating new Stream for audio 762.060291667
TS: Adding Track Reference audio 1_1748330140.009000000
AVSB: receiving order to push data to the SourceBuffer video video P: 1_1748329378.000000000 A: 3 R: video=4714400 S: 759-1.92
AVSB: pushing segment video video P: 1_1748329378.000000000 A: 3 R: video=4714400 S: 759-1.92
SBI: receiving order to push data to the SourceBuffer video
SF: Segment request ended with success video P: 1_1748329378.000000000 A: 3 R: video=4714400 S: 759-1.92
ABR: New last stable representation 4714400
SF: Beginning request video P: 1_1748329378.000000000 A: 3 R: video=4714400 S: 760.92-1.12
XHR: Sending GET 
MMSI: Merging 2 segments together for perf audio
SBI: updating timestampOffset audio/mp4;codecs="mp4a.40.2" 0 -1748329377.020625
SBI: setting `appendWindowEnd` 762.160291667
SBI: pushing segment audio
SI: synchronizing unknown buffered ranges: 
SBI: updating timestampOffset video/mp4;codecs="avc1.640029" 0 -1748329377.0083334
SBI: setting `appendWindowEnd` 762.160291667
SBI: pushing segment video
SI: synchronizing unknown buffered ranges: 
ABR: Incrementing blocking raise in BufferBasedChooser due to unstable quality 9000
ABR: Choosing representation with bandwidth estimation. 4714400 video=4714400
Stream: new video bitrate estimate 25472834.621735845
API: current media element state tick event loadedmetadata position 0 seeking 0 internalSeek false rebuffering false freezing false ended false paused true playbackRate 1 readyState 1 pendingPosition 761
API: current playback timeline:
^0 
loadedmetadata
SI: synchronizing unknown buffered ranges: 
SI: synchronizing unknown buffered ranges: 758.997333-762.047999
Stream: full "empty" AdaptationStream text
ABR: Choosing representation with bandwidth estimation. 4714400 video=4714400
API: Seeking internally 761
SI: first segment pushed audio 758.9973333333334 760.9173333333333
SI: synchronizing audio buffered ranges: 758.997333-762.047999
SI: found true buffered start audio 758.997333 758.9973333333334
SI: range end too far from expected end audio 762.047999 760.9173333333333
SI: Pushing segment strictly after previous one. audio 760.9173333333333 760.9173333333333
SI: synchronizing audio buffered ranges: 758.997333-762.047999
SI: found true buffered end audio 762.047999 762.048
SI: current audio inventory timeline:
759.00|A|762.05
[A] P: 1_1748329378.000000000 || R: audio_112034_cze=112000(112000)
API: current media element state tick event internal-seeking position 761 seeking 1 internalSeek true rebuffering true freezing false ended false paused true playbackRate 1 readyState 1 pendingPosition null
API: current playback timeline:
759.00|==0.20==|759.20
                      ^761 
seeking
SI: synchronizing unknown buffered ranges: 759-759.2
SI: synchronizing audio buffered ranges: 758.997333-762.047999
SI: current audio inventory timeline:
759.00|A|762.05
[A] P: 1_1748329378.000000000 || R: audio_112034_cze=112000(112000)
Stream: full "empty" AdaptationStream text
RS: discontinuity encountered at the end of the current period audio 762.047999 762.060291667
ABR: Choosing representation with bandwidth estimation. 4714400 video=4714400
Init: Pause playback to build buffer
AVSB: receiving order to push data to the SourceBuffer video video P: 1_1748329378.000000000 A: 3 R: video=4714400 S: 760.92-1.12
AVSB: pushing segment video video P: 1_1748329378.000000000 A: 3 R: video=4714400 S: 760.92-1.12
SBI: receiving order to push data to the SourceBuffer video
SF: Segment request ended with success video P: 1_1748329378.000000000 A: 3 R: video=4714400 S: 760.92-1.12
Stream: Creating new Stream for video 762.060291667
TS: Adding Track Reference video 1_1748330140.009000000
Stream: Updating audio adaptation A: 1 P: 0
Stream: Reusing a previous SegmentSink for the type audio
Stream: Updating audio adaptation A: 1 P: 762.060291667
Stream: Reusing a previous SegmentSink for the type audio
Stream: Set no text Adaptation. P: 762.060291667
Stream: Updating video adaptation A: 2 P: 762.060291667
Stream: Reusing a previous SegmentSink for the type video
Stream: changing representation audio audio_112034_cze=112000 112000
Stream: Creating RepresentationStream audio 112000
RS: discontinuity encountered at the end of the current period audio 762.047999 762.060291667
Stream: changing representation audio audio_eng=112000 112000
Stream: Creating RepresentationStream audio 112000
SQ: Media segments now need to be requested. Starting queue. audio 10
SF: Beginning request audio P: 1_1748330140.009000000 A: 1 R: audio_eng=112000 S: 762.0602916669999-2.020136054421769
XHR: Sending GET 
SF: Beginning request audio P: 1_1748330140.009000000 A: 1 R: audio_eng=112000 S: init
XHR: Sending GET 
ABR: Steps for buffer based chooser. bufferLevel: 4, bitrate: 642000 ,bufferLevel: 10.652397231778178, bitrate: 880000 ,bufferLevel: 14.752304288996756, bitrate: 1369000 ,bufferLevel: 18.848191176853625, bitrate: 1810000 ,bufferLevel: 23.37358523655493, bitrate: 3240000 ,bufferLevel: 28.408682777792837, bitrate: 4311000 ,bufferLevel: 31.46669683503628, bitrate: 5622000
ABR: enter starvation mode.
ABR: Choosing representation with bandwidth estimation. 5622000 video=5622000
Stream: new video bitrate estimate 25787843.004140638
ABR: Steps for buffer based chooser. bufferLevel: 4, bitrate: 642000 ,bufferLevel: 10.652397231778178, bitrate: 880000 ,bufferLevel: 14.752304288996756, bitrate: 1369000 ,bufferLevel: 18.848191176853625, bitrate: 1810000 ,bufferLevel: 23.37358523655493, bitrate: 3240000 ,bufferLevel: 28.408682777792837, bitrate: 4311000 ,bufferLevel: 31.46669683503628, bitrate: 5622000
ABR: Choosing representation with bandwidth estimation. 5622000 video=5622000
Stream: changing representation video video=5622000 5622000
Stream: Creating RepresentationStream video 5622000
SQ: Media segments now need to be requested. Starting queue. video 5
SF: Beginning request video P: 1_1748330140.009000000 A: 2 R: video=5622000 S: 762.060291667-4
XHR: Sending GET 
SF: Beginning request video P: 1_1748330140.009000000 A: 2 R: video=5622000 S: init
XHR: Sending GET 
API: current media element state tick event ratechange position 761 seeking 1 internalSeek true rebuffering true freezing false ended false paused true playbackRate 0 readyState 1 pendingPosition null
API: current playback timeline:
759.00|==0.52==|759.52
                      ^761 
ratechange
SI: synchronizing unknown buffered ranges: 759-759.52
SI: synchronizing audio buffered ranges: 758.997333-762.047999
SI: current audio inventory timeline:
759.00|A|762.05
[A] P: 1_1748329378.000000000 || R: audio_112034_cze=112000(112000)
Stream: full "empty" AdaptationStream text
ABR: Choosing representation with bandwidth estimation. 4714400 video=4714400
Stream: new video bitrate estimate 25787843.004140638
RS: discontinuity encountered at the end of the current period audio 762.047999 762.060291667
ABR: Choosing representation with bandwidth estimation. 5622000 video=5622000
SF: Segment request ended with success audio P: 1_1748330140.009000000 A: 1 R: audio_eng=112000 S: 762.0602916669999-2.020136054421769
AVSB: receiving order to push data to the SourceBuffer audio audio P: 1_1748330140.009000000 A: 1 R: audio_eng=112000 S: init
AVSB: pushing segment audio audio P: 1_1748330140.009000000 A: 1 R: audio_eng=112000 S: init
SBI: receiving order to push data to the SourceBuffer audio
SBI: updating timestampOffset audio/mp4;codecs="mp4a.40.2" -1748329377.020625 0
SBI: re-setting `appendWindowEnd` to `Infinity`
SBI: pushing segment audio
SQ: no more init segment to request. Cancelling queue. audio
SF: Segment request cancelled audio P: 1_1748330140.009000000 A: 1 R: audio_eng=112000 S: init
ABR: New last stable representation 112000
AVSB: receiving order to push data to the SourceBuffer audio audio P: 1_1748330140.009000000 A: 1 R: audio_eng=112000 S: 762.0602916669999-2.020136054421769
AVSB: pushing segment audio audio P: 1_1748330140.009000000 A: 1 R: audio_eng=112000 S: 762.0602916669999-2.020136054421769
SBI: receiving order to push data to the SourceBuffer audio
SF: Segment request ended with success audio P: 1_1748330140.009000000 A: 1 R: audio_eng=112000 S: init
AVSB: receiving order to push data to the SourceBuffer video video P: 1_1748330140.009000000 A: 2 R: video=5622000 S: init
AVSB: pushing segment video video P: 1_1748330140.009000000 A: 2 R: video=5622000 S: init
SBI: receiving order to push data to the SourceBuffer video
SQ: no more init segment to request. Cancelling queue. video
SF: Segment request cancelled video P: 1_1748330140.009000000 A: 2 R: video=5622000 S: init
SF: Segment request ended with success video P: 1_1748330140.009000000 A: 2 R: video=5622000 S: init
SBI: updating timestampOffset audio/mp4;codecs="mp4a.40.2" 0 762.0602916669999
SBI: setting `appendWindowStart` 761.860291667
SBI: setting `appendWindowEnd` 812.222581916
SBI: pushing segment audio
SI: synchronizing audio buffered ranges: 758.997333-762.047999
SI: current audio inventory timeline:
759.00|A|762.05
[A] P: 1_1748329378.000000000 || R: audio_112034_cze=112000(112000)
AVSB: receiving order to push data to the SourceBuffer video video P: 1_1748330140.009000000 A: 2 R: video=5622000 S: 762.060291667-4
AVSB: pushing segment video video P: 1_1748330140.009000000 A: 2 R: video=5622000 S: 762.060291667-4
SBI: receiving order to push data to the SourceBuffer video
SF: Segment request ended with success video P: 1_1748330140.009000000 A: 2 R: video=5622000 S: 762.060291667-4
ABR: New last stable representation 5622000
SF: Beginning request audio P: 1_1748330140.009000000 A: 1 R: audio_eng=112000 S: 764.0804277214218-1.9969160997732427
XHR: Sending GET 
SI: Pushing segment strictly after previous one. audio 762.0602916669999 762.047999
SI: synchronizing audio buffered ranges: 758.997333-764.080426
SI: found true buffered end audio 764.080426 764.0804277214216
SI: current audio inventory timeline:
759.00|A|762.05 ~ 762.05|B|764.08
[A] P: 1_1748329378.000000000 || R: audio_112034_cze=112000(112000)
[B] P: 1_1748330140.009000000 || R: audio_eng=112000(112000)
SBI: pushing segment video
SI: first segment pushed video 758.9999997965494 760.9199997965494
SI: synchronizing video buffered ranges: 759-760.92
SI: found true buffered start video 759 758.9999997965494
SI: found true buffered end video 760.92 760.9199997965494
ABR: Incrementing blocking raise in BufferBasedChooser due to unstable quality 12000
ABR: Choosing representation with bandwidth estimation. 4714400 video=4714400
Stream: new video bitrate estimate 41206257.69900714
AVSB: receiving order to push data to the SourceBuffer audio audio P: 1_1748330140.009000000 A: 1 R: audio_eng=112000 S: 764.0804277214218-1.9969160997732427
AVSB: pushing segment audio audio P: 1_1748330140.009000000 A: 1 R: audio_eng=112000 S: 764.0804277214218-1.9969160997732427
SBI: receiving order to push data to the SourceBuffer audio
SBI: pushing segment audio
SF: Segment request ended with success audio P: 1_1748330140.009000000 A: 1 R: audio_eng=112000 S: 764.0804277214218-1.9969160997732427
SF: Beginning request video P: 1_1748330140.009000000 A: 2 R: video=5622000 S: 766.060291667-4
XHR: Sending GET 
SF: Beginning request audio P: 1_1748330140.009000000 A: 1 R: audio_eng=112000 S: 766.077343821195-1.9969160997732427
XHR: Sending GET 
SI: Pushing segment strictly after previous one. audio 764.0804277214218 764.0804277214216
SI: synchronizing audio buffered ranges: 758.997333-766.077342
SI: found true buffered end audio 766.077342 766.077343821195
SI: current audio inventory timeline:
759.00|A|762.05 ~ 762.05|B|766.08
[A] P: 1_1748329378.000000000 || R: audio_112034_cze=112000(112000)
[B] P: 1_1748330140.009000000 || R: audio_eng=112000(112000)
AVSB: receiving order to push data to the SourceBuffer audio audio P: 1_1748330140.009000000 A: 1 R: audio_eng=112000 S: 766.077343821195-1.9969160997732427
AVSB: pushing segment audio audio P: 1_1748330140.009000000 A: 1 R: audio_eng=112000 S: 766.077343821195-1.9969160997732427
SBI: receiving order to push data to the SourceBuffer audio
SBI: pushing segment audio
SF: Segment request ended with success audio P: 1_1748330140.009000000 A: 1 R: audio_eng=112000 S: 766.077343821195-1.9969160997732427
SF: Beginning request audio P: 1_1748330140.009000000 A: 1 R: audio_eng=112000 S: 768.0742599209682-1.9969160997732427
XHR: Sending GET
SBI: updating timestampOffset video/mp4;codecs="avc1.640029" -1748329377.0083334 0
SBI: re-setting `appendWindowEnd` to `Infinity`
SBI: pushing segment video
SI: Pushing segment strictly after previous one. video 760.9199997965495 760.9199997965494
SI: synchronizing video buffered ranges: 759-762.04
SI: found true buffered end video 762.04 762.0399997965495
SI: current video inventory timeline:
759.00|A|762.04
[A] P: 1_1748329378.000000000 || R: video=4714400(4714400)
ABR: Incrementing blocking raise in BufferBasedChooser due to unstable quality 15000
ABR: Choosing representation with bandwidth estimation. 4714400 video=4714400
AVSB: receiving order to push data to the SourceBuffer audio audio P: 1_1748330140.009000000 A: 1 R: audio_eng=112000 S: 768.0742599209682-1.9969160997732427
AVSB: pushing segment audio audio P: 1_1748330140.009000000 A: 1 R: audio_eng=112000 S: 768.0742599209682-1.9969160997732427
SBI: receiving order to push data to the SourceBuffer audio
SF: Segment request ended with success audio P: 1_1748330140.009000000 A: 1 R: audio_eng=112000 S: 768.0742599209682-1.9969160997732427
SBI: pushing segment audio
SI: Pushing segment strictly after previous one. audio 766.077343821195 766.077343821195
SI: synchronizing audio buffered ranges: 758.997333-768.074258
SI: found true buffered end audio 768.074258 768.0742599209682
SI: current audio inventory timeline:
759.00|A|762.05 ~ 762.05|B|768.07
[A] P: 1_1748329378.000000000 || R: audio_112034_cze=112000(112000)
[B] P: 1_1748330140.009000000 || R: audio_eng=112000(112000)
AVSB: receiving order to push data to the SourceBuffer video video P: 1_1748330140.009000000 A: 2 R: video=5622000 S: 766.060291667-4
AVSB: pushing segment video video P: 1_1748330140.009000000 A: 2 R: video=5622000 S: 766.060291667-4
SBI: receiving order to push data to the SourceBuffer video
SF: Segment request ended with success video P: 1_1748330140.009000000 A: 2 R: video=5622000 S: 766.060291667-4
SF: Beginning request audio P: 1_1748330140.009000000 A: 1 R: audio_eng=112000 S: 770.0711760207414-1.9969160997732427
XHR: Sending GET 
SF: Beginning request video P: 1_1748330140.009000000 A: 2 R: video=5622000 S: 770.060291667-4
XHR: Sending GET 
MMSI: Merging 2 segments together for perf video
SBI: updating timestampOffset video/mp4;codecs="avc1.640029" 0 762.060291667
SBI: setting `appendWindowStart` 761.860291667
SBI: setting `appendWindowEnd` 812.222581916
SBI: pushing segment video
SI: synchronizing video buffered ranges: 759-762.04
SI: current video inventory timeline:
759.00|A|762.04
[A] P: 1_1748329378.000000000 || R: video=4714400(4714400)
ABR: Incrementing blocking raise in BufferBasedChooser due to unstable quality 9000
ABR: Choosing representation with bandwidth estimation. 5622000 video=5622000
Stream: new video bitrate estimate 47229901.98995867
AVSB: receiving order to push data to the SourceBuffer audio audio P: 1_1748330140.009000000 A: 1 R: audio_eng=112000 S: 770.0711760207414-1.9969160997732427
AVSB: pushing segment audio audio P: 1_1748330140.009000000 A: 1 R: audio_eng=112000 S: 770.0711760207414-1.9969160997732427
SBI: receiving order to push data to the SourceBuffer audio
SF: Segment request ended with success audio P: 1_1748330140.009000000 A: 1 R: audio_eng=112000 S: 770.0711760207414-1.9969160997732427
SF: Beginning request audio P: 1_1748330140.009000000 A: 1 R: audio_eng=112000 S: 772.0680921205146-1.9969160997732427
XHR: Sending GET 
SBI: pushing segment audio
SI: Pushing segment strictly after previous one. audio 768.0742599209682 768.0742599209682
SI: synchronizing audio buffered ranges: 758.997333-770.071174
SI: found true buffered end audio 770.071174 770.0711760207414
SI: current audio inventory timeline:
759.00|A|762.05 ~ 762.05|B|770.07
[A] P: 1_1748329378.000000000 || R: audio_112034_cze=112000(112000)
[B] P: 1_1748330140.009000000 || R: audio_eng=112000(112000)
API: current media element state tick event timeupdate position 761 seeking 1 internalSeek true rebuffering true freezing false ended false paused true playbackRate 0 readyState 1 pendingPosition null
API: current playback timeline:
759.00|==3.04==|762.04
           ^761 
timeupdate
SI: synchronizing video buffered ranges: 759-762.04
SI: current video inventory timeline:
759.00|A|762.04
[A] P: 1_1748329378.000000000 || R: video=4714400(4714400)
SI: synchronizing audio buffered ranges: 758.997333-772.06809
SI: current audio inventory timeline:
759.00|A|762.05 ~ 762.05|B|770.07
[A] P: 1_1748329378.000000000 || R: audio_112034_cze=112000(112000)
[B] P: 1_1748330140.009000000 || R: audio_eng=112000(112000)
Stream: full "empty" AdaptationStream text
ABR: Choosing representation with bandwidth estimation. 4714400 video=4714400
Stream: new video bitrate estimate 47229901.98995867
ABR: Choosing representation with bandwidth estimation. 5622000 video=5622000
AVSB: receiving order to push data to the SourceBuffer audio audio P: 1_1748330140.009000000 A: 1 R: audio_eng=112000 S: 772.0680921205146-1.9969160997732427
AVSB: pushing segment audio audio P: 1_1748330140.009000000 A: 1 R: audio_eng=112000 S: 772.0680921205146-1.9969160997732427
SBI: receiving order to push data to the SourceBuffer audio
SF: Segment request ended with success audio P: 1_1748330140.009000000 A: 1 R: audio_eng=112000 S: 772.0680921205146-1.9969160997732427
SBI: pushing segment audio
SI: Pushing segment strictly after previous one. audio 770.0711760207414 770.0711760207414
SI: synchronizing audio buffered ranges: 758.997333-772.06809
SI: found true buffered end audio 772.06809 772.0680921205146
SI: current audio inventory timeline:
759.00|A|762.05 ~ 762.05|B|772.07
[A] P: 1_1748329378.000000000 || R: audio_112034_cze=112000(112000)
[B] P: 1_1748330140.009000000 || R: audio_eng=112000(112000)
AVSB: receiving order to push data to the SourceBuffer video video P: 1_1748330140.009000000 A: 2 R: video=5622000 S: 770.060291667-4
AVSB: pushing segment video video P: 1_1748330140.009000000 A: 2 R: video=5622000 S: 770.060291667-4
SBI: receiving order to push data to the SourceBuffer video
SF: Segment request ended with success video P: 1_1748330140.009000000 A: 2 R: video=5622000 S: 770.060291667-4
SF: Beginning request audio P: 1_1748330140.009000000 A: 1 R: audio_eng=112000 S: 774.065008220288-1.9969160997732427
XHR: Sending GET 
SF: Beginning request video P: 1_1748330140.009000000 A: 2 R: video=5622000 S: 774.060291667-4
XHR: Sending GET 
SI: Pushing segment strictly after previous one. audio 772.0680921205146 772.0680921205146
SI: synchronizing audio buffered ranges: 758.997333-774.065006
SI: found true buffered end audio 774.065006 774.0650082202878
SI: current audio inventory timeline:
759.00|A|762.05 ~ 762.05|B|774.07
[A] P: 1_1748329378.000000000 || R: audio_112034_cze=112000(112000)
[B] P: 1_1748330140.009000000 || R: audio_eng=112000(112000)
AVSB: receiving order to push data to the SourceBuffer audio audio P: 1_1748330140.009000000 A: 1 R: audio_eng=112000 S: 774.065008220288-1.9969160997732427
AVSB: pushing segment audio audio P: 1_1748330140.009000000 A: 1 R: audio_eng=112000 S: 774.065008220288-1.9969160997732427
SBI: receiving order to push data to the SourceBuffer audio
SBI: pushing segment audio
SF: Segment request ended with success audio P: 1_1748330140.009000000 A: 1 R: audio_eng=112000 S: 774.065008220288-1.9969160997732427
SF: Beginning request audio P: 1_1748330140.009000000 A: 1 R: audio_eng=112000 S: 776.0619243200612-2.020136054421769
XHR: Sending GET 
SI: Pushing segment strictly after previous one. audio 774.065008220288 774.0650082202878
SI: synchronizing audio buffered ranges: 758.997333-776.061922
SI: found true buffered end audio 776.061922 776.0619243200612
SI: current audio inventory timeline:
759.00|A|762.05 ~ 762.05|B|776.06
[A] P: 1_1748329378.000000000 || R: audio_112034_cze=112000(112000)
[B] P: 1_1748330140.009000000 || R: audio_eng=112000(112000)
AVSB: receiving order to push data to the SourceBuffer audio audio P: 1_1748330140.009000000 A: 1 R: audio_eng=112000 S: 776.0619243200612-2.020136054421769
AVSB: pushing segment audio audio P: 1_1748330140.009000000 A: 1 R: audio_eng=112000 S: 776.0619243200612-2.020136054421769
SBI: receiving order to push data to the SourceBuffer audio
SBI: pushing segment audio
SF: Segment request ended with success audio P: 1_1748330140.009000000 A: 1 R: audio_eng=112000 S: 776.0619243200612-2.020136054421769
SF: Beginning request audio P: 1_1748330140.009000000 A: 1 R: audio_eng=112000 S: 778.0820603744829-1.9969160997732427
XHR: Sending GET 
AVSB: receiving order to push data to the SourceBuffer video video P: 1_1748330140.009000000 A: 2 R: video=5622000 S: 774.060291667-4
AVSB: pushing segment video video P: 1_1748330140.009000000 A: 2 R: video=5622000 S: 774.060291667-4
SBI: receiving order to push data to the SourceBuffer video
SF: Segment request ended with success video P: 1_1748330140.009000000 A: 2 R: video=5622000 S: 774.060291667-4
SF: Beginning request video P: 1_1748330140.009000000 A: 2 R: video=5622000 S: 778.060291667-4
XHR: Sending GET 
AVSB: receiving order to push data to the SourceBuffer audio audio P: 1_1748330140.009000000 A: 1 R: audio_eng=112000 S: 778.0820603744829-1.9969160997732427
AVSB: pushing segment audio audio P: 1_1748330140.009000000 A: 1 R: audio_eng=112000 S: 778.0820603744829-1.9969160997732427
SBI: receiving order to push data to the SourceBuffer audio
SF: Segment request ended with success audio P: 1_1748330140.009000000 A: 1 R: audio_eng=112000 S: 778.0820603744829-1.9969160997732427
SBI: pushing segment audio
SI: Pushing segment strictly after previous one. audio 776.0619243200612 776.0619243200612
SI: synchronizing audio buffered ranges: 758.997333-778.082058
SI: found true buffered end audio 778.082058 778.0820603744829
SI: current audio inventory timeline:
759.00|A|762.05 ~ 762.05|B|778.08
[A] P: 1_1748329378.000000000 || R: audio_112034_cze=112000(112000)
[B] P: 1_1748330140.009000000 || R: audio_eng=112000(112000)
SI: Pushing segment strictly after previous one. audio 778.0820603744829 778.0820603744829
SI: synchronizing audio buffered ranges: 758.997333-780.078974
SI: found true buffered end audio 780.078974 780.0789764742561
SI: current audio inventory timeline:
759.00|A|762.05 ~ 762.05|B|780.08
[A] P: 1_1748329378.000000000 || R: audio_112034_cze=112000(112000)
[B] P: 1_1748330140.009000000 || R: audio_eng=112000(112000)
AVSB: receiving order to push data to the SourceBuffer video video P: 1_1748330140.009000000 A: 2 R: video=5622000 S: 778.060291667-4
AVSB: pushing segment video video P: 1_1748330140.009000000 A: 2 R: video=5622000 S: 778.060291667-4
SBI: receiving order to push data to the SourceBuffer video
SF: Segment request ended with success video P: 1_1748330140.009000000 A: 2 R: video=5622000 S: 778.060291667-4
SF: Beginning request audio P: 1_1748330140.009000000 A: 1 R: audio_eng=112000 S: 780.0789764742561-1.9969160997732427
XHR: Sending GET 
AVSB: receiving order to push data to the SourceBuffer audio audio P: 1_1748330140.009000000 A: 1 R: audio_eng=112000 S: 780.0789764742561-1.9969160997732427
AVSB: pushing segment audio audio P: 1_1748330140.009000000 A: 1 R: audio_eng=112000 S: 780.0789764742561-1.9969160997732427
SBI: receiving order to push data to the SourceBuffer audio
SBI: pushing segment audio
SF: Segment request ended with success audio P: 1_1748330140.009000000 A: 1 R: audio_eng=112000 S: 780.0789764742561-1.9969160997732427
SI: Pushing segment strictly after previous one. audio 780.0789764742561 780.0789764742561
SI: synchronizing audio buffered ranges: 758.997333-782.07589
SI: found true buffered end audio 782.07589 782.0758925740294
SI: current audio inventory timeline:
759.00|A|762.05 ~ 762.05|B|782.08
[A] P: 1_1748329378.000000000 || R: audio_112034_cze=112000(112000)
[B] P: 1_1748330140.009000000 || R: audio_eng=112000(112000)
MMSI: Merging 3 segments together for perf video
SBI: pushing segment video
SI: Pushing segment strictly after previous one. video 762.060291667 762.04
SI: synchronizing video buffered ranges: 759-770.060291
SI: range end too far from expected end video 770.060291 766.060291667
SI: current video inventory timeline:
759.00|A|762.04 ~ 762.04|B|766.06
[A] P: 1_1748329378.000000000 || R: video=4714400(4714400)
[B] P: 1_1748330140.009000000 || R: video=5622000(5622000)
SI: Pushing segment strictly after previous one. video 766.060291667 766.060291667
SI: synchronizing video buffered ranges: 759-770.060291
SI: found true buffered end video 770.060291 770.060291667
SI: current video inventory timeline:
759.00|A|762.04 ~ 762.04|B|770.06
[A] P: 1_1748329378.000000000 || R: video=4714400(4714400)
[B] P: 1_1748330140.009000000 || R: video=5622000(5622000)
ABR: Incrementing blocking raise in BufferBasedChooser due to unstable quality 12000
ABR: Choosing representation with bandwidth estimation. 5622000 video=5622000
Stream: new video bitrate estimate 61948585.21744582
ABR: Incrementing blocking raise in BufferBasedChooser due to unstable quality 15000
ABR: Choosing representation with bandwidth estimation. 5622000 video=5622000
API: current media element state tick event timeupdate position 761 seeking 1 internalSeek true rebuffering true freezing false ended false paused true playbackRate 0 readyState 1 pendingPosition null
API: current playback timeline:
759.00|==11.10==|770.10
            ^761 
timeupdate
SI: synchronizing video buffered ranges: 759-770.100291
SI: current video inventory timeline:
759.00|A|762.04 ~ 762.04|B|770.10
[A] P: 1_1748329378.000000000 || R: video=4714400(4714400)
[B] P: 1_1748330140.009000000 || R: video=5622000(5622000)
SI: synchronizing audio buffered ranges: 758.997333-782.07589
SI: current audio inventory timeline:
759.00|A|762.05 ~ 762.05|B|782.08
[A] P: 1_1748329378.000000000 || R: audio_112034_cze=112000(112000)
[B] P: 1_1748330140.009000000 || R: audio_eng=112000(112000)
FR: Freeze detected 2680840.3550000004 2374.2599999997765
Stream: full "empty" AdaptationStream text
ABR: exit starvation mode.
ABR: Choosing representation with bandwidth estimation. 4714400 video=4714400
Stream: new video bitrate estimate 61948585.21744582
ABR: exit starvation mode.
ABR: Choosing representation with bandwidth estimation. 5622000 video=5622000
SI: Segment pushed updates end of previous one video 770.060291667 774.060291667 766.060291667 770.100291
SI: synchronizing video buffered ranges: 759-782.060291
SI: calculating buffered end of contiguous segment video 770.060291667 770.060291667
SI: range end too far from expected end video 782.060291 774.060291667
SI: current video inventory timeline:
759.00|A|762.04 ~ 762.04|B|774.06
[A] P: 1_1748329378.000000000 || R: video=4714400(4714400)
[B] P: 1_1748330140.009000000 || R: video=5622000(5622000)
SI: Pushing segment strictly after previous one. video 774.060291667 774.060291667
SI: synchronizing video buffered ranges: 759-782.060291
SI: range end too far from expected end video 782.060291 778.060291667
SI: current video inventory timeline:
759.00|A|762.04 ~ 762.04|B|778.06
[A] P: 1_1748329378.000000000 || R: video=4714400(4714400)
[B] P: 1_1748330140.009000000 || R: video=5622000(5622000)
SI: Pushing segment strictly after previous one. video 778.060291667 778.060291667
SI: synchronizing video buffered ranges: 759-782.060291
SI: found true buffered end video 782.060291 782.060291667
SI: current video inventory timeline:
759.00|A|762.04 ~ 762.04|B|782.06
[A] P: 1_1748329378.000000000 || R: video=4714400(4714400)
[B] P: 1_1748330140.009000000 || R: video=5622000(5622000)
ABR: Incrementing blocking raise in BufferBasedChooser due to unstable quality 15000
ABR: Choosing representation with bandwidth estimation. 5622000 video=5622000
ABR: Incrementing blocking raise in BufferBasedChooser due to unstable quality 15000
ABR: Choosing representation with bandwidth estimation. 5622000 video=5622000
ABR: Incrementing blocking raise in BufferBasedChooser due to unstable quality 15000
ABR: Choosing representation with bandwidth estimation. 5622000 video=5622000
API: current media element state tick event timeupdate position 761 seeking 1 internalSeek true rebuffering true freezing false ended false paused true playbackRate 0 readyState 1 pendingPosition null
API: current playback timeline:
759.00|==23.06==|782.06
            ^761 
timeupdate
SI: synchronizing video buffered ranges: 759-782.060291
SI: current video inventory timeline:
759.00|A|762.04 ~ 762.04|B|782.06
[A] P: 1_1748329378.000000000 || R: video=4714400(4714400)
[B] P: 1_1748330140.009000000 || R: video=5622000(5622000)
SI: synchronizing audio buffered ranges: 758.997333-782.07589
SI: current audio inventory timeline:
759.00|A|762.05 ~ 762.05|B|782.08
[A] P: 1_1748329378.000000000 || R: audio_112034_cze=112000(112000)
[B] P: 1_1748330140.009000000 || R: audio_eng=112000(112000)
FR: Freeze detected 2680840.3550000004 4217.379999999888
FR: Start of a potential decipherability freeze detected
Stream: full "empty" AdaptationStream text
ABR: Choosing representation with bandwidth estimation. 4714400 video=4714400
ABR: Choosing representation with bandwidth estimation. 5622000 video=5622000
API: current media element state tick event timeupdate position 761 seeking 1 internalSeek true rebuffering true freezing false ended false paused true playbackRate 0 readyState 1 pendingPosition null
API: current playback timeline:
759.00|==23.06==|782.06
            ^761 
timeupdate
SI: synchronizing video buffered ranges: 759-782.060291
SI: current video inventory timeline:
759.00|A|762.04 ~ 762.04|B|782.06
[A] P: 1_1748329378.000000000 || R: video=4714400(4714400)
[B] P: 1_1748330140.009000000 || R: video=5622000(5622000)
SI: synchronizing audio buffered ranges: 758.997333-782.07589
SI: current audio inventory timeline:
759.00|A|762.05 ~ 762.05|B|782.08
[A] P: 1_1748329378.000000000 || R: audio_112034_cze=112000(112000)
[B] P: 1_1748330140.009000000 || R: audio_eng=112000(112000)
FR: Freeze detected 2680840.3550000004 4309.354999999516
Stream: full "empty" AdaptationStream text
ABR: Choosing representation with bandwidth estimation. 4714400 video=4714400
ABR: Choosing representation with bandwidth estimation. 5622000 video=5622000
API: current media element state tick event timeupdate position 761 seeking 1 internalSeek true rebuffering true freezing false ended false paused true playbackRate 0 readyState 1 pendingPosition null
API: current playback timeline:
759.00|==23.06==|782.06
            ^761 
timeupdate
SI: synchronizing video buffered ranges: 759-782.060291
SI: current video inventory timeline:
759.00|A|762.04 ~ 762.04|B|782.06
[A] P: 1_1748329378.000000000 || R: video=4714400(4714400)
[B] P: 1_1748330140.009000000 || R: video=5622000(5622000)
SI: synchronizing audio buffered ranges: 758.997333-782.07589
SI: current audio inventory timeline:
759.00|A|762.05 ~ 762.05|B|782.08
[A] P: 1_1748329378.000000000 || R: audio_112034_cze=112000(112000)
[B] P: 1_1748330140.009000000 || R: audio_eng=112000(112000)
FR: Freeze detected 2680840.3550000004 5309.114999999758
Stream: full "empty" AdaptationStream text
ABR: Choosing representation with bandwidth estimation. 4714400 video=4714400
ABR: Choosing representation with bandwidth estimation. 5622000 video=5622000
API: current media element state tick event timeupdate position 761 seeking 1 internalSeek true rebuffering true freezing false ended false paused true playbackRate 0 readyState 1 pendingPosition null
API: current playback timeline:
759.00|==23.06==|782.06
            ^761 
timeupdate
SI: synchronizing video buffered ranges: 759-782.060291
SI: current video inventory timeline:
759.00|A|762.04 ~ 762.04|B|782.06
[A] P: 1_1748329378.000000000 || R: video=4714400(4714400)
[B] P: 1_1748330140.009000000 || R: video=5622000(5622000)
SI: synchronizing audio buffered ranges: 758.997333-782.07589
SI: current audio inventory timeline:
759.00|A|762.05 ~ 762.05|B|782.08
[A] P: 1_1748329378.000000000 || R: audio_112034_cze=112000(112000)
[B] P: 1_1748330140.009000000 || R: audio_eng=112000(112000)
FR: Freeze detected 2680840.3550000004 6310.549999999814
FR: trying to flush to un-freeze
Init: Flushing buffer due to freeze

May 27 '25 10:05 TomaszKowalik

Hi @TomaszKowalik,

From what I see here in this particular case, there is a "freeze" as in: the readyState is set to 1 despite having only decipherable data in the buffer.

What's relatively weird is that it happens a little more than 1s before the Period switch, so it's still normally decoding the first Period. But there's a high probability that it's due to the incoming one.

What's the encryption status here: are any of those Periods encrypted? I don't see any license-fetching or init-data-communication going on so it's as if none of the content is encrypted yet - but the readyState to 1 is often an indicator of not having the decryption key.

Also the last two logs:

FR: trying to flush to un-freeze
Init: Flushing buffer due to freeze

Seems to indicate that we just tried, at the moment you captured the logs, to un-freeze playback by performing a small seek. I'm guessing it didn't have an effect?

Note that you can also add timestamp information to the logs now by doing:

RxPlayer.LogFormat = "full"

Here this helps us seeing how much time we were in which situation for example.

Jun 24 '25 11:06 peaBerberian

Hi, That behavior really varies between devices. On that particular device, when I was able to reproduce it, the stream usually didn’t unfreeze, although a few times it managed to recover after a longer period of time. Today, on a different device (where the bug was also reported by QA), there is an observable freeze when changing periods, but it lasts only a few seconds. Audio is audible, but the video is black, and after that, the video also starts playing. I'm attaching logs for that event. I’ve heard that such issues are not unique to smart TVs, as they can also be observed in the web app. These periods are not encrypted

09:06:22.001:Rx player state changed to SEEKING
[Violation] 'seeking' handler took 236ms
79760.20 [log] SF: Segment request aborted video P: 1_1750921539.000000000 A: 3 R: video=4714400 S: 504.96-1.92
79812.10 [log] TF: Thumbnail request ended with success 576
79828.40 [log] SI: synchronizing video buffered ranges: 482-505.04
79831.20 [log] SI: current video inventory timeline:
482.00|A|505.04
[A] P: 1_1750921539.000000000 || R: video=4714400(4714400)
79833.50 [log] SI: synchronizing audio buffered ranges: 480.213333-504.96
79836.00 [log] SI: current audio inventory timeline:
480.21|A|504.96
[A] P: 1_1750921539.000000000 || R: audio_112034_cze=112000(112000)
79838.80 [log] Stream: full "empty" AdaptationStream text
79841.10 [log] GC: cleaning range from SegmentSink 480.213333 504.96
79843.80 [log] AVSB: receiving order to remove data from the SourceBuffer audio 480.213333 504.96
79847.00 [log] SBI: receiving order to remove data from the SourceBuffer audio 480.213333 504.96
79855.00 [log] GC: cleaning range from SegmentSink 482 505.04
79857.50 [log] AVSB: receiving order to remove data from the SourceBuffer video 482 505.04
79860.40 [log] SBI: receiving order to remove data from the SourceBuffer video 482 505.04
79867.30 [log] ABR: Choosing representation with bandwidth estimation. 4714400 video=4714400
79924.30 [log] TF: Thumbnail request ended with success 588
79963.90 [log] API: current media element state tick event ratechange position 609.289 seeking 2 internalSeek false rebuffering true freezing false ended false paused false playbackRate 1 readyState 1 pendingPosition null
79966.40 [log] API: current playback timeline:

^609.289 
ratechange
79968.70 [log] SI: synchronizing video buffered ranges: 
79971.40 [log] SI: A segment at the end has been completely GCed video 482-483.84
79973.90 [log] SI: A segment at the end has been completely GCed video 483.84-485.76
79976.20 [log] SI: A segment at the end has been completely GCed video 485.76-487.68
79978.50 [log] SI: A segment at the end has been completely GCed video 487.68-489.6
79980.70 [log] SI: A segment at the end has been completely GCed video 489.6-491.52
79982.90 [log] SI: A segment at the end has been completely GCed video 491.52-493.44
79985.10 [log] SI: A segment at the end has been completely GCed video 493.44-495.36
79987.40 [log] SI: A segment at the end has been completely GCed video 495.36-497.28
79989.40 [log] SI: A segment at the end has been completely GCed video 497.28-499.2
79991.80 [log] SI: A segment at the end has been completely GCed video 499.2-501.12
79994.20 [log] SI: A segment at the end has been completely GCed video 501.12-503.04
79996.30 [log] SI: A segment at the end has been completely GCed video 503.04-505.04
79998.60 [log] SI: current video inventory timeline:

80000.90 [log] SI: synchronizing audio buffered ranges: 480.213333-506.88
80003.70 [log] SI: current audio inventory timeline:
480.21|A|504.96
[A] P: 1_1750921539.000000000 || R: audio_112034_cze=112000(112000)
80006.60 [log] Stream: full "empty" AdaptationStream text
80009.00 [log] GC: cleaning range from SegmentSink 480.213333 506.88
80011.80 [log] AVSB: receiving order to remove data from the SourceBuffer audio 480.213333 506.88
80014.60 [log] SBI: receiving order to remove data from the SourceBuffer audio 480.213333 506.88
80024.40 [log] ABR: Choosing representation with bandwidth estimation. 4714400 video=4714400
80056.90 [log] SBI: removing data from SourceBuffer audio 480.213333 504.96
80059.70 [log] SI: Pushing segment strictly after previous one. audio 504.96 504.96
80061.60 [log] SI: synchronizing audio buffered ranges: 480.213333-506.88
80063.00 [log] SI: found true buffered end audio 506.88 506.88
80064.60 [log] SI: current audio inventory timeline:
480.21|A|506.88
[A] P: 1_1750921539.000000000 || R: audio_112034_cze=112000(112000)
80068.50 [log] API: current media element state tick event ratechange position 609.289 seeking 2 internalSeek false rebuffering true freezing false ended false paused false playbackRate 0 readyState 1 pendingPosition null
80072.60 [log] API: current playback timeline:

^609.289 
ratechange
80075.00 [log] SI: synchronizing unknown buffered ranges: 
80083.10 [log] SI: synchronizing audio buffered ranges: 480.213333-506.88
80087.80 [log] SI: current audio inventory timeline:
480.21|A|506.88
[A] P: 1_1750921539.000000000 || R: audio_112034_cze=112000(112000)
80092.30 [log] Stream: full "empty" AdaptationStream text
80097.30 [log] GC: cleaning range from SegmentSink 480.213333 506.88
80101.10 [log] AVSB: receiving order to remove data from the SourceBuffer audio 480.213333 506.88
80105.10 [log] SBI: receiving order to remove data from the SourceBuffer audio 480.213333 506.88
80119.40 [log] ABR: Choosing representation with bandwidth estimation. 4714400 video=4714400
80133.70 [log] SBI: removing data from SourceBuffer video 482 505.04
80138.50 [log] SI: synchronizing unknown buffered ranges: 
80172.50 [log] AVSB: receiving order to push data to the SourceBuffer audio audio P: 1_1750921539.000000000 A: 1 R: audio_112034_cze=112000 S: 608.448-0.96
80176.30 [log] AVSB: pushing segment audio audio P: 1_1750921539.000000000 A: 1 R: audio_112034_cze=112000 S: 608.448-0.96
80178.90 [log] SBI: receiving order to push data to the SourceBuffer audio
80184.50 [log] SF: Segment request ended with success audio P: 1_1750921539.000000000 A: 1 R: audio_112034_cze=112000 S: 608.448-0.96
80188.30 [info] Stream: Creating new Stream for audio 609.409104167
80191.80 [log] TS: Adding Track Reference audio 1_1750922148.289000000
80218.90 [log] API: current media element state tick event ratechange position 609.289 seeking 2 internalSeek false rebuffering true freezing false ended false paused false playbackRate 0 readyState 1 pendingPosition null
80220.70 [log] API: current playback timeline:

^609.289 
ratechange
80222.80 [log] SI: synchronizing unknown buffered ranges: 
80224.80 [log] SI: synchronizing audio buffered ranges: 504.96-506.88
80227.00 [log] SI: 13 segments GCed. audio
80230.40 [log] Stream: full "empty" AdaptationStream text
80232.40 [log] GC: cleaning range from SegmentSink 504.96 506.88
80235.00 [log] AVSB: receiving order to remove data from the SourceBuffer audio 504.96 506.88
80237.60 [log] SBI: receiving order to remove data from the SourceBuffer audio 504.96 506.88
80244.70 [log] ABR: Choosing representation with bandwidth estimation. 4714400 video=4714400
80264.70 [log] SBI: removing data from SourceBuffer audio 480.213333 504.96
80267.00 [log] SI: synchronizing audio buffered ranges: 504.96-506.88
80286.80 [log] SI: synchronizing unknown buffered ranges: 
80324.00 [log] AVSB: receiving order to push data to the SourceBuffer video video P: 1_1750921539.000000000 A: 3 R: video=4714400 S: 608.44-0.96
80327.40 [log] AVSB: pushing segment video video P: 1_1750921539.000000000 A: 3 R: video=4714400 S: 608.44-0.96
80332.60 [log] SBI: receiving order to push data to the SourceBuffer video
80337.20 [log] SBI: pushing segment video
80350.70 [log] SF: Segment request ended with success video P: 1_1750921539.000000000 A: 3 R: video=4714400 S: 608.44-0.96
80356.00 [info] Stream: Creating new Stream for video 609.409104167
80359.20 [log] TS: Adding Track Reference video 1_1750922148.289000000
80384.10 [info] Stream: Updating audio adaptation A: 1 P: 0
80389.50 [info] Stream: Reusing a previous SegmentSink for the type audio
80401.10 [info] Stream: Updating audio adaptation A: 1 P: 609.409104167
80404.50 [info] Stream: Reusing a previous SegmentSink for the type audio
80412.80 [info] Stream: Set no text Adaptation. P: 609.409104167
80420.90 [info] Stream: Updating video adaptation A: 2 P: 609.409104167
80422.70 [info] Stream: Reusing a previous SegmentSink for the type video
80430.50 [log] GC: cleaning range from SegmentSink 504.96 506.88
80432.60 [log] AVSB: receiving order to remove data from the SourceBuffer audio 504.96 506.88
80435.10 [log] SBI: receiving order to remove data from the SourceBuffer audio 504.96 506.88
80444.70 [log] GC: cleaning range from SegmentSink 504.96 506.88
80446.40 [log] AVSB: receiving order to remove data from the SourceBuffer audio 504.96 506.88
80448.30 [log] SBI: receiving order to remove data from the SourceBuffer audio 504.96 506.88
80474.90 [info] Stream: changing representation audio audio_112034_cze=112000 112000
80479.10 [log] Stream: Creating RepresentationStream audio 112000
80486.10 [log] GC: cleaning range from SegmentSink 504.96 506.88
80488.00 [log] AVSB: receiving order to remove data from the SourceBuffer audio 504.96 506.88
80491.40 [log] SBI: receiving order to remove data from the SourceBuffer audio 504.96 506.88
80496.90 [log] GC: cleaning range from SegmentSink 504.96 506.88
80499.10 [log] AVSB: receiving order to remove data from the SourceBuffer audio 504.96 506.88
80501.10 [log] SBI: receiving order to remove data from the SourceBuffer audio 504.96 506.88
80507.30 [info] Stream: changing representation audio audio_eng=112000 112000
80509.70 [log] Stream: Creating RepresentationStream audio 112000
80518.40 [log] SQ: Media segments now need to be requested. Starting queue. audio 10
80520.80 [log] SF: Beginning request audio P: 1_1750922148.289000000 A: 1 R: audio_eng=112000 S: 609.409104167-2.020136054421769
80532.10 [log] XHR: Sending GET  type=arraybuffer to=30 cto=15
80547.30 [log] SF: Beginning request audio P: 1_1750922148.289000000 A: 1 R: audio_eng=112000 S: init
80562.60 [log] XHR: Sending GET type=arraybuffer to=30 cto=15
80588.10 [log] ABR: Steps for buffer based chooser. bufferLevel: 4, bitrate: 642000 ,bufferLevel: 10.652397231778178, bitrate: 880000 ,bufferLevel: 14.752304288996756, bitrate: 1369000 ,bufferLevel: 18.848191176853625, bitrate: 1810000 ,bufferLevel: 23.37358523655493, bitrate: 3240000 ,bufferLevel: 28.408682777792837, bitrate: 4311000 ,bufferLevel: 31.46669683503628, bitrate: 5622000
80594.30 [info] ABR: enter starvation mode.
80596.00 [log] ABR: Choosing representation with bandwidth estimation. 5622000 video=5622000
80598.10 [log] Stream: new video bitrate estimate 20320169.522891782
80601.00 [log] ABR: Steps for buffer based chooser. bufferLevel: 4, bitrate: 642000 ,bufferLevel: 10.652397231778178, bitrate: 880000 ,bufferLevel: 14.752304288996756, bitrate: 1369000 ,bufferLevel: 18.848191176853625, bitrate: 1810000 ,bufferLevel: 23.37358523655493, bitrate: 3240000 ,bufferLevel: 28.408682777792837, bitrate: 4311000 ,bufferLevel: 31.46669683503628, bitrate: 5622000
80603.10 [log] ABR: Choosing representation with bandwidth estimation. 5622000 video=5622000
80606.20 [info] Stream: changing representation video video=5622000 5622000
80608.40 [log] Stream: Creating RepresentationStream video 5622000
80614.80 [log] SQ: Media segments now need to be requested. Starting queue. video 5
80617.40 [log] SF: Beginning request video P: 1_1750922148.289000000 A: 2 R: video=5622000 S: 609.409104167-4
80626.90 [log] XHR: Sending GET type=arraybuffer to=30 cto=15
80641.80 [log] SF: Beginning request video P: 1_1750922148.289000000 A: 2 R: video=5622000 S: init
80656.30 [log] XHR: Sending GET  type=arraybuffer to=30 cto=15
80685.00 [log] SBI: removing data from SourceBuffer audio 480.213333 506.88
80686.50 [log] SI: synchronizing audio buffered ranges: 504.96-506.88
80706.70 [log] SBI: removing data from SourceBuffer audio 480.213333 506.88
80708.20 [log] SI: synchronizing audio buffered ranges: 
80711.30 [log] SI: current audio inventory timeline:
504.96|A|506.88
[A] P: 1_1750921539.000000000 || R: audio_112034_cze=112000(112000)
80735.90 [log] SBI: pushing segment audio
80737.70 [log] SI: synchronizing audio buffered ranges: 
80739.80 [log] SI: current audio inventory timeline:
504.96|A|506.88
[A] P: 1_1750921539.000000000 || R: audio_112034_cze=112000(112000)
80742.00 [log] SI: first segment pushed video 608.44 609.4000000000001
80743.40 [log] SI: synchronizing video buffered ranges: 608.52-609.48
80744.60 [log] SI: found true buffered start video 608.52 608.44
80745.80 [log] SI: found true buffered end video 609.48 609.4000000000001
80747.90 [log] ABR: Incrementing blocking raise in BufferBasedChooser due to unstable quality 15000
80750.50 [log] ABR: Choosing representation with bandwidth estimation. 4714400 video=4714400
80752.00 [log] Stream: new video bitrate estimate 20320169.522891782
80762.50 [log] SBI: removing data from SourceBuffer audio 504.96 506.88
80766.00 [log] SI: Pushing segment strictly after previous one. audio 608.448 506.88
80768.10 [log] SI: synchronizing audio buffered ranges: 608.448-609.408
80772.80 [log] SI: 1 segments GCed. audio
80775.40 [log] SI: found true buffered start audio 608.448 608.448
80778.20 [log] SI: found true buffered end audio 609.408 609.408
80781.20 [log] SBI: removing data from SourceBuffer audio 504.96 506.88
80783.10 [log] SI: synchronizing audio buffered ranges: 608.448-609.408
80799.10 [log] AVSB: receiving order to push data to the SourceBuffer audio audio P: 1_1750922148.289000000 A: 1 R: audio_eng=112000 S: init
80802.30 [log] AVSB: pushing segment audio audio P: 1_1750922148.289000000 A: 1 R: audio_eng=112000 S: init
80806.30 [log] SBI: receiving order to push data to the SourceBuffer audio
80816.50 [log] SQ: no more init segment to request. Cancelling queue. audio
80825.50 [log] SF: Segment request cancelled audio P: 1_1750922148.289000000 A: 1 R: audio_eng=112000 S: init
80833.40 [log] SF: Segment request ended with success audio P: 1_1750922148.289000000 A: 1 R: audio_eng=112000 S: init
80846.80 [log] SBI: removing data from SourceBuffer audio 504.96 506.88
80852.50 [log] SI: synchronizing audio buffered ranges: 608.448-609.408
80862.10 [log] AVSB: receiving order to push data to the SourceBuffer audio audio P: 1_1750922148.289000000 A: 1 R: audio_eng=112000 S: 609.409104167-2.020136054421769
80866.40 [log] AVSB: pushing segment audio audio P: 1_1750922148.289000000 A: 1 R: audio_eng=112000 S: 609.409104167-2.020136054421769
80872.60 [log] SBI: receiving order to push data to the SourceBuffer audio
80883.20 [log] SF: Segment request ended with success audio P: 1_1750922148.289000000 A: 1 R: audio_eng=112000 S: 609.409104167-2.020136054421769
80893.40 [log] ABR: New last stable representation 112000
80925.00 [log] AVSB: receiving order to push data to the SourceBuffer video video P: 1_1750922148.289000000 A: 2 R: video=5622000 S: init
80933.00 [log] AVSB: pushing segment video video P: 1_1750922148.289000000 A: 2 R: video=5622000 S: init
80938.70 [log] SBI: receiving order to push data to the SourceBuffer video
80943.50 [log] SBI: updating timestampOffset video/mp4;codecs="avc1.640029" -1750921538.8883333 0
80946.40 [log] SBI: re-setting `appendWindowEnd` to `Infinity`
80948.80 [log] SBI: pushing segment video
80959.70 [log] SQ: no more init segment to request. Cancelling queue. video
80967.20 [log] SF: Segment request cancelled video P: 1_1750922148.289000000 A: 2 R: video=5622000 S: init
80975.60 [log] SF: Segment request ended with success video P: 1_1750922148.289000000 A: 2 R: video=5622000 S: init
80983.60 [log] SBI: removing data from SourceBuffer audio 504.96 506.88
80985.20 [log] SI: synchronizing audio buffered ranges: 608.448-609.408
80988.90 [log] SI: synchronizing video buffered ranges: 608.52-609.48
80992.00 [log] ABR: Incrementing blocking raise in BufferBasedChooser due to unstable quality 9000
80994.70 [log] ABR: Choosing representation with bandwidth estimation. 5622000 video=5622000
81001.20 [log] SBI: removing data from SourceBuffer audio 504.96 506.88
81003.80 [log] SI: synchronizing audio buffered ranges: 608.448-609.408
81015.60 [log] SBI: updating timestampOffset audio/mp4;codecs="mp4a.40.2" -1750921538.8894374 0
81018.20 [log] SBI: re-setting `appendWindowEnd` to `Infinity`
81020.20 [log] SBI: pushing segment audio
81024.00 [log] SI: synchronizing audio buffered ranges: 608.448-609.408
81035.00 [log] SBI: updating timestampOffset audio/mp4;codecs="mp4a.40.2" 0 609.409104167
81036.60 [log] SBI: setting `appendWindowStart` 609.209104167
81038.50 [log] SBI: setting `appendWindowEnd` 659.5713944160001
81040.30 [log] SBI: pushing segment audio
81043.20 [log] SI: synchronizing audio buffered ranges: 608.448-609.408
81065.50 [log] SI: Pushing segment strictly after previous one. audio 609.409104167 609.408
81067.70 [log] SI: synchronizing audio buffered ranges: 608.448-611.392957
81073.60 [log] SI: found true buffered end audio 611.392957 611.4292402214218
81079.60 [log] SI: current audio inventory timeline:
608.45|A|609.41 ~ 609.41|B|611.43
[A] P: 1_1750921539.000000000 || R: audio_112034_cze=112000(112000)
[B] P: 1_1750922148.289000000 || R: audio_eng=112000(112000)
81146.30 [log] AVSB: receiving order to push data to the SourceBuffer video video P: 1_1750922148.289000000 A: 2 R: video=5622000 S: 609.409104167-4
81148.50 [log] AVSB: pushing segment video video P: 1_1750922148.289000000 A: 2 R: video=5622000 S: 609.409104167-4
81151.50 [log] SBI: receiving order to push data to the SourceBuffer video
81154.50 [log] SBI: updating timestampOffset video/mp4;codecs="avc1.640029" 0 609.409104167
81157.20 [log] SBI: setting `appendWindowStart` 609.209104167
81160.10 [log] SBI: setting `appendWindowEnd` 659.5713944160001
81162.80 [log] SBI: pushing segment video
81177.10 [log] SF: Segment request ended with success video P: 1_1750922148.289000000 A: 2 R: video=5622000 S: 609.409104167-4
81180.00 [log] ABR: New last stable representation 5622000
81182.20 [log] SF: Beginning request audio P: 1_1750922148.289000000 A: 1 R: audio_eng=112000 S: 611.4292402214218-1.9969160997732427
81188.60 [log] XHR: Sending GET type=arraybuffer to=30 cto=15
81262.20 [log] API: current media element state tick event timeupdate position 609.289 seeking 2 internalSeek false rebuffering true freezing false ended false paused false playbackRate 0 readyState 1 pendingPosition null
81263.70 [log] API: current playback timeline:
608.52|==0.96==|609.48
           ^609.289 
timeupdate
81265.30 [log] SI: synchronizing video buffered ranges: 608.52-609.48
81266.90 [log] SI: synchronizing audio buffered ranges: 608.448-611.392957
81268.40 [log] SI: current audio inventory timeline:
608.45|A|609.41 ~ 609.41|B|611.39
[A] P: 1_1750921539.000000000 || R: audio_112034_cze=112000(112000)
[B] P: 1_1750922148.289000000 || R: audio_eng=112000(112000)
81270.60 [log] Stream: full "empty" AdaptationStream text
81273.10 [log] ABR: Choosing representation with bandwidth estimation. 4714400 video=4714400
81275.40 [log] Stream: new video bitrate estimate 21877710.527699832
81286.00 [log] ABR: Choosing representation with bandwidth estimation. 5622000 video=5622000
81291.20 [log] Stream: new video bitrate estimate 21877710.527699832
81348.10 [log] AVSB: receiving order to push data to the SourceBuffer audio audio P: 1_1750922148.289000000 A: 1 R: audio_eng=112000 S: 611.4292402214218-1.9969160997732427
81352.60 [log] AVSB: pushing segment audio audio P: 1_1750922148.289000000 A: 1 R: audio_eng=112000 S: 611.4292402214218-1.9969160997732427
81356.90 [log] SBI: receiving order to push data to the SourceBuffer audio
81362.10 [log] SBI: pushing segment audio
81375.90 [log] SF: Segment request ended with success audio P: 1_1750922148.289000000 A: 1 R: audio_eng=112000 S: 611.4292402214218-1.9969160997732427
81381.40 [log] SF: Beginning request video P: 1_1750922148.289000000 A: 2 R: video=5622000 S: 613.409104167-4
81392.70 [log] XHR: Sending GET type=arraybuffer to=30 cto=15
81410.80 [log] SF: Beginning request audio P: 1_1750922148.289000000 A: 1 R: audio_eng=112000 S: 613.4261563211951-1.9969160997732427
81427.30 [log] XHR: Sending GET type=arraybuffer to=30 cto=15
81489.40 [log] SI: Pushing segment strictly after previous one. audio 611.4292402214218 611.392957
81492.40 [log] SI: synchronizing audio buffered ranges: 608.448-613.389874
81495.80 [log] SI: found true buffered end audio 613.389874 613.426156321195
81501.60 [log] SI: current audio inventory timeline:
608.45|A|609.41 ~ 609.41|B|613.43
[A] P: 1_1750921539.000000000 || R: audio_112034_cze=112000(112000)
[B] P: 1_1750922148.289000000 || R: audio_eng=112000(112000)
81600.50 [log] AVSB: receiving order to push data to the SourceBuffer audio audio P: 1_1750922148.289000000 A: 1 R: audio_eng=112000 S: 613.4261563211951-1.9969160997732427
81605.00 [log] AVSB: pushing segment audio audio P: 1_1750922148.289000000 A: 1 R: audio_eng=112000 S: 613.4261563211951-1.9969160997732427
81608.10 [log] SBI: receiving order to push data to the SourceBuffer audio
81611.70 [log] SBI: pushing segment audio
81620.60 [log] SF: Segment request ended with success audio P: 1_1750922148.289000000 A: 1 R: audio_eng=112000 S: 613.4261563211951-1.9969160997732427
81624.60 [log] SF: Beginning request audio P: 1_1750922148.289000000 A: 1 R: audio_eng=112000 S: 615.4230724209683-1.9969160997732427
81636.20 [log] XHR: Sending GET  type=arraybuffer to=30 cto=15
81720.40 [log] SI: Pushing segment strictly after previous one. audio 613.4261563211951 613.426156321195
81723.00 [log] SI: synchronizing audio buffered ranges: 608.448-615.38679
81724.60 [log] SI: found true buffered end audio 615.38679 615.4230724209683
81726.90 [log] SI: current audio inventory timeline:
608.45|A|609.41 ~ 609.41|B|615.42
[A] P: 1_1750921539.000000000 || R: audio_112034_cze=112000(112000)
[B] P: 1_1750922148.289000000 || R: audio_eng=112000(112000)
81804.20 [log] AVSB: receiving order to push data to the SourceBuffer audio audio P: 1_1750922148.289000000 A: 1 R: audio_eng=112000 S: 615.4230724209683-1.9969160997732427
81807.40 [log] AVSB: pushing segment audio audio P: 1_1750922148.289000000 A: 1 R: audio_eng=112000 S: 615.4230724209683-1.9969160997732427
81810.30 [log] SBI: receiving order to push data to the SourceBuffer audio
81813.40 [log] SBI: pushing segment audio
81821.30 [log] SF: Segment request ended with success audio P: 1_1750922148.289000000 A: 1 R: audio_eng=112000 S: 615.4230724209683-1.9969160997732427
81846.10 [log] SI: Segment pushed updates end of previous one video 609.409104167 613.409104167 608.52 609.48
81848.40 [log] SI: synchronizing video buffered ranges: 608.52-613.409104
81850.60 [log] SI: calculating buffered end of contiguous segment video 609.409104167 609.409104167
81852.90 [log] SI: found true buffered end video 613.409104 613.409104167
81862.50 [log] SI: current video inventory timeline:
608.52|A|609.41 ~ 609.41|B|613.41
[A] P: 1_1750921539.000000000 || R: video=4714400(4714400)
[B] P: 1_1750922148.289000000 || R: video=5622000(5622000)
81865.00 [log] ABR: Incrementing blocking raise in BufferBasedChooser due to unstable quality 12000
81867.10 [log] ABR: Choosing representation with bandwidth estimation. 5622000 video=5622000
81872.00 [log] SI: Pushing segment strictly after previous one. audio 615.4230724209683 615.4230724209683
81873.60 [log] SI: synchronizing audio buffered ranges: 608.448-617.383706
81875.50 [log] SI: found true buffered end audio 617.383706 617.4199885207415
81877.60 [log] SI: current audio inventory timeline:
608.45|A|609.41 ~ 609.41|B|617.42
[A] P: 1_1750921539.000000000 || R: audio_112034_cze=112000(112000)
[B] P: 1_1750922148.289000000 || R: audio_eng=112000(112000)
81948.10 [log] AVSB: receiving order to push data to the SourceBuffer video video P: 1_1750922148.289000000 A: 2 R: video=5622000 S: 613.409104167-4
81951.20 [log] AVSB: pushing segment video video P: 1_1750922148.289000000 A: 2 R: video=5622000 S: 613.409104167-4
81954.10 [log] SBI: receiving order to push data to the SourceBuffer video
81957.10 [log] SBI: pushing segment video
81973.70 [log] SF: Segment request ended with success video P: 1_1750922148.289000000 A: 2 R: video=5622000 S: 613.409104167-4
81977.60 [log] SF: Beginning request audio P: 1_1750922148.289000000 A: 1 R: audio_eng=112000 S: 617.4199885207415-1.9969160997732427
81985.50 [log] XHR: Sending GET type=arraybuffer to=30 cto=15
81996.60 [log] SF: Beginning request video P: 1_1750922148.289000000 A: 2 R: video=5622000 S: 617.409104167-4
82007.70 [log] XHR: Sending GET type=arraybuffer to=30 cto=15
82192.20 [log] AVSB: receiving order to push data to the SourceBuffer audio audio P: 1_1750922148.289000000 A: 1 R: audio_eng=112000 S: 617.4199885207415-1.9969160997732427
82199.00 [log] AVSB: pushing segment audio audio P: 1_1750922148.289000000 A: 1 R: audio_eng=112000 S: 617.4199885207415-1.9969160997732427
82203.30 [log] SBI: receiving order to push data to the SourceBuffer audio
82206.60 [log] SBI: pushing segment audio
82220.60 [log] SF: Segment request ended with success audio P: 1_1750922148.289000000 A: 1 R: audio_eng=112000 S: 617.4199885207415-1.9969160997732427
82226.10 [log] SF: Beginning request audio P: 1_1750922148.289000000 A: 1 R: audio_eng=112000 S: 619.4169046205147-1.9969160997732427
82237.60 [log] XHR: Sending GET  type=arraybuffer to=30 cto=15
82274.40 [log] API: current media element state tick event timeupdate position 609.289 seeking 2 internalSeek false rebuffering true freezing false ended false paused false playbackRate 0 readyState 1 pendingPosition null
82276.30 [log] API: current playback timeline:
608.52|==8.89==|617.41
           ^609.289 
timeupdate
82278.10 [log] SI: synchronizing video buffered ranges: 608.52-617.409104
82280.60 [log] SI: current video inventory timeline:
608.52|A|609.41 ~ 609.41|B|613.41
[A] P: 1_1750921539.000000000 || R: video=4714400(4714400)
[B] P: 1_1750922148.289000000 || R: video=5622000(5622000)
82282.40 [log] SI: synchronizing audio buffered ranges: 608.448-619.380622
82284.40 [log] SI: current audio inventory timeline:
608.45|A|609.41 ~ 609.41|B|617.42
[A] P: 1_1750921539.000000000 || R: audio_112034_cze=112000(112000)
[B] P: 1_1750922148.289000000 || R: audio_eng=112000(112000)
82287.00 [info] FR: Freeze detected 79522.50000000095 2763.999999999214
82289.80 [log] Stream: full "empty" AdaptationStream text
82292.90 [info] ABR: exit starvation mode.
82295.80 [log] ABR: Choosing representation with bandwidth estimation. 4714400 video=4714400
82297.40 [log] Stream: new video bitrate estimate 23138426.6824868
82311.40 [info] ABR: exit starvation mode.
82315.00 [log] ABR: Choosing representation with bandwidth estimation. 5622000 video=5622000
82316.80 [log] Stream: new video bitrate estimate 23138426.6824868
82324.60 [log] SI: Pushing segment strictly after previous one. video 613.409104167 613.409104167
82326.20 [log] SI: synchronizing video buffered ranges: 608.52-617.409104
82327.50 [log] SI: found true buffered end video 617.409104 617.409104167
82329.60 [log] SI: current video inventory timeline:
608.52|A|609.41 ~ 609.41|B|617.41
[A] P: 1_1750921539.000000000 || R: video=4714400(4714400)
[B] P: 1_1750922148.289000000 || R: video=5622000(5622000)
82332.90 [log] ABR: Incrementing blocking raise in BufferBasedChooser due to unstable quality 15000
82334.60 [log] ABR: Choosing representation with bandwidth estimation. 5622000 video=5622000
82346.90 [log] SI: Pushing segment strictly after previous one. audio 617.4199885207415 617.4199885207415
82348.40 [log] SI: synchronizing audio buffered ranges: 608.448-619.380622
82359.50 [log] SI: found true buffered end audio 619.380622 619.4169046205147
82361.10 [log] SI: current audio inventory timeline:
608.45|A|609.41 ~ 609.41|B|619.42
[A] P: 1_1750921539.000000000 || R: audio_112034_cze=112000(112000)
[B] P: 1_1750922148.289000000 || R: audio_eng=112000(112000)
82391.40 [log] AVSB: receiving order to push data to the SourceBuffer audio audio P: 1_1750922148.289000000 A: 1 R: audio_eng=112000 S: 619.4169046205147-1.9969160997732427
82394.30 [log] AVSB: pushing segment audio audio P: 1_1750922148.289000000 A: 1 R: audio_eng=112000 S: 619.4169046205147-1.9969160997732427
82396.60 [log] SBI: receiving order to push data to the SourceBuffer audio
82399.40 [log] SBI: pushing segment audio
82411.30 [log] SF: Segment request ended with success audio P: 1_1750922148.289000000 A: 1 R: audio_eng=112000 S: 619.4169046205147-1.9969160997732427
82443.00 [log] SI: Pushing segment strictly after previous one. audio 619.4169046205147 619.4169046205147
82445.80 [log] SI: synchronizing audio buffered ranges: 608.448-621.377538
82447.40 [log] SI: found true buffered end audio 621.377538 621.413820720288
82448.90 [log] SI: current audio inventory timeline:
608.45|A|609.41 ~ 609.41|B|621.41
[A] P: 1_1750921539.000000000 || R: audio_112034_cze=112000(112000)
[B] P: 1_1750922148.289000000 || R: audio_eng=112000(112000)
82463.90 [log] API: current media element state tick event seeked position 609.289 seeking 0 internalSeek false rebuffering false freezing false ended false paused false playbackRate 0 readyState 4 pendingPosition null
82469.80 [log] API: current playback timeline:
608.52|==8.89==|617.41
           ^609.289 
seeked
82474.20 [log] SI: synchronizing video buffered ranges: 608.52-617.409104
82477.30 [log] SI: current video inventory timeline:
608.52|A|609.41 ~ 609.41|B|617.41
[A] P: 1_1750921539.000000000 || R: video=4714400(4714400)
[B] P: 1_1750922148.289000000 || R: video=5622000(5622000)
82481.50 [log] SI: synchronizing audio buffered ranges: 608.448-621.377538
82484.00 [log] SI: current audio inventory timeline:
608.45|A|609.41 ~ 609.41|B|621.38
[A] P: 1_1750921539.000000000 || R: audio_112034_cze=112000(112000)
[B] P: 1_1750922148.289000000 || R: audio_eng=112000(112000)
82487.10 [log] Stream: full "empty" AdaptationStream text
82490.80 [log] ABR: Choosing representation with bandwidth estimation. 4714400 video=4714400
82500.40 [log] ABR: Choosing representation with bandwidth estimation. 5622000 video=5622000
82504.30 [info] Init: Resume playback speed 1
82512.00 [info] API: playerStateChange event PLAYING
82571.20 [log] API: current media element state tick event canplay position 609.289 seeking 0 internalSeek false rebuffering false freezing false ended false paused false playbackRate 1 readyState 4 pendingPosition null
82574.50 [log] API: current playback timeline:
608.52|==8.89==|617.41
           ^609.289 
canplay
82576.50 [log] SI: synchronizing video buffered ranges: 608.52-617.409104
82578.80 [log] SI: current video inventory timeline:
608.52|A|609.41 ~ 609.41|B|617.41
[A] P: 1_1750921539.000000000 || R: video=4714400(4714400)
[B] P: 1_1750922148.289000000 || R: video=5622000(5622000)
82580.80 [log] SI: synchronizing audio buffered ranges: 608.448-621.377538
82582.90 [log] SI: current audio inventory timeline:
608.45|A|609.41 ~ 609.41|B|621.38
[A] P: 1_1750921539.000000000 || R: audio_112034_cze=112000(112000)
[B] P: 1_1750922148.289000000 || R: audio_eng=112000(112000)
82584.90 [log] Stream: full "empty" AdaptationStream text
82588.00 [log] ABR: Choosing representation with bandwidth estimation. 4714400 video=4714400
82596.40 [log] ABR: Choosing representation with bandwidth estimation. 5622000 video=5622000
82608.90 [log] API: current media element state tick event ratechange position 609.289 seeking 0 internalSeek false rebuffering false freezing false ended false paused false playbackRate 1 readyState 4 pendingPosition null
82611.30 [log] API: current playback timeline:
608.52|==8.89==|617.41
           ^609.289 
ratechange
82613.90 [log] SI: synchronizing video buffered ranges: 608.52-617.409104
82616.90 [log] SI: current video inventory timeline:
608.52|A|609.41 ~ 609.41|B|617.41
[A] P: 1_1750921539.000000000 || R: video=4714400(4714400)
[B] P: 1_1750922148.289000000 || R: video=5622000(5622000)
82618.90 [log] SI: synchronizing audio buffered ranges: 608.448-621.377538
82620.90 [log] SI: current audio inventory timeline:
608.45|A|609.41 ~ 609.41|B|621.38
[A] P: 1_1750921539.000000000 || R: audio_112034_cze=112000(112000)
[B] P: 1_1750922148.289000000 || R: audio_eng=112000(112000)
82623.00 [log] Stream: full "empty" AdaptationStream text
82626.00 [log] ABR: Choosing representation with bandwidth estimation. 4714400 video=4714400
82633.70 [log] ABR: Choosing representation with bandwidth estimation. 5622000 video=5622000
82735.70 [log] AVSB: receiving order to push data to the SourceBuffer video video P: 1_1750922148.289000000 A: 2 R: video=5622000 S: 617.409104167-4
82738.20 [log] AVSB: pushing segment video video P: 1_1750922148.289000000 A: 2 R: video=5622000 S: 617.409104167-4
82740.40 [log] SBI: receiving order to push data to the SourceBuffer video
82743.10 [log] SBI: pushing segment video
82762.90 [log] SF: Segment request ended with success video P: 1_1750922148.289000000 A: 2 R: video=5622000 S: 617.409104167-4
82766.30 [log] SF: Beginning request audio P: 1_1750922148.289000000 A: 1 R: audio_eng=112000 S: 621.4138207202881-1.9969160997732427
82777.10 [log] XHR: Sending GET type=arraybuffer to=30 cto=15
82786.80 [log] SF: Beginning request video P: 1_1750922148.289000000 A: 2 R: video=5622000 S: 621.409104167-4
82799.00 [log] XHR: Sending GET type=arraybuffer to=30 cto=15
83029.60 [log] SI: Pushing segment strictly after previous one. video 617.409104167 617.409104
83031.60 [log] SI: synchronizing video buffered ranges: 608.52-621.409104
83033.00 [log] SI: found true buffered end video 621.409104 621.409104167
83034.90 [log] SI: current video inventory timeline:
608.52|A|609.41 ~ 609.41|B|621.41
[A] P: 1_1750921539.000000000 || R: video=4714400(4714400)
[B] P: 1_1750922148.289000000 || R: video=5622000(5622000)
83037.00 [log] ABR: Incrementing blocking raise in BufferBasedChooser due to unstable quality 15000
83038.60 [log] ABR: Choosing representation with bandwidth estimation. 5622000 video=5622000
83044.30 [log] Stream: new video bitrate estimate 23778809.778665125
83058.70 [log] AVSB: receiving order to push data to the SourceBuffer audio audio P: 1_1750922148.289000000 A: 1 R: audio_eng=112000 S: 621.4138207202881-1.9969160997732427
83062.00 [log] AVSB: pushing segment audio audio P: 1_1750922148.289000000 A: 1 R: audio_eng=112000 S: 621.4138207202881-1.9969160997732427
83064.90 [log] SBI: receiving order to push data to the SourceBuffer audio
83067.50 [log] SBI: pushing segment audio
83086.80 [log] SF: Segment request ended with success audio P: 1_1750922148.289000000 A: 1 R: audio_eng=112000 S: 621.4138207202881-1.9969160997732427
83095.50 [log] SF: Beginning request audio P: 1_1750922148.289000000 A: 1 R: audio_eng=112000 S: 623.4107368200613-2.020136054421769
83105.40 [log] XHR: Sending GET type=arraybuffer to=30 cto=15
83146.40 [log] SI: Pushing segment strictly after previous one. audio 621.4138207202881 621.377538
83148.20 [log] SI: synchronizing audio buffered ranges: 608.448-623.374454
83149.90 [log] SI: found true buffered end audio 623.374454 623.4107368200613
83154.00 [log] SI: current audio inventory timeline:
608.45|A|609.41 ~ 609.41|B|623.41
[A] P: 1_1750921539.000000000 || R: audio_112034_cze=112000(112000)
[B] P: 1_1750922148.289000000 || R: audio_eng=112000(112000)
83203.20 [log] AVSB: receiving order to push data to the SourceBuffer audio audio P: 1_1750922148.289000000 A: 1 R: audio_eng=112000 S: 623.4107368200613-2.020136054421769
83205.50 [log] AVSB: pushing segment audio audio P: 1_1750922148.289000000 A: 1 R: audio_eng=112000 S: 623.4107368200613-2.020136054421769
83207.90 [log] SBI: receiving order to push data to the SourceBuffer audio
83212.00 [log] SBI: pushing segment audio
83221.00 [log] SF: Segment request ended with success audio P: 1_1750922148.289000000 A: 1 R: audio_eng=112000 S: 623.4107368200613-2.020136054421769
83224.40 [log] SF: Beginning request audio P: 1_1750922148.289000000 A: 1 R: audio_eng=112000 S: 625.430872874483-1.9969160997732427
83233.30 [log] XHR: Sending GET type=arraybuffer to=30 cto=15
83268.50 [log] SI: Pushing segment strictly after previous one. audio 623.4107368200613 623.4107368200613
83271.40 [log] SI: synchronizing audio buffered ranges: 608.448-625.39459
83272.80 [log] SI: found true buffered end audio 625.39459 625.430872874483
83274.90 [log] SI: current audio inventory timeline:
608.45|A|609.41 ~ 609.41|B|625.43
[A] P: 1_1750921539.000000000 || R: audio_112034_cze=112000(112000)
[B] P: 1_1750922148.289000000 || R: audio_eng=112000(112000)
83333.40 [log] AVSB: receiving order to push data to the SourceBuffer audio audio P: 1_1750922148.289000000 A: 1 R: audio_eng=112000 S: 625.430872874483-1.9969160997732427
83336.20 [log] AVSB: pushing segment audio audio P: 1_1750922148.289000000 A: 1 R: audio_eng=112000 S: 625.430872874483-1.9969160997732427
83340.00 [log] SBI: receiving order to push data to the SourceBuffer audio
83343.10 [log] SBI: pushing segment audio
83355.60 [log] SF: Segment request ended with success audio P: 1_1750922148.289000000 A: 1 R: audio_eng=112000 S: 625.430872874483-1.9969160997732427
83411.40 [log] SI: Pushing segment strictly after previous one. audio 625.430872874483 625.430872874483
83413.20 [log] SI: synchronizing audio buffered ranges: 608.448-627.391506
83414.70 [log] SI: found true buffered end audio 627.391506 627.4277889742563
83416.70 [log] SI: current audio inventory timeline:
608.45|A|609.41 ~ 609.41|B|627.43
[A] P: 1_1750921539.000000000 || R: audio_112034_cze=112000(112000)
[B] P: 1_1750922148.289000000 || R: audio_eng=112000(112000)
83441.50 [log] AVSB: receiving order to push data to the SourceBuffer video video P: 1_1750922148.289000000 A: 2 R: video=5622000 S: 621.409104167-4
83444.30 [log] AVSB: pushing segment video video P: 1_1750922148.289000000 A: 2 R: video=5622000 S: 621.409104167-4
83446.70 [log] SBI: receiving order to push data to the SourceBuffer video
83450.00 [log] SBI: pushing segment video
83465.50 [log] SF: Segment request ended with success video P: 1_1750922148.289000000 A: 2 R: video=5622000 S: 621.409104167-4
83468.80 [log] SF: Beginning request video P: 1_1750922148.289000000 A: 2 R: video=5622000 S: 625.409104167-4
83479.40 [log] XHR: Sending GET  type=arraybuffer to=30 cto=15
83622.10 [log] API: current media element state tick event timeupdate position 610.265027 seeking 0 internalSeek false rebuffering false freezing false ended false paused false playbackRate 1 readyState 4 pendingPosition null
83624.80 [log] API: current playback timeline:
608.52|==14.57==|623.09
            ^610.265027 
timeupdate
83627.30 [log] SI: synchronizing video buffered ranges: 608.52-623.089104
83630.00 [log] SI: current video inventory timeline:
608.52|A|609.41 ~ 609.41|B|621.41
[A] P: 1_1750921539.000000000 || R: video=4714400(4714400)
[B] P: 1_1750922148.289000000 || R: video=5622000(5622000)
83631.60 [log] SI: synchronizing audio buffered ranges: 608.448-627.391506
83633.30 [log] SI: current audio inventory timeline:
608.45|A|609.41 ~ 609.41|B|627.39
[A] P: 1_1750921539.000000000 || R: audio_112034_cze=112000(112000)
[B] P: 1_1750922148.289000000 || R: audio_eng=112000(112000)
83637.20 [info] Stream: Destroying PeriodStream as the current playhead moved above it video 0 610.265027 609.409104167
83718.80 [log] TS: Removing Track Reference video 1_1750921539.000000000
83729.60 [info] Stream: Destroying PeriodStream as the current playhead moved above it audio 0 610.265027 609.409104167
83732.60 [log] TS: Removing Track Reference audio 1_1750921539.000000000
83746.10 [info] Stream: Destroying PeriodStream as the current playhead moved above it text 0 610.265027 609.409104167
83751.70 [log] TS: Removing Track Reference text 1_1750921539.000000000
83765.10 [log] SQ: Priority of next media segment changed, updating audio 5 4
83771.30 [log] SF: Beginning request audio P: 1_1750922148.289000000 A: 1 R: audio_eng=112000 S: 627.4277889742563-1.9969160997732427
83787.60 [log] XHR: Sending GET type=arraybuffer to=30 cto=15
83818.70 [log] ABR: Choosing representation with bandwidth estimation. 5622000 video=5622000
83823.00 [log] Stream: new video bitrate estimate 24085960.131476093
c @ nderstand this error
83966.00 [log] AVSB: receiving order to push data to the SourceBuffer audio audio P: 1_1750922148.289000000 A: 1 R: audio_eng=112000 S: 627.4277889742563-1.9969160997732427
83969.90 [log] AVSB: pushing segment audio audio P: 1_1750922148.289000000 A: 1 R: audio_eng=112000 S: 627.4277889742563-1.9969160997732427
83973.70 [log] SBI: receiving order to push data to the SourceBuffer audio
83976.20 [log] SBI: pushing segment audio
84027.10 [log] SF: Segment request ended with success audio P: 1_1750922148.289000000 A: 1 R: audio_eng=112000 S: 627.4277889742563-1.9969160997732427
84131.60 [log] SI: Pushing segment strictly after previous one. audio 627.4277889742563 627.391506
84141.90 [log] SI: synchronizing audio buffered ranges: 608.448-629.388422
84145.00 [log] SI: found true buffered end audio 629.388422 629.4247050740295
84146.60 [log] SI: current audio inventory timeline:
608.45|A|609.41 ~ 609.41|B|629.42
[A] P: 1_1750921539.000000000 || R: audio_112034_cze=112000(112000)
[B] P: 1_1750922148.289000000 || R: audio_eng=112000(112000)
84226.50 [log] SI: Pushing segment strictly after previous one. video 621.409104167 621.409104167
84228.70 [log] SI: synchronizing video buffered ranges: 608.52-625.409104
84230.60 [log] SI: found true buffered end video 625.409104 625.409104167
84233.20 [log] SI: current video inventory timeline:
608.52|A|609.41 ~ 609.41|B|625.41
[A] P: 1_1750921539.000000000 || R: video=4714400(4714400)
[B] P: 1_1750922148.289000000 || R: video=5622000(5622000)
84235.60 [log] ABR: Incrementing blocking raise in BufferBasedChooser due to unstable quality 15000
84237.90 [log] ABR: Choosing representation with bandwidth estimation. 5622000 video=5622000
84380.30 [log] AVSB: receiving order to push data to the SourceBuffer video video P: 1_1750922148.289000000 A: 2 R: video=5622000 S: 625.409104167-4
84382.90 [log] AVSB: pushing segment video video P: 1_1750922148.289000000 A: 2 R: video=5622000 S: 625.409104167-4
84385.20 [log] SBI: receiving order to push data to the SourceBuffer video
84388.10 [log] SBI: pushing segment video
84409.40 [log] SF: Segment request ended with success video P: 1_1750922148.289000000 A: 2 R: video=5622000 S: 625.409104167-4
84413.10 [log] SF: Beginning request audio P: 1_1750922148.289000000 A: 1 R: audio_eng=112000 S: 629.4247050740295-1.9969160997732427
84421.20 [log] XHR: Sending GET  type=arraybuffer to=30 cto=15
84431.90 [log] SF: Beginning request video P: 1_1750922148.289000000 A: 2 R: video=5622000 S: 629.409104167-4
84442.50 [log] XHR: Sending GET  type=arraybuffer to=30 cto=15
84625.10 [log] API: current media element state tick event timeupdate position 611.268654 seeking 0 internalSeek false rebuffering false freezing false ended false paused false playbackRate 1 readyState 4 pendingPosition null
84627.30 [log] API: current playback timeline:
608.52|==19.93==|628.45
            ^611.268654 
timeupdate
84629.40 [log] SI: synchronizing video buffered ranges: 608.52-628.449104
84631.60 [log] SI: current video inventory timeline:
608.52|A|609.41 ~ 609.41|B|625.41
[A] P: 1_1750921539.000000000 || R: video=4714400(4714400)
[B] P: 1_1750922148.289000000 || R: video=5622000(5622000)
84634.80 [log] SI: synchronizing audio buffered ranges: 608.448-629.388422
84637.30 [log] SI: current audio inventory timeline:
608.45|A|609.41 ~ 609.41|B|629.39
[A] P: 1_1750921539.000000000 || R: audio_112034_cze=112000(112000)
[B] P: 1_1750922148.289000000 || R: audio_eng=112000(112000)
84644.40 [log] ABR: Choosing representation with bandwidth estimation. 5622000 video=5622000
84646.60 [log] Stream: new video bitrate estimate 24651637.550715484
84664.20 [log] AVSB: receiving order to push data to the SourceBuffer audio audio P: 1_1750922148.289000000 A: 1 R: audio_eng=112000 S: 629.4247050740295-1.9969160997732427
84667.50 [log] AVSB: pushing segment audio audio P: 1_1750922148.289000000 A: 1 R: audio_eng=112000 S: 629.4247050740295-1.9969160997732427
84673.40 [log] SBI: receiving order to push data to the SourceBuffer audio
84680.90 [log] SBI: pushing segment audio
84694.80 [log] SF: Segment request ended with success audio P: 1_1750922148.289000000 A: 1 R: audio_eng=112000 S: 629.4247050740295-1.9969160997732427
84763.20 [log] SI: Pushing segment strictly after previous one. audio 629.4247050740295 629.388422
84765.00 [log] SI: synchronizing audio buffered ranges: 608.448-631.385338
84766.90 [log] SI: found true buffered end audio 631.385338 631.4216211738027
84768.90 [log] SI: current audio inventory timeline:
608.45|A|609.41 ~ 609.41|B|631.42
[A] P: 1_1750921539.000000000 || R: audio_112034_cze=112000(112000)
[B] P: 1_1750922148.289000000 || R: audio_eng=112000(112000)

Jun 26 '25 08:06 TomaszKowalik

Those logs seems "normal": I see a seek for a few seconds just before the end of the first Period (I suppose it was on the application side from the logs), then playback with both audio and video data in the buffer.

Maybe there's something in the content that browsers/decoders don't like when going from one to the other? If you have a runnable clear demo that reproduces it (preferably on web but we also have a few smart TVs), I take it.

Jun 26 '25 09:06 peaBerberian

Hi @TomaszKowalik , not sure if this helps:

We've met playback issue on LG2019 (WebOS4) with widevine, after switch DRM to playready, everything works fine.

From your log, I think your TV device pick up widevine as well, So it's worth to try PlayReady to see if the issue still there

Aug 12 '25 03:08 KunXi-Fox

@KunXi-Fox

Thank you for your suggestion, indeed looks like changing to DASH/PR fixes that nasty issue!

Aug 21 '25 12:08 TomaszKowalik