search

shaka-project / shaka-player-embedded

Shaka Player in a C++ Framework
Apache License 2.0
239 stars 62 forks source link

MP4 audio not working with unencrypted content #107

Closed jgongo closed 4 years ago

jgongo commented 4 years ago

I'm trying to make the Shaka Player Embedded work with our content and I have started testing unencrypted content (trailers). I can't get the audio to work and I can't see anything in the logs indicating any error, other than a puzzling message (that also appears when successfully playing other videos):

[Log]: "Found variant with audio and video content, so filtering out audio-only content in all periods."

This seems to imply that we have some variant with both audio and video (this isn't the case, as far as I know) so the audio only content is filtered out. Despite this message the logs shows audio activity, although no audio is played in the device.

Here you have the MPD (I could provide others, this happened with all the trailers I tried):

<?xml version='1.0' encoding='utf-8'?>
<MPD xmlns="urn:mpeg:dash:schema:mpd:2011" mediaPresentationDuration="PT2M33.028S" minBufferTime="PT2.00S" profiles="urn:mpeg:dash:profile:isoff-on-demand:2011" type="static">
  <BaseURL>https://prod-stpeter-pmd.akamai.cdn.rakuten.tv/2/d/3/2d325cfd91cdb073207203618b698b19-mc-0-137-0-0_DS2BBC/</BaseURL>
  <Period>
    <AdaptationSet maxHeight="1080" maxWidth="1920" mimeType="video/mp4" minHeight="432" minWidth="768" par="16:9" sar="1:1" segmentAlignment="true" startWithSAP="1">
      <Representation bandwidth="1446413" codecs="avc1.4D401E" frameRate="1644369/68584" height="432" id="video-avc1-1" scanType="progressive" width="768">
        <BaseURL>video-avc1-1.ismv?token=st=1580212989~exp=1580234589~acl=*/2/d/3/2d325cfd91cdb073207203618b698b19-mc-0-137-0-0_DS2BBC/*~hmac=1174409952f947ec26a6256aaa5e436c6a07c2cb41f6f0de0864d06444ab8b53</BaseURL>
        <SegmentBase indexRange="689-1644"><Initialization range="0-688"/></SegmentBase>
      </Representation>
      <Representation bandwidth="2021259" codecs="avc1.4D401F" frameRate="1644369/68584" height="540" id="video-avc1-2" scanType="progressive" width="960">
        <BaseURL>video-avc1-2.ismv?token=st=1580212989~exp=1580234589~acl=*/2/d/3/2d325cfd91cdb073207203618b698b19-mc-0-137-0-0_DS2BBC/*~hmac=1174409952f947ec26a6256aaa5e436c6a07c2cb41f6f0de0864d06444ab8b53</BaseURL>
        <SegmentBase indexRange="690-1645"><Initialization range="0-689"/></SegmentBase>
      </Representation>
    </AdaptationSet>
    <AdaptationSet lang="spa" mimeType="audio/mp4" segmentAlignment="true" startWithSAP="1">
      <Representation audioSamplingRate="48000" bandwidth="195469" codecs="mp4a.40.2" id="audio-spa-mp4a-1"><AudioChannelConfiguration schemeIdUri="urn:mpeg:dash:23003:3:audio_channel_configuration:2011" value="2"/>
        <BaseURL>audio-spa-mp4a-1.isma?token=st=1580212989~exp=1580234589~acl=*/2/d/3/2d325cfd91cdb073207203618b698b19-mc-0-137-0-0_DS2BBC/*~hmac=1174409952f947ec26a6256aaa5e436c6a07c2cb41f6f0de0864d06444ab8b53</BaseURL>
        <SegmentBase indexRange="624-1579"><Initialization range="0-623"/></SegmentBase>
      </Representation>
    </AdaptationSet>
  </Period>
</MPD>
and here you have the log: ``` [Info]: "Starting attach..." [Info]: "Starting load of https://prod-kami.wuaki.tv/v1/delivery/dash/stpeter/0d500e7d-328c-480d-971c-e8f98bc9a119.mpd..." [Info]: "No Period ID given for Period with start time 0, Assigning a default" [Debug]: "notifyNewSegmentDuration:" "maxSegmentDuration=1" [Log]: "Found variant with audio and video content, so filtering out audio-only content in all periods." [Log]: "codecs" "avc1-mp4a" "avg bandwidth" 1929305 [Log]: "onChooseStreams_" {startTime:0, textStreams:[...], variants:[...]} [Log]: "Choosing new streams after period changed" [Debug]: "(all) setting up Stream 4" [Debug]: "(all) setting up Stream 1" [Debug]: "notifySegments:" "maxSegmentDuration=2.005330200000003" [Debug]: "notifySegments:" "maxSegmentDuration=2.005330200000003" [Debug]: "(all) setup Stream 4" [Debug]: "(all) setup Stream 1" [Log]: "init: completed initial Stream setup" [Debug]: "(audio:4)" "next position unknown: nothing buffered" [Log]: "(audio:4)" "looking up segment:" "presentationTime=0" "currentPeriod.startTime=0" [Debug]: "(audio:4)" "fetchAndAppend_:" "presentationTime=0" "currentPeriod.startTime=0" "reference.position=0" "reference.startTime=0" "reference.endTime=2.0053302" [Debug]: "(audio:4)" "setting timestamp offset to 0" [Debug]: "(audio:4)" "setting append window start to 0" [Debug]: "(audio:4)" "setting append window end to 153.028" [Debug]: "(audio:4)" "fetching init segment" [Debug]: "(video:1)" "next position unknown: nothing buffered" [Log]: "(video:1)" "looking up segment:" "presentationTime=0" "currentPeriod.startTime=0" [Debug]: "(video:1)" "fetchAndAppend_:" "presentationTime=0" "currentPeriod.startTime=0" "reference.position=0" "reference.startTime=0" "reference.endTime=2.0020032" [Debug]: "(video:1)" "setting timestamp offset to 0" [Debug]: "(video:1)" "setting append window start to 0" [Debug]: "(video:1)" "setting append window end to 153.028" [Debug]: "(video:1)" "fetching init segment" [Debug]: "(audio:4)" "appending init segment" I0128 13:08:02.661818 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(audio:4)" "appending media segment" [mov,mp4,m4a,3gp,3g2,mj2 @ 0x108046600] Protocol name not provided, cannot determine if input is local or a network protocol, buffers and access patterns cannot be configured optimally without knowing the protocol I0128 13:08:02.753578 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(audio:4)" "finished fetch and append" I0128 13:08:02.759498 1834594304 ffmpeg_decoder.cc:140] Reconfiguring decoder I0128 13:08:02.759557 1834594304 ffmpeg_decoder.cc:257] No hardware-accelerators available, using decoder: aac_at [Debug]: "(audio:4)" "fetchAndAppend_:" "presentationTime=0" "currentPeriod.startTime=0" "reference.position=1" "reference.startTime=2.0053302" "reference.endTime=4.0106604" [Debug]: "(video:1)" "appending init segment" I0128 13:08:02.767297 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(audio:4)" "appending media segment" I0128 13:08:02.950345 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(audio:4)" "finished fetch and append" [Debug]: "(video:1)" "appending media segment" [mov,mp4,m4a,3gp,3g2,mj2 @ 0x10788c400] Protocol name not provided, cannot determine if input is local or a network protocol, buffers and access patterns cannot be configured optimally without knowing the protocol I0128 13:08:03.136893 1828859904 source_buffer.cc:207] Finish appending media segment: success [Info]: "Jumping forward" 0.0834168 "seconds because of gap before start time of" 0.0834168 [Log]: "(video:1)" "startup complete" [Log]: "(all) setting up Period 0" [Log]: "(all) Stream 1 is being or has been set up" [Log]: "(all) Stream 4 is being or has been set up" [Debug]: "(all) setting up Stream 2" I0128 13:08:03.142962 1833447424 ffmpeg_decoder.cc:140] Reconfiguring decoder I0128 13:08:03.143025 1833447424 ffmpeg_decoder.cc:263] Using decoder: h264, with hardware accelerator: videotoolbox [Log]: "(all) Period 0 is being or has been set up" [Debug]: "(video:1)" "finished fetch and append" [Debug]: "Playhead in safe region or in buffered region." [Debug]: "Seek to 0.0834168" [Log]: "(all): seeked: buffered seek: presentationTime=0.0834168" [Debug]: "notifySegments:" "maxSegmentDuration=2.005330200000003" [Debug]: "(all) setup Stream 2" [Debug]: "(video:1)" "calling onCanSwitch()..." [Log]: "canSwitch_" [Debug]: "(all) setup Period 0" [Log]: "Calling switch_(), bandwidth=5892 kbps" [Log]: "switch_" [Log]: "switch: switching to Stream (video:2)" [Log]: "switch: Stream (audio:4) already active" [Debug]: "(video:2)" "next position unknown: another Stream buffered" [Log]: "(video:2)" "looking up segment:" "presentationTime=2.0020032" "currentPeriod.startTime=0" [Debug]: "(video:2)" "fetchAndAppend_:" "presentationTime=0.0834168" "currentPeriod.startTime=0" "reference.position=1" "reference.startTime=2.0020032" "reference.endTime=4.0040064" [Debug]: "(video:2)" "setting timestamp offset to 0" [Debug]: "(video:2)" "setting append window start to 0" [Debug]: "(video:2)" "setting append window end to 153.028" I0128 13:08:03.282359 1833447424 ffmpeg_decoder.cc:140] Reconfiguring decoder I0128 13:08:03.282444 1833447424 ffmpeg_decoder.cc:263] Using decoder: h264, with hardware accelerator: videotoolbox [Debug]: "(video:2)" "fetching init segment" [Debug]: "(video:2)" "appending init segment" [mov,mp4,m4a,3gp,3g2,mj2 @ 0x10788c400] Found duplicated MOOV Atom. Stopping. I0128 13:08:03.362903 1836888064 ffmpeg_demuxer.cc:247] Reinitializing demuxer I0128 13:08:03.363358 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(video:2)" "appending media segment" [mov,mp4,m4a,3gp,3g2,mj2 @ 0x10811e200] Protocol name not provided, cannot determine if input is local or a network protocol, buffers and access patterns cannot be configured optimally without knowing the protocol I0128 13:08:03.653175 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(video:2)" "finished fetch and append" [Debug]: "(video:2)" "fetchAndAppend_:" "presentationTime=0.4494168000000003" "currentPeriod.startTime=0" "reference.position=2" "reference.startTime=4.0040064" "reference.endTime=6.0060096" [Debug]: "(audio:4)" "fetchAndAppend_:" "presentationTime=0.7454168000000005" "currentPeriod.startTime=0" "reference.position=2" "reference.startTime=4.0106604" "reference.endTime=5.9946573" [Debug]: "(audio:4)" "appending media segment" I0128 13:08:04.046183 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(audio:4)" "finished fetch and append" [Debug]: "(audio:4)" "fetchAndAppend_:" "presentationTime=0.8464168000000006" "currentPeriod.startTime=0" "reference.position=3" "reference.startTime=5.9946573" "reference.endTime=7.9999875" [Debug]: "(video:2)" "appending media segment" I0128 13:08:04.107758 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(video:2)" "finished fetch and append" [Debug]: "(video:2)" "fetchAndAppend_:" "presentationTime=0.9024168000000007" "currentPeriod.startTime=0" "reference.position=3" "reference.startTime=6.0060096" "reference.endTime=8.0080128" [Debug]: "(audio:4)" "appending media segment" I0128 13:08:04.139559 1833447424 ffmpeg_decoder.cc:140] Reconfiguring decoder I0128 13:08:04.139986 1833447424 ffmpeg_decoder.cc:263] Using decoder: h264, with hardware accelerator: videotoolbox I0128 13:08:04.142740 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(audio:4)" "finished fetch and append" [Debug]: "(audio:4)" "fetchAndAppend_:" "presentationTime=0.9394168000000007" "currentPeriod.startTime=0" "reference.position=4" "reference.startTime=7.9999875" "reference.endTime=10.0053177" [Debug]: "(audio:4)" "appending media segment" I0128 13:08:04.248314 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(audio:4)" "finished fetch and append" [Debug]: "(video:2)" "appending media segment" I0128 13:08:04.489312 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(video:2)" "finished fetch and append" [Debug]: "(video:2)" "fetchAndAppend_:" "presentationTime=1.2854167999999997" "currentPeriod.startTime=0" "reference.position=4" "reference.startTime=8.0080128" "reference.endTime=10.010016" [Debug]: "(video:2)" "appending media segment" I0128 13:08:04.813616 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(video:2)" "finished fetch and append" [Debug]: "(video:2)" "fetchAndAppend_:" "presentationTime=1.6094167999999982" "currentPeriod.startTime=0" "reference.position=5" "reference.startTime=10.010016" "reference.endTime=12.0120192" [Debug]: "(video:2)" "appending media segment" I0128 13:08:05.101299 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(video:2)" "finished fetch and append" [Debug]: "(audio:4)" "fetchAndAppend_:" "presentationTime=2.0464167999999967" "currentPeriod.startTime=0" "reference.position=5" "reference.startTime=10.0053177" "reference.endTime=12.0106479" [Debug]: "(audio:4)" "appending media segment" I0128 13:08:05.338284 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(audio:4)" "finished fetch and append" [Debug]: "(audio:4)" "fetchAndAppend_:" "presentationTime=2.1354167999999967" "currentPeriod.startTime=0" "reference.position=6" "reference.startTime=12.0106479" "reference.endTime=13.9946448" [Debug]: "(audio:4)" "appending media segment" I0128 13:08:05.428463 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(audio:4)" "finished fetch and append" [Debug]: "(video:2)" "fetchAndAppend_:" "presentationTime=2.399416799999996" "currentPeriod.startTime=0" "reference.position=6" "reference.startTime=12.0120192" "reference.endTime=14.0140224" [Debug]: "(video:2)" "appending media segment" I0128 13:08:05.956140 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(video:2)" "finished fetch and append" [Debug]: "(audio:4)" "fetchAndAppend_:" "presentationTime=4.2574167999999935" "currentPeriod.startTime=0" "reference.position=7" "reference.startTime=13.9946448" "reference.endTime=15.999975" [Debug]: "(video:2)" "fetchAndAppend_:" "presentationTime=4.286416799999993" "currentPeriod.startTime=0" "reference.position=7" "reference.startTime=14.0140224" "reference.endTime=16.0160256" [Debug]: "(audio:4)" "appending media segment" I0128 13:08:07.574506 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(audio:4)" "finished fetch and append" [Debug]: "(video:2)" "appending media segment" I0128 13:08:07.711218 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(video:2)" "finished fetch and append" [Debug]: "(audio:4)" "fetchAndAppend_:" "presentationTime=6.387416799999977" "currentPeriod.startTime=0" "reference.position=8" "reference.startTime=15.999975" "reference.endTime=18.0053052" [Debug]: "(video:2)" "fetchAndAppend_:" "presentationTime=6.530416799999976" "currentPeriod.startTime=0" "reference.position=8" "reference.startTime=16.0160256" "reference.endTime=18.0180288" [Debug]: "(audio:4)" "appending media segment" I0128 13:08:09.765698 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(audio:4)" "finished fetch and append" [Debug]: "(video:2)" "appending media segment" I0128 13:08:09.959477 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(video:2)" "finished fetch and append" [Debug]: "(audio:4)" "fetchAndAppend_:" "presentationTime=8.084416799999962" "currentPeriod.startTime=0" "reference.position=9" "reference.startTime=18.0053052" "reference.endTime=20.0106354" [Log]: "Calling switch_(), bandwidth=11589 kbps" [Log]: "switch_" [Log]: "switch: Stream (video:2) already active" [Log]: "switch: Stream (audio:4) already active" [Debug]: "(audio:4)" "appending media segment" I0128 13:08:11.423079 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(audio:4)" "finished fetch and append" [Debug]: "(video:2)" "fetchAndAppend_:" "presentationTime=8.27341679999996" "currentPeriod.startTime=0" "reference.position=9" "reference.startTime=18.0180288" "reference.endTime=20.020032" [Debug]: "(video:2)" "appending media segment" I0128 13:08:11.815701 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(video:2)" "finished fetch and append" [Debug]: "(video:2)" "fetchAndAppend_:" "presentationTime=10.141416799999947" "currentPeriod.startTime=0" "reference.position=10" "reference.startTime=20.020032" "reference.endTime=22.0220352" [Debug]: "(audio:4)" "fetchAndAppend_:" "presentationTime=10.241416799999946" "currentPeriod.startTime=0" "reference.position=10" "reference.startTime=20.0106354" "reference.endTime=21.9946323" [Debug]: "(audio:4)" "appending media segment" I0128 13:08:13.541396 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(audio:4)" "finished fetch and append" [Debug]: "(video:2)" "appending media segment" I0128 13:08:13.672844 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(video:2)" "finished fetch and append" [Debug]: "(audio:4)" "fetchAndAppend_:" "presentationTime=12.369416799999918" "currentPeriod.startTime=0" "reference.position=11" "reference.startTime=21.9946323" "reference.endTime=23.9999625" [Debug]: "(audio:4)" "appending media segment" I0128 13:08:15.677073 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(audio:4)" "finished fetch and append" [Debug]: "(video:2)" "fetchAndAppend_:" "presentationTime=12.485416799999918" "currentPeriod.startTime=0" "reference.position=11" "reference.startTime=22.0220352" "reference.endTime=24.0240384" [Debug]: "(video:2)" "appending media segment" I0128 13:08:15.924508 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(video:2)" "finished fetch and append" [Debug]: "(video:2)" "fetchAndAppend_:" "presentationTime=14.232416799999895" "currentPeriod.startTime=0" "reference.position=12" "reference.startTime=24.0240384" "reference.endTime=26.0260416" [Debug]: "(audio:4)" "fetchAndAppend_:" "presentationTime=14.491416799999891" "currentPeriod.startTime=0" "reference.position=12" "reference.startTime=23.9999625" "reference.endTime=26.0052927" [Debug]: "(audio:4)" "appending media segment" I0128 13:08:17.789754 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(audio:4)" "finished fetch and append" [Debug]: "(video:2)" "appending media segment" I0128 13:08:17.801726 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(video:2)" "finished fetch and append" [Debug]: "(audio:4)" "fetchAndAppend_:" "presentationTime=16.108416799999873" "currentPeriod.startTime=0" "reference.position=13" "reference.startTime=26.0052927" "reference.endTime=28.0106229" [Debug]: "(video:2)" "fetchAndAppend_:" "presentationTime=16.135416799999874" "currentPeriod.startTime=0" "reference.position=13" "reference.startTime=26.0260416" "reference.endTime=28.0280448" [Log]: "Calling switch_(), bandwidth=12612 kbps" [Log]: "switch_" [Log]: "switch: Stream (video:2) already active" [Log]: "switch: Stream (audio:4) already active" [Debug]: "(audio:4)" "appending media segment" I0128 13:08:19.435392 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(audio:4)" "finished fetch and append" [Debug]: "(video:2)" "appending media segment" I0128 13:08:19.574268 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(video:2)" "finished fetch and append" [Debug]: "(audio:4)" "fetchAndAppend_:" "presentationTime=18.26041679999992" "currentPeriod.startTime=0" "reference.position=14" "reference.startTime=28.0106229" "reference.endTime=29.9946198" [Debug]: "(video:2)" "fetchAndAppend_:" "presentationTime=18.389416799999925" "currentPeriod.startTime=0" "reference.position=14" "reference.startTime=28.0280448" "reference.endTime=30.030048" [Debug]: "(audio:4)" "appending media segment" I0128 13:08:21.625051 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(audio:4)" "finished fetch and append" [Debug]: "(video:2)" "appending media segment" I0128 13:08:21.833359 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(video:2)" "finished fetch and append" [Debug]: "(video:2)" "fetchAndAppend_:" "presentationTime=20.148416799999953" "currentPeriod.startTime=0" "reference.position=15" "reference.startTime=30.030048" "reference.endTime=32.0320512" [Debug]: "(audio:4)" "fetchAndAppend_:" "presentationTime=20.442416799999965" "currentPeriod.startTime=0" "reference.position=15" "reference.startTime=29.9946198" "reference.endTime=31.99995" [Debug]: "(audio:4)" "appending media segment" I0128 13:08:23.735667 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(audio:4)" "finished fetch and append" [Debug]: "(video:2)" "appending media segment" I0128 13:08:23.758858 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(video:2)" "finished fetch and append" [Debug]: "(audio:4)" "fetchAndAppend_:" "presentationTime=22.061416799999996" "currentPeriod.startTime=0" "reference.position=16" "reference.startTime=31.99995" "reference.endTime=34.0052802" [Debug]: "(audio:4)" "appending media segment" I0128 13:08:25.398949 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(audio:4)" "finished fetch and append" [Debug]: "(video:2)" "fetchAndAppend_:" "presentationTime=22.606416800000016" "currentPeriod.startTime=0" "reference.position=16" "reference.startTime=32.0320512" "reference.endTime=34.0340544" [Debug]: "(video:2)" "appending media segment" I0128 13:08:26.005874 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(video:2)" "finished fetch and append" [Debug]: "(audio:4)" "fetchAndAppend_:" "presentationTime=24.232416800000042" "currentPeriod.startTime=0" "reference.position=17" "reference.startTime=34.0052802" "reference.endTime=36.0106104" [Debug]: "(video:2)" "fetchAndAppend_:" "presentationTime=24.31341680000005" "currentPeriod.startTime=0" "reference.position=17" "reference.startTime=34.0340544" "reference.endTime=36.0360576" [Log]: "Calling switch_(), bandwidth=12754 kbps" [Log]: "switch_" [Log]: "switch: Stream (video:2) already active" [Log]: "switch: Stream (audio:4) already active" [Debug]: "(audio:4)" "appending media segment" I0128 13:08:27.547976 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(audio:4)" "finished fetch and append" [Debug]: "(video:2)" "appending media segment" I0128 13:08:27.803874 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(video:2)" "finished fetch and append" [Debug]: "(video:2)" "fetchAndAppend_:" "presentationTime=26.125416800000075" "currentPeriod.startTime=0" "reference.position=18" "reference.startTime=36.0360576" "reference.endTime=38.0380608" [Debug]: "(video:2)" "appending media segment" [Debug]: "(audio:4)" "fetchAndAppend_:" "presentationTime=26.373416800000083" "currentPeriod.startTime=0" "reference.position=18" "reference.startTime=36.0106104" "reference.endTime=37.9946073" I0128 13:08:29.589700 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(video:2)" "finished fetch and append" [Debug]: "(audio:4)" "appending media segment" I0128 13:08:29.665668 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(audio:4)" "finished fetch and append" I0128 13:08:31.299470 1828859904 js_engine.cc:33] Begin GC run I0128 13:08:31.362771 1828859904 object_tracker.cc:143] Deleted 4321 object(s). I0128 13:08:31.368631 1828859904 js_engine.cc:44] End GC run [Debug]: "(video:2)" "fetchAndAppend_:" "presentationTime=28.40941680000011" "currentPeriod.startTime=0" "reference.position=19" "reference.startTime=38.0380608" "reference.endTime=40.040064" [Debug]: "(audio:4)" "fetchAndAppend_:" "presentationTime=28.48741680000011" "currentPeriod.startTime=0" "reference.position=19" "reference.startTime=37.9946073" "reference.endTime=39.9999375" [Debug]: "(audio:4)" "appending media segment" I0128 13:08:31.792181 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(audio:4)" "finished fetch and append" [Debug]: "(video:2)" "appending media segment" I0128 13:08:31.878803 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(video:2)" "finished fetch and append" [Debug]: "(audio:4)" "fetchAndAppend_:" "presentationTime=30.10841680000013" "currentPeriod.startTime=0" "reference.position=20" "reference.startTime=39.9999375" "reference.endTime=42.0052677" [Debug]: "(video:2)" "fetchAndAppend_:" "presentationTime=30.190416800000133" "currentPeriod.startTime=0" "reference.position=20" "reference.startTime=40.040064" "reference.endTime=42.0420672" [Debug]: "(audio:4)" "buffer behind too large:" "presentationTime=30.10841680000013" "bufferedBehind=30.10841680000013" "bufferBehind=30" "overflow=0.10841680000012843" [Debug]: "(audio:4)" "evicted 0.10841680000012843 seconds" [Debug]: "(audio:4)" "appending media segment" I0128 13:08:33.428331 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(audio:4)" "finished fetch and append" [Debug]: "(video:2)" "buffer behind too large:" "presentationTime=30.190416800000133" "bufferedBehind=30.107000000000134" "bufferBehind=30" "overflow=0.10700000000013432" [Debug]: "(video:2)" "evicted 0.10700000000013432 seconds" [Debug]: "(video:2)" "appending media segment" I0128 13:08:33.591745 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(video:2)" "finished fetch and append" [Debug]: "(audio:4)" "fetchAndAppend_:" "presentationTime=32.26341680000017" "currentPeriod.startTime=0" "reference.position=21" "reference.startTime=42.0052677" "reference.endTime=44.0105979" [Log]: "Calling switch_(), bandwidth=12934 kbps" [Log]: "switch_" [Log]: "switch: Stream (video:2) already active" [Log]: "switch: Stream (audio:4) already active" [Debug]: "(audio:4)" "buffer behind too large:" "presentationTime=32.26341680000017" "bufferedBehind=32.135417000000174" "bufferBehind=30" "overflow=2.1354170000001744" [Debug]: "(audio:4)" "evicted 2.1354170000001744 seconds" [Debug]: "(audio:4)" "appending media segment" I0128 13:08:35.601097 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(audio:4)" "finished fetch and append" [Debug]: "(video:2)" "fetchAndAppend_:" "presentationTime=32.417416800000176" "currentPeriod.startTime=0" "reference.position=21" "reference.startTime=42.0420672" "reference.endTime=44.0440704" [Debug]: "(video:2)" "buffer behind too large:" "presentationTime=32.417416800000176" "bufferedBehind=30.331996800000177" "bufferBehind=30" "overflow=0.3319968000001765" [Debug]: "(video:2)" "evicted 0.3319968000001765 seconds" [Debug]: "(video:2)" "appending media segment" I0128 13:08:35.873154 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(video:2)" "finished fetch and append" [Debug]: "(video:2)" "fetchAndAppend_:" "presentationTime=34.18741680000027" "currentPeriod.startTime=0" "reference.position=22" "reference.startTime=44.0440704" "reference.endTime=46.0460736" [Debug]: "(audio:4)" "fetchAndAppend_:" "presentationTime=34.418416800000266" "currentPeriod.startTime=0" "reference.position=22" "reference.startTime=44.0105979" "reference.endTime=45.9945948" [Debug]: "(audio:4)" "buffer behind too large:" "presentationTime=34.418416800000266" "bufferedBehind=32.135753700000265" "bufferBehind=30" "overflow=2.135753700000265" [Debug]: "(audio:4)" "evicted 2.135753700000265 seconds" [Debug]: "(audio:4)" "appending media segment" I0128 13:08:37.716073 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(audio:4)" "finished fetch and append" [Debug]: "(video:2)" "buffer behind too large:" "presentationTime=34.18741680000027" "bufferedBehind=30.09999360000027" "bufferBehind=30" "overflow=0.09999360000027124" [Debug]: "(video:2)" "evicted 0.09999360000027124 seconds" [Debug]: "(video:2)" "appending media segment" I0128 13:08:37.730345 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(video:2)" "finished fetch and append" [Debug]: "(audio:4)" "fetchAndAppend_:" "presentationTime=36.03741680000036" "currentPeriod.startTime=0" "reference.position=23" "reference.startTime=45.9945948" "reference.endTime=47.999925" [Debug]: "(audio:4)" "buffer behind too large:" "presentationTime=36.03741680000036" "bufferedBehind=31.600090400000358" "bufferBehind=30" "overflow=1.600090400000358" [Debug]: "(audio:4)" "evicted 1.600090400000358 seconds" [Debug]: "(audio:4)" "appending media segment" I0128 13:08:39.384004 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(audio:4)" "finished fetch and append" [Debug]: "(video:2)" "fetchAndAppend_:" "presentationTime=36.56841680000037" "currentPeriod.startTime=0" "reference.position=23" "reference.startTime=46.0460736" "reference.endTime=48.0480768" [Debug]: "(video:2)" "buffer behind too large:" "presentationTime=36.56841680000037" "bufferedBehind=30.47899040000037" "bufferBehind=30" "overflow=0.4789904000003702" [Debug]: "(video:2)" "evicted 0.4789904000003702 seconds" [Debug]: "(video:2)" "appending media segment" I0128 13:08:40.050402 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(video:2)" "finished fetch and append" [Debug]: "(audio:4)" "fetchAndAppend_:" "presentationTime=38.21241680000048" "currentPeriod.startTime=0" "reference.position=24" "reference.startTime=47.999925" "reference.endTime=50.0052552" [Debug]: "(audio:4)" "buffer behind too large:" "presentationTime=38.21241680000048" "bufferedBehind=32.153759600000484" "bufferBehind=30" "overflow=2.153759600000484" [Debug]: "(audio:4)" "evicted 2.153759600000484 seconds" [Debug]: "(audio:4)" "appending media segment" I0128 13:08:41.547113 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(audio:4)" "finished fetch and append" [Debug]: "(video:2)" "fetchAndAppend_:" "presentationTime=38.372416800000494" "currentPeriod.startTime=0" "reference.position=24" "reference.startTime=48.0480768" "reference.endTime=50.05008" [Debug]: "(video:2)" "buffer behind too large:" "presentationTime=38.372416800000494" "bufferedBehind=30.280987200000496" "bufferBehind=30" "overflow=0.2809872000004958" [Debug]: "(video:2)" "evicted 0.2809872000004958 seconds" [Debug]: "(video:2)" "appending media segment" I0128 13:08:41.855943 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(video:2)" "finished fetch and append" [Debug]: "(video:2)" "fetchAndAppend_:" "presentationTime=40.174416800000564" "currentPeriod.startTime=0" "reference.position=25" "reference.startTime=50.05008" "reference.endTime=52.0520832" [Debug]: "(audio:4)" "fetchAndAppend_:" "presentationTime=40.36241680000056" "currentPeriod.startTime=0" "reference.position=25" "reference.startTime=50.0052552" "reference.endTime=52.0105854" [Log]: "Calling switch_(), bandwidth=13530 kbps" [Log]: "switch_" [Log]: "switch: Stream (video:2) already active" [Log]: "switch: Stream (audio:4) already active" [Debug]: "(audio:4)" "buffer behind too large:" "presentationTime=40.36241680000056" "bufferedBehind=32.14909630000056" "bufferBehind=30" "overflow=2.149096300000558" [Debug]: "(audio:4)" "evicted 2.149096300000558 seconds" [Debug]: "(audio:4)" "appending media segment" I0128 13:08:43.674150 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(audio:4)" "finished fetch and append" [Debug]: "(video:2)" "buffer behind too large:" "presentationTime=40.174416800000564" "bufferedBehind=30.080984000000562" "bufferBehind=30" "overflow=0.08098400000056216" [Debug]: "(video:2)" "evicted 0.08098400000056216 seconds" [Debug]: "(video:2)" "appending media segment" I0128 13:08:43.724279 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(video:2)" "finished fetch and append" [Debug]: "(audio:4)" "fetchAndAppend_:" "presentationTime=42.503416800000615" "currentPeriod.startTime=0" "reference.position=26" "reference.startTime=52.0105854" "reference.endTime=53.9945823" [Debug]: "(video:2)" "fetchAndAppend_:" "presentationTime=42.54441680000061" "currentPeriod.startTime=0" "reference.position=26" "reference.startTime=52.0520832" "reference.endTime=54.0540864" [Debug]: "(audio:4)" "buffer behind too large:" "presentationTime=42.503416800000615" "bufferedBehind=32.13543300000062" "bufferBehind=30" "overflow=2.135433000000617" [Debug]: "(audio:4)" "evicted 2.135433000000617 seconds" [Debug]: "(audio:4)" "appending media segment" I0128 13:08:45.859935 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(audio:4)" "finished fetch and append" [Debug]: "(video:2)" "buffer behind too large:" "presentationTime=42.54441680000061" "bufferedBehind=30.448980800000612" "bufferBehind=30" "overflow=0.44898080000061213" [Debug]: "(video:2)" "evicted 0.44898080000061213 seconds" [Debug]: "(video:2)" "appending media segment" I0128 13:08:45.961604 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(video:2)" "finished fetch and append" [Debug]: "(audio:4)" "fetchAndAppend_:" "presentationTime=44.179416800000666" "currentPeriod.startTime=0" "reference.position=27" "reference.startTime=53.9945823" "reference.endTime=55.9999125" [Debug]: "(audio:4)" "buffer behind too large:" "presentationTime=44.179416800000666" "bufferedBehind=31.656769700000666" "bufferBehind=30" "overflow=1.656769700000666" [Debug]: "(audio:4)" "evicted 1.656769700000666 seconds" [Debug]: "(audio:4)" "appending media segment" [Debug]: "(video:2)" "fetchAndAppend_:" "presentationTime=44.27541680000068" "currentPeriod.startTime=0" "reference.position=27" "reference.startTime=54.0540864" "reference.endTime=56.0560896" I0128 13:08:47.494340 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(audio:4)" "finished fetch and append" [Debug]: "(video:2)" "buffer behind too large:" "presentationTime=44.27541680000068" "bufferedBehind=30.177977600000677" "bufferBehind=30" "overflow=0.17797760000067697" [Debug]: "(video:2)" "evicted 0.17797760000067697 seconds" [Debug]: "(video:2)" "appending media segment" I0128 13:08:47.790048 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(video:2)" "finished fetch and append" [Debug]: "(audio:4)" "fetchAndAppend_:" "presentationTime=46.31341680000078" "currentPeriod.startTime=0" "reference.position=28" "reference.startTime=55.9999125" "reference.endTime=58.0052427" [Debug]: "(audio:4)" "buffer behind too large:" "presentationTime=46.31341680000078" "bufferedBehind=32.12677230000078" "bufferBehind=30" "overflow=2.1267723000007805" [Debug]: "(audio:4)" "evicted 2.1267723000007805 seconds" [Debug]: "(audio:4)" "appending media segment" I0128 13:08:49.678869 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(audio:4)" "finished fetch and append" [Debug]: "(video:2)" "fetchAndAppend_:" "presentationTime=46.612416800000794" "currentPeriod.startTime=0" "reference.position=28" "reference.startTime=56.0560896" "reference.endTime=58.0580928" [Debug]: "(video:2)" "buffer behind too large:" "presentationTime=46.612416800000794" "bufferedBehind=30.512974400000793" "bufferBehind=30" "overflow=0.5129744000007932" [Debug]: "(video:2)" "evicted 0.5129744000007932 seconds" [Debug]: "(video:2)" "appending media segment" I0128 13:08:50.260653 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(video:2)" "finished fetch and append" [Debug]: "(audio:4)" "fetchAndAppend_:" "presentationTime=48.4944168000009" "currentPeriod.startTime=0" "reference.position=29" "reference.startTime=58.0052427" "reference.endTime=60.0105729" [Debug]: "(video:2)" "fetchAndAppend_:" "presentationTime=48.575416800000895" "currentPeriod.startTime=0" "reference.position=29" "reference.startTime=58.0580928" "reference.endTime=60.060096" [Log]: "Calling switch_(), bandwidth=13042 kbps" [Log]: "switch_" [Log]: "switch: Stream (video:2) already active" [Log]: "switch: Stream (audio:4) already active" [Debug]: "(audio:4)" "buffer behind too large:" "presentationTime=48.4944168000009" "bufferedBehind=32.1744423000009" "bufferBehind=30" "overflow=2.174442300000898" [Debug]: "(audio:4)" "evicted 2.174442300000898 seconds" [Debug]: "(audio:4)" "appending media segment" I0128 13:08:51.811816 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(audio:4)" "finished fetch and append" [Debug]: "(video:2)" "buffer behind too large:" "presentationTime=48.575416800000895" "bufferedBehind=30.473971200000896" "bufferBehind=30" "overflow=0.47397120000089643" [Debug]: "(video:2)" "evicted 0.47397120000089643 seconds" [Debug]: "(video:2)" "appending media segment" I0128 13:08:51.971040 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(video:2)" "finished fetch and append" [Debug]: "(audio:4)" "fetchAndAppend_:" "presentationTime=50.14041680000099" "currentPeriod.startTime=0" "reference.position=30" "reference.startTime=60.0105729" "reference.endTime=61.9945698" [Debug]: "(audio:4)" "buffer behind too large:" "presentationTime=50.14041680000099" "bufferedBehind=31.644445700000993" "bufferBehind=30" "overflow=1.6444457000009933" [Debug]: "(audio:4)" "evicted 1.6444457000009933 seconds" [Debug]: "(audio:4)" "appending media segment" I0128 13:08:53.460422 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(audio:4)" "finished fetch and append" [Debug]: "(video:2)" "fetchAndAppend_:" "presentationTime=50.289416800000986" "currentPeriod.startTime=0" "reference.position=30" "reference.startTime=60.060096" "reference.endTime=62.0620992" [Debug]: "(video:2)" "buffer behind too large:" "presentationTime=50.289416800000986" "bufferedBehind=30.185968000000987" "bufferBehind=30" "overflow=0.18596800000098668" [Debug]: "(video:2)" "evicted 0.18596800000098668 seconds" [Debug]: "(video:2)" "appending media segment" I0128 13:08:53.759141 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(video:2)" "finished fetch and append" [Debug]: "(audio:4)" "fetchAndAppend_:" "presentationTime=52.28141680000109" "currentPeriod.startTime=0" "reference.position=31" "reference.startTime=61.9945698" "reference.endTime=63.9999" [Debug]: "(audio:4)" "buffer behind too large:" "presentationTime=52.28141680000109" "bufferedBehind=32.12144830000109" "bufferBehind=30" "overflow=2.1214483000010915" [Debug]: "(audio:4)" "evicted 2.1214483000010915 seconds" [Debug]: "(audio:4)" "appending media segment" I0128 13:08:55.628859 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(audio:4)" "finished fetch and append" [Debug]: "(video:2)" "fetchAndAppend_:" "presentationTime=52.58541680000109" "currentPeriod.startTime=0" "reference.position=31" "reference.startTime=62.0620992" "reference.endTime=64.0641024" [Debug]: "(video:2)" "buffer behind too large:" "presentationTime=52.58541680000109" "bufferedBehind=30.479964800001092" "bufferBehind=30" "overflow=0.4799648000010919" [Debug]: "(video:2)" "evicted 0.4799648000010919 seconds" [Debug]: "(video:2)" "appending media segment" I0128 13:08:56.128033 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(video:2)" "finished fetch and append" [Debug]: "(video:2)" "fetchAndAppend_:" "presentationTime=54.44041680000121" "currentPeriod.startTime=0" "reference.position=32" "reference.startTime=64.0641024" "reference.endTime=66.0661056" [Debug]: "(audio:4)" "fetchAndAppend_:" "presentationTime=54.46741680000122" "currentPeriod.startTime=0" "reference.position=32" "reference.startTime=63.9999" "reference.endTime=66.0052302" [Debug]: "(audio:4)" "buffer behind too large:" "presentationTime=54.46741680000122" "bufferedBehind=32.17411830000122" "bufferBehind=30" "overflow=2.1741183000012185" [Debug]: "(audio:4)" "evicted 2.1741183000012185 seconds" [Debug]: "(audio:4)" "appending media segment" I0128 13:08:57.777338 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(audio:4)" "finished fetch and append" [Debug]: "(video:2)" "buffer behind too large:" "presentationTime=54.44041680000121" "bufferedBehind=30.33296160000121" "bufferBehind=30" "overflow=0.3329616000012088" [Debug]: "(video:2)" "evicted 0.3329616000012088 seconds" [Debug]: "(video:2)" "appending media segment" I0128 13:08:57.898383 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(video:2)" "finished fetch and append" [Debug]: "(audio:4)" "fetchAndAppend_:" "presentationTime=56.086416800001345" "currentPeriod.startTime=0" "reference.position=33" "reference.startTime=66.0052302" "reference.endTime=68.0105604" [Debug]: "(audio:4)" "buffer behind too large:" "presentationTime=56.086416800001345" "bufferedBehind=31.617121700001345" "bufferBehind=30" "overflow=1.617121700001345" [Debug]: "(audio:4)" "evicted 1.617121700001345 seconds" [Debug]: "(audio:4)" "appending media segment" [Debug]: "(video:2)" "fetchAndAppend_:" "presentationTime=56.20941680000135" "currentPeriod.startTime=0" "reference.position=33" "reference.startTime=66.0661056" "reference.endTime=68.0681088" I0128 13:08:59.426208 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(audio:4)" "finished fetch and append" [Debug]: "(video:2)" "buffer behind too large:" "presentationTime=56.20941680000135" "bufferedBehind=30.099958400001352" "bufferBehind=30" "overflow=0.09995840000135203" [Debug]: "(video:2)" "evicted 0.09995840000135203 seconds" [Debug]: "(video:2)" "appending media segment" I0128 13:08:59.700474 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(video:2)" "finished fetch and append" I0128 13:09:01.300720 1828859904 js_engine.cc:33] Begin GC run I0128 13:09:01.344861 1828859904 object_tracker.cc:143] Deleted 3458 object(s). I0128 13:09:01.351053 1828859904 js_engine.cc:44] End GC run [Debug]: "(audio:4)" "fetchAndAppend_:" "presentationTime=58.25241680000141" "currentPeriod.startTime=0" "reference.position=34" "reference.startTime=68.0105604" "reference.endTime=69.9945573" [Log]: "Calling switch_(), bandwidth=12923 kbps" [Log]: "switch_" [Log]: "switch: Stream (video:2) already active" [Log]: "switch: Stream (audio:4) already active" [Debug]: "(audio:4)" "buffer behind too large:" "presentationTime=58.25241680000141" "bufferedBehind=32.161790900001414" "bufferBehind=30" "overflow=2.1617909000014137" [Debug]: "(audio:4)" "evicted 2.1617909000014137 seconds" [Debug]: "(audio:4)" "appending media segment" I0128 13:09:01.587071 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(audio:4)" "finished fetch and append" [Debug]: "(video:2)" "fetchAndAppend_:" "presentationTime=58.52241680000141" "currentPeriod.startTime=0" "reference.position=34" "reference.startTime=68.0681088" "reference.endTime=70.070112" [Debug]: "(video:2)" "buffer behind too large:" "presentationTime=58.52241680000141" "bufferedBehind=30.41095520000141" "bufferBehind=30" "overflow=0.4109552000014105" [Debug]: "(video:2)" "evicted 0.4109552000014105 seconds" [Debug]: "(video:2)" "appending media segment" I0128 13:09:01.991466 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(video:2)" "finished fetch and append" [Debug]: "(video:2)" "fetchAndAppend_:" "presentationTime=60.312416800001515" "currentPeriod.startTime=0" "reference.position=35" "reference.startTime=70.070112" "reference.endTime=72.0721152" [Debug]: "(audio:4)" "fetchAndAppend_:" "presentationTime=60.39441680000152" "currentPeriod.startTime=0" "reference.position=35" "reference.startTime=69.9945573" "reference.endTime=71.9998875" [Debug]: "(audio:4)" "buffer behind too large:" "presentationTime=60.39441680000152" "bufferedBehind=32.127794300001526" "bufferBehind=30" "overflow=2.1277943000015256" [Debug]: "(audio:4)" "evicted 2.1277943000015256 seconds" [Debug]: "(audio:4)" "appending media segment" I0128 13:09:03.700807 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(audio:4)" "finished fetch and append" [Debug]: "(video:2)" "buffer behind too large:" "presentationTime=60.312416800001515" "bufferedBehind=30.198952000001515" "bufferBehind=30" "overflow=0.19895200000151547" [Debug]: "(video:2)" "evicted 0.19895200000151547 seconds" [Debug]: "(video:2)" "appending media segment" I0128 13:09:03.886950 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(video:2)" "finished fetch and append" [Debug]: "(audio:4)" "fetchAndAppend_:" "presentationTime=62.01941680000157" "currentPeriod.startTime=0" "reference.position=36" "reference.startTime=71.9998875" "reference.endTime=74.0052177" [Debug]: "(audio:4)" "buffer behind too large:" "presentationTime=62.01941680000157" "bufferedBehind=31.619464300001574" "bufferBehind=30" "overflow=1.6194643000015745" [Debug]: "(audio:4)" "evicted 1.6194643000015745 seconds" [Debug]: "(audio:4)" "appending media segment" I0128 13:09:05.332624 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(audio:4)" "finished fetch and append" [Debug]: "(video:2)" "fetchAndAppend_:" "presentationTime=62.195416800001574" "currentPeriod.startTime=0" "reference.position=36" "reference.startTime=72.0721152" "reference.endTime=74.0741184" [Debug]: "(video:2)" "buffer behind too large:" "presentationTime=62.195416800001574" "bufferedBehind=30.079948800001574" "bufferBehind=30" "overflow=0.07994880000157423" [Debug]: "(video:2)" "evicted 0.07994880000157423 seconds" [Debug]: "(video:2)" "appending media segment" I0128 13:09:05.630823 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(video:2)" "finished fetch and append" [Debug]: "(audio:4)" "fetchAndAppend_:" "presentationTime=64.14941680000167" "currentPeriod.startTime=0" "reference.position=37" "reference.startTime=74.0052177" "reference.endTime=76.0105479" [Debug]: "(audio:4)" "buffer behind too large:" "presentationTime=64.14941680000167" "bufferedBehind=32.128133500001674" "bufferBehind=30" "overflow=2.128133500001674" [Debug]: "(audio:4)" "evicted 2.128133500001674 seconds" [Debug]: "(audio:4)" "appending media segment" I0128 13:09:07.509066 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(audio:4)" "finished fetch and append" [Debug]: "(video:2)" "fetchAndAppend_:" "presentationTime=64.44241680000167" "currentPeriod.startTime=0" "reference.position=37" "reference.startTime=74.0741184" "reference.endTime=76.0761216" [Debug]: "(video:2)" "buffer behind too large:" "presentationTime=64.44241680000167" "bufferedBehind=30.32494560000167" "bufferBehind=30" "overflow=0.3249456000016693" [Debug]: "(video:2)" "evicted 0.3249456000016693 seconds" [Debug]: "(video:2)" "appending media segment" I0128 13:09:07.879263 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(video:2)" "finished fetch and append" [Debug]: "(video:2)" "fetchAndAppend_:" "presentationTime=66.19241680000171" "currentPeriod.startTime=0" "reference.position=38" "reference.startTime=76.0761216" "reference.endTime=78.0781248" [Debug]: "(audio:4)" "fetchAndAppend_:" "presentationTime=66.34741680000171" "currentPeriod.startTime=0" "reference.position=38" "reference.startTime=76.0105479" "reference.endTime=77.9945448" [Log]: "Calling switch_(), bandwidth=13621 kbps" [Log]: "switch_" [Log]: "switch: Stream (video:2) already active" [Log]: "switch: Stream (audio:4) already active" [Debug]: "(audio:4)" "buffer behind too large:" "presentationTime=66.34741680000171" "bufferedBehind=32.19280350000171" "bufferBehind=30" "overflow=2.192803500001709" [Debug]: "(video:2)" "buffer behind too large:" "presentationTime=66.19241680000171" "bufferedBehind=30.072942400001708" "bufferBehind=30" "overflow=0.0729424000017076" [Debug]: "(audio:4)" "evicted 2.192803500001709 seconds" [Debug]: "(audio:4)" "appending media segment" [Debug]: "(video:2)" "evicted 0.0729424000017076 seconds" [Debug]: "(video:2)" "appending media segment" I0128 13:09:09.656407 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(video:2)" "finished fetch and append" I0128 13:09:09.662465 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(audio:4)" "finished fetch and append" [Debug]: "(video:2)" "fetchAndAppend_:" "presentationTime=68.47341680000169" "currentPeriod.startTime=0" "reference.position=39" "reference.startTime=78.0781248" "reference.endTime=80.080128" [Debug]: "(audio:4)" "fetchAndAppend_:" "presentationTime=68.5024168000017" "currentPeriod.startTime=0" "reference.position=39" "reference.startTime=77.9945448" "reference.endTime=79.999875" [Debug]: "(audio:4)" "buffer behind too large:" "presentationTime=68.5024168000017" "bufferedBehind=32.150473600001696" "bufferBehind=30" "overflow=2.150473600001696" [Debug]: "(audio:4)" "evicted 2.150473600001696 seconds" [Debug]: "(audio:4)" "appending media segment" I0128 13:09:11.828907 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(audio:4)" "finished fetch and append" [Debug]: "(video:2)" "buffer behind too large:" "presentationTime=68.47341680000169" "bufferedBehind=30.351939200001688" "bufferBehind=30" "overflow=0.3519392000016879" [Debug]: "(video:2)" "evicted 0.3519392000016879 seconds" [Debug]: "(video:2)" "appending media segment" I0128 13:09:11.908710 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(video:2)" "finished fetch and append" [Debug]: "(audio:4)" "fetchAndAppend_:" "presentationTime=70.14041680000174" "currentPeriod.startTime=0" "reference.position=40" "reference.startTime=79.999875" "reference.endTime=82.0052052" [Debug]: "(video:2)" "fetchAndAppend_:" "presentationTime=70.22041680000174" "currentPeriod.startTime=0" "reference.position=40" "reference.startTime=80.080128" "reference.endTime=82.0821312" [Debug]: "(audio:4)" "buffer behind too large:" "presentationTime=70.14041680000174" "bufferedBehind=31.63381030000175" "bufferBehind=30" "overflow=1.6338103000017483" [Debug]: "(audio:4)" "evicted 1.6338103000017483 seconds" [Debug]: "(audio:4)" "appending media segment" I0128 13:09:13.458616 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(audio:4)" "finished fetch and append" [Debug]: "(video:2)" "buffer behind too large:" "presentationTime=70.22041680000174" "bufferedBehind=30.096936000001747" "bufferBehind=30" "overflow=0.0969360000017474" [Debug]: "(video:2)" "evicted 0.0969360000017474 seconds" [Debug]: "(video:2)" "appending media segment" I0128 13:09:13.737185 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(video:2)" "finished fetch and append" [Debug]: "(audio:4)" "fetchAndAppend_:" "presentationTime=72.2694168000017" "currentPeriod.startTime=0" "reference.position=41" "reference.startTime=82.0052052" "reference.endTime=84.0105354" [Debug]: "(audio:4)" "buffer behind too large:" "presentationTime=72.2694168000017" "bufferedBehind=32.12014620000169" "bufferBehind=30" "overflow=2.1201462000016917" [Debug]: "(audio:4)" "evicted 2.1201462000016917 seconds" [Debug]: "(audio:4)" "appending media segment" I0128 13:09:15.637810 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(audio:4)" "finished fetch and append" [Debug]: "(video:2)" "fetchAndAppend_:" "presentationTime=72.55941680000171" "currentPeriod.startTime=0" "reference.position=41" "reference.startTime=82.0821312" "reference.endTime=84.0841344" [Debug]: "(video:2)" "buffer behind too large:" "presentationTime=72.55941680000171" "bufferedBehind=30.433932800001713" "bufferBehind=30" "overflow=0.4339328000017133" [Debug]: "(video:2)" "evicted 0.4339328000017133 seconds" [Debug]: "(video:2)" "appending media segment" I0128 13:09:16.172122 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(video:2)" "finished fetch and append" [Debug]: "(audio:4)" "fetchAndAppend_:" "presentationTime=74.45141680000182" "currentPeriod.startTime=0" "reference.position=42" "reference.startTime=84.0105354" "reference.endTime=85.9945323" [Debug]: "(video:2)" "fetchAndAppend_:" "presentationTime=74.48441680000182" "currentPeriod.startTime=0" "reference.position=42" "reference.startTime=84.0841344" "reference.endTime=86.0861376" [Log]: "Calling switch_(), bandwidth=13141 kbps" [Log]: "switch_" [Log]: "switch: Stream (video:2) already active" [Log]: "switch: Stream (audio:4) already active" [Debug]: "(audio:4)" "buffer behind too large:" "presentationTime=74.45141680000182" "bufferedBehind=32.16881620000183" "bufferBehind=30" "overflow=2.168816200001828" [Debug]: "(audio:4)" "evicted 2.168816200001828 seconds" [Debug]: "(audio:4)" "appending media segment" I0128 13:09:17.785823 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(audio:4)" "finished fetch and append" [Debug]: "(video:2)" "buffer behind too large:" "presentationTime=74.48441680000182" "bufferedBehind=30.356929600001827" "bufferBehind=30" "overflow=0.35692960000182694" [Debug]: "(video:2)" "evicted 0.35692960000182694 seconds" [Debug]: "(video:2)" "appending media segment" I0128 13:09:17.913893 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(video:2)" "finished fetch and append" [Debug]: "(audio:4)" "fetchAndAppend_:" "presentationTime=76.1034168000019" "currentPeriod.startTime=0" "reference.position=43" "reference.startTime=85.9945323" "reference.endTime=87.9998625" [Debug]: "(video:2)" "fetchAndAppend_:" "presentationTime=76.22741680000189" "currentPeriod.startTime=0" "reference.position=43" "reference.startTime=86.0861376" "reference.endTime=88.0881408" [Debug]: "(audio:4)" "buffer behind too large:" "presentationTime=76.1034168000019" "bufferedBehind=31.644819600001895" "bufferBehind=30" "overflow=1.6448196000018953" [Debug]: "(audio:4)" "evicted 1.6448196000018953 seconds" [Debug]: "(audio:4)" "appending media segment" I0128 13:09:19.473592 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(audio:4)" "finished fetch and append" [Debug]: "(video:2)" "buffer behind too large:" "presentationTime=76.22741680000189" "bufferedBehind=30.097926400001896" "bufferBehind=30" "overflow=0.09792640000189579" [Debug]: "(video:2)" "evicted 0.09792640000189579 seconds" [Debug]: "(video:2)" "appending media segment" I0128 13:09:19.717757 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(video:2)" "finished fetch and append" [Debug]: "(audio:4)" "fetchAndAppend_:" "presentationTime=78.29741680000195" "currentPeriod.startTime=0" "reference.position=44" "reference.startTime=87.9998625" "reference.endTime=90.0051927" [Debug]: "(audio:4)" "buffer behind too large:" "presentationTime=78.29741680000195" "bufferedBehind=32.174822200001955" "bufferBehind=30" "overflow=2.1748222000019553" [Debug]: "(audio:4)" "evicted 2.1748222000019553 seconds" [Debug]: "(audio:4)" "appending media segment" I0128 13:09:21.676640 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(audio:4)" "finished fetch and append" [Debug]: "(video:2)" "fetchAndAppend_:" "presentationTime=78.52941680000195" "currentPeriod.startTime=0" "reference.position=44" "reference.startTime=88.0881408" "reference.endTime=90.090144" [Debug]: "(video:2)" "buffer behind too large:" "presentationTime=78.52941680000195" "bufferedBehind=30.397923200001955" "bufferBehind=30" "overflow=0.397923200001955" [Debug]: "(video:2)" "evicted 0.397923200001955 seconds" [Debug]: "(video:2)" "appending media segment" I0128 13:09:21.921816 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(video:2)" "finished fetch and append" [Debug]: "(video:2)" "fetchAndAppend_:" "presentationTime=80.23541680000199" "currentPeriod.startTime=0" "reference.position=45" "reference.startTime=90.090144" "reference.endTime=92.0921472" [Debug]: "(audio:4)" "fetchAndAppend_:" "presentationTime=80.49341680000204" "currentPeriod.startTime=0" "reference.position=45" "reference.startTime=90.0051927" "reference.endTime=92.0105229" [Debug]: "(video:2)" "buffer behind too large:" "presentationTime=80.23541680000199" "bufferedBehind=30.10192000000199" "bufferBehind=30" "overflow=0.10192000000198931" [Debug]: "(video:2)" "evicted 0.10192000000198931 seconds" [Debug]: "(video:2)" "appending media segment" I0128 13:09:23.728294 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(video:2)" "finished fetch and append" [Debug]: "(audio:4)" "buffer behind too large:" "presentationTime=80.49341680000204" "bufferedBehind=32.19482560000204" "bufferBehind=30" "overflow=2.1948256000020407" [Debug]: "(audio:4)" "evicted 2.1948256000020407 seconds" [Debug]: "(audio:4)" "appending media segment" I0128 13:09:23.789433 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(audio:4)" "finished fetch and append" [Debug]: "(audio:4)" "fetchAndAppend_:" "presentationTime=82.10441680000211" "currentPeriod.startTime=0" "reference.position=46" "reference.startTime=92.0105229" "reference.endTime=93.9945198" [Debug]: "(audio:4)" "buffer behind too large:" "presentationTime=82.10441680000211" "bufferedBehind=31.608495700002116" "bufferBehind=30" "overflow=1.6084957000021163" [Debug]: "(audio:4)" "evicted 1.6084957000021163 seconds" [Debug]: "(audio:4)" "appending media segment" I0128 13:09:25.418072 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(audio:4)" "finished fetch and append" [Debug]: "(video:2)" "fetchAndAppend_:" "presentationTime=82.54341680000213" "currentPeriod.startTime=0" "reference.position=46" "reference.startTime=92.0921472" "reference.endTime=94.0941504" [Log]: "Calling switch_(), bandwidth=13325 kbps" [Log]: "switch_" [Log]: "switch: Stream (video:2) already active" [Log]: "switch: Stream (audio:4) already active" [Debug]: "(video:2)" "buffer behind too large:" "presentationTime=82.54341680000213" "bufferedBehind=30.407916800002134" "bufferBehind=30" "overflow=0.40791680000213404" [Debug]: "(video:2)" "evicted 0.40791680000213404 seconds" [Debug]: "(video:2)" "appending media segment" I0128 13:09:26.072680 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(video:2)" "finished fetch and append" [Debug]: "(audio:4)" "fetchAndAppend_:" "presentationTime=84.2304168000022" "currentPeriod.startTime=0" "reference.position=47" "reference.startTime=93.9945198" "reference.endTime=95.99985" [Debug]: "(audio:4)" "buffer behind too large:" "presentationTime=84.2304168000022" "bufferedBehind=32.113164900002204" "bufferBehind=30" "overflow=2.1131649000022037" [Debug]: "(audio:4)" "evicted 2.1131649000022037 seconds" [Debug]: "(audio:4)" "appending media segment" I0128 13:09:27.566614 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(audio:4)" "finished fetch and append" [Debug]: "(video:2)" "fetchAndAppend_:" "presentationTime=84.37841680000219" "currentPeriod.startTime=0" "reference.position=47" "reference.startTime=94.0941504" "reference.endTime=96.0961536" [Debug]: "(video:2)" "buffer behind too large:" "presentationTime=84.37841680000219" "bufferedBehind=30.240913600002187" "bufferBehind=30" "overflow=0.24091360000218742" [Debug]: "(video:2)" "evicted 0.24091360000218742 seconds" [Debug]: "(video:2)" "appending media segment" I0128 13:09:27.871177 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(video:2)" "finished fetch and append" [Debug]: "(video:2)" "fetchAndAppend_:" "presentationTime=86.19641680000224" "currentPeriod.startTime=0" "reference.position=48" "reference.startTime=96.0961536" "reference.endTime=98.0981568" [Debug]: "(audio:4)" "fetchAndAppend_:" "presentationTime=86.37641680000226" "currentPeriod.startTime=0" "reference.position=48" "reference.startTime=95.99985" "reference.endTime=98.0051802" [Debug]: "(audio:4)" "buffer behind too large:" "presentationTime=86.37641680000226" "bufferedBehind=32.12583490000226" "bufferBehind=30" "overflow=2.1258349000022605" [Debug]: "(audio:4)" "evicted 2.1258349000022605 seconds" [Debug]: "(audio:4)" "appending media segment" I0128 13:09:29.675617 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(audio:4)" "finished fetch and append" [Debug]: "(video:2)" "buffer behind too large:" "presentationTime=86.19641680000224" "bufferedBehind=30.056910400002245" "bufferBehind=30" "overflow=0.0569104000022449" [Debug]: "(video:2)" "evicted 0.0569104000022449 seconds" [Debug]: "(video:2)" "appending media segment" I0128 13:09:29.751245 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(video:2)" "finished fetch and append" I0128 13:09:31.300952 1828859904 js_engine.cc:33] Begin GC run I0128 13:09:31.356005 1828859904 object_tracker.cc:143] Deleted 3469 object(s). I0128 13:09:31.361368 1828859904 js_engine.cc:44] End GC run [Debug]: "(audio:4)" "fetchAndAppend_:" "presentationTime=88.49741680000237" "currentPeriod.startTime=0" "reference.position=49" "reference.startTime=98.0051802" "reference.endTime=100.0105104" [Debug]: "(video:2)" "fetchAndAppend_:" "presentationTime=88.57741680000235" "currentPeriod.startTime=0" "reference.position=49" "reference.startTime=98.0981568" "reference.endTime=100.10016" [Debug]: "(audio:4)" "buffer behind too large:" "presentationTime=88.49741680000237" "bufferedBehind=32.113504900002376" "bufferBehind=30" "overflow=2.1135049000023756" [Debug]: "(audio:4)" "evicted 2.1135049000023756 seconds" [Debug]: "(audio:4)" "appending media segment" I0128 13:09:31.842515 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(audio:4)" "finished fetch and append" [Debug]: "(video:2)" "buffer behind too large:" "presentationTime=88.57741680000235" "bufferedBehind=30.435907200002355" "bufferBehind=30" "overflow=0.4359072000023545" [Debug]: "(video:2)" "evicted 0.4359072000023545 seconds" [Debug]: "(video:2)" "appending media segment" I0128 13:09:31.969871 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(video:2)" "finished fetch and append" [Debug]: "(audio:4)" "fetchAndAppend_:" "presentationTime=90.17141680000242" "currentPeriod.startTime=0" "reference.position=50" "reference.startTime=100.0105104" "reference.endTime=101.9945073" [Debug]: "(video:2)" "fetchAndAppend_:" "presentationTime=90.28441680000243" "currentPeriod.startTime=0" "reference.position=50" "reference.startTime=100.10016" "reference.endTime=102.1021632" [Debug]: "(audio:4)" "buffer behind too large:" "presentationTime=90.17141680000242" "bufferedBehind=31.654174900002424" "bufferBehind=30" "overflow=1.654174900002424" [Debug]: "(audio:4)" "evicted 1.654174900002424 seconds" [Debug]: "(audio:4)" "appending media segment" I0128 13:09:33.537472 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(audio:4)" "finished fetch and append" [Debug]: "(video:2)" "buffer behind too large:" "presentationTime=90.28441680000243" "bufferedBehind=30.140904000002436" "bufferBehind=30" "overflow=0.1409040000024362" [Debug]: "(video:2)" "evicted 0.1409040000024362 seconds" [Debug]: "(video:2)" "appending media segment" I0128 13:09:33.755827 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(video:2)" "finished fetch and append" [Debug]: "(audio:4)" "fetchAndAppend_:" "presentationTime=92.36541680000249" "currentPeriod.startTime=0" "reference.position=51" "reference.startTime=101.9945073" "reference.endTime=103.9998375" [Log]: "Calling switch_(), bandwidth=13917 kbps" [Log]: "switch_" [Log]: "switch: Stream (video:2) already active" [Log]: "switch: Stream (audio:4) already active" [Debug]: "(audio:4)" "buffer behind too large:" "presentationTime=92.36541680000249" "bufferedBehind=32.1841775000025" "bufferBehind=30" "overflow=2.184177500002498" [Debug]: "(audio:4)" "evicted 2.184177500002498 seconds" [Debug]: "(audio:4)" "appending media segment" I0128 13:09:35.713897 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(audio:4)" "finished fetch and append" [Debug]: "(video:2)" "fetchAndAppend_:" "presentationTime=92.58541680000253" "currentPeriod.startTime=0" "reference.position=51" "reference.startTime=102.1021632" "reference.endTime=104.1041664" [Debug]: "(video:2)" "buffer behind too large:" "presentationTime=92.58541680000253" "bufferedBehind=30.439900800002533" "bufferBehind=30" "overflow=0.4399008000025333" [Debug]: "(video:2)" "evicted 0.4399008000025333 seconds" [Debug]: "(video:2)" "appending media segment" I0128 13:09:35.984369 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(video:2)" "finished fetch and append" [Debug]: "(audio:4)" "fetchAndAppend_:" "presentationTime=94.02741680000256" "currentPeriod.startTime=0" "reference.position=52" "reference.startTime=103.9998375" "reference.endTime=106.0051677" [Debug]: "(audio:4)" "buffer behind too large:" "presentationTime=94.02741680000256" "bufferedBehind=31.64884760000256" "bufferBehind=30" "overflow=1.6488476000025614" [Debug]: "(audio:4)" "evicted 1.6488476000025614 seconds" [Debug]: "(audio:4)" "appending media segment" I0128 13:09:37.377812 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(audio:4)" "finished fetch and append" [Debug]: "(video:2)" "fetchAndAppend_:" "presentationTime=94.29941680000252" "currentPeriod.startTime=0" "reference.position=52" "reference.startTime=104.1041664" "reference.endTime=106.1061696" [Debug]: "(video:2)" "buffer behind too large:" "presentationTime=94.29941680000252" "bufferedBehind=30.151897600002528" "bufferBehind=30" "overflow=0.1518976000025276" [Debug]: "(video:2)" "evicted 0.1518976000025276 seconds" [Debug]: "(video:2)" "appending media segment" I0128 13:09:37.748558 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(video:2)" "finished fetch and append" [Debug]: "(audio:4)" "fetchAndAppend_:" "presentationTime=96.19041680000258" "currentPeriod.startTime=0" "reference.position=53" "reference.startTime=106.0051677" "reference.endTime=108.0104979" [Debug]: "(audio:4)" "buffer behind too large:" "presentationTime=96.19041680000258" "bufferedBehind=32.14785020000258" "bufferBehind=30" "overflow=2.14785020000258" [Debug]: "(audio:4)" "evicted 2.14785020000258 seconds" [Debug]: "(audio:4)" "appending media segment" I0128 13:09:39.601907 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(audio:4)" "finished fetch and append" [Debug]: "(video:2)" "fetchAndAppend_:" "presentationTime=96.58241680000263" "currentPeriod.startTime=0" "reference.position=53" "reference.startTime=106.1061696" "reference.endTime=108.1081728" [Debug]: "(video:2)" "buffer behind too large:" "presentationTime=96.58241680000263" "bufferedBehind=30.43289440000264" "bufferBehind=30" "overflow=0.43289440000263824" [Debug]: "(video:2)" "evicted 0.43289440000263824 seconds" [Debug]: "(video:2)" "appending media segment" I0128 13:09:40.001669 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(video:2)" "finished fetch and append" [Debug]: "(video:2)" "fetchAndAppend_:" "presentationTime=98.31341680000263" "currentPeriod.startTime=0" "reference.position=54" "reference.startTime=108.1081728" "reference.endTime=110.110176" [Debug]: "(audio:4)" "fetchAndAppend_:" "presentationTime=98.42041680000263" "currentPeriod.startTime=0" "reference.position=54" "reference.startTime=108.0104979" "reference.endTime=109.9944948" [Debug]: "(audio:4)" "buffer behind too large:" "presentationTime=98.42041680000263" "bufferedBehind=32.22318690000263" "bufferBehind=30" "overflow=2.2231869000026308" [Debug]: "(audio:4)" "evicted 2.2231869000026308 seconds" [Debug]: "(audio:4)" "appending media segment" I0128 13:09:41.731415 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(audio:4)" "finished fetch and append" [Debug]: "(video:2)" "buffer behind too large:" "presentationTime=98.31341680000263" "bufferedBehind=30.16189120000263" "bufferBehind=30" "overflow=0.16189120000262847" [Debug]: "(video:2)" "evicted 0.16189120000262847 seconds" [Debug]: "(video:2)" "appending media segment" I0128 13:09:41.764000 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(video:2)" "finished fetch and append" [Debug]: "(audio:4)" "fetchAndAppend_:" "presentationTime=100.04141680000266" "currentPeriod.startTime=0" "reference.position=55" "reference.startTime=109.9944948" "reference.endTime=111.999825" [Debug]: "(audio:4)" "buffer behind too large:" "presentationTime=100.04141680000266" "bufferedBehind=31.604190400002665" "bufferBehind=30" "overflow=1.6041904000026648" [Debug]: "(audio:4)" "evicted 1.6041904000026648 seconds" [Debug]: "(audio:4)" "appending media segment" I0128 13:09:43.354801 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(audio:4)" "finished fetch and append" [Debug]: "(video:2)" "fetchAndAppend_:" "presentationTime=100.5784168000027" "currentPeriod.startTime=0" "reference.position=55" "reference.startTime=110.110176" "reference.endTime=112.1121792" [Log]: "Calling switch_(), bandwidth=14049 kbps" [Log]: "switch_" [Log]: "switch: Stream (video:2) already active" [Log]: "switch: Stream (audio:4) already active" [Debug]: "(video:2)" "buffer behind too large:" "presentationTime=100.5784168000027" "bufferedBehind=30.424888000002696" "bufferBehind=30" "overflow=0.4248880000026958" [Debug]: "(video:2)" "evicted 0.4248880000026958 seconds" [Debug]: "(video:2)" "appending media segment" I0128 13:09:44.323719 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(video:2)" "finished fetch and append" [Debug]: "(audio:4)" "fetchAndAppend_:" "presentationTime=102.16541680000276" "currentPeriod.startTime=0" "reference.position=56" "reference.startTime=111.999825" "reference.endTime=114.0051552" [Debug]: "(audio:4)" "buffer behind too large:" "presentationTime=102.16541680000276" "bufferedBehind=32.106859600002764" "bufferBehind=30" "overflow=2.106859600002764" [Debug]: "(audio:4)" "evicted 2.106859600002764 seconds" [Debug]: "(audio:4)" "appending media segment" I0128 13:09:45.499588 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(audio:4)" "finished fetch and append" [Debug]: "(video:2)" "fetchAndAppend_:" "presentationTime=102.64741680000279" "currentPeriod.startTime=0" "reference.position=56" "reference.startTime=112.1121792" "reference.endTime=114.1141824" [Debug]: "(video:2)" "buffer behind too large:" "presentationTime=102.64741680000279" "bufferedBehind=30.491884800002794" "bufferBehind=30" "overflow=0.49188480000279355" [Debug]: "(video:2)" "evicted 0.49188480000279355 seconds" [Debug]: "(video:2)" "appending media segment" I0128 13:09:46.107120 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(video:2)" "finished fetch and append" [Debug]: "(audio:4)" "fetchAndAppend_:" "presentationTime=104.31141680000282" "currentPeriod.startTime=0" "reference.position=57" "reference.startTime=114.0051552" "reference.endTime=116.0104854" [Debug]: "(video:2)" "fetchAndAppend_:" "presentationTime=104.41041680000282" "currentPeriod.startTime=0" "reference.position=57" "reference.startTime=114.1141824" "reference.endTime=116.1161856" [Debug]: "(audio:4)" "buffer behind too large:" "presentationTime=104.31141680000282" "bufferedBehind=32.140862900002816" "bufferBehind=30" "overflow=2.1408629000028156" [Debug]: "(audio:4)" "evicted 2.1408629000028156 seconds" [Debug]: "(audio:4)" "appending media segment" I0128 13:09:47.648528 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(audio:4)" "finished fetch and append" [Debug]: "(video:2)" "buffer behind too large:" "presentationTime=104.41041680000282" "bufferedBehind=30.252881600002823" "bufferBehind=30" "overflow=0.2528816000028229" [Debug]: "(video:2)" "evicted 0.2528816000028229 seconds" [Debug]: "(video:2)" "appending media segment" I0128 13:09:47.901381 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(video:2)" "finished fetch and append" [Debug]: "(video:2)" "fetchAndAppend_:" "presentationTime=106.21441680000287" "currentPeriod.startTime=0" "reference.position=58" "reference.startTime=116.1161856" "reference.endTime=118.1181888" [Debug]: "(audio:4)" "fetchAndAppend_:" "presentationTime=106.4624168000029" "currentPeriod.startTime=0" "reference.position=58" "reference.startTime=116.0104854" "reference.endTime=117.9944823" [Debug]: "(audio:4)" "buffer behind too large:" "presentationTime=106.4624168000029" "bufferedBehind=32.1371996000029" "bufferBehind=30" "overflow=2.1371996000029014" [Debug]: "(audio:4)" "evicted 2.1371996000029014 seconds" [Debug]: "(audio:4)" "appending media segment" I0128 13:09:49.766461 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(audio:4)" "finished fetch and append" [Debug]: "(video:2)" "buffer behind too large:" "presentationTime=106.21441680000287" "bufferedBehind=30.054878400002863" "bufferBehind=30" "overflow=0.05487840000286326" [Debug]: "(video:2)" "evicted 0.05487840000286326 seconds" [Debug]: "(video:2)" "appending media segment" I0128 13:09:49.929967 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(video:2)" "finished fetch and append" [Debug]: "(audio:4)" "fetchAndAppend_:" "presentationTime=108.08641680000292" "currentPeriod.startTime=0" "reference.position=59" "reference.startTime=117.9944823" "reference.endTime=119.9998125" [Debug]: "(audio:4)" "buffer behind too large:" "presentationTime=108.08641680000292" "bufferedBehind=31.60653630000293" "bufferBehind=30" "overflow=1.6065363000029294" [Debug]: "(audio:4)" "evicted 1.6065363000029294 seconds" [Debug]: "(audio:4)" "appending media segment" I0128 13:09:51.439357 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(audio:4)" "finished fetch and append" [Debug]: "(video:2)" "fetchAndAppend_:" "presentationTime=108.25041680000294" "currentPeriod.startTime=0" "reference.position=59" "reference.startTime=118.1181888" "reference.endTime=120.120192" [Debug]: "(video:2)" "buffer behind too large:" "presentationTime=108.25041680000294" "bufferedBehind=30.088875200002946" "bufferBehind=30" "overflow=0.08887520000294558" [Debug]: "(video:2)" "evicted 0.08887520000294558 seconds" [Debug]: "(video:2)" "appending media segment" I0128 13:09:51.738701 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(video:2)" "finished fetch and append" [Debug]: "(audio:4)" "fetchAndAppend_:" "presentationTime=110.26541680000304" "currentPeriod.startTime=0" "reference.position=60" "reference.startTime=119.9998125" "reference.endTime=122.0051427" [Log]: "Calling switch_(), bandwidth=13763 kbps" [Log]: "switch_" [Log]: "switch: Stream (video:2) already active" [Log]: "switch: Stream (audio:4) already active" [Debug]: "(audio:4)" "buffer behind too large:" "presentationTime=110.26541680000304" "bufferedBehind=32.164205500003035" "bufferBehind=30" "overflow=2.1642055000030354" [Debug]: "(audio:4)" "evicted 2.1642055000030354 seconds" [Debug]: "(audio:4)" "appending media segment" I0128 13:09:53.613370 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(audio:4)" "finished fetch and append" [Debug]: "(video:2)" "fetchAndAppend_:" "presentationTime=110.56541680000308" "currentPeriod.startTime=0" "reference.position=60" "reference.startTime=120.120192" "reference.endTime=122.1221952" [Debug]: "(video:2)" "buffer behind too large:" "presentationTime=110.56541680000308" "bufferedBehind=30.40187200000308" "bufferBehind=30" "overflow=0.4018720000030811" [Debug]: "(video:2)" "evicted 0.4018720000030811 seconds" [Debug]: "(video:2)" "appending media segment" I0128 13:09:54.002924 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(video:2)" "finished fetch and append" [Debug]: "(video:2)" "fetchAndAppend_:" "presentationTime=112.3304168000032" "currentPeriod.startTime=0" "reference.position=61" "reference.startTime=122.1221952" "reference.endTime=124.1241984" [Debug]: "(audio:4)" "fetchAndAppend_:" "presentationTime=112.42141680000323" "currentPeriod.startTime=0" "reference.position=61" "reference.startTime=122.0051427" "reference.endTime=124.0104729" [Debug]: "(audio:4)" "buffer behind too large:" "presentationTime=112.42141680000323" "bufferedBehind=32.144208900003235" "bufferBehind=30" "overflow=2.1442089000032354" [Debug]: "(audio:4)" "evicted 2.1442089000032354 seconds" [Debug]: "(audio:4)" "appending media segment" I0128 13:09:55.742035 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(audio:4)" "finished fetch and append" [Debug]: "(video:2)" "buffer behind too large:" "presentationTime=112.3304168000032" "bufferedBehind=30.164868800003205" "bufferBehind=30" "overflow=0.16486880000320525" [Debug]: "(video:2)" "evicted 0.16486880000320525 seconds" [Debug]: "(video:2)" "appending media segment" I0128 13:09:55.856820 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(video:2)" "finished fetch and append" [Debug]: "(audio:4)" "fetchAndAppend_:" "presentationTime=114.04741680000323" "currentPeriod.startTime=0" "reference.position=62" "reference.startTime=124.0104729" "reference.endTime=125.9944698" [Debug]: "(audio:4)" "buffer behind too large:" "presentationTime=114.04741680000323" "bufferedBehind=31.61554560000323" "bufferBehind=30" "overflow=1.6155456000032302" [Debug]: "(audio:4)" "evicted 1.6155456000032302 seconds" [Debug]: "(audio:4)" "appending media segment" I0128 13:09:57.366705 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(audio:4)" "finished fetch and append" [Debug]: "(video:2)" "fetchAndAppend_:" "presentationTime=114.67841680000328" "currentPeriod.startTime=0" "reference.position=62" "reference.startTime=124.1241984" "reference.endTime=126.1262016" [Debug]: "(video:2)" "buffer behind too large:" "presentationTime=114.67841680000328" "bufferedBehind=30.510865600003285" "bufferBehind=30" "overflow=0.5108656000032852" [Debug]: "(video:2)" "evicted 0.5108656000032852 seconds" [Debug]: "(video:2)" "appending media segment" I0128 13:09:58.226696 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(video:2)" "finished fetch and append" [Debug]: "(audio:4)" "fetchAndAppend_:" "presentationTime=116.18641680000336" "currentPeriod.startTime=0" "reference.position=63" "reference.startTime=125.9944698" "reference.endTime=127.9998" [Debug]: "(audio:4)" "buffer behind too large:" "presentationTime=116.18641680000336" "bufferedBehind=32.13321480000336" "bufferBehind=30" "overflow=2.1332148000033584" [Debug]: "(audio:4)" "evicted 2.1332148000033584 seconds" [Debug]: "(audio:4)" "appending media segment" I0128 13:09:59.581544 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(audio:4)" "finished fetch and append" [Debug]: "(video:2)" "fetchAndAppend_:" "presentationTime=116.5424168000034" "currentPeriod.startTime=0" "reference.position=63" "reference.startTime=126.1262016" "reference.endTime=128.1282048" [Debug]: "(video:2)" "buffer behind too large:" "presentationTime=116.5424168000034" "bufferedBehind=30.3728624000034" "bufferBehind=30" "overflow=0.3728624000033989" [Debug]: "(video:2)" "evicted 0.3728624000033989 seconds" [Debug]: "(video:2)" "appending media segment" I0128 13:10:00.002897 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(video:2)" "finished fetch and append" I0128 13:10:01.301955 1828859904 js_engine.cc:33] Begin GC run I0128 13:10:01.348368 1828859904 object_tracker.cc:143] Deleted 3448 object(s). I0128 13:10:01.353670 1828859904 js_engine.cc:44] End GC run [Debug]: "(video:2)" "fetchAndAppend_:" "presentationTime=118.3194168000034" "currentPeriod.startTime=0" "reference.position=64" "reference.startTime=128.1282048" "reference.endTime=130.130208" [Debug]: "(audio:4)" "fetchAndAppend_:" "presentationTime=118.3994168000034" "currentPeriod.startTime=0" "reference.position=64" "reference.startTime=127.9998" "reference.endTime=130.0051302" [Log]: "Calling switch_(), bandwidth=13701 kbps" [Log]: "switch_" [Log]: "switch: Stream (video:2) already active" [Log]: "switch: Stream (audio:4) already active" [Debug]: "(audio:4)" "buffer behind too large:" "presentationTime=118.3994168000034" "bufferedBehind=32.212884800003394" "bufferBehind=30" "overflow=2.212884800003394" [Debug]: "(audio:4)" "evicted 2.212884800003394 seconds" [Debug]: "(audio:4)" "appending media segment" I0128 13:10:01.701035 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(audio:4)" "finished fetch and append" [Debug]: "(video:2)" "buffer behind too large:" "presentationTime=118.3194168000034" "bufferedBehind=30.147859200003396" "bufferBehind=30" "overflow=0.14785920000339559" [Debug]: "(video:2)" "evicted 0.14785920000339559 seconds" [Debug]: "(video:2)" "appending media segment" I0128 13:10:01.793757 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(video:2)" "finished fetch and append" [Debug]: "(audio:4)" "fetchAndAppend_:" "presentationTime=120.00741680000344" "currentPeriod.startTime=0" "reference.position=65" "reference.startTime=130.0051302" "reference.endTime=132.0104604" [Debug]: "(audio:4)" "buffer behind too large:" "presentationTime=120.00741680000344" "bufferedBehind=31.602221600003446" "bufferBehind=30" "overflow=1.6022216000034462" [Debug]: "(audio:4)" "evicted 1.6022216000034462 seconds" [Debug]: "(audio:4)" "appending media segment" I0128 13:10:03.359074 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(audio:4)" "finished fetch and append" [Debug]: "(video:2)" "fetchAndAppend_:" "presentationTime=120.61841680000349" "currentPeriod.startTime=0" "reference.position=65" "reference.startTime=130.130208" "reference.endTime=132.1322112" [Debug]: "(video:2)" "buffer behind too large:" "presentationTime=120.61841680000349" "bufferedBehind=30.444856000003497" "bufferBehind=30" "overflow=0.44485600000349734" [Debug]: "(video:2)" "evicted 0.44485600000349734 seconds" [Debug]: "(video:2)" "appending media segment" I0128 13:10:04.122507 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(video:2)" "finished fetch and append" [Debug]: "(audio:4)" "fetchAndAppend_:" "presentationTime=122.17541680000357" "currentPeriod.startTime=0" "reference.position=66" "reference.startTime=132.0104604" "reference.endTime=133.9944573" [Debug]: "(audio:4)" "buffer behind too large:" "presentationTime=122.17541680000357" "bufferedBehind=32.148890800003585" "bufferBehind=30" "overflow=2.148890800003585" [Debug]: "(audio:4)" "evicted 2.148890800003585 seconds" [Debug]: "(audio:4)" "appending media segment" I0128 13:10:05.507064 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(audio:4)" "finished fetch and append" [Debug]: "(video:2)" "fetchAndAppend_:" "presentationTime=122.42641680000355" "currentPeriod.startTime=0" "reference.position=66" "reference.startTime=132.1322112" "reference.endTime=134.1342144" [Debug]: "(video:2)" "buffer behind too large:" "presentationTime=122.42641680000355" "bufferedBehind=30.250852800003557" "bufferBehind=30" "overflow=0.2508528000035568" [Debug]: "(video:2)" "evicted 0.2508528000035568 seconds" [Debug]: "(video:2)" "appending media segment" I0128 13:10:05.762318 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(video:2)" "finished fetch and append" [Debug]: "(audio:4)" "fetchAndAppend_:" "presentationTime=124.34041680000352" "currentPeriod.startTime=0" "reference.position=67" "reference.startTime=133.9944573" "reference.endTime=135.9997875" [Debug]: "(audio:4)" "buffer behind too large:" "presentationTime=124.34041680000352" "bufferedBehind=32.159227500003524" "bufferBehind=30" "overflow=2.1592275000035244" [Debug]: "(audio:4)" "evicted 2.1592275000035244 seconds" [Debug]: "(audio:4)" "appending media segment" I0128 13:10:07.760076 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(audio:4)" "finished fetch and append" [Debug]: "(video:2)" "fetchAndAppend_:" "presentationTime=124.58741680000351" "currentPeriod.startTime=0" "reference.position=67" "reference.startTime=134.1342144" "reference.endTime=136.1362176" [Debug]: "(video:2)" "buffer behind too large:" "presentationTime=124.58741680000351" "bufferedBehind=30.40984960000351" "bufferBehind=30" "overflow=0.4098496000035112" [Debug]: "(video:2)" "evicted 0.4098496000035112 seconds" [Debug]: "(video:2)" "appending media segment" I0128 13:10:07.898577 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(video:2)" "finished fetch and append" [Debug]: "(audio:4)" "fetchAndAppend_:" "presentationTime=126.08541680000357" "currentPeriod.startTime=0" "reference.position=68" "reference.startTime=135.9997875" "reference.endTime=138.0051177" [Debug]: "(audio:4)" "buffer behind too large:" "presentationTime=126.08541680000357" "bufferedBehind=31.72823090000358" "bufferBehind=30" "overflow=1.728230900003581" [Debug]: "(audio:4)" "evicted 1.728230900003581 seconds" [Debug]: "(audio:4)" "appending media segment" I0128 13:10:09.503808 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(audio:4)" "finished fetch and append" [Debug]: "(video:2)" "fetchAndAppend_:" "presentationTime=126.7174168000036" "currentPeriod.startTime=0" "reference.position=68" "reference.startTime=136.1362176" "reference.endTime=138.1382208" [Log]: "Calling switch_(), bandwidth=12876 kbps" [Log]: "switch_" [Log]: "switch: Stream (video:2) already active" [Log]: "switch: Stream (audio:4) already active" [Debug]: "(video:2)" "buffer behind too large:" "presentationTime=126.7174168000036" "bufferedBehind=30.537846400003616" "bufferBehind=30" "overflow=0.537846400003616" [Debug]: "(video:2)" "evicted 0.537846400003616 seconds" [Debug]: "(video:2)" "appending media segment" I0128 13:10:10.172418 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(video:2)" "finished fetch and append" [Debug]: "(audio:4)" "fetchAndAppend_:" "presentationTime=128.32341680000363" "currentPeriod.startTime=0" "reference.position=69" "reference.startTime=138.0051177" "reference.endTime=140.0104479" [Debug]: "(audio:4)" "buffer behind too large:" "presentationTime=128.32341680000363" "bufferedBehind=32.21690030000363" "bufferBehind=30" "overflow=2.21690030000363" [Debug]: "(audio:4)" "evicted 2.21690030000363 seconds" [Debug]: "(audio:4)" "appending media segment" I0128 13:10:11.650554 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(audio:4)" "finished fetch and append" [Debug]: "(video:2)" "fetchAndAppend_:" "presentationTime=128.48241680000365" "currentPeriod.startTime=0" "reference.position=69" "reference.startTime=138.1382208" "reference.endTime=140.140224" [Debug]: "(video:2)" "buffer behind too large:" "presentationTime=128.48241680000365" "bufferedBehind=30.300843200003655" "bufferBehind=30" "overflow=0.30084320000365494" [Debug]: "(video:2)" "evicted 0.30084320000365494 seconds" [Debug]: "(video:2)" "appending media segment" I0128 13:10:11.971808 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(video:2)" "finished fetch and append" [Debug]: "(video:2)" "fetchAndAppend_:" "presentationTime=130.28441680000398" "currentPeriod.startTime=0" "reference.position=70" "reference.startTime=140.140224" "reference.endTime=142.1422272" [Debug]: "(audio:4)" "fetchAndAppend_:" "presentationTime=130.46341680000398" "currentPeriod.startTime=0" "reference.position=70" "reference.startTime=140.0104479" "reference.endTime=141.9944448" [Debug]: "(video:2)" "buffer behind too large:" "presentationTime=130.28441680000398" "bufferedBehind=30.100840000003984" "bufferBehind=30" "overflow=0.10084000000398419" [Debug]: "(video:2)" "evicted 0.10084000000398419 seconds" [Debug]: "(video:2)" "appending media segment" I0128 13:10:13.780659 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(video:2)" "finished fetch and append" [Debug]: "(audio:4)" "buffer behind too large:" "presentationTime=130.46341680000398" "bufferedBehind=32.13823710000399" "bufferBehind=30" "overflow=2.138237100003991" [Debug]: "(audio:4)" "evicted 2.138237100003991 seconds" [Debug]: "(audio:4)" "appending media segment" I0128 13:10:14.066182 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(audio:4)" "finished fetch and append" [Debug]: "(audio:4)" "fetchAndAppend_:" "presentationTime=132.37041680000422" "currentPeriod.startTime=0" "reference.position=71" "reference.startTime=141.9944448" "reference.endTime=143.999775" [Debug]: "(audio:4)" "buffer behind too large:" "presentationTime=132.37041680000422" "bufferedBehind=31.89057380000422" "bufferBehind=30" "overflow=1.8905738000042192" [Debug]: "(audio:4)" "evicted 1.8905738000042192 seconds" [Debug]: "(audio:4)" "appending media segment" I0128 13:10:15.687726 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(audio:4)" "finished fetch and append" [Debug]: "(video:2)" "fetchAndAppend_:" "presentationTime=132.59641680000428" "currentPeriod.startTime=0" "reference.position=71" "reference.startTime=142.1422272" "reference.endTime=144.1442304" [Debug]: "(video:2)" "buffer behind too large:" "presentationTime=132.59641680000428" "bufferedBehind=30.410836800004276" "bufferBehind=30" "overflow=0.4108368000042759" [Debug]: "(video:2)" "evicted 0.4108368000042759 seconds" [Debug]: "(video:2)" "appending media segment" I0128 13:10:16.041540 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(video:2)" "finished fetch and append" [Debug]: "(video:2)" "fetchAndAppend_:" "presentationTime=134.3584168000044" "currentPeriod.startTime=0" "reference.position=72" "reference.startTime=144.1442304" "reference.endTime=146.1462336" [Debug]: "(audio:4)" "fetchAndAppend_:" "presentationTime=134.4974168000044" "currentPeriod.startTime=0" "reference.position=72" "reference.startTime=143.999775" "reference.endTime=146.0051052" [Log]: "Calling switch_(), bandwidth=10320 kbps" [Log]: "switch_" [Log]: "switch: Stream (video:2) already active" [Log]: "switch: Stream (audio:4) already active" [Debug]: "(audio:4)" "buffer behind too large:" "presentationTime=134.4974168000044" "bufferedBehind=32.11891010000441" "bufferBehind=30" "overflow=2.1189101000044133" [Debug]: "(audio:4)" "evicted 2.1189101000044133 seconds" [Debug]: "(audio:4)" "appending media segment" I0128 13:10:18.103366 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(audio:4)" "finished fetch and append" [Debug]: "(video:2)" "buffer behind too large:" "presentationTime=134.3584168000044" "bufferedBehind=30.1708336000044" "bufferBehind=30" "overflow=0.17083360000439995" [Debug]: "(video:2)" "evicted 0.17083360000439995 seconds" [Debug]: "(video:2)" "appending media segment" I0128 13:10:18.232372 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(video:2)" "finished fetch and append" [Debug]: "(audio:4)" "fetchAndAppend_:" "presentationTime=136.40941680000478" "currentPeriod.startTime=0" "reference.position=73" "reference.startTime=146.0051052" "reference.endTime=148.0104354" [Debug]: "(video:2)" "fetchAndAppend_:" "presentationTime=136.54241680000476" "currentPeriod.startTime=0" "reference.position=73" "reference.startTime=146.1462336" "reference.endTime=148.1482368" [Debug]: "(audio:4)" "buffer behind too large:" "presentationTime=136.40941680000478" "bufferedBehind=31.897580100004788" "bufferBehind=30" "overflow=1.897580100004788" [Debug]: "(audio:4)" "evicted 1.897580100004788 seconds" [Debug]: "(audio:4)" "appending media segment" I0128 13:10:21.873821 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(audio:4)" "finished fetch and append" [Debug]: "(audio:4)" "fetchAndAppend_:" "presentationTime=138.66641680000507" "currentPeriod.startTime=0" "reference.position=74" "reference.startTime=148.0104354" "reference.endTime=149.9944323" [Debug]: "(audio:4)" "buffer behind too large:" "presentationTime=138.66641680000507" "bufferedBehind=32.25591640000508" "bufferBehind=30" "overflow=2.255916400005077" [Debug]: "(audio:4)" "evicted 2.255916400005077 seconds" [Debug]: "(audio:4)" "appending media segment" I0128 13:10:21.975766 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(audio:4)" "finished fetch and append" [Debug]: "(video:2)" "buffer behind too large:" "presentationTime=136.54241680000476" "bufferedBehind=30.352830400004763" "bufferBehind=30" "overflow=0.3528304000047626" [Debug]: "(video:2)" "evicted 0.3528304000047626 seconds" [Debug]: "(video:2)" "appending media segment" I0128 13:10:22.140630 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(video:2)" "finished fetch and append" [Debug]: "(video:2)" "fetchAndAppend_:" "presentationTime=138.93341680000506" "currentPeriod.startTime=0" "reference.position=74" "reference.startTime=148.1482368" "reference.endTime=150.15024" [Debug]: "(video:2)" "buffer behind too large:" "presentationTime=138.93341680000506" "bufferedBehind=30.741827200005062" "bufferBehind=30" "overflow=0.7418272000050621" [Debug]: "(video:2)" "evicted 0.7418272000050621 seconds" [Debug]: "(video:2)" "appending media segment" I0128 13:10:22.380379 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(video:2)" "finished fetch and append" [Debug]: "(audio:4)" "fetchAndAppend_:" "presentationTime=140.2784168000052" "currentPeriod.startTime=0" "reference.position=75" "reference.startTime=149.9944323" "reference.endTime=151.9997625" [Debug]: "(audio:4)" "buffer behind too large:" "presentationTime=140.2784168000052" "bufferedBehind=31.606586600005215" "bufferBehind=30" "overflow=1.6065866000052154" [Debug]: "(audio:4)" "evicted 1.6065866000052154 seconds" [Debug]: "(audio:4)" "appending media segment" I0128 13:10:23.633826 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(audio:4)" "finished fetch and append" [Debug]: "(video:2)" "fetchAndAppend_:" "presentationTime=140.6844168000052" "currentPeriod.startTime=0" "reference.position=75" "reference.startTime=150.15024" "reference.endTime=152.1522432" [Debug]: "(video:2)" "buffer behind too large:" "presentationTime=140.6844168000052" "bufferedBehind=30.49082400000522" "bufferBehind=30" "overflow=0.49082400000521886" [Debug]: "(video:2)" "evicted 0.49082400000521886 seconds" [Debug]: "(video:2)" "appending media segment" I0128 13:10:24.158326 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(video:2)" "finished fetch and append" 2020-01-28 13:10:24.572166+0100 sample_xcode_project[414:47397] <_UISystemGestureGateGestureRecognizer: 0x2834743c0>: Gesture: Failed to receive system gesture state notification before next touch 2020-01-28 13:10:24.572470+0100 sample_xcode_project[414:47397] <_UISystemGestureGateGestureRecognizer: 0x2834741e0>: Touch: Failed to receive system gesture state notification before next touch [Debug]: "(audio:4)" "fetchAndAppend_:" "presentationTime=142.44441680000543" "currentPeriod.startTime=0" "reference.position=76" "reference.startTime=151.9997625" "reference.endTime=153.028" [Debug]: "(video:2)" "fetchAndAppend_:" "presentationTime=142.4764168000054" "currentPeriod.startTime=0" "reference.position=76" "reference.startTime=152.1522432" "reference.endTime=153.028" [Debug]: "(audio:4)" "buffer behind too large:" "presentationTime=142.44441680000543" "bufferedBehind=32.15125580000543" "bufferBehind=30" "overflow=2.15125580000543" [Debug]: "(audio:4)" "evicted 2.15125580000543 seconds" [Debug]: "(audio:4)" "appending media segment" I0128 13:10:25.759275 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(audio:4)" "finished fetch and append" [Log]: "(audio:4)" "buffered to end of presentation" [Debug]: "(video:2)" "buffer behind too large:" "presentationTime=142.4764168000054" "bufferedBehind=30.280820800005415" "bufferBehind=30" "overflow=0.2808208000054151" [Debug]: "(video:2)" "evicted 0.2808208000054151 seconds" [Debug]: "(video:2)" "appending media segment" I0128 13:10:25.781970 1828859904 source_buffer.cc:207] Finish appending media segment: success [Debug]: "(video:2)" "finished fetch and append" [Log]: "(video:2)" "buffered to end of presentation" [Debug]: "(video:2)" "calling endOfStream()..." I0128 13:10:31.303905 1828859904 js_engine.cc:33] Begin GC run I0128 13:10:31.351135 1828859904 object_tracker.cc:143] Deleted 4718 object(s). I0128 13:10:31.356952 1828859904 js_engine.cc:44] End GC run I0128 13:10:36.237754 1834594304 ffmpeg_decoder.cc:140] Reconfiguring decoder I0128 13:10:36.238047 1834594304 ffmpeg_decoder.cc:257] No hardware-accelerators available, using decoder: aac_at I0128 13:11:01.304411 1828859904 js_engine.cc:33] Begin GC run I0128 13:11:01.357417 1828859904 object_tracker.cc:143] Deleted 4769 object(s). I0128 13:11:01.363940 1828859904 js_engine.cc:44] End GC run I0128 13:11:31.309440 1828859904 js_engine.cc:33] Begin GC run I0128 13:11:31.360498 1828859904 object_tracker.cc:143] Deleted 4945 object(s). I0128 13:11:31.367842 1828859904 js_engine.cc:44] End GC run I0128 13:12:01.310215 1828859904 js_engine.cc:33] Begin GC run I0128 13:12:01.361274 1828859904 object_tracker.cc:143] Deleted 4948 object(s). I0128 13:12:01.367846 1828859904 js_engine.cc:44] End GC run I0128 13:12:31.310648 1828859904 js_engine.cc:33] Begin GC run I0128 13:12:31.362766 1828859904 object_tracker.cc:143] Deleted 4947 object(s). I0128 13:12:31.369365 1828859904 js_engine.cc:44] End GC run I0128 13:13:01.311736 1828859904 js_engine.cc:33] Begin GC run I0128 13:13:01.363349 1828859904 object_tracker.cc:143] Deleted 4946 object(s). I0128 13:13:01.369402 1828859904 js_engine.cc:44] End GC run I0128 13:13:31.312322 1828859904 js_engine.cc:33] Begin GC run I0128 13:13:31.364066 1828859904 object_tracker.cc:143] Deleted 4945 object(s). I0128 13:13:31.370290 1828859904 js_engine.cc:44] End GC run I0128 13:14:01.313783 1828859904 js_engine.cc:33] Begin GC run I0128 13:14:01.372973 1828859904 object_tracker.cc:143] Deleted 4949 object(s). I0128 13:14:01.379643 1828859904 js_engine.cc:44] End GC run I0128 13:14:31.313859 1828859904 js_engine.cc:33] Begin GC run I0128 13:14:31.366871 1828859904 object_tracker.cc:143] Deleted 4946 object(s). I0128 13:14:31.373488 1828859904 js_engine.cc:44] End GC run I0128 13:15:01.315582 1828859904 js_engine.cc:33] Begin GC run I0128 13:15:01.374245 1828859904 object_tracker.cc:143] Deleted 4945 object(s). I0128 13:15:01.380846 1828859904 js_engine.cc:44] End GC run I0128 13:15:31.317616 1828859904 js_engine.cc:33] Begin GC run I0128 13:15:31.364425 1828859904 object_tracker.cc:143] Deleted 4949 object(s). I0128 13:15:31.371045 1828859904 js_engine.cc:44] End GC run I0128 13:16:01.318400 1828859904 js_engine.cc:33] Begin GC run I0128 13:16:01.376313 1828859904 object_tracker.cc:143] Deleted 4946 object(s). I0128 13:16:01.382974 1828859904 js_engine.cc:44] End GC run I0128 13:16:31.320418 1828859904 js_engine.cc:33] Begin GC run I0128 13:16:31.370781 1828859904 object_tracker.cc:143] Deleted 4949 object(s). I0128 13:16:31.377354 1828859904 js_engine.cc:44] End GC run ```
jgongo commented 4 years ago

By the way, what do you mean with variant? I have quickly reviewed the DASH specification and I haven't found any reference to this term. A Period? An AdaptationSet? A Representation?

jgongo commented 4 years ago

I've noticed that the audio randomly works (or stops working) after seeking, and tends to stop working when seeking to places that have already been played without sound.

TheModMaker commented 4 years ago

Variant is a term from HLS that refers to an audio+video pair. In DASH, you specify a list of video tracks and a list of audio tracks and we are free to pick any pair. In HLS, there are only specific pairs allowed. So the DASH parser converts to variants so we can support both.

jgongo commented 4 years ago

I see. So this in fact means that the player is working properly and have found an audio+video pair chosen from the specified representations.

joeyparrish commented 4 years ago

Yes, and it filtered out any audio-only variants, of which there were zero. Sorry for the confusing wording.

TheModMaker commented 4 years ago

I just pushed a change that fixes sometimes when the audio doesn't play. Please try again with the latest master.

jgongo commented 4 years ago

It seems to be working now. Thanks!