React-native-video: Video stops playing when streamed from remote server

Created on 22 Aug 2016  ·  32Comments  ·  Source: react-native-video/react-native-video

Hello,

I'm loading an mp4 from a remote server using the uri: syntax and the video with stop playing after about ~50%. The same video plays in it's entirety if loaded locally using the require syntax.

I'm using the latest version or react-native and the latest version of react-native-video. I am building an app for android only.

The video is 13mb in size.

Any idea why this might be happening?

Thanks

Android
Source
picture magicspon
👍14

Most helpful comment

I've come across this error also, the error is consistent but happens at different points during the video's duration. onError is called twice and returns:

{
  error: {
    extra:-1004,
    what:1
  }
}

A quick google search suggests that it's an error in Android's MediaPlayer (MEDIA_ERROR_IO).

picture adam-cowley on 7 Sep 2016
👍5

All 32 comments

Are there any errors thrown or in the console?

mattapperson picture mattapperson on 29 Aug 2016

Nope, nothing... just decides to stop.... sad panda!

magicspon picture magicspon on 29 Aug 2016

Can you share the URL you are using so we can try to repeat the issue?

mattapperson picture mattapperson on 29 Aug 2016

sure: https://player.vimeo.com/external/177625290.hd.mp4?s=49a8e7ed3bb0a6b023bf3c87cea2436567da8e6f&profile_id=174

magicspon picture magicspon on 29 Aug 2016

@magicismight is the onError event returning anything?

mattapperson picture mattapperson on 30 Aug 2016

I'm having a bit of a mare... I'm having problems getting remote debugging working so at this stage i can't tell... I'll create a new app and see if i can still replicate the issue... Thanks

magicspon picture magicspon on 30 Aug 2016

No errors. Example code (nothing else)

<Video source={{uri: "https://player.vimeo.com/external/177625290.hd.mp4?s=49a8e7ed3bb0a6b023bf3c87cea2436567da8e6f&profile_id=174"}}
                 style={styles.fullScreen}
                 rate={this.state.rate}
                 paused={this.state.paused}
                 volume={this.state.volume}
                 muted={this.state.muted}
                 resizeMode={this.state.resizeMode}
                 onLoad={this.onLoad}
                 onProgress={this.onProgress}
                 onEnd={() => { console.log('Done!') }}
                 onError={(err) => console.log(err)}
                 repeat={false} />
magicspon picture magicspon on 30 Aug 2016

I have the same issue and did a bit of debugging. Using logcat I found this warning right after it paused:

09-06 11:15:01.584 3753 28136 I NuPlayer: buffer low, pausing...

and after that it won't resume and it keeps logging:

09-06 11:21:22.609  3753 28627 D NuPlayerDriver: notifyListener_l(0xece2aba0), (3, 41, 0), loop setting(0, 1)
09-06 11:21:23.610  3753 28627 D NuPlayerDriver: notifyListener_l(0xece2aba0), (3, 41, 0), loop setting(0, 1)
09-06 11:21:24.613  3753 28627 D NuPlayerDriver: notifyListener_l(0xece2aba0), (3, 41, 0), loop setting(0, 1)
09-06 11:21:25.614  3753 28627 D NuPlayerDriver: notifyListener_l(0xece2aba0), (3, 41, 0), loop setting(0, 1)
09-06 11:21:26.615  3753 28627 D NuPlayerDriver: notifyListener_l(0xece2aba0), (3, 41, 0), loop setting(0, 1)
09-06 11:21:27.617  3753 28627 D NuPlayerDriver: notifyListener_l(0xece2aba0), (3, 41, 0), loop setting(0, 1)
cornedor picture cornedor on 6 Sep 2016

I've come across this error also, the error is consistent but happens at different points during the video's duration. onError is called twice and returns:

{
  error: {
    extra:-1004,
    what:1
  }
}

A quick google search suggests that it's an error in Android's MediaPlayer (MEDIA_ERROR_IO).

adam-cowley picture adam-cowley on 7 Sep 2016
👍5

I am also facing this issue on my Android 5.1 Lolipop mobile(Lenovo P1) but it is perfectly working in marshmallow(Oneplus 2) mobile.

itsmebins picture itsmebins on 18 Jun 2017

Has anyone been able to fix this? I'm also trying to stream from Vimeo. I was able to replicate it with a fresh project on React Native 0.47.2 and react-native-video 1.0.0. Tested on a Nexus 6P running Android 7.1.2. The video streams fine for about a minute and a half until it freezes. I've noticed this error occurs in logcat about 30 seconds before the freeze:

08-30 17:15:40.143 27418 27516 W MediaHTTPConnection: readAt 26509312 / 32768 => java.net.ProtocolException: unexpected end of stream

Here is the full log:

08-30 17:15:39.824   728 27690 D NuPlayerDriver: notifyListener_l(0xf5a2b740), (3, 11, 0), loop setting(0, 1)
08-30 17:15:39.846   728 27694 I NuCachedSource2: restarting prefetcher, totalSize = 5242880
08-30 17:15:40.143 27418 27516 W MediaHTTPConnection: readAt 26509312 / 32768 => java.net.ProtocolException: unexpected end of stream
08-30 17:15:40.143   728 27694 E NuCachedSource2: source returned error -1010, 0 retries left
08-30 17:15:59.052  2385  3272 I WifiHAL : Got channel list with 11 channels
08-30 17:15:59.054  2385  2482 I ProcessStatsService: Prepared write state in 14ms
08-30 17:15:59.057  2385  3272 I WifiHAL : Got channel list with 9 channels
08-30 17:15:59.059  2385  3272 I WifiHAL : Got channel list with 15 channels
08-30 17:16:39.828  5205  5205 I clatd   : Detecting NAT64 prefix from DNS...
08-30 17:16:39.831  5205  5205 E clatd   : plat_prefix/dns(ipv4only.arpa) status = 7/No address associated with hostname
08-30 17:16:39.831  5205  5205 W clatd   : dns64_detection -- error, sleeping for 120 seconds
08-30 17:17:01.916  5074 20702 I CastDatabase: Saving the database
08-30 17:17:01.917  5074 20702 I SQLiteCastStore: Saving 0 CastNetworkInfo instances, 0 CastDeviceInfo instances, 0 paired guest mode devices.
08-30 17:17:02.126  5074 20702 E SQLiteCastStore: End saving paired guest mode devices
08-30 17:17:50.831   728 27698 E NuPlayerDecoder: Stream error for OMX.qcom.video.decoder.avc (err=-1004), EOS successfully queued
08-30 17:17:50.831   728 27690 E NuPlayer: received error(0xfffffc14) from video decoder, flushing(0), now shutting down
08-30 17:17:50.832   728 27690 D NuPlayerDriver: notifyListener_l(0xf5a2b740), (100, 1, -1004), loop setting(0, 1)
08-30 17:17:50.832 27418 27529 E MediaPlayer: error (1, -1004)
08-30 17:17:50.836 27418 27418 E MediaPlayer: Error (1,-1004)
08-30 17:17:50.865   557   650 I OMX-VDEC-1080P: omx_vdec::component_deinit() complete
08-30 17:17:50.869   557   650 I OMX-VDEC-1080P: Exit OMX vdec Destructor: fd=8
08-30 17:17:50.874   728 27690 W AMessage: failed to post message as target looper for handler 0 is gone.
08-30 17:17:50.897   728 27690 D NuPlayerDriver: notifyListener_l(0xf5a2b740), (6, 0, 0), loop setting(0, 1)
08-30 17:17:50.904   728 27701 E NuPlayerDecoder: Stream error for OMX.google.aac.decoder (err=-1004), EOS successfully queued
08-30 17:17:50.905   728 27690 E NuPlayer: received error(0xfffffc14) from audio decoder, flushing(0), now shutting down
08-30 17:17:50.905   728 27690 D NuPlayerDriver: notifyListener_l(0xf5a2b740), (100, 1, -1004), loop setting(0, 1)
08-30 17:17:50.905   728 27701 E NuPlayerDecoder: Stream error for OMX.google.aac.decoder (err=-1004), EOS successfully queued
08-30 17:17:50.905   728 27690 E NuPlayer: received error(0xfffffc14) from audio decoder, flushing(2), now shutting down
08-30 17:17:50.905   728 27690 D NuPlayerDriver: notifyListener_l(0xf5a2b740), (100, 1, -1004), loop setting(0, 1)
08-30 17:17:50.905 27418 27530 E MediaPlayer: error (1, -1004)
08-30 17:17:50.905   728 27701 E NuPlayerDecoder: Stream error for OMX.google.aac.decoder (err=-1004), EOS successfully queued
08-30 17:17:50.905   728 27690 E NuPlayer: received error(0xfffffc14) from audio decoder, flushing(2), now shutting down
08-30 17:17:50.905   728 27690 D NuPlayerDriver: notifyListener_l(0xf5a2b740), (100, 1, -1004), loop setting(0, 1)
08-30 17:17:50.905 27418 27418 E MediaPlayer: Error (1,-1004)
08-30 17:17:50.906 27418 27530 E MediaPlayer: error (1, -1004)
08-30 17:17:50.906 27418 27530 E MediaPlayer: error (1, -1004)
08-30 17:17:50.907 27418 27418 E MediaPlayer: Error (1,-1004)
08-30 17:17:50.907 27418 27418 E MediaPlayer: Error (1,-1004)
08-30 17:17:50.913   728 27690 W AMessage: failed to post message as target looper for handler 0 is gone.
08-30 17:17:51.420   726   835 I AudioFlinger: BUFFER TIMEOUT: remove(4099) from active list on thread 0xeaf83100
08-30 17:17:51.530   726   835 D audio_hw_primary: disable_audio_route: usecase(1) reset and update mixer path: low-latency-playback speaker
08-30 17:17:51.547   726   835 D audio_hw_primary: disable_snd_device: snd_device(2: speaker)
jamealg picture jamealg on 30 Aug 2017

I have a client who is willing to pay for this bug fix. If anyone here knows of any Android developers willing to tackle this please let me know!

jamealg picture jamealg on 11 Sep 2017

Hi @jamealg, I'd be interested in taking the challenge. I'm an Android dev currently working full time on React Native.

carlos-peru picture carlos-peru on 15 Sep 2017

@carlos-peru Awesome. Let's talk over email so we don't blow up this thread. You can reach me at [email protected].

jamealg picture jamealg on 18 Sep 2017

Hey @carlos-peru are you still interested in this? I've been working with an Android developer to investigate some potential solutions but he doesn't believe the issue can be fixed without a significant overhaul of the library. I'm looking for a second opinion.

jamealg picture jamealg on 25 Sep 2017

Maybe you guys can share the fix ?

theanimashaun picture theanimashaun on 21 Oct 2017

@jamealg @carlos-peru

theanimashaun picture theanimashaun on 21 Oct 2017

@omoprodigi I haven't heard anything from Carlos. I haven't been able to find anyone who can actually fix the issue.

jamealg picture jamealg on 23 Oct 2017
😕1

a similar logcat segment, without removing any lines. I'm willing to pay for a fix too.

02-01 15:15:33.774: V/MediaPlayer-JNI(28572): getCurrentPosition: 9458 (msec)
02-01 15:15:33.810: D/NuCachedSource2(2554): Remaining (16433K), HighWaterThreshold (90631) [player]
02-01 15:15:33.816: D/WifiTrafficPoller(3058): notifyOnDataActivity() - mBusMaxLock.acquire()
02-01 15:15:33.837: D/NuCachedSource2(2554): Remaining (16497K), HighWaterThreshold (90631) [player]
02-01 15:15:33.863: D/NuCachedSource2(2554): Remaining (16561K), HighWaterThreshold (90631) [player]
02-01 15:15:33.872: I/NuCachedSource2(2554): readAt: mIsMetadataRetriever == 0
02-01 15:15:33.914: D/NuCachedSource2(2554): Remaining (80K), HighWaterThreshold (90631) [player]
02-01 15:15:33.914: E/NuCachedSource2(2554): read internal mExtractionCacheOffset =0, mExtractionCache->totalSize() = 0
02-01 15:15:33.914: D/NuCachedSource2(2554): seek : original code [player]
02-01 15:15:33.914: I/NuCachedSource2(2554): new range: offset= 19710137 [player]
02-01 15:15:34.017: D/MediaHTTPConnection(28572): setReadTimeout with 30000ms
02-01 15:15:34.018: I/System.out(28572): (HTTPLog)-Static: isSBSettingEnabled false
02-01 15:15:34.019: I/System.out(28572): (HTTPLog)-Static: isSBSettingEnabled false
02-01 15:15:34.023: D/EnterpriseController(2555): netId is 0
02-01 15:15:34.023: D/Netd(2555): getNetworkForDns: using netid 503 for uid 10388
02-01 15:15:34.023: D/DnsProxyListener(2555): DNSDBG::dns addrinfo af 2
02-01 15:15:34.026: V/MediaPlayer-JNI(28572): getCurrentPosition: 9710 (msec)
02-01 15:15:34.070: D/GenericSource(2554): cachedDurationUs = 0.00 secs, eos=0
02-01 15:15:34.070: I/GenericSource(2554): cache is running low (0.00 secs), pausing.
02-01 15:15:34.070: V/GenericSource(2554): [Flag] set 0x80 -> mFlags = 0x91
02-01 15:15:34.070: V/GenericSource(2554): [Flag] clear 0x1 -> mFlags = 0x90
02-01 15:15:34.070: V/NuPlayer(2554): kWhatSourceNotify with buffering-update for NuPlayer 33
02-01 15:15:34.070: V/NuPlayer(2554): msg : 3, ext1 : 33, ext2 : 0
02-01 15:15:34.070: D/NuPlayerDriver(2554): notifyListener_l(0xd277f180), (3, 33, 0), loop setting(0, 0)
02-01 15:15:34.070: V/MediaPlayerService(2554): [52] notify (0xedf3b500, 3, 33, 0)
02-01 15:15:34.070: D/NuPlayer(2554): post kWhatPause!
02-01 15:15:34.071: V/NuPlayer(2554): msg : 200, ext1 : 701, ext2 : 0
02-01 15:15:34.071: D/NuPlayerDriver(2554): notifyListener_l(0xd277f180), (200, 701, 0), loop setting(0, 0)
02-01 15:15:34.071: V/MediaPlayer(28572): message received msg=3, ext1=33, ext2=0
02-01 15:15:34.071: V/MediaPlayer(28572): buffering 33
02-01 15:15:34.071: V/MediaPlayerService(2554): [52] notify (0xedf3b500, 200, 701, 0)
02-01 15:15:34.071: V/MediaPlayer(28572): callback application
02-01 15:15:34.071: V/GenericSource(2554): pause
02-01 15:15:34.071: V/GenericSource(2554): [Flag] clear 0x1 -> mFlags = 0x90
02-01 15:15:34.071: V/MediaPlayer(28572): back from callback
02-01 15:15:34.071: I/NuPlayerRenderer(2554): onPause
02-01 15:15:34.071: I/MediaClock(2554): setPlaybackRate rate : 0.000000
02-01 15:15:34.071: V/MediaPlayer(28572): message received msg=200, ext1=701, ext2=0
02-01 15:15:34.071: V/AudioSink(2554): pause
02-01 15:15:34.071: W/MediaPlayer(28572): info/warning (701, 0)
02-01 15:15:34.071: V/MediaPlayer(28572): callback application
02-01 15:15:34.071: V/MediaPlayer(28572): back from callback
02-01 15:15:34.078: I/NuPlayerRenderer(2554): kWhatDrainVideoQueue generation is not matched generation : 1, mVideoDrainGeneration : 2
02-01 15:15:34.105: I/AudioSAEffectSecPlus(2538): EFFECT_CMD_SET_VOLUME
02-01 15:15:34.105: I/AudioSAEffectSecPlus(2538): command EFFECT_CMD_SET_VOLUME left = 0.000000, right =0.000000
02-01 15:15:34.105: D/AudioSAEffectSecPlus(2538): Don't apply mixer volume!
02-01 15:15:34.105: I/SoundBoosterEffectPlus(2538): SB_setVolume(0xe78a5e00)
02-01 15:15:34.105: I/SoundBoosterEffectPlus(2538): command EFFECT_CMD_SET_VOLUME left = 0.000000, right =0.000000
02-01 15:15:34.106: I/AudioSAEffectSecPlus(2538): EFFECT_CMD_SET_VOLUME
02-01 15:15:34.106: I/AudioSAEffectSecPlus(2538): command EFFECT_CMD_SET_VOLUME left = 0.000000, right =0.000000
02-01 15:15:34.106: D/AudioSAEffectSecPlus(2538): Don't apply mixer volume!
02-01 15:15:34.106: I/SoundBoosterEffectPlus(2538): SB_setVolume(0xe78a5ee0)
02-01 15:15:34.106: I/SoundBoosterEffectPlus(2538): command EFFECT_CMD_SET_VOLUME left = 0.000000, right =0.000000
02-01 15:15:34.205: I/SoundBoosterEffectPlus(2538): SB_reset SoundBooster sessionId(-3)
02-01 15:15:34.205: I/SoundBooster7(2538): BuffClear()
02-01 15:15:34.216: W/MySpaceEffect(2538): MySpaceEffect_setEnabled() MySpace is already disabled
02-01 15:15:34.217: D/MySpaceEffect(2538): EffectRelease : All effects are no longer instantiated
02-01 15:15:34.217: D/MySpace(2538): MySpace::Close 
02-01 15:15:34.217: D/MySpaceEffect(2538): MySpaceEffect::~MySpaceEffect 
02-01 15:15:34.217: D/MySpace(2538): MySpace::~MySpace() -
02-01 15:15:34.217: I/AudioEffectStage(2538): getBuffer primary_output_sub1_normal, 0xe788b000
02-01 15:15:34.217: I/AudioEffectStage(2538): getBuffer primary_output_sub1_normal
02-01 15:15:34.217: I/APM_AudioPolicyManager(2538): stopOutput() output 13, stream 3, session 793
02-01 15:15:34.217: I/APM_AudioPolicyManager(2538): write_logging() type(2) device(0x2) time(10) volume(11)
02-01 15:15:34.278: V/MediaPlayer-JNI(28572): getCurrentPosition: 9756 (msec)
02-01 15:15:34.398: I/MediaHTTPConnection(28572): response code = 206
02-01 15:15:34.529: V/MediaPlayer-JNI(28572): getCurrentPosition: 9756 (msec)
02-01 15:15:34.559: D/NuCachedSource2(2554): Remaining (64K), HighWaterThreshold (90631) [player]
02-01 15:15:34.559: E/NuCachedSource2(2554): read internal mExtractionCacheOffset =0, mExtractionCache->totalSize() = 0
02-01 15:15:34.559: D/NuCachedSource2(2554): seek : ignore. remaining = 65536 [player]
02-01 15:15:34.622: D/NuCachedSource2(2554): Remaining (128K), HighWaterThreshold (90631) [player]
02-01 15:15:34.622: E/NuCachedSource2(2554): read internal mExtractionCacheOffset =0, mExtractionCache->totalSize() = 0
02-01 15:15:34.622: D/NuCachedSource2(2554): seek : original code [player]
02-01 15:15:34.652: D/NuCachedSource2(2554): Remaining (192K), HighWaterThreshold (90631) [player]
02-01 15:15:34.691: D/NuCachedSource2(2554): Remaining (256K), HighWaterThreshold (90631) [player]
02-01 15:15:34.691: E/NuCachedSource2(2554): read internal mExtractionCacheOffset =0, mExtractionCache->totalSize() = 0
02-01 15:15:34.691: I/NuCachedSource2(2554): readAt: waiting end ( player case )
02-01 15:15:34.692: I/NuCachedSource2(2554): readAt: mIsMetadataRetriever == 0
02-01 15:15:34.717: D/NuCachedSource2(2554): Remaining (190K), HighWaterThreshold (90631) [player]
02-01 15:15:34.717: E/NuCachedSource2(2554): read internal mExtractionCacheOffset =0, mExtractionCache->totalSize() = 0
02-01 15:15:34.717: D/NuCachedSource2(2554): seek : original code [player]
02-01 15:15:34.718: I/NuCachedSource2(2554): new range: offset= 20273272 [player]
02-01 15:15:34.782: V/MediaPlayer-JNI(28572): getCurrentPosition: 9756 (msec)
02-01 15:15:34.816: D/WifiTrafficPoller(3058): notifyOnDataActivity() - mBusMaxLock.acquire()
02-01 15:15:34.820: D/MediaHTTPConnection(28572): setReadTimeout with 30000ms
02-01 15:15:34.821: I/System.out(28572): (HTTPLog)-Static: isSBSettingEnabled false
02-01 15:15:34.821: I/System.out(28572): (HTTPLog)-Static: isSBSettingEnabled false
02-01 15:15:35.024: D/wifi(3058): onLinkStatsResults: radio_stat.on_time = 8771721
02-01 15:15:35.024: D/wifi(3058): onLinkStatsResults: radio_stat.tx_time = 53092
02-01 15:15:35.024: D/wifi(3058): onLinkStatsResults: radio_stat.rx_time = 228596
02-01 15:15:35.024: D/wifi(3058): android_net_wifi_getLinkLayerStats: radio_stat.on_time = 8771721
02-01 15:15:35.024: D/wifi(3058): android_net_wifi_getLinkLayerStats: radio_stat.tx_time = 53092
02-01 15:15:35.024: D/wifi(3058): android_net_wifi_getLinkLayerStats: radio_stat.rx_time = 228596
02-01 15:15:35.030: D/WifiStateMachine(3058): Current network is: "Szemere2" , ID is: 5
02-01 15:15:35.030: D/WifiStateMachine(3058): Current network is still qualified due to heavy traffic, txSuccessRate=1543.523731648922 rxSuccessRate=0.0
02-01 15:15:35.031: D/WifiStateMachine(3058): Heavy traffic on current network (txSuccessRate=1543.523731648922 rxSuccessRate=0.0), bail out...
02-01 15:15:35.033: V/MediaPlayer-JNI(28572): getCurrentPosition: 9756 (msec)
02-01 15:15:35.070: V/GenericSource(2554): cached=19798.1KB (0.0s / req=8.0), Final Status=0, netBW=12886Kbps, BR=7250.5Kbps, score=0.0s
02-01 15:15:35.071: V/NuPlayer(2554): kWhatSourceNotify with buffering-update for NuPlayer 37
02-01 15:15:35.071: V/NuPlayer(2554): msg : 3, ext1 : 37, ext2 : 0
02-01 15:15:35.071: D/NuPlayerDriver(2554): notifyListener_l(0xd277f180), (3, 37, 0), loop setting(0, 0)
02-01 15:15:35.071: V/MediaPlayerService(2554): [52] notify (0xedf3b500, 3, 37, 0)
02-01 15:15:35.071: D/GenericSource(2554): cachedDurationUs = 0.00 secs, eos=0
02-01 15:15:35.071: V/MediaPlayer(28572): message received msg=3, ext1=37, ext2=0
02-01 15:15:35.071: V/MediaPlayer(28572): buffering 37
02-01 15:15:35.071: V/MediaPlayer(28572): callback application
02-01 15:15:35.071: V/MediaPlayer(28572): back from callback
02-01 15:15:35.285: V/MediaPlayer-JNI(28572): getCurrentPosition: 9756 (msec)
02-01 15:15:35.538: V/MediaPlayer-JNI(28572): getCurrentPosition: 9756 (msec)
02-01 15:15:35.543: D/EnterpriseController(2555): netId is 0
02-01 15:15:35.543: D/Netd(2555): getNetworkForDns: using netid 503 for uid 10388
02-01 15:15:35.543: D/DnsProxyListener(2555): DNSDBG::dns addrinfo af 2
02-01 15:15:35.791: V/MediaPlayer-JNI(28572): getCurrentPosition: 9756 (msec)
02-01 15:15:35.818: D/WifiTrafficPoller(3058): notifyOnDataActivity() - mBusMaxLock.acquire()
02-01 15:15:35.914: D/SSRM:j(3058): SIOP:: AP = 300, PST = 284 (W:26), BAT = 281, USB = 0, CHG = 0
02-01 15:15:36.043: V/MediaPlayer-JNI(28572): getCurrentPosition: 9756 (msec)
02-01 15:15:36.071: V/GenericSource(2554): cached=19798.1KB (0.0s / req=8.0), Final Status=0, netBW=12886Kbps, BR=7250.5Kbps, score=0.0s
02-01 15:15:36.071: D/GenericSource(2554): cachedDurationUs = 0.00 secs, eos=0
02-01 15:15:36.071: V/NuPlayer(2554): kWhatSourceNotify with buffering-update for NuPlayer 37
02-01 15:15:36.071: V/NuPlayer(2554): msg : 3, ext1 : 37, ext2 : 0
02-01 15:15:36.071: D/NuPlayerDriver(2554): notifyListener_l(0xd277f180), (3, 37, 0), loop setting(0, 0)
02-01 15:15:36.071: V/MediaPlayerService(2554): [52] notify (0xedf3b500, 3, 37, 0)
02-01 15:15:36.072: V/MediaPlayer(28572): message received msg=3, ext1=37, ext2=0
02-01 15:15:36.072: V/MediaPlayer(28572): buffering 37
02-01 15:15:36.072: V/MediaPlayer(28572): callback application
02-01 15:15:36.072: V/MediaPlayer(28572): back from callback
02-01 15:15:36.295: V/MediaPlayer-JNI(28572): getCurrentPosition: 9756 (msec)
02-01 15:15:36.329: D/WifiWatchdogStateMachine(3058):  [|205] []
02-01 15:15:36.359: I/MediaHTTPConnection(28572): response code = 206
02-01 15:15:36.503: D/io_stats(4132): !@ 179,0 r 926859 33969725 w 209450 4971928 d 17795 3097652 f 62660 62626 iot 692380 672466 th 51200 0 0 pt 0 inp 0 0 11487.125
02-01 15:15:36.523: D/NuCachedSource2(2554): Remaining (64K), HighWaterThreshold (90631) [player]
02-01 15:15:36.523: E/NuCachedSource2(2554): read internal mExtractionCacheOffset =0, mExtractionCache->totalSize() = 0
02-01 15:15:36.523: D/NuCachedSource2(2554): seek : ignore. remaining = 65536 [player]
02-01 15:15:36.547: V/MediaPlayer-JNI(28572): getCurrentPosition: 9756 (msec)
02-01 15:15:36.593: D/NuCachedSource2(2554): Remaining (128K), HighWaterThreshold (90631) [player]
02-01 15:15:36.593: E/NuCachedSource2(2554): read internal mExtractionCacheOffset =0, mExtractionCache->totalSize() = 0
02-01 15:15:36.593: D/NuCachedSource2(2554): seek : original code [player]
02-01 15:15:36.653: D/NuCachedSource2(2554): Remaining (192K), HighWaterThreshold (90631) [player]
02-01 15:15:36.653: E/NuCachedSource2(2554): read internal mExtractionCacheOffset =0, mExtractionCache->totalSize() = 0
02-01 15:15:36.653: I/NuCachedSource2(2554): readAt: waiting end ( player case )
02-01 15:15:36.653: I/NuCachedSource2(2554): readAt: mIsMetadataRetriever == 0
02-01 15:15:36.683: D/NuCachedSource2(2554): Remaining (126K), HighWaterThreshold (90631) [player]
02-01 15:15:36.684: E/NuCachedSource2(2554): read internal mExtractionCacheOffset =0, mExtractionCache->totalSize() = 0
02-01 15:15:36.684: D/NuCachedSource2(2554): seek : original code [player]
02-01 15:15:36.684: I/NuCachedSource2(2554): new range: offset= 20782641 [player]
02-01 15:15:36.786: D/MediaHTTPConnection(28572): setReadTimeout with 30000ms
02-01 15:15:36.787: I/System.out(28572): (HTTPLog)-Static: isSBSettingEnabled false
02-01 15:15:36.788: I/System.out(28572): (HTTPLog)-Static: isSBSettingEnabled false
02-01 15:15:36.792: D/EnterpriseController(2555): netId is 0
02-01 15:15:36.792: D/Netd(2555): getNetworkForDns: using netid 503 for uid 10388
02-01 15:15:36.792: D/DnsProxyListener(2555): DNSDBG::dns addrinfo af 2
02-01 15:15:36.798: V/MediaPlayer-JNI(28572): getCurrentPosition: 9756 (msec)
02-01 15:15:36.820: D/WifiTrafficPoller(3058): notifyOnDataActivity() - mBusMaxLock.acquire()
02-01 15:15:37.050: V/MediaPlayer-JNI(28572): getCurrentPosition: 9756 (msec)
02-01 15:15:37.072: V/GenericSource(2554): cached=20295.5KB (0.0s / req=8.0), Final Status=0, netBW=9042Kbps, BR=7250.5Kbps, score=0.0s
02-01 15:15:37.072: D/GenericSource(2554): cachedDurationUs = 0.00 secs, eos=0
02-01 15:15:37.072: V/NuPlayer(2554): kWhatSourceNotify with buffering-update for NuPlayer 38
02-01 15:15:37.072: V/NuPlayer(2554): msg : 3, ext1 : 38, ext2 : 0
02-01 15:15:37.072: D/NuPlayerDriver(2554): notifyListener_l(0xd277f180), (3, 38, 0), loop setting(0, 0)
02-01 15:15:37.072: V/MediaPlayerService(2554): [52] notify (0xedf3b500, 3, 38, 0)
02-01 15:15:37.072: V/MediaPlayer(28572): message received msg=3, ext1=38, ext2=0
02-01 15:15:37.072: V/MediaPlayer(28572): buffering 38
02-01 15:15:37.072: V/MediaPlayer(28572): callback application
02-01 15:15:37.072: V/MediaPlayer(28572): back from callback
02-01 15:15:37.116: D/audio_hw_spk_amp(2538): check_speaker_amp_off: turning_off_speaker_amp = true 
02-01 15:15:37.116: D/tfa98xxApp(2538): [NXP] tfa_enable: turning tfa off
02-01 15:15:37.116: D/tfa98xxApp(2538): [NXP] tfa_speakeroff: begin
02-01 15:15:37.116: D/tfa98xxApp(2538): [NXP] tfa_speakeroff: Loaded container file speaker_off /system/etc/Tfa9896.cnt.
02-01 15:15:37.116: D/tfa_dsp(2538): tfa_stop: device = 0, Stopping device [Left]
02-01 15:15:37.116: D/tfa_dsp(2538): [NXP] tfa_stop : device = 0, status = 0xd05e stop1 (running)
02-01 15:15:37.117: D/tfa_dsp(2538): [NXP] tfa_stop : device = 0, status = 0xd05e stop2 (soft mute)
02-01 15:15:37.149: D/tfa_dsp(2538): tfaRunMute: -------------------- muted --------------------
02-01 15:15:37.150: D/tfa_dsp(2538): [NXP] tfa_stop : device = 0, status = 0xc05e stop3 (mute amp)
02-01 15:15:37.150: D/tfa_dsp(2538): tfa98xx_powerdown: power off (PWDN=1)
02-01 15:15:37.150: D/tfa_dsp(2538): [NXP] tfa_stop : device = 0, status = 0x001c stop4 (power down)
02-01 15:15:37.151: D/tfa_dsp(2538): [NXP] tfa_stop : device = 0, status = 0x001c stop5
02-01 15:15:37.151: D/tfa98xxApp(2538): [NXP] tfa_speakeroff: device goes to stop success
02-01 15:15:37.151: D/tfa98xxApp(2538): [NXP] tfa_speakeroff: end
02-01 15:15:37.151: D/tfa98xxApp(2538): [NXP] tfa_enable: end
02-01 15:15:37.151: I/audio_hw_primary(2538): do_out_standby +
02-01 15:15:37.166: D/audio_hw_primary(2538): output_devices devices:0
02-01 15:15:37.166: D/audio_hw_primary(2538): output_devices devices:0
02-01 15:15:37.166: V/audio_hw_primary(2538): select_devices output_scenario:-1 input_scenario:-1 out_snd_device 0x0 in_snd_device:0x0
02-01 15:15:37.166: D/audio_hw_spk_amp(2538): check_speaker_amp_off: turning_off_speaker_amp = true 
02-01 15:15:37.166: D/tfa98xxApp(2538): [NXP] tfa_enable: turning tfa off
02-01 15:15:37.166: D/tfa98xxApp(2538): [NXP] tfa_enable: Tfa is already turned off
02-01 15:15:37.168: D/tfa_usrdrv(2538): [NXP] tfa_show_status: device = 0, status reg = 0x001c
02-01 15:15:37.168: D/tfa98xxApp(2538): [NXP] tfa_enable: end
02-01 15:15:37.168: I/audio_route(2538): > audio_route_reset :
02-01 15:15:37.168: D/audio_hw_spk_amp(2538): check_speaker_amp_off: turning_off_speaker_amp = true 
02-01 15:15:37.168: D/tfa98xxApp(2538): [NXP] tfa_enable: turning tfa off
02-01 15:15:37.168: D/tfa98xxApp(2538): [NXP] tfa_enable: Tfa is already turned off
02-01 15:15:37.169: D/tfa_usrdrv(2538): [NXP] tfa_show_status: device = 0, status reg = 0x001c
02-01 15:15:37.169: D/tfa98xxApp(2538): [NXP] tfa_enable: end
02-01 15:15:37.169: I/audio_route(2538): > audio_route_update_mixer : +
02-01 15:15:37.169: I/audio_route(2538): > audio_route_update_mixer : changed(6) -
02-01 15:15:37.169: I/audio_route(2538): > audio_route_update_mixer : +
02-01 15:15:37.169: I/audio_route(2538): > audio_route_update_mixer : changed(0) -
02-01 15:15:37.169: I/audio_hw_primary(2538): select_devices - 
02-01 15:15:37.169: I/audio_hw_primary(2538): do_out_standby -
02-01 15:15:37.302: V/MediaPlayer-JNI(28572): getCurrentPosition: 9756 (msec)
02-01 15:15:37.309: I/MediaHTTPConnection(28572): response code = 206
02-01 15:15:37.422: D/TelephonyManager(3058): getCellLocation : Caller (PID / UID / TID): 3058 / 1000 / 3058
02-01 15:15:37.422: D/NetworkController.MobileSignalController(0/1)(3425): onSignalStrengthsChanged signalStrength=SignalStrength: 11 99 -16 -200 -16 -200 -1 99 -90 -12 -20 2 2147483647 0x4000 gsm|lte level=4
02-01 15:15:37.422: D/NetworkController.MobileSignalController(0/1)(3425): getMobileIconGroup(): 13
02-01 15:15:37.490: D/NuCachedSource2(2554): Remaining (64K), HighWaterThreshold (90631) [player]
02-01 15:15:37.490: E/NuCachedSource2(2554): read internal mExtractionCacheOffset =0, mExtractionCache->totalSize() = 0
02-01 15:15:37.490: D/NuCachedSource2(2554): seek : ignore. remaining = 65536 [player]
02-01 15:15:37.553: D/EnterpriseController(2555): netId is 0
02-01 15:15:37.553: D/Netd(2555): getNetworkForDns: using netid 503 for uid 10388
02-01 15:15:37.553: D/DnsProxyListener(2555): DNSDBG::dns addrinfo af 2
02-01 15:15:37.554: V/MediaPlayer-JNI(28572): getCurrentPosition: 9756 (msec)
02-01 15:15:37.575: D/NuCachedSource2(2554): Remaining (128K), HighWaterThreshold (90631) [player]
02-01 15:15:37.575: E/NuCachedSource2(2554): read internal mExtractionCacheOffset =0, mExtractionCache->totalSize() = 0
02-01 15:15:37.575: D/NuCachedSource2(2554): seek : original code [player]
02-01 15:15:37.636: D/NuCachedSource2(2554): Remaining (192K), HighWaterThreshold (90631) [player]
02-01 15:15:37.636: E/NuCachedSource2(2554): read internal mExtractionCacheOffset =0, mExtractionCache->totalSize() = 0
02-01 15:15:37.637: I/NuCachedSource2(2554): readAt: waiting end ( player case )
02-01 15:15:37.637: I/NuCachedSource2(2554): readAt: mIsMetadataRetriever == 0
02-01 15:15:37.681: D/NuCachedSource2(2554): Remaining (123K), HighWaterThreshold (90631) [player]
02-01 15:15:37.681: E/NuCachedSource2(2554): read internal mExtractionCacheOffset =0, mExtractionCache->totalSize() = 0
02-01 15:15:37.681: D/NuCachedSource2(2554): seek : original code [player]
02-01 15:15:37.681: I/NuCachedSource2(2554): new range: offset= 21459227 [player]
02-01 15:15:37.783: D/MediaHTTPConnection(28572): setReadTimeout with 30000ms
02-01 15:15:37.784: I/System.out(28572): (HTTPLog)-Static: isSBSettingEnabled false
02-01 15:15:37.785: I/System.out(28572): (HTTPLog)-Static: isSBSettingEnabled false
02-01 15:15:37.806: V/MediaPlayer-JNI(28572): getCurrentPosition: 9756 (msec)
02-01 15:15:37.822: D/WifiTrafficPoller(3058): notifyOnDataActivity() - mBusMaxLock.acquire()
02-01 15:15:37.873: V/DeviceStorageMonitorService(3058): restatDataDir : /data= 273297/334560
02-01 15:15:37.873: V/DeviceStorageMonitorService(3058): freeMemory=1667350528
02-01 15:15:37.873: I/DeviceStorageMonitorService(3058): Posting Message again
02-01 15:15:38.032: D/wifi(3058): onLinkStatsResults: radio_stat.on_time = 8774135
02-01 15:15:38.032: D/wifi(3058): onLinkStatsResults: radio_stat.tx_time = 53189
02-01 15:15:38.032: D/wifi(3058): onLinkStatsResults: radio_stat.rx_time = 228868
02-01 15:15:38.033: D/wifi(3058): android_net_wifi_getLinkLayerStats: radio_stat.on_time = 8774135
02-01 15:15:38.033: D/wifi(3058): android_net_wifi_getLinkLayerStats: radio_stat.tx_time = 53189
02-01 15:15:38.033: D/wifi(3058): android_net_wifi_getLinkLayerStats: radio_stat.rx_time = 228868
02-01 15:15:38.039: D/WifiStateMachine(3058): Current network is: "Szemere2" , ID is: 5
02-01 15:15:38.039: D/WifiStateMachine(3058): Current network is still qualified due to heavy traffic, txSuccessRate=1279.761865824461 rxSuccessRate=0.0
02-01 15:15:38.039: D/WifiStateMachine(3058): Heavy traffic on current network (txSuccessRate=1279.761865824461 rxSuccessRate=0.0), bail out...
02-01 15:15:38.058: V/MediaPlayer-JNI(28572): getCurrentPosition: 9756 (msec)
02-01 15:15:38.072: V/GenericSource(2554): cached=20956.3KB (0.0s / req=8.0), Final Status=0, netBW=7837Kbps, BR=7250.5Kbps, score=0.0s
02-01 15:15:38.072: V/NuPlayer(2554): kWhatSourceNotify with buffering-update for NuPlayer 38
02-01 15:15:38.072: V/NuPlayer(2554): msg : 3, ext1 : 38, ext2 : 0
02-01 15:15:38.072: D/NuPlayerDriver(2554): notifyListener_l(0xd277f180), (3, 38, 0), loop setting(0, 0)
02-01 15:15:38.072: V/MediaPlayerService(2554): [52] notify (0xedf3b500, 3, 38, 0)
02-01 15:15:38.072: V/MediaPlayer(28572): message received msg=3, ext1=38, ext2=0
02-01 15:15:38.073: V/MediaPlayer(28572): buffering 38
02-01 15:15:38.073: V/MediaPlayer(28572): callback application
02-01 15:15:38.073: D/GenericSource(2554): cachedDurationUs = 0.00 secs, eos=0
02-01 15:15:38.077: V/MediaPlayer(28572): back from callback
02-01 15:15:38.240: I/MediaHTTPConnection(28572): response code = 206
02-01 15:15:38.310: V/MediaPlayer-JNI(28572): getCurrentPosition: 9756 (msec)
02-01 15:15:38.418: D/NuCachedSource2(2554): Remaining (64K), HighWaterThreshold (90631) [player]
02-01 15:15:38.418: E/NuCachedSource2(2554): read internal mExtractionCacheOffset =0, mExtractionCache->totalSize() = 0
02-01 15:15:38.418: D/NuCachedSource2(2554): seek : ignore. remaining = 65536 [player]
02-01 15:15:38.481: D/NuCachedSource2(2554): Remaining (128K), HighWaterThreshold (90631) [player]
02-01 15:15:38.482: E/NuCachedSource2(2554): read internal mExtractionCacheOffset =0, mExtractionCache->totalSize() = 0
02-01 15:15:38.482: D/NuCachedSource2(2554): seek : original code [player]
02-01 15:15:38.518: D/NuCachedSource2(2554): Remaining (192K), HighWaterThreshold (90631) [player]
02-01 15:15:38.545: D/NuCachedSource2(2554): Remaining (256K), HighWaterThreshold (90631) [player]
02-01 15:15:38.546: E/NuCachedSource2(2554): read internal mExtractionCacheOffset =0, mExtractionCache->totalSize() = 0
02-01 15:15:38.546: I/NuCachedSource2(2554): readAt: waiting end ( player case )
02-01 15:15:38.550: I/NuCachedSource2(2554): readAt: mIsMetadataRetriever == 0
02-01 15:15:38.561: V/MediaPlayer-JNI(28572): getCurrentPosition: 9756 (msec)
02-01 15:15:38.584: D/NuCachedSource2(2554): Remaining (190K), HighWaterThreshold (90631) [player]
02-01 15:15:38.584: E/NuCachedSource2(2554): read internal mExtractionCacheOffset =0, mExtractionCache->totalSize() = 0
02-01 15:15:38.584: D/NuCachedSource2(2554): seek : original code [player]
02-01 15:15:38.584: I/NuCachedSource2(2554): new range: offset= 2418998 [player]
02-01 15:15:38.685: D/MediaHTTPConnection(28572): setReadTimeout with 30000ms
02-01 15:15:38.686: I/System.out(28572): (HTTPLog)-Static: isSBSettingEnabled false
02-01 15:15:38.686: I/System.out(28572): (HTTPLog)-Static: isSBSettingEnabled false
02-01 15:15:38.813: V/MediaPlayer-JNI(28572): getCurrentPosition: 9756 (msec)
02-01 15:15:38.824: D/WifiTrafficPoller(3058): notifyOnDataActivity() - mBusMaxLock.acquire()
02-01 15:15:39.026: I/MediaHTTPConnection(28572): response code = 206
02-01 15:15:39.066: V/MediaPlayer-JNI(28572): getCurrentPosition: 9756 (msec)
02-01 15:15:39.073: V/GenericSource(2554): cached=2362.3KB (0.0s / req=8.0), Final Status=0, netBW=7015Kbps, BR=7250.5Kbps, score=0.0s
02-01 15:15:39.073: D/GenericSource(2554): cachedDurationUs = 0.00 secs, eos=0
02-01 15:15:39.073: V/NuPlayer(2554): kWhatSourceNotify with buffering-update for NuPlayer 4
02-01 15:15:39.073: V/NuPlayer(2554): msg : 3, ext1 : 4, ext2 : 0
02-01 15:15:39.074: D/NuPlayerDriver(2554): notifyListener_l(0xd277f180), (3, 4, 0), loop setting(0, 0)
02-01 15:15:39.074: V/MediaPlayerService(2554): [52] notify (0xedf3b500, 3, 4, 0)
02-01 15:15:39.074: V/MediaPlayer(28572): message received msg=3, ext1=4, ext2=0
02-01 15:15:39.074: V/MediaPlayer(28572): buffering 4
02-01 15:15:39.074: V/MediaPlayer(28572): callback application
02-01 15:15:39.075: V/MediaPlayer(28572): back from callback
02-01 15:15:39.196: D/NuCachedSource2(2554): Remaining (64K), HighWaterThreshold (90631) [player]
02-01 15:15:39.196: E/NuCachedSource2(2554): read internal mExtractionCacheOffset =0, mExtractionCache->totalSize() = 0
02-01 15:15:39.196: D/NuCachedSource2(2554): seek : ignore. remaining = 65536 [player]
02-01 15:15:39.276: D/NuCachedSource2(2554): Remaining (128K), HighWaterThreshold (90631) [player]
02-01 15:15:39.277: E/NuCachedSource2(2554): read internal mExtractionCacheOffset =0, mExtractionCache->totalSize() = 0
02-01 15:15:39.277: D/NuCachedSource2(2554): seek : original code [player]
02-01 15:15:39.317: V/MediaPlayer-JNI(28572): getCurrentPosition: 9756 (msec)
02-01 15:15:39.332: D/NuCachedSource2(2554): Remaining (192K), HighWaterThreshold (90631) [player]
02-01 15:15:39.332: E/NuCachedSource2(2554): read internal mExtractionCacheOffset =0, mExtractionCache->totalSize() = 0
02-01 15:15:39.332: I/NuCachedSource2(2554): readAt: waiting end ( player case )
02-01 15:15:39.332: I/NuCachedSource2(2554): readAt: mIsMetadataRetriever == 0
02-01 15:15:39.405: D/NuCachedSource2(2554): Remaining (64K), HighWaterThreshold (90631) [player]
02-01 15:15:39.405: E/NuCachedSource2(2554): read internal mExtractionCacheOffset =0, mExtractionCache->totalSize() = 0
02-01 15:15:39.405: I/NuCachedSource2(2554): readAt: waiting end ( player case )
02-01 15:15:39.406: I/NuCachedSource2(2554): readAt: mIsMetadataRetriever == 0
02-01 15:15:39.433: D/NuCachedSource2(2554): Remaining (64K), HighWaterThreshold (90631) [player]
02-01 15:15:39.433: E/NuCachedSource2(2554): read internal mExtractionCacheOffset =0, mExtractionCache->totalSize() = 0
02-01 15:15:39.433: I/NuCachedSource2(2554): readAt: waiting end ( player case )
02-01 15:15:39.435: I/NuCachedSource2(2554): readAt: mIsMetadataRetriever == 0
02-01 15:15:39.467: D/NuCachedSource2(2554): Remaining (109K), HighWaterThreshold (90631) [player]
02-01 15:15:39.467: E/NuCachedSource2(2554): read internal mExtractionCacheOffset =0, mExtractionCache->totalSize() = 0
02-01 15:15:39.467: I/NuCachedSource2(2554): readAt: waiting end ( player case )
02-01 15:15:39.467: I/NuCachedSource2(2554): readAt: mIsMetadataRetriever == 0
02-01 15:15:39.503: D/NuCachedSource2(2554): Remaining (108K), HighWaterThreshold (90631) [player]
02-01 15:15:39.503: E/NuCachedSource2(2554): read internal mExtractionCacheOffset =0, mExtractionCache->totalSize() = 0
02-01 15:15:39.503: I/NuCachedSource2(2554): readAt: waiting end ( player case )
02-01 15:15:39.504: I/NuCachedSource2(2554): readAt: mIsMetadataRetriever == 0
02-01 15:15:39.525: D/NuCachedSource2(2554): Remaining (108K), HighWaterThreshold (90631) [player]
02-01 15:15:39.525: E/NuCachedSource2(2554): read internal mExtractionCacheOffset =0, mExtractionCache->totalSize() = 0
02-01 15:15:39.525: I/NuCachedSource2(2554): readAt: waiting end ( player case )
02-01 15:15:39.528: I/NuCachedSource2(2554): readAt: mIsMetadataRetriever == 0
02-01 15:15:39.549: D/NuCachedSource2(2554): Remaining (94K), HighWaterThreshold (90631) [player]
02-01 15:15:39.549: E/NuCachedSource2(2554): read internal mExtractionCacheOffset =0, mExtractionCache->totalSize() = 0
02-01 15:15:39.549: I/NuCachedSource2(2554): readAt: waiting end ( player case )
02-01 15:15:39.549: I/NuCachedSource2(2554): readAt: mIsMetadataRetriever == 0
02-01 15:15:39.565: D/EnterpriseController(2555): netId is 0
02-01 15:15:39.565: D/Netd(2555): getNetworkForDns: using netid 503 for uid 10388
02-01 15:15:39.565: D/DnsProxyListener(2555): DNSDBG::dns addrinfo af 2
02-01 15:15:39.568: V/MediaPlayer-JNI(28572): getCurrentPosition: 9756 (msec)
02-01 15:15:39.576: D/NuCachedSource2(2554): Remaining (93K), HighWaterThreshold (90631) [player]
02-01 15:15:39.576: E/NuCachedSource2(2554): read internal mExtractionCacheOffset =0, mExtractionCache->totalSize() = 0
02-01 15:15:39.576: I/NuCachedSource2(2554): readAt: waiting end ( player case )
02-01 15:15:39.577: I/NuCachedSource2(2554): readAt: mIsMetadataRetriever == 0
02-01 15:15:39.604: D/NuCachedSource2(2554): Remaining (93K), HighWaterThreshold (90631) [player]
02-01 15:15:39.604: E/NuCachedSource2(2554): read internal mExtractionCacheOffset =0, mExtractionCache->totalSize() = 0
02-01 15:15:39.604: I/NuCachedSource2(2554): readAt: waiting end ( player case )
02-01 15:15:39.607: I/NuCachedSource2(2554): readAt: mIsMetadataRetriever == 0
02-01 15:15:39.629: D/NuCachedSource2(2554): Remaining (117K), HighWaterThreshold (90631) [player]
02-01 15:15:39.629: E/NuCachedSource2(2554): read internal mExtractionCacheOffset =0, mExtractionCache->totalSize() = 0
02-01 15:15:39.629: I/NuCachedSource2(2554): readAt: waiting end ( player case )
02-01 15:15:39.630: I/NuCachedSource2(2554): readAt: mIsMetadataRetriever == 0
02-01 15:15:39.649: D/NuCachedSource2(2554): Remaining (116K), HighWaterThreshold (90631) [player]
02-01 15:15:39.649: E/NuCachedSource2(2554): read internal mExtractionCacheOffset =0, mExtractionCache->totalSize() = 0
02-01 15:15:39.649: I/NuCachedSource2(2554): readAt: waiting end ( player case )
02-01 15:15:39.650: I/NuCachedSource2(2554): readAt: mIsMetadataRetriever == 0
02-01 15:15:39.682: D/NuCachedSource2(2554): Remaining (116K), HighWaterThreshold (90631) [player]
02-01 15:15:39.682: E/NuCachedSource2(2554): read internal mExtractionCacheOffset =0, mExtractionCache->totalSize() = 0
02-01 15:15:39.682: I/NuCachedSource2(2554): readAt: waiting end ( player case )
02-01 15:15:39.683: I/NuCachedSource2(2554): readAt: mIsMetadataRetriever == 0
02-01 15:15:39.704: D/NuCachedSource2(2554): Remaining (116K), HighWaterThreshold (90631) [player]
02-01 15:15:39.704: E/NuCachedSource2(2554): read internal mExtractionCacheOffset =0, mExtractionCache->totalSize() = 0
02-01 15:15:39.705: I/NuCachedSource2(2554): readAt: waiting end ( player case )
02-01 15:15:39.734: D/NuCachedSource2(2554): Remaining (120K), HighWaterThreshold (90631) [player]
02-01 15:15:39.775: D/NuCachedSource2(2554): Remaining (184K), HighWaterThreshold (90631) [player]
02-01 15:15:39.797: D/NuCachedSource2(2554): Remaining (248K), HighWaterThreshold (90631) [player]
02-01 15:15:39.820: V/MediaPlayer-JNI(28572): getCurrentPosition: 9756 (msec)
02-01 15:15:39.826: D/WifiTrafficPoller(3058): notifyOnDataActivity() - mBusMaxLock.acquire()
02-01 15:15:40.050: D/NuCachedSource2(2554): Remaining (312K), HighWaterThreshold (90631) [player]
02-01 15:15:40.059: D/NuCachedSource2(2554): Remaining (376K), HighWaterThreshold (90631) [player]
02-01 15:15:40.068: D/NuCachedSource2(2554): Remaining (440K), HighWaterThreshold (90631) [player]
02-01 15:15:40.072: V/MediaPlayer-JNI(28572): getCurrentPosition: 9756 (msec)
02-01 15:15:40.074: V/GenericSource(2554): cached=3706.3KB (0.5s / req=8.0), Final Status=0, netBW=6435Kbps, BR=7250.5Kbps, score=0.4s
02-01 15:15:40.074: V/NuPlayer(2554): kWhatSourceNotify with buffering-update for NuPlayer 4
02-01 15:15:40.074: V/NuPlayer(2554): msg : 3, ext1 : 4, ext2 : 0
02-01 15:15:40.074: D/NuPlayerDriver(2554): notifyListener_l(0xd277f180), (3, 4, 0), loop setting(0, 0)
02-01 15:15:40.074: V/MediaPlayerService(2554): [52] notify (0xedf3b500, 3, 4, 0)
02-01 15:15:40.074: V/MediaPlayer(28572): message received msg=3, ext1=4, ext2=0
02-01 15:15:40.074: V/MediaPlayer(28572): buffering 4
02-01 15:15:40.074: V/MediaPlayer(28572): callback application
02-01 15:15:40.075: V/MediaPlayer(28572): back from callback
02-01 15:15:40.075: D/GenericSource(2554): cachedDurationUs = 0.49 secs, eos=0
02-01 15:15:40.076: D/NuCachedSource2(2554): Remaining (504K), HighWaterThreshold (90631) [player]
02-01 15:15:40.087: D/NuCachedSource2(2554): Remaining (568K), HighWaterThreshold (90631) [player]
02-01 15:15:40.095: D/NuCachedSource2(2554): Remaining (632K), HighWaterThreshold (90631) [player]
02-01 15:15:40.104: D/NuCachedSource2(2554): Remaining (696K), HighWaterThreshold (90631) [player]
02-01 15:15:40.114: D/NuCachedSource2(2554): Remaining (760K), HighWaterThreshold (90631) [player]
02-01 15:15:40.139: D/NuCachedSource2(2554): Remaining (824K), HighWaterThreshold (90631) [player]
02-01 15:15:40.168: D/NuCachedSource2(2554): Remaining (888K), HighWaterThreshold (90631) [player]
02-01 15:15:40.197: D/NuCachedSource2(2554): Remaining (952K), HighWaterThreshold (90631) [player]
02-01 15:15:40.324: V/MediaPlayer-JNI(28572): getCurrentPosition: 9756 (msec)
02-01 15:15:40.576: V/MediaPlayer-JNI(28572): getCurrentPosition: 9756 (msec)
02-01 15:15:40.606: D/NuCachedSource2(2554): Remaining (1016K), HighWaterThreshold (90631) [player]
02-01 15:15:40.611: D/NuCachedSource2(2554): Remaining (1080K), HighWaterThreshold (90631) [player]
02-01 15:15:40.617: D/NuCachedSource2(2554): Remaining (1144K), HighWaterThreshold (90631) [player]
02-01 15:15:40.622: D/NuCachedSource2(2554): Remaining (1208K), HighWaterThreshold (90631) [player]
02-01 15:15:40.627: D/NuCachedSource2(2554): Remaining (1272K), HighWaterThreshold (90631) [player]
02-01 15:15:40.631: D/NuCachedSource2(2554): Remaining (1336K), HighWaterThreshold (90631) [player]
02-01 15:15:40.636: D/NuCachedSource2(2554): Remaining (1400K), HighWaterThreshold (90631) [player]
02-01 15:15:40.640: D/NuCachedSource2(2554): Remaining (1464K), HighWaterThreshold (90631) [player]
02-01 15:15:40.644: D/NuCachedSource2(2554): Remaining (1528K), HighWaterThreshold (90631) [player]
02-01 15:15:40.648: D/NuCachedSource2(2554): Remaining (1592K), HighWaterThreshold (90631) [player]
02-01 15:15:40.652: D/NuCachedSource2(2554): Remaining (1656K), HighWaterThreshold (90631) [player]
02-01 15:15:40.761: D/NuCachedSource2(2554): Remaining (1720K), HighWaterThreshold (90631) [player]
02-01 15:15:40.828: D/WifiTrafficPoller(3058): notifyOnDataActivity() - mBusMaxLock.acquire()
02-01 15:15:40.829: V/MediaPlayer-JNI(28572): getCurrentPosition: 9756 (msec)
02-01 15:15:40.865: D/NuCachedSource2(2554): Remaining (1784K), HighWaterThreshold (90631) [player]
02-01 15:15:40.922: D/NuCachedSource2(2554): Remaining (1848K), HighWaterThreshold (90631) [player]
02-01 15:15:40.985: D/NuCachedSource2(2554): Remaining (1912K), HighWaterThreshold (90631) [player]
02-01 15:15:41.015: D/NuCachedSource2(2554): Remaining (1976K), HighWaterThreshold (90631) [player]
02-01 15:15:41.041: D/wifi(3058): onLinkStatsResults: radio_stat.on_time = 8777073
02-01 15:15:41.041: D/wifi(3058): onLinkStatsResults: radio_stat.tx_time = 53387
02-01 15:15:41.041: D/wifi(3058): onLinkStatsResults: radio_stat.rx_time = 229502
02-01 15:15:41.042: D/wifi(3058): android_net_wifi_getLinkLayerStats: radio_stat.on_time = 8777073
02-01 15:15:41.042: D/wifi(3058): android_net_wifi_getLinkLayerStats: radio_stat.tx_time = 53387
02-01 15:15:41.042: D/wifi(3058): android_net_wifi_getLinkLayerStats: radio_stat.rx_time = 229502
02-01 15:15:41.048: D/WifiStateMachine(3058): Current network is: "Szemere2" , ID is: 5
02-01 15:15:41.048: D/WifiStateMachine(3058): Current network is still qualified due to heavy traffic, txSuccessRate=1596.3809329122305 rxSuccessRate=0.0
02-01 15:15:41.049: D/WifiStateMachine(3058): Heavy traffic on current network (txSuccessRate=1596.3809329122305 rxSuccessRate=0.0), bail out...
02-01 15:15:41.074: D/NuCachedSource2(2554): Remaining (2040K), HighWaterThreshold (90631) [player]
02-01 15:15:41.075: V/GenericSource(2554): cached=5306.3KB (2.3s / req=8.0), Final Status=0, netBW=6263Kbps, BR=7250.5Kbps, score=1.9s
02-01 15:15:41.076: V/NuPlayer(2554): kWhatSourceNotify with buffering-update for NuPlayer 10
02-01 15:15:41.076: V/NuPlayer(2554): msg : 3, ext1 : 10, ext2 : 0
02-01 15:15:41.076: D/NuPlayerDriver(2554): notifyListener_l(0xd277f180), (3, 10, 0), loop setting(0, 0)
02-01 15:15:41.076: V/MediaPlayerService(2554): [52] notify (0xedf3b500, 3, 10, 0)
02-01 15:15:41.076: D/GenericSource(2554): cachedDurationUs = 2.25 secs, eos=0
02-01 15:15:41.076: V/MediaPlayer(28572): message received msg=3, ext1=10, ext2=0
02-01 15:15:41.076: V/MediaPlayer(28572): buffering 10
02-01 15:15:41.076: V/MediaPlayer(28572): callback application
02-01 15:15:41.076: V/MediaPlayer(28572): back from callback
02-01 15:15:41.080: V/MediaPlayer-JNI(28572): getCurrentPosition: 9756 (msec)
02-01 15:15:41.096: D/NuCachedSource2(2554): Remaining (2104K), HighWaterThreshold (90631) [player]
02-01 15:15:41.155: D/NuCachedSource2(2554): Remaining (2168K), HighWaterThreshold (90631) [player]
02-01 15:15:41.176: D/NuCachedSource2(2554): Remaining (2232K), HighWaterThreshold (90631) [player]
02-01 15:15:41.205: D/NuCachedSource2(2554): Remaining (2296K), HighWaterThreshold (90631) [player]
02-01 15:15:41.233: D/NuCachedSource2(2554): Remaining (2360K), HighWaterThreshold (90631) [player]
02-01 15:15:41.259: D/NuCachedSource2(2554): Remaining (2424K), HighWaterThreshold (90631) [player]
02-01 15:15:41.286: D/NuCachedSource2(2554): Remaining (2488K), HighWaterThreshold (90631) [player]
02-01 15:15:41.318: D/NuCachedSource2(2554): Remaining (2552K), HighWaterThreshold (90631) [player]
02-01 15:15:41.332: V/MediaPlayer-JNI(28572): getCurrentPosition: 9756 (msec)
02-01 15:15:41.354: D/NuCachedSource2(2554): Remaining (2616K), HighWaterThreshold (90631) [player]
02-01 15:15:41.368: D/NuCachedSource2(2554): Remaining (2680K), HighWaterThreshold (90631) [player]
02-01 15:15:41.394: D/NuCachedSource2(2554): Remaining (2744K), HighWaterThreshold (90631) [player]
02-01 15:15:41.424: D/NuCachedSource2(2554): Remaining (2808K), HighWaterThreshold (90631) [player]
02-01 15:15:41.455: D/NuCachedSource2(2554): Remaining (2872K), HighWaterThreshold (90631) [player]
02-01 15:15:41.473: D/NuCachedSource2(2554): Remaining (2936K), HighWaterThreshold (90631) [player]
02-01 15:15:41.511: D/io_stats(4132): !@ 179,0 r 926860 33969789 w 209453 4971940 d 17795 3097652 f 62660 62626 iot 692380 672467 th 51200 0 0 pt 0 inp 0 0 11492.133
02-01 15:15:41.579: D/EnterpriseController(2555): netId is 0
02-01 15:15:41.579: D/Netd(2555): getNetworkForDns: using netid 503 for uid 10388
02-01 15:15:41.579: D/DnsProxyListener(2555): DNSDBG::dns addrinfo af 2
02-01 15:15:41.584: V/MediaPlayer-JNI(28572): getCurrentPosition: 9756 (msec)
02-01 15:15:41.740: D/NuCachedSource2(2554): Remaining (3000K), HighWaterThreshold (90631) [player]
02-01 15:15:41.749: D/NuCachedSource2(2554): Remaining (3064K), HighWaterThreshold (90631) [player]
02-01 15:15:41.758: D/NuCachedSource2(2554): Remaining (3128K), HighWaterThreshold (90631) [player]
02-01 15:15:41.764: D/NuCachedSource2(2554): Remaining (3192K), HighWaterThreshold (90631) [player]
02-01 15:15:41.828: D/WifiTrafficPoller(3058): notifyOnDataActivity() - mBusMaxLock.acquire()
02-01 15:15:41.837: V/MediaPlayer-JNI(28572): getCurrentPosition: 9756 (msec)
02-01 15:15:41.848: D/NuCachedSource2(2554): Remaining (3256K), HighWaterThreshold (90631) [player]
02-01 15:15:41.858: D/NuCachedSource2(2554): Remaining (3320K), HighWaterThreshold (90631) [player]
02-01 15:15:41.862: D/NuCachedSource2(2554): Remaining (3384K), HighWaterThreshold (90631) [player]
02-01 15:15:41.868: D/NuCachedSource2(2554): Remaining (3448K), HighWaterThreshold (90631) [player]
02-01 15:15:41.873: D/NuCachedSource2(2554): Remaining (3512K), HighWaterThreshold (90631) [player]
02-01 15:15:41.878: D/NuCachedSource2(2554): Remaining (3576K), HighWaterThreshold (90631) [player]
02-01 15:15:41.883: D/NuCachedSource2(2554): Remaining (3640K), HighWaterThreshold (90631) [player]
02-01 15:15:41.935: D/NuCachedSource2(2554): Remaining (3704K), HighWaterThreshold (90631) [player]
02-01 15:15:41.949: D/NuCachedSource2(2554): Remaining (3768K), HighWaterThreshold (90631) [player]
02-01 15:15:42.000: D/NuCachedSource2(2554): Remaining (3832K), HighWaterThreshold (90631) [player]
02-01 15:15:42.026: D/NuCachedSource2(2554): Remaining (3896K), HighWaterThreshold (90631) [player]
02-01 15:15:42.054: D/NuCachedSource2(2554): Remaining (3960K), HighWaterThreshold (90631) [player]
02-01 15:15:42.076: V/GenericSource(2554): cached=7226.3KB (4.4s / req=8.0), Final Status=0, netBW=6178Kbps, BR=7250.5Kbps, score=3.6s
02-01 15:15:42.076: D/GenericSource(2554): cachedDurationUs = 4.37 secs, eos=0
02-01 15:15:42.076: V/NuPlayer(2554): kWhatSourceNotify with buffering-update for NuPlayer 10
02-01 15:15:42.077: V/NuPlayer(2554): msg : 3, ext1 : 10, ext2 : 0
02-01 15:15:42.077: D/NuPlayerDriver(2554): notifyListener_l(0xd277f180), (3, 10, 0), loop setting(0, 0)
02-01 15:15:42.077: V/MediaPlayerService(2554): [52] notify (0xedf3b500, 3, 10, 0)
02-01 15:15:42.077: V/MediaPlayer(28572): message received msg=3, ext1=10, ext2=0
02-01 15:15:42.077: V/MediaPlayer(28572): buffering 10
02-01 15:15:42.077: V/MediaPlayer(28572): callback application
02-01 15:15:42.077: V/MediaPlayer(28572): back from callback
02-01 15:15:42.089: V/MediaPlayer-JNI(28572): getCurrentPosition: 9756 (msec)
02-01 15:15:42.094: D/NuCachedSource2(2554): Remaining (4024K), HighWaterThreshold (90631) [player]
02-01 15:15:42.119: D/NuCachedSource2(2554): Remaining (4088K), HighWaterThreshold (90631) [player]
02-01 15:15:42.148: D/NuCachedSource2(2554): Remaining (4152K), HighWaterThreshold (90631) [player]
02-01 15:15:42.209: D/NuCachedSource2(2554): Remaining (4216K), HighWaterThreshold (90631) [player]
02-01 15:15:42.231: D/NuCachedSource2(2554): Remaining (4280K), HighWaterThreshold (90631) [player]
02-01 15:15:42.269: D/NuCachedSource2(2554): Remaining (4344K), HighWaterThreshold (90631) [player]
02-01 15:15:42.315: D/NuCachedSource2(2554): Remaining (4408K), HighWaterThreshold (90631) [player]
02-01 15:15:42.340: V/MediaPlayer-JNI(28572): getCurrentPosition: 9756 (msec)
02-01 15:15:42.345: D/NuCachedSource2(2554): Remaining (4472K), HighWaterThreshold (90631) [player]
02-01 15:15:42.377: D/NuCachedSource2(2554): Remaining (4536K), HighWaterThreshold (90631) [player]
02-01 15:15:42.414: D/NuCachedSource2(2554): Remaining (4600K), HighWaterThreshold (90631) [player]
02-01 15:15:42.443: D/NuCachedSource2(2554): Remaining (4664K), HighWaterThreshold (90631) [player]
02-01 15:15:42.469: D/NuCachedSource2(2554): Remaining (4728K), HighWaterThreshold (90631) [player]
02-01 15:15:42.517: D/NuCachedSource2(2554): Remaining (4792K), HighWaterThreshold (90631) [player]
02-01 15:15:42.539: D/NuCachedSource2(2554): Remaining (4856K), HighWaterThreshold (90631) [player]
02-01 15:15:42.575: D/NuCachedSource2(2554): Remaining (4920K), HighWaterThreshold (90631) [player]
02-01 15:15:42.593: V/MediaPlayer-JNI(28572): getCurrentPosition: 9756 (msec)
02-01 15:15:42.609: D/NuCachedSource2(2554): Remaining (4984K), HighWaterThreshold (90631) [player]
02-01 15:15:42.636: D/NuCachedSource2(2554): Remaining (5048K), HighWaterThreshold (90631) [player]
02-01 15:15:42.658: D/NuCachedSource2(2554): Remaining (5112K), HighWaterThreshold (90631) [player]
02-01 15:15:42.685: D/NuCachedSource2(2554): Remaining (5176K), HighWaterThreshold (90631) [player]
02-01 15:15:42.740: D/NuCachedSource2(2554): Remaining (5240K), HighWaterThreshold (90631) [player]
02-01 15:15:42.770: D/NuCachedSource2(2554): Remaining (5304K), HighWaterThreshold (90631) [player]
02-01 15:15:42.791: D/NuCachedSource2(2554): Remaining (5368K), HighWaterThreshold (90631) [player]
02-01 15:15:42.830: D/WifiTrafficPoller(3058): notifyOnDataActivity() - mBusMaxLock.acquire()
02-01 15:15:42.839: D/NuCachedSource2(2554): Remaining (5432K), HighWaterThreshold (90631) [player]
02-01 15:15:42.844: V/MediaPlayer-JNI(28572): getCurrentPosition: 9756 (msec)
02-01 15:15:42.920: D/NuCachedSource2(2554): Remaining (5496K), HighWaterThreshold (90631) [player]
02-01 15:15:42.950: D/NuCachedSource2(2554): Remaining (5560K), HighWaterThreshold (90631) [player]
02-01 15:15:42.985: D/NuCachedSource2(2554): Remaining (5624K), HighWaterThreshold (90631) [player]
02-01 15:15:43.077: D/NuCachedSource2(2554): Remaining (5688K), HighWaterThreshold (90631) [player]
02-01 15:15:43.077: V/GenericSource(2554): cached=8890.3KB (6.2s / req=8.0), Final Status=0, netBW=12183Kbps, BR=7250.5Kbps, score=10.2s
02-01 15:15:43.077: I/GenericSource(2554): [REBUFF] mEarlyStart!
02-01 15:15:43.077: V/NuPlayer(2554): kWhatSourceNotify with buffering-update for NuPlayer 17
02-01 15:15:43.077: V/NuPlayer(2554): msg : 3, ext1 : 17, ext2 : 0
02-01 15:15:43.077: D/NuPlayerDriver(2554): notifyListener_l(0xd277f180), (3, 17, 0), loop setting(0, 0)
02-01 15:15:43.077: V/MediaPlayerService(2554): [52] notify (0xedf3b500, 3, 17, 0)
02-01 15:15:43.078: D/GenericSource(2554): cachedDurationUs = 6.28 secs, eos=0
02-01 15:15:43.078: V/GenericSource(2554): network bitrate is faster than content bitrate, cache has filled up (6.28 secs), resuming.
02-01 15:15:43.078: V/GenericSource(2554): [Flag] clear 0x80 -> mFlags = 0x10
02-01 15:15:43.078: V/GenericSource(2554): [Flag] set 0x1 -> mFlags = 0x11
02-01 15:15:43.078: V/MediaPlayer(28572): message received msg=3, ext1=17, ext2=0
02-01 15:15:43.078: V/MediaPlayer(28572): buffering 17
02-01 15:15:43.078: V/MediaPlayer(28572): callback application
02-01 15:15:43.078: D/NuPlayer(2554): post kWhatStart!
02-01 15:15:43.078: V/NuPlayer(2554): kWhatStart
02-01 15:15:43.078: V/MediaPlayer(28572): back from callback
02-01 15:15:43.078: V/GenericSource(2554): resume
02-01 15:15:43.078: V/GenericSource(2554): [Flag] set 0x1 -> mFlags = 0x11
02-01 15:15:43.078: I/NuPlayerRenderer(2554): onResume
02-01 15:15:43.078: V/AudioSink(2554): start
02-01 15:15:43.079: I/AudioEffectStage(2538): getBuffer primary_output_sub1_normal, 0xe788b000
02-01 15:15:43.079: I/AudioEffectStage(2538): getBuffer primary_output_sub1_normal
02-01 15:15:43.079: D/MySpaceEffect(2538): MySpace EffectSetFactor start - session 793
02-01 15:15:43.079: D/MySpaceEffect(2538): MS EffectSetFactor - Allocating SessionNo 0 for SessionId 793
02-01 15:15:43.079: D/MySpaceEffect(2538): MySpace EffectCreate start - session 793
02-01 15:15:43.079: D/MySpaceEffect(2538): MS EffectCreate - Allocating SessionNo 0 for SessionId 793
02-01 15:15:43.079: D/MySpaceEffect(2538): MS EffectCreate - This is the first effect in current sessionId 793 sessionNo 0
02-01 15:15:43.079: D/MySpaceEffect(2538): MySpaceEffect::MySpaceEffect ctor
02-01 15:15:43.079: D/MySpace(2538): MySpace::Init, mSampleRate = 48000, mFrameCount = 52
02-01 15:15:43.079: I/MySpace(2538): SoundAlive_3DPosisiton_Factory Create
02-01 15:15:43.081: D/MySpace(2538): MySpace Initialize mPreset = 0
02-01 15:15:43.081: D/MySpace(2538): MySpace::Init, mSampleRate = 48000, mFrameCount = 960
02-01 15:15:43.081: D/MySpace(2538): MySpace Initialize mPreset = 0
02-01 15:15:43.081: I/MySpace(2538): MySpace setDevice 2, 0
02-01 15:15:43.081: D/MySpaceEffect(2538): MySpace EffectSetFactor start - session 793
02-01 15:15:43.081: D/MySpaceEffect(2538): MS EffectSetFactor - Allocating SessionNo 0 for SessionId 793
02-01 15:15:43.081: I/APM_AudioPolicyManager(2538): startOutput() output 13, stream 3, session 793
02-01 15:15:43.081: D/APM::SoundKitchen(2538): getDevice, uid = 10388, device = 0
02-01 15:15:43.081: D/APM_AudioPolicyManager(2538): newDevice=0
02-01 15:15:43.082: D/audio_hw_primary(2538): adev_set_parameters: enter: kvpairs: TFA_SETVOLUME=4
02-01 15:15:43.086: I/AudioEffectStage(2538): getBuffer primary_output_sub1_normal, 0xe788b000
02-01 15:15:43.086: I/AudioEffectStage(2538): getBuffer primary_output_sub1_normal
02-01 15:15:43.086: I/AudioSAEffectSecPlus(2538): EFFECT_CMD_SET_VOLUME
02-01 15:15:43.086: I/AudioSAEffectSecPlus(2538): command EFFECT_CMD_SET_VOLUME left = 0.250977, right =0.250977
02-01 15:15:43.086: D/AudioSAEffectSecPlus(2538): Don't apply mixer volume!
02-01 15:15:43.086: I/SoundBoosterEffectPlus(2538): SB_setVolume(0xe78a5e00)
02-01 15:15:43.086: I/SoundBoosterEffectPlus(2538): command EFFECT_CMD_SET_VOLUME left = 0.250977, right =0.250977
02-01 15:15:43.086: I/AudioSAEffectSecPlus(2538): EFFECT_CMD_SET_VOLUME
02-01 15:15:43.086: I/AudioSAEffectSecPlus(2538): command EFFECT_CMD_SET_VOLUME left = 0.250977, right =0.250977
02-01 15:15:43.086: D/AudioSAEffectSecPlus(2538): Don't apply mixer volume!
02-01 15:15:43.086: I/SoundBoosterEffectPlus(2538): SB_setVolume(0xe78a5ee0)
02-01 15:15:43.086: I/SoundBoosterEffectPlus(2538): command EFFECT_CMD_SET_VOLUME left = 0.250977, right =0.250977
02-01 15:15:43.087: I/NuPlayerRenderer(2554): onResume() send kWhatPollingAudioStarted audio_out : 9940000
02-01 15:15:43.087: D/SoundAliveResampler(2538): [SoundAliveResampler] Init+++
02-01 15:15:43.087: V/AudioSink(2554): setPlaybackRate(1.000000 1.000000 0 0)
02-01 15:15:43.093: D/NuCachedSource2(2554): Remaining (5752K), HighWaterThreshold (90631) [player]
02-01 15:15:43.094: V/audio_hw_primary(2538): start_output_stream+, out->device : 00000002 , out->type = 0
02-01 15:15:43.094: D/audio_hw_primary(2538): start_output_stream jack_skip_check false
02-01 15:15:43.097: V/MediaPlayer-JNI(28572): getCurrentPosition: 9756 (msec)
02-01 15:15:43.100: D/NuCachedSource2(2554): Remaining (5816K), HighWaterThreshold (90631) [player]
02-01 15:15:43.110: D/NuCachedSource2(2554): Remaining (5880K), HighWaterThreshold (90631) [player]
02-01 15:15:43.134: D/NuCachedSource2(2554): Remaining (5944K), HighWaterThreshold (90631) [player]
02-01 15:15:43.159: D/audio_hw_primary(2538): start_output_stream (0xe6d65000) out->pcm_device:9 out->config.rate:48000 out->config.format:0 out->period_size:960
02-01 15:15:43.159: D/audio_hw_primary(2538): start_output_stream (0xe6d65000) out->config.rate:48000 out->config.format:0 out->period_size:960
02-01 15:15:43.159: V/audio_hw_primary(2538): select_devices output_scenario:0 input_scenario:-1 out_snd_device 0x2 in_snd_device:0x0
02-01 15:15:43.159: D/audio_hw_spk_amp(2538): check_speaker_amp_off: turning_off_speaker_amp = true 
02-01 15:15:43.159: D/tfa98xxApp(2538): [NXP] tfa_enable: turning tfa off
02-01 15:15:43.159: D/tfa98xxApp(2538): [NXP] tfa_enable: Tfa is already turned off
02-01 15:15:43.160: D/tfa_usrdrv(2538): [NXP] tfa_show_status: device = 0, status reg = 0x001c
02-01 15:15:43.160: D/tfa98xxApp(2538): [NXP] tfa_enable: end
02-01 15:15:43.160: I/audio_route(2538): > audio_route_reset :
02-01 15:15:43.160: I/audio_route(2538): > audio_route_update_mixer : +
02-01 15:15:43.160: I/audio_route(2538): > audio_route_update_mixer : changed(0) -
02-01 15:15:43.160: I/audio_route(2538): > audio_route_apply_path : "media-speaker"
02-01 15:15:43.160: V/audio_hw_primary(2538): select_devices() output_route "media-speaker" 
02-01 15:15:43.160: I/audio_route(2538): > audio_route_apply_path : "gain-media-speaker"
02-01 15:15:43.160: V/audio_hw_primary(2538): select_devices() output_gain "gain-media-speaker" 
02-01 15:15:43.160: I/audio_route(2538): > audio_route_update_mixer : +
02-01 15:15:43.160: I/audio_route(2538): > audio_route_update_mixer : changed(0) -
02-01 15:15:43.160: D/audio_hw_primary(2538): select_devices: pcm_voice_out 0x0
02-01 15:15:43.160: D/audio_hw_primary(2538): select_devices: pcm_fm_out 0x0
02-01 15:15:43.160: I/audio_hw_primary(2538): select_devices - 
02-01 15:15:43.160: D/audio_hw_spk_amp(2538): check_speaker_amp_on: turning_on_speaker_amp = true 
02-01 15:15:43.160: D/tfa98xxApp(2538): [NXP] [MAR 28, 2017] tfa_enable: turning tfa on 
02-01 15:15:43.160: D/tfa98xxApp(2538): [NXP] tfa_enable: tfa_i2c_dev_name = /dev/i2c-20, tfa_i2c_speed = 300 kbps, tfa_log_enable_cnt = 76, tfa_log_coldboot_cnt = 1
02-01 15:15:43.160: D/tfa98xxApp(2538): [NXP] tfa_enable: coldstartup_flag = 1
02-01 15:15:43.160: D/tfa98xxApp(2538): [NXP] tfa_enable: check speakerOn(0) && (tfaMode(0) == prev_tfaMode(0)) 
02-01 15:15:43.161: D/tfa98xxApp(2538): [NXP] tfa_iscoldboot: Loaded container file /system/etc/Tfa9896.cnt.
02-01 15:15:43.161: D/tfa_usrdrv(2538): [NXP] tfaRunIsCold: status reg = 0x001c 
02-01 15:15:43.161: D/tfa98xxApp(2538): [NXP] tfa_enable: warm boot 
02-01 15:15:43.161: D/tfa98xxApp(2538): [NXP] tfa_enable: ic stable time 80 ms
02-01 15:15:43.167: D/NuCachedSource2(2554): Remaining (6008K), HighWaterThreshold (90631) [player]
02-01 15:15:43.199: D/NuCachedSource2(2554): Remaining (6072K), HighWaterThreshold (90631) [player]
02-01 15:15:43.228: D/NuCachedSource2(2554): Remaining (6136K), HighWaterThreshold (90631) [player]
02-01 15:15:43.241: D/tfa98xxApp(2538): [NXP] tfa_enable: Tfa mode would be switched. prev = 0, current = 0
02-01 15:15:43.241: D/tfa98xxApp(2538): [NXP] tfa_speakeron: begin
02-01 15:15:43.241: D/tfa98xxApp(2538): [NXP] tfa_speakeron: set mode 0, current mode 0
02-01 15:15:43.242: D/tfa98xxApp(2538): [NXP] tfa_speakeron: Loaded container file speaker_on /system/etc/Tfa9896.cnt.
02-01 15:15:43.242: D/tfa98xxApp(2538): [NXP] tfa_speakeron: tfa start with volume 4.
02-01 15:15:43.244: D/tfa_dsp(2538): tfaRunMute: -------------------- muted --------------------
02-01 15:15:43.244: D/tfa_container(2538): tfaContWriteRegsProf: ----- profile: HQ (0) -----
02-01 15:15:43.245: D/tfa_dsp(2538): tfa_cf_powerup: power on (PWDN=0)
02-01 15:15:43.254: D/NuCachedSource2(2554): Remaining (6200K), HighWaterThreshold (90631) [player]
02-01 15:15:43.257: D/tfa_dsp(2538): [NXP] tfa_start: status = 0xd05e (after power up)
02-01 15:15:43.268: D/tfa_dsp(2538): tfaRunUnmute: -------------------unmuted ------------------
02-01 15:15:43.268: D/tfa98xxApp(2538): [NXP] tfa_speakeron: start usecase success
02-01 15:15:43.268: D/tfa98xxApp(2538): [NXP] tfa_speakeron: tfa mode same, nothing to be done
02-01 15:15:43.268: D/tfa98xxApp(2538): [NXP] tfa_speakeron: tfa start use case success
02-01 15:15:43.268: D/tfa98xxApp(2538): [NXP] tfa_speakeron: end
02-01 15:15:43.268: D/tfa98xxApp(2538): [NXP] tfa_enable: tfaVolume : 4
02-01 15:15:43.268: D/tfa98xxApp(2538): [NXP] tfa_setvolumestep: begin
02-01 15:15:43.268: D/tfa98xxApp(2538): [NXP] tfa_setvolumestep: tfa setvolume - leftvolume(4) rightvolume(4) tfa_mode(0)
02-01 15:15:43.268: D/tfa98xxApp(2538): [NXP] tfa_setvolumestep: volume set to : 4
02-01 15:15:43.268: D/tfa_usrdrv(2538): [NXP] tfaContVstepMapping: vstep mapping vstep(4) -> vstep_in_cnt(0)
02-01 15:15:43.268: D/tfa_container(2538): [NXP] tfaContWriteFile: begin
02-01 15:15:43.268: D/tfa_container(2538): [NXP] tfaContWriteFile: type = vstep (device = 0, slave 0x34)
02-01 15:15:43.269: D/tfa_container(2538): [NXP] tfaContWriteVstep: set volume attenuation VOL = 0 (-0.0 dB)
02-01 15:15:43.269: D/tfa_dsp(2538): tfa98xx_dsp_write_preset: write preset size=87
02-01 15:15:43.274: D/tfa_container(2538): [NXP] tfaContWriteFile: end
02-01 15:15:43.274: D/tfa98xxApp(2538): [NXP] tfa_setvolumestep: tfa set volume successful
02-01 15:15:43.274: D/tfa98xxApp(2538): [NXP] tfa_setvolumestep: end
02-01 15:15:43.276: D/tfa_usrdrv(2538): [NXP] tfa_show_status_device: /dev/i2c-20 0x34, device = 0, status = 0xd05e, WDS = 0, ACS = 0, CHSA = 2, DCA = 1, CHS12 = 3, SWS = 1, PLLS = 1, SBSL = 1, RCV = 0, INPLVL = 0, PWDN = 0, re0 = 0x1734 (5.8008 ohm)
02-01 15:15:43.276: D/tfa98xxApp(2538): [NXP] tfa_enable: end
02-01 15:15:43.276: I/PostProcess(2538): PostProcessInit mSoundBooster[0]
02-01 15:15:43.276: V/audio_hw_primary(2538): start_output_stream-
02-01 15:15:43.276: D/AudioFlinger(2538): mixer(0xe708c240) Spend too much time to write: delta 189(effect 5, stage 1)
02-01 15:15:43.282: D/NuCachedSource2(2554): Remaining (6264K), HighWaterThreshold (90631) [player]
02-01 15:15:43.283: W/AudioTrack(2554): getTimestamp() location moved from kernel to server
02-01 15:15:43.283: I/NuPlayerRenderer(2554): kWhatPollingAudioStarted clock running played_out : 9959523 us, audio_out : 9940000 us, gap : 19523 us
02-01 15:15:43.283: I/MediaClock(2554): setPlaybackRate rate : 1.000000
02-01 15:15:43.293: V/NuPlayer(2554): media rendering started
02-01 15:15:43.293: V/NuPlayer(2554): msg : 6, ext1 : 0, ext2 : 0
02-01 15:15:43.293: D/NuPlayerDriver(2554): notifyListener_l(0xd277f180), (6, 0, 0), loop setting(0, 0)
02-01 15:15:43.293: V/MediaPlayerService(2554): [52] notify (0xedf3b500, 6, 0, 0)
02-01 15:15:43.293: V/NuPlayer(2554): msg : 200, ext1 : 702, ext2 : 0
02-01 15:15:43.293: D/NuPlayerDriver(2554): notifyListener_l(0xd277f180), (200, 702, 0), loop setting(0, 0)
02-01 15:15:43.293: V/MediaPlayerService(2554): [52] notify (0xedf3b500, 200, 702, 0)
02-01 15:15:43.293: V/NuPlayer(2554): setDecoderBooster enable = No
02-01 15:15:43.293: V/MediaPlayer(28572): message received msg=6, ext1=0, ext2=0
02-01 15:15:43.294: V/MediaPlayer(28572): unrecognized message: (6, 0, 0)
02-01 15:15:43.294: V/MediaPlayer(28572): callback application
02-01 15:15:43.294: V/MediaPlayer(28572): back from callback
02-01 15:15:43.294: V/MediaPlayer(28572): message received msg=200, ext1=702, ext2=0
02-01 15:15:43.294: W/MediaPlayer(28572): info/warning (702, 0)
02-01 15:15:43.294: V/MediaPlayer(28572): callback application
02-01 15:15:43.294: V/MediaPlayer(28572): back from callback
02-01 15:15:43.313: D/NuCachedSource2(2554): Remaining (6095K), HighWaterThreshold (90631) [player]
02-01 15:15:43.337: I/MediaClock(2554): updateClock correction (30000).  gapTimeUs(86139), nowMediaUs(9895270), oldMediaUs(9809131)
02-01 15:15:43.342: D/AudioFlinger(2538): mixer(0xe708c240) throttle end: throttle time(29)
02-01 15:15:43.348: D/NuCachedSource2(2554): Remaining (5616K), HighWaterThreshold (90631) [player]
02-01 15:15:43.349: V/MediaPlayer-JNI(28572): getCurrentPosition: 9851 (msec)
nagyv picture nagyv on 1 Feb 2018

I've reported this issue in the Android developer tracker. https://issuetracker.google.com/issues/72786390

nagyv picture nagyv on 1 Feb 2018

@nagyv Looks like the issue got closed in the Android developer tracker as won't fix.

SuhairZain picture SuhairZain on 15 Mar 2018

Yeah, I've had no time to support them, and provide them an example app.

2018-03-15 6:22 GMT+01:00 Suhair Zain notifications@github.com:

@nagyv https://github.com/nagyv Looks like the issue got closed in the
Android developer tracker as won't fix.

—
You are receiving this because you were mentioned.
Reply to this email directly, view it on GitHub
https://github.com/react-native-community/react-native-video/issues/303#issuecomment-373264356,
or mute the thread
https://github.com/notifications/unsubscribe-auth/AAHuz_LTEXStodB1JvHQmobL9iQIY2Wgks5tefqYgaJpZM4JqEHn
.

nagyv picture nagyv on 15 Mar 2018

No news ? This is killing me :(

arielalvarez88 picture arielalvarez88 on 7 May 2018

as per the android life cycle when android device screen goes off than onPause() method call, and this bug occurred when screen goes off, when we again open the screen than onResume() method call, so now how can we manage this video view as per android life cycle.

this is what happen:

  1. screen goes off : onPause() => onStop()
  2. screen on: onRestart() => onStart() => onResume()
ZeroCool00 picture ZeroCool00 on 2 Jun 2018

Anyone arriving here after seeing the cryptic error:

{ 
  extras: -1004,
  what: 1
}

This is what fixed it for me:
https://github.com/react-native-community/react-native-video/issues/1032#issuecomment-392672620

Still having issues with playing the video :-/ but at least it made that error go away

urikphytech picture urikphytech on 14 Jun 2018

I still haven't found a solution to this bug, but I have had success with an alternative video solution using WebView based on react-native-vimeo. The performance probably isn't as good and I've lost background audio but I was able to find a way to hide Vimeo's default control bar and implement my own custom one with fullscreen support. If anyone is interested I'd be happy to post the source.

jamealg picture jamealg on 25 Jun 2018

I'd strongly suggest everyone switching over the ExoPlayer player for Android. Not sure it will fix this particular issue, but in general it works better.

cobarx picture cobarx on 7 Aug 2018

I experienced the same issue with some of the videos: {extras: -1004, what: 1}. Switching to ExoPlayer fixed it for me. 👍

alizbazar picture alizbazar on 28 Nov 2018
👍1

Doing what @alizbazar suggested did not work for me...I still get Error 403

fabiorbap picture fabiorbap on 18 Apr 2019

@fabiorbap did you actually do the instructed changes to the Android build.gradle etc files to enable Exoplayer? Just upgrading the react-native-video version is not enough.

alizbazar picture alizbazar on 25 Apr 2019

the problem is still there， how can i do it ?

Bekys picture Bekys on 30 Jun 2020

error in android:

{extras: -1004, what: 1}

any help please?

KingAmo picture KingAmo on 30 Dec 2020
Was this page helpful?
0 / 5 - 0 ratings

Related issues

nechmads picture nechmads  ·  20Comments
hathnv picture hathnv  ·  25Comments
thetrevlore picture thetrevlore  ·  21Comments
braco picture braco  ·  37Comments
shlokamin picture shlokamin  ·  22Comments