bug while buffering video

buffer bug

06-11 10:59:20.417 1786-2802/? D/WVCdm: Instantiating CDM.
06-11 10:59:20.497 29752-29752/com.flexline I/ExoPlayerImpl: Init ExoPlayerLib/2.4.0 [tulip-ads, QUAD-CORE R18 ads, Allwinner, 23]
06-11 10:59:20.679 29752-29752/com.flexline I/VideoControllerView: VideoControllerView
06-11 10:59:20.679 29752-29752/com.flexline I/VideoControllerView: VideoControllerView
06-11 10:59:20.725 29752-29752/com.flexline I/Choreographer: Skipped 37 frames!  The application may be doing too much work on its main thread.
06-11 10:59:21.825 29752-4994/com.flexline W/art: Method processed more than once: java.lang.String com.google.android.exoplayer2.util.ParsableByteArray.readString(int, java.nio.charset.Charset)
06-11 10:59:21.896 29752-4894/com.flexline W/VideoCapabilities: Unsupported mime video/jpeg
06-11 10:59:21.900 29752-4894/com.flexline W/VideoCapabilities: Unsupported mime video/mpeg1
06-11 10:59:21.905 29752-4894/com.flexline W/VideoCapabilities: Unrecognized profile/level 32768/2 for video/mp4v-es
06-11 10:59:21.910 29752-4894/com.flexline W/VideoCapabilities: Unsupported mime video/x-vnd.on2.vp6
06-11 10:59:21.921 29752-4894/com.flexline W/VideoCapabilities: Unsupported mime video/wvc1
06-11 10:59:21.923 29752-4894/com.flexline W/VideoCapabilities: Unsupported mime video/x-ms-mpeg4v1
06-11 10:59:21.925 29752-4894/com.flexline W/VideoCapabilities: Unsupported mime video/x-ms-mpeg4v2
06-11 10:59:21.927 29752-4894/com.flexline W/VideoCapabilities: Unsupported mime video/divx
06-11 10:59:21.930 29752-4894/com.flexline W/VideoCapabilities: Unsupported mime video/xvid
06-11 10:59:21.931 29752-4894/com.flexline W/VideoCapabilities: Unsupported mime video/flv1
06-11 10:59:21.935 29752-4894/com.flexline W/VideoCapabilities: Unsupported mime video/rvg2
06-11 10:59:21.937 29752-4894/com.flexline W/VideoCapabilities: Unsupported mime video/wmv1
06-11 10:59:21.939 29752-4894/com.flexline W/VideoCapabilities: Unsupported mime video/wmv2
06-11 10:59:21.964 29752-4894/com.flexline I/VideoCapabilities: Unsupported profile 4 for video/mp4v-es
06-11 10:59:22.009 29752-5014/com.flexline I/OMXClient: Using client-side OMX mux.
06-11 10:59:22.025 1786-5016/? I/SoftAAC2: limiting to stereo output
06-11 10:59:22.028 1786-5016/? I/SoftAAC2: Reconfiguring decoder: 0->44100 Hz, 0->2 channels
06-11 10:59:22.029 29752-5019/com.flexline I/OMXClient: Using client-side OMX mux.
06-11 10:59:22.033 1786-1786/? D/awplayer: <omx_vdec:255>: (f:omx_vdec, l:255) 
06-11 10:59:22.033 1786-1786/? D/awplayer: <getCallingProcessName:212>: Calling process is: com.flexline
06-11 10:59:22.033 1786-1786/? D/awplayer: <omx_vdec:343>: ion open fd = 19
06-11 10:59:22.033 1786-1786/? D/awplayer: <component_init:513>: (f:component_init, l:513) name = OMX.allwinner.video.decoder.avc
06-11 10:59:22.040 29752-5018/com.flexline I/MediaCodec: [OMX.allwinner.video.decoder.avc] setting surface generation to 30466049
06-11 10:59:22.046 1786-2994/? W/awplayer: <set_parameter:1368>: set_parameter, OMX_IndexParamPortDefinition, OutPortDef : change nBufferSize[38016] to [146016] to suit frame width[416] and height[234]
06-11 10:59:22.046 1786-2994/? D/awplayer: <set_parameter:1375>: (omx_vdec, f:set_parameter, l:1375) OMX_IndexParamPortDefinition, width = 416, height = 234, nPortIndex[1], nBufferCountActual[4], nBufferCountMin[4], nBufferSize[146016],AlloSize[0]
06-11 10:59:22.048 1786-1786/? W/awplayer: <get_config:1827>: +++++ get display crop: top[0],left[0],width[416],height[234]
06-11 10:59:22.049 1786-5021/? D/awplayer: <ComponentThread:3706>:  set state command, cmd = 0, cmddata = 2.
06-11 10:59:22.050 1786-5022/? D/awplayer: <ComponentVdrvThread:4516>: (f:ComponentVdrvThread, l:4516) vdrvThread receive cmd[0x0]
06-11 10:59:22.050 1786-5022/? D/awplayer: <ComponentVdrvThread:4521>: (f:ComponentVdrvThread, l:4521)(OMX_VdrvCommand_PrepareVdecLib)
06-11 10:59:22.051 1786-5021/? D/awplayer: <ComponentThread:3855>: (f:ComponentThread, l:3855) wait for OMX_VdrvCommand_PrepareVdecLib
06-11 10:59:22.051 1786-5022/? D/awplayer: <ComponentVdrvThread:4567>:  wait for m_semExtraOutBufferNum
06-11 10:59:22.052 29752-5019/com.flexline D/SurfaceUtils: set up nativeWindow 0x7f7bab5010 for 416x234, color 0x32315659, rotation 0, usage 0x2d00
06-11 10:59:22.055 1786-3390/? D/awplayer: <set_parameter:1343>: sem_post m_semExtraOutBufferNum, num = 4
06-11 10:59:22.055 1786-3390/? D/awplayer: <set_parameter:1375>: (omx_vdec, f:set_parameter, l:1375) OMX_IndexParamPortDefinition, width = 416, height = 234, nPortIndex[1], nBufferCountActual[8], nBufferCountMin[4], nBufferSize[146016],AlloSize[0]
06-11 10:59:22.055 1786-5022/? D/awplayer: <ComponentVdrvThread:4569>:  wait for m_semExtraOutBufferNum done
06-11 10:59:22.055 1786-5022/? D/awplayer: <ComponentVdrvThread:4575>: set nDisplayHoldingFrameBufferNum : 4
06-11 10:59:22.074 1786-5022/? E/secureMemoryAdapter: initialize context failed, -65528
06-11 10:59:22.074 1786-5022/? E/secureMemoryAdapter: secure adapter has not been initialized
06-11 10:59:22.074 1786-5022/? W/awplayer: <InitializeVideoDecoder:388>: warning: the nDeInterlaceHoldingFrameBufferNum is 0
06-11 10:59:22.079 1786-5022/? D/awplayer: <CreateSpecificDecoder:962>: Create decoder '115:h264'
06-11 10:59:22.090 1786-5021/? D/awplayer: <ComponentThread:3857>: (f:ComponentThread, l:3857) wait for OMX_VdrvCommand_PrepareVdecLib done!
06-11 10:59:22.090 1786-5021/? D/awplayer: <ComponentThread:3868>: bEnabled[1],[1],bPopulated[1],[1]
06-11 10:59:22.091 1786-5021/? D/awplayer: <ComponentThread:3706>:  set state command, cmd = 0, cmddata = 3.
06-11 10:59:22.091 1786-5021/? W/awplayer: <ComponentThread:4408>: *** get video fbm buf!
06-11 10:59:22.096 1740-3472/? I/[Gralloc]: ion_alloc from ion_client:18 via heap type DMA(mask:16) for 146016 Bytes uncached buffer successfully, usage = 0x00002d00
06-11 10:59:22.096 1786-5021/? W/awplayer: <ComponentThread:4408>: *** get video fbm buf!
06-11 10:59:22.102 1786-5021/? W/awplayer: <ComponentThread:4408>: *** get video fbm buf!
06-11 10:59:22.105 1786-5021/? W/awplayer: <ComponentThread:4418>: *** get new command when get video fbm-buf-info
06-11 10:59:22.106 1786-5021/? W/awplayer: <ComponentThread:4408>: *** get video fbm buf!
06-11 10:59:22.107 1786-5021/? W/awplayer: <ComponentThread:4418>: *** get new command when get video fbm-buf-info
06-11 10:59:22.107 1786-5021/? W/awplayer: <ComponentThread:4408>: *** get video fbm buf!
06-11 10:59:22.109 1740-2320/? I/[Gralloc]: ion_alloc from ion_client:18 via heap type DMA(mask:16) for 146016 Bytes uncached buffer successfully, usage = 0x00002d00
06-11 10:59:22.113 1786-5021/? W/awplayer: <ComponentThread:4408>: *** get video fbm buf!
06-11 10:59:22.114 1786-5021/? W/awplayer: <ComponentThread:4418>: *** get new command when get video fbm-buf-info
06-11 10:59:22.114 1786-5021/? W/awplayer: <ComponentThread:4408>: *** get video fbm buf!
06-11 10:59:22.114 1786-5021/? W/awplayer: <ComponentThread:4418>: *** get new command when get video fbm-buf-info
06-11 10:59:22.115 1786-5021/? W/awplayer: <ComponentThread:4408>: *** get video fbm buf!
06-11 10:59:22.117 1740-2322/? I/[Gralloc]: ion_alloc from ion_client:18 via heap type DMA(mask:16) for 146016 Bytes uncached buffer successfully, usage = 0x00002d00
06-11 10:59:22.120 1786-5021/? W/awplayer: <ComponentThread:4408>: *** get video fbm buf!
06-11 10:59:22.123 1786-5021/? W/awplayer: <ComponentThread:4418>: *** get new command when get video fbm-buf-info
06-11 10:59:22.123 1786-5021/? W/awplayer: <ComponentThread:4408>: *** get video fbm buf!
06-11 10:59:22.123 1786-5021/? W/awplayer: <ComponentThread:4418>: *** get new command when get video fbm-buf-info
06-11 10:59:22.123 1786-5021/? W/awplayer: <ComponentThread:4408>: *** get video fbm buf!
06-11 10:59:22.130 1786-5021/? W/awplayer: <ComponentThread:4408>: *** get video fbm buf!
06-11 10:59:22.136 1786-5021/? W/awplayer: <ComponentThread:4408>: *** get video fbm buf!
06-11 10:59:22.141 1786-5021/? W/awplayer: <ComponentThread:4408>: *** get video fbm buf!
06-11 10:59:22.146 1786-5021/? W/awplayer: <ComponentThread:4408>: *** get video fbm buf!
06-11 10:59:22.151 1786-5021/? W/awplayer: <ComponentThread:4408>: *** get video fbm buf!
06-11 10:59:22.157 1786-5021/? W/awplayer: <ComponentThread:4408>: *** get video fbm buf!
06-11 10:59:22.162 1740-3472/? I/[Gralloc]: ion_alloc from ion_client:18 via heap type DMA(mask:16) for 146016 Bytes uncached buffer successfully, usage = 0x00002d00
06-11 10:59:22.162 1786-5021/? W/awplayer: <ComponentThread:4408>: *** get video fbm buf!
06-11 10:59:22.166 1786-5021/? W/awplayer: <ComponentThread:4418>: *** get new command when get video fbm-buf-info
06-11 10:59:22.166 1786-5021/? W/awplayer: <ComponentThread:4408>: *** get video fbm buf!
06-11 10:59:22.166 1786-5021/? W/awplayer: <ComponentThread:4418>: *** get new command when get video fbm-buf-info
06-11 10:59:22.166 1786-5021/? W/awplayer: <ComponentThread:4408>: *** get video fbm buf!
06-11 10:59:22.166 1786-5021/? W/awplayer: <ComponentThread:4418>: *** get new command when get video fbm-buf-info
06-11 10:59:22.166 1786-5021/? W/awplayer: <ComponentThread:4408>: *** get video fbm buf!
06-11 10:59:22.172 1786-5021/? W/awplayer: <ComponentThread:4408>: *** get video fbm buf!
06-11 10:59:22.173 1786-5021/? W/awplayer: <ComponentThread:4418>: *** get new command when get video fbm-buf-info
06-11 10:59:22.173 1786-5021/? W/awplayer: <ComponentThread:4408>: *** get video fbm buf!
06-11 10:59:22.173 1786-5021/? W/awplayer: <ComponentThread:4418>: *** get new command when get video fbm-buf-info
06-11 10:59:22.173 1786-5021/? W/awplayer: <ComponentThread:4408>: *** get video fbm buf!
06-11 10:59:22.174 1786-5022/? D/awplayer: <H264DecVuiParameters:270>: nFrameRate=29
06-11 10:59:22.174 1786-5022/? D/fbm.c: <FbmCreateBuffer:105>: FbmCreate, total fbm number: 11, decoder needed: 4,   nWidth=416, nHeight=240 
06-11 10:59:22.175 1786-5021/? W/awplayer: <ComponentThread:4418>: *** get new command when get video fbm-buf-info
06-11 10:59:22.175 1786-5021/? W/awplayer: <ComponentThread:4408>: *** get video fbm buf!
06-11 10:59:22.175 1786-5021/? D/awplayer: <OmxGetVideoFbmBufInfo:3351>: video buffer info: nWidth[416],nHeight[256],nBufferCount[11],ePixelFormat[4]
06-11 10:59:22.175 1786-5021/? D/awplayer: <OmxGetVideoFbmBufInfo:3354>: video buffer info: nAlignValue[32],bProgressiveFlag[1],bIsSoftDecoderFlag[0]
06-11 10:59:22.182 1786-5021/? D/awplayer: <ComponentThread:3979>:  stop port command, cmddata = 1.
06-11 10:59:22.193 1786-5021/? D/awplayer: <ComponentThread:4127>:  restart port command.pSelf->m_state[3]
06-11 10:59:22.193 29752-5019/com.flexline D/SurfaceUtils: set up nativeWindow 0x7f7bab5010 for 416x256, color 0x32315659, rotation 0, usage 0x2d00
06-11 10:59:22.194 1786-2994/? D/awplayer: <set_parameter:1343>: sem_post m_semExtraOutBufferNum, num = 4
06-11 10:59:22.194 1786-2994/? D/awplayer: <set_parameter:1375>: (omx_vdec, f:set_parameter, l:1375) OMX_IndexParamPortDefinition, width = 416, height = 256, nPortIndex[1], nBufferCountActual[11], nBufferCountMin[7], nBufferSize[159744],AlloSize[0]
06-11 10:59:22.204 1740-3570/? I/[Gralloc]: ion_alloc from ion_client:18 via heap type DMA(mask:16) for 159744 Bytes uncached buffer successfully, usage = 0x00002d00
06-11 10:59:22.207 1786-5021/? D/awplayer: <OmxReturnBufferToDecoder:3543>: *** call SetVideoFbmBufAddress: pVideoPicture(0xf1861018)
06-11 10:59:22.207 1740-2662/? I/[Gralloc]: ion_alloc from ion_client:18 via heap type DMA(mask:16) for 159744 Bytes uncached buffer successfully, usage = 0x00002d00
06-11 10:59:22.210 1786-5021/? D/awplayer: <OmxReturnBufferToDecoder:3543>: *** call SetVideoFbmBufAddress: pVideoPicture(0xf18610c8)
06-11 10:59:22.212 1740-2320/? I/[Gralloc]: ion_alloc from ion_client:18 via heap type DMA(mask:16) for 159744 Bytes uncached buffer successfully, usage = 0x00002d00
06-11 10:59:22.215 1786-5021/? D/awplayer: <OmxReturnBufferToDecoder:3543>: *** call SetVideoFbmBufAddress: pVideoPicture(0xf1861178)
06-11 10:59:22.217 1740-3570/? I/[Gralloc]: ion_alloc from ion_client:18 via heap type DMA(mask:16) for 159744 Bytes uncached buffer successfully, usage = 0x00002d00
06-11 10:59:22.219 1786-5021/? D/awplayer: <OmxReturnBufferToDecoder:3543>: *** call SetVideoFbmBufAddress: pVideoPicture(0xf1861228)
06-11 10:59:22.220 1786-2994/? W/awplayer: <get_config:1827>: +++++ get display crop: top[0],left[0],width[416],height[234]
06-11 10:59:22.227 1740-2320/? I/[Gralloc]: ion_alloc from ion_client:18 via heap type DMA(mask:16) for 159744 Bytes uncached buffer successfully, usage = 0x00002d00
06-11 10:59:22.232 1786-5021/? D/awplayer: <OmxReturnBufferToDecoder:3543>: *** call SetVideoFbmBufAddress: pVideoPicture(0xf18612d8)
06-11 10:59:22.232 1740-2322/? I/[Gralloc]: ion_alloc from ion_client:18 via heap type DMA(mask:16) for 159744 Bytes uncached buffer successfully, usage = 0x00002d00
06-11 10:59:22.235 1786-5021/? D/awplayer: <OmxReturnBufferToDecoder:3543>: *** call SetVideoFbmBufAddress: pVideoPicture(0xf1861388)
06-11 10:59:22.236 1740-3472/? I/[Gralloc]: ion_alloc from ion_client:18 via heap type DMA(mask:16) for 159744 Bytes uncached buffer successfully, usage = 0x00002d00
06-11 10:59:22.241 1786-5021/? D/awplayer: <OmxReturnBufferToDecoder:3543>: *** call SetVideoFbmBufAddress: pVideoPicture(0xf1861438)
06-11 10:59:22.270 1740-3570/? I/[Gralloc]: ion_alloc from ion_client:18 via heap type DMA(mask:16) for 159744 Bytes uncached buffer successfully, usage = 0x00002d00
06-11 10:59:22.274 1786-5021/? D/awplayer: <OmxReturnBufferToDecoder:3543>: *** call SetVideoFbmBufAddress: pVideoPicture(0xf18614e8)
06-11 10:59:22.299 2623-3019/system_process I/MediaFocusControl:  AudioFocus  requestAudioFocus() from android.media.AudioManager@d5e020ecom.devbrackets.android.exomedia.ui.widget.VideoView$AudioFocusHelper@a7b542f req=1flags=0x0
06-11 10:59:22.303 29752-4003/com.flexline D/OkHttp: --> POST http://ec2-52-53-157-8.us-west-1.compute.amazonaws.com/api/v1/workouts/ http/1.1
06-11 10:59:22.303 29752-4003/com.flexline D/OkHttp: Content-Type: application/json
06-11 10:59:22.303 29752-4003/com.flexline D/OkHttp: Content-Length: 12
06-11 10:59:22.303 29752-4003/com.flexline D/OkHttp: Authorization: Bearer 5zuqjOIWDx6CM2fWYmzCXhAzXGhHmA
06-11 10:59:22.303 29752-4003/com.flexline D/OkHttp: Authorization_Profile: 62
06-11 10:59:22.304 29752-4003/com.flexline D/OkHttp: Host: ec2-52-53-157-8.us-west-1.compute.amazonaws.com
06-11 10:59:22.304 29752-4003/com.flexline D/OkHttp: Connection: Keep-Alive
06-11 10:59:22.304 29752-4003/com.flexline D/OkHttp: Accept-Encoding: gzip
06-11 10:59:22.304 29752-4003/com.flexline D/OkHttp: User-Agent: okhttp/3.8.0
06-11 10:59:22.304 29752-4003/com.flexline D/OkHttp: {"video":37}
06-11 10:59:22.305 29752-4003/com.flexline D/OkHttp: --> END POST (12-byte body)
06-11 10:59:22.311 1786-2799/? V/audio_hw_primary: out_set_parameters: routing=2
06-11 10:59:22.311 1786-2799/? V/audio_hw_primary: out_set_parameters, line: 2425
06-11 10:59:22.311 1786-2799/? V/audio_hw_primary: >>>>>>>> adev->mode:0,adev->out_device is: 2, val is : 2
06-11 10:59:22.318 1740-3570/? I/[Gralloc]: ion_alloc from ion_client:18 via heap type DMA(mask:16) for 159744 Bytes uncached buffer successfully, usage = 0x00002d00
06-11 10:59:22.323 1786-2799/? V/audio_hw_primary: start_output_stream, line: 1929
06-11 10:59:22.323 1786-2799/? V/audio_hw_primary: start_output_stream, line: 1971
06-11 10:59:22.323 1786-2799/? D/audio_hw_primary: >>>>>> select_device
06-11 10:59:22.323 1786-2799/? V/audio_hw_primary: active_output, ****LINE:1749,FUNC:select_device, adev->out_device:2
06-11 10:59:22.323 1786-2799/? V/audio_hw_primary: ****LINE:1755,FUNC:select_device, headset_on:0, headphone_on:0, speaker_on:2, earpiece_on:0, bt_on:0
06-11 10:59:22.323 1786-2799/? V/audio_hw_primary: ****LINE:1782,FUNC:select_device, output_device_id:0
06-11 10:59:22.323 1786-2799/? V/audio_hw_primary: NORMAL mode, ****LINE:1786,FUNC:select_device, adev->out_device:2
06-11 10:59:22.325 1786-5021/? D/awplayer: <OmxReturnBufferToDecoder:3543>: *** call SetVideoFbmBufAddress: pVideoPicture(0xf1861598)
06-11 10:59:22.326 1786-2799/? V/audio_hw_primary: use AUDIO_CODEC to playback audio
06-11 10:59:22.327 1786-2799/? V/audio_hw_primary: do not use out resampler
06-11 10:59:22.458 1786-2799/? D/AudioFlinger: mixer(0xf1e40000) throttle end: throttle time(33)
06-11 10:59:22.478 1740-2662/? I/[Gralloc]: ion_alloc from ion_client:18 via heap type DMA(mask:16) for 159744 Bytes uncached buffer successfully, usage = 0x00002d00
06-11 10:59:22.484 1786-5021/? D/awplayer: <OmxReturnBufferToDecoder:3543>: *** call SetVideoFbmBufAddress: pVideoPicture(0xf1861648)
06-11 10:59:22.595 29752-4003/com.flexline D/OkHttp: <-- 201 Created http://ec2-52-53-157-8.us-west-1.compute.amazonaws.com/api/v1/workouts/ (290ms)
06-11 10:59:22.596 29752-4003/com.flexline D/OkHttp: Server: nginx/1.10.0 (Ubuntu)
06-11 10:59:22.596 29752-4003/com.flexline D/OkHttp: Date: Sun, 11 Jun 2017 02:59:22 GMT
06-11 10:59:22.596 29752-4003/com.flexline D/OkHttp: Content-Type: application/json
06-11 10:59:22.596 29752-4003/com.flexline D/OkHttp: Content-Length: 108
06-11 10:59:22.596 29752-4003/com.flexline D/OkHttp: Connection: keep-alive
06-11 10:59:22.596 29752-4003/com.flexline D/OkHttp: X-Frame-Options: SAMEORIGIN
06-11 10:59:22.596 29752-4003/com.flexline D/OkHttp: Allow: GET, POST, OPTIONS
06-11 10:59:22.596 29752-4003/com.flexline D/OkHttp: Vary: Accept
06-11 10:59:22.597 29752-4003/com.flexline D/OkHttp: {"id":1137,"video":37,"summary_metrics":{"weight":null,"reps":null,"power":null,"energy":null,"calories":0}}
06-11 10:59:22.598 29752-4003/com.flexline D/OkHttp: <-- END HTTP (108-byte body)
06-11 10:59:22.601 29752-29752/com.flexline E/WorkoutInteractor: Server code: 201
06-11 10:59:22.865 5086-5086/? I/pppd: Starting pppd 
06-11 10:59:23.822 2623-2751/system_process D/ActivityManager: readMemInfo: freeMem=1044062208,threshold=226492416
06-11 10:59:23.822 2623-2751/system_process D/ActivityManager: NOTIFY_CHECK_BACKGROUND_TASK_MSG spends 10ms
06-11 10:59:27.969 5402-5402/? I/pppd: Starting pppd 
06-11 10:59:33.066 5707-5707/? I/pppd: Starting pppd 
06-11 10:59:38.164 6006-6006/? I/pppd: Starting pppd 
06-11 10:59:43.291 6322-6322/? I/pppd: Starting pppd 
06-11 10:59:48.389 6644-6644/? I/pppd: Starting pppd 
06-11 10:59:52.495 6895-6895/? I/pppd: Starting pppd 
06-11 10:59:53.722 2623-4993/system_process D/NetlinkSocketObserver: NeighborEvent{elapsedMs=681310, 192.168.43.1, [9268C3FACD27], RTM_NEWNEIGH, NUD_STALE}
06-11 10:59:57.600 7214-7214/? I/pppd: Starting pppd 
06-11 11:00:02.759 7522-7522/? I/pppd: Starting pppd 
06-11 11:00:02.882 2623-4993/system_process D/NetlinkSocketObserver: NeighborEvent{elapsedMs=690470, 192.168.43.1, [9268C3FACD27], RTM_NEWNEIGH, NUD_REACHABLE}
06-11 11:00:07.859 7844-7844/? I/pppd: Starting pppd 
06-11 11:00:12.946 8159-8159/? I/pppd: Starting pppd 
06-11 11:00:18.044 8482-8482/? I/pppd: Starting pppd 
06-11 11:00:23.153 8823-8823/? I/pppd: Starting pppd 
06-11 11:00:23.839 2623-2751/system_process D/ActivityManager: readMemInfo: freeMem=975339520,threshold=226492416
06-11 11:00:23.839 2623-2751/system_process D/ActivityManager: NOTIFY_CHECK_BACKGROUND_TASK_MSG spends 15ms
06-11 11:00:27.183 2623-9128/system_process E/NdkMediaExtractor: sf error code: -1010
06-11 11:00:27.183 2623-9128/system_process E/SoundPool: Unable to load sample
06-11 11:00:27.183 2623-9128/system_process E/NdkMediaExtractor: sf error code: -1010
06-11 11:00:27.183 2623-9128/system_process E/SoundPool: Unable to load sample
06-11 11:00:27.183 2623-9128/system_process E/NdkMediaExtractor: sf error code: -1010
06-11 11:00:27.183 2623-9128/system_process E/SoundPool: Unable to load sample
06-11 11:00:27.183 2623-2993/system_process W/AudioService: onLoadSoundEffects(), Error -10000 while loading samples
06-11 11:00:27.185 2623-9128/system_process W/MessageQueue: Handler (android.media.SoundPool$EventHandler) {3be9358} sending message to a Handler on a dead thread
                                                            java.lang.IllegalStateException: Handler (android.media.SoundPool$EventHandler) {3be9358} sending message to a Handler on a dead thread
                                                                at android.os.MessageQueue.enqueueMessage(MessageQueue.java:543)
                                                                at android.os.Handler.enqueueMessage(Handler.java:631)
                                                                at android.os.Handler.sendMessageAtTime(Handler.java:600)
                                                                at android.os.Handler.sendMessageDelayed(Handler.java:570)
                                                                at android.os.Handler.sendMessage(Handler.java:507)
                                                                at android.media.SoundPool.postEventFromNative(SoundPool.java:532)
06-11 11:00:27.185 2623-9128/system_process E/NdkMediaExtractor: sf error code: -1010
06-11 11:00:27.185 2623-9128/system_process E/SoundPool: Unable to load sample
06-11 11:00:28.250 9195-9195/? I/pppd: Starting pppd 
06-11 11:00:28.307 2623-2701/system_process I/MediaFocusControl:  AudioFocus  abandonAudioFocus() from android.media.AudioManager@d5e020ecom.devbrackets.android.exomedia.ui.widget.VideoView$AudioFocusHelper@a7b542f
06-11 11:00:28.314 2623-9202/system_process E/NdkMediaExtractor: sf error code: -1010
06-11 11:00:28.314 2623-9202/system_process E/SoundPool: Unable to load sample
06-11 11:00:28.315 2623-2993/system_process W/AudioService: onLoadSoundEffects(), Error -10000 while loading samples
06-11 11:00:28.316 2623-9202/system_process E/NdkMediaExtractor: sf error code: -1010
06-11 11:00:28.316 2623-9202/system_process E/SoundPool: Unable to load sample
06-11 11:00:31.340 1786-2799/? D/audio_hw_primary: out_standby
06-11 11:00:33.368 9514-9514/? I/pppd: Starting pppd 
06-11 11:00:35.994 1786-5021/? D/awplayer: <ComponentThread:4175>:  flush command.
06-11 11:00:35.994 1786-5021/? D/awplayer: <ComponentThread:4184>:  flush all port! we reset decoder!
06-11 11:00:35.994 1786-5021/? D/awplayer: <ComponentThread:4186>: (f:ComponentThread, l:4186) wait for OMX_VdrvCommand_FlushVdecLib
06-11 11:00:35.994 1786-5022/? D/awplayer: <ComponentVdrvThread:4516>: (f:ComponentVdrvThread, l:4516) vdrvThread receive cmd[0x2]
06-11 11:00:35.994 1786-5022/? D/awplayer: <ComponentVdrvThread:4611>: (f:ComponentVdrvThread, l:4611)(OMX_VdrvCommand_FlushVdecLib)
06-11 11:00:35.994 1786-5021/? D/awplayer: <ComponentThread:4188>: (f:ComponentThread, l:4188) wait for OMX_VdrvCommand_FlushVdecLib done!
06-11 11:00:35.995 1786-5021/? W/awplayer: <ComponentThread:4251>: ** return pic when flush,i[0],pPic[0xf1861018]
06-11 11:00:35.995 1786-5021/? W/awplayer: <ComponentThread:4251>: ** return pic when flush,i[1],pPic[0xf18610c8]
06-11 11:00:35.995 1786-5021/? W/awplayer: <ComponentThread:4251>: ** return pic when flush,i[2],pPic[0xf1861178]
06-11 11:00:35.995 1786-5021/? W/awplayer: <ComponentThread:4251>: ** return pic when flush,i[3],pPic[0xf1861228]
06-11 11:00:35.995 1786-5021/? W/awplayer: <ComponentThread:4251>: ** return pic when flush,i[4],pPic[0xf18612d8]
06-11 11:00:35.995 1786-5021/? W/awplayer: <ComponentThread:4251>: ** return pic when flush,i[5],pPic[0xf1861388]
06-11 11:00:35.995 1786-5021/? W/awplayer: <ComponentThread:4251>: ** return pic when flush,i[6],pPic[0xf1861438]
06-11 11:00:35.995 1786-5021/? D/awplayer: <ComponentThread:4242>: fillBufferDone when flush: i[7],pHeadBufInfo[0xf5cdf270],mStatus[2]
06-11 11:00:35.996 1786-5021/? W/awplayer: <ComponentThread:4251>: ** return pic when flush,i[8],pPic[0xf1861598]
06-11 11:00:35.996 1786-5021/? W/awplayer: <ComponentThread:4251>: ** return pic when flush,i[9],pPic[0xf1861648]
06-11 11:00:35.996 1786-5021/? D/awplayer: <ComponentThread:4270>: ion_free: handle_ion[0x1],i[0]
06-11 11:00:35.996 1786-5021/? D/awplayer: <ComponentThread:4270>: ion_free: handle_ion[0x2],i[1]
06-11 11:00:35.996 1786-5021/? D/awplayer: <ComponentThread:4270>: ion_free: handle_ion[0x3],i[2]
06-11 11:00:35.996 1786-5021/? D/awplayer: <ComponentThread:4270>: ion_free: handle_ion[0x4],i[3]
06-11 11:00:35.996 1786-5021/? D/awplayer: <ComponentThread:4270>: ion_free: handle_ion[0x5],i[4]
06-11 11:00:35.996 1786-5021/? D/awplayer: <ComponentThread:4270>: ion_free: handle_ion[0x6],i[5]
06-11 11:00:35.996 1786-5021/? D/awplayer: <ComponentThread:4270>: ion_free: handle_ion[0x7],i[6]
06-11 11:00:35.996 1786-5021/? D/awplayer: <ComponentThread:4270>: ion_free: handle_ion[0x8],i[7]
06-11 11:00:35.996 1786-5021/? D/awplayer: <ComponentThread:4270>: ion_free: handle_ion[0x9],i[8]
06-11 11:00:35.996 1786-5021/? D/awplayer: <ComponentThread:4270>: ion_free: handle_ion[0xa],i[9]
06-11 11:00:36.005 2623-9653/system_process E/NdkMediaExtractor: sf error code: -1010
06-11 11:00:36.005 2623-9653/system_process E/SoundPool: Unable to load sample
06-11 11:00:36.005 2623-9653/system_process E/NdkMediaExtractor: sf error code: -1010
06-11 11:00:36.005 2623-9653/system_process E/SoundPool: Unable to load sample
06-11 11:00:36.005 2623-9653/system_process E/NdkMediaExtractor: sf error code: -1010
06-11 11:00:36.005 2623-9653/system_process E/SoundPool: Unable to load sample
06-11 11:00:36.005 2623-9653/system_process E/NdkMediaExtractor: sf error code: -1010
06-11 11:00:36.005 2623-9653/system_process E/SoundPool: Unable to load sample
06-11 11:00:36.006 2623-9653/system_process E/NdkMediaExtractor: sf error code: -1010
06-11 11:00:36.006 2623-9653/system_process E/SoundPool: Unable to load sample
06-11 11:00:36.006 2623-9653/system_process E/NdkMediaExtractor: sf error code: -1010
06-11 11:00:36.006 2623-9653/system_process E/SoundPool: Unable to load sample
06-11 11:00:36.007 2623-2993/system_process W/AudioService: onLoadSoundEffects(), Error -10000 while loading samples
06-11 11:00:37.230 1786-2799/? V/audio_hw_primary: out_set_parameters: routing=2
06-11 11:00:37.230 1786-2799/? V/audio_hw_primary: out_set_parameters, line: 2425
06-11 11:00:37.230 1786-2799/? V/audio_hw_primary: >>>>>>>> adev->mode:0,adev->out_device is: 2, val is : 2
06-11 11:00:37.242 1786-2799/? V/audio_hw_primary: start_output_stream, line: 1929
06-11 11:00:37.242 1786-2799/? V/audio_hw_primary: start_output_stream, line: 1971
06-11 11:00:37.242 1786-2799/? D/audio_hw_primary: >>>>>> select_device
06-11 11:00:37.243 1786-2799/? V/audio_hw_primary: active_output, ****LINE:1749,FUNC:select_device, adev->out_device:2
06-11 11:00:37.243 1786-2799/? V/audio_hw_primary: ****LINE:1755,FUNC:select_device, headset_on:0, headphone_on:0, speaker_on:2, earpiece_on:0, bt_on:0
06-11 11:00:37.243 1786-2799/? V/audio_hw_primary: ****LINE:1782,FUNC:select_device, output_device_id:0
06-11 11:00:37.243 1786-2799/? V/audio_hw_primary: NORMAL mode, ****LINE:1786,FUNC:select_device, adev->out_device:2
06-11 11:00:37.244 1786-2799/? V/audio_hw_primary: use AUDIO_CODEC to playback audio
06-11 11:00:37.244 1786-2799/? V/audio_hw_primary: do not use out resampler
06-11 11:00:37.378 1786-2799/? D/AudioFlinger: mixer(0xf1e40000) throttle end: throttle time(33)
06-11 11:00:38.476 9804-9804/? I/pppd: Starting pppd 
06-11 11:00:42.491 2623-10040/system_process E/NdkMediaExtractor: sf error code: -1010
06-11 11:00:42.491 2623-10040/system_process E/SoundPool: Unable to load sample
06-11 11:00:42.492 2623-10040/system_process E/NdkMediaExtractor: sf error code: -1010
06-11 11:00:42.492 2623-10040/system_process E/SoundPool: Unable to load sample
06-11 11:00:42.492 2623-2993/system_process W/AudioService: onLoadSoundEffects(), Error -10000 while loading samples
06-11 11:00:42.493 2623-10040/system_process W/MessageQueue: Handler (android.media.SoundPool$EventHandler) {5358d04} sending message to a Handler on a dead thread
                                                             java.lang.IllegalStateException: Handler (android.media.SoundPool$EventHandler) {5358d04} sending message to a Handler on a dead thread
                                                                 at android.os.MessageQueue.enqueueMessage(MessageQueue.java:543)
                                                                 at android.os.Handler.enqueueMessage(Handler.java:631)
                                                                 at android.os.Handler.sendMessageAtTime(Handler.java:600)
                                                                 at android.os.Handler.sendMessageDelayed(Handler.java:570)
                                                                 at android.os.Handler.sendMessage(Handler.java:507)
                                                                 at android.media.SoundPool.postEventFromNative(SoundPool.java:532)
06-11 11:00:42.493 2623-10040/system_process E/NdkMediaExtractor: sf error code: -1010
06-11 11:00:42.493 2623-10040/system_process E/SoundPool: Unable to load sample
06-11 11:00:42.577 10047-10047/? I/pppd: Starting pppd 
06-11 11:00:43.446 2623-10103/system_process E/NdkMediaExtractor: sf error code: -1010
06-11 11:00:43.446 2623-10103/system_process E/SoundPool: Unable to load sample
06-11 11:00:43.447 2623-10103/system_process E/NdkMediaExtractor: sf error code: -1010
06-11 11:00:43.447 2623-10103/system_process E/SoundPool: Unable to load sample
06-11 11:00:43.447 2623-10103/system_process E/NdkMediaExtractor: sf error code: -1010
06-11 11:00:43.447 2623-10103/system_process E/SoundPool: Unable to load sample
06-11 11:00:43.447 2623-10103/system_process E/NdkMediaExtractor: sf error code: -1010
06-11 11:00:43.447 2623-10103/system_process E/SoundPool: Unable to load sample
06-11 11:00:43.447 2623-10103/system_process E/NdkMediaExtractor: sf error code: -1010
06-11 11:00:43.447 2623-10103/system_process E/SoundPool: Unable to load sample
06-11 11:00:43.448 2623-2993/system_process W/AudioService: onLoadSoundEffects(), Error -10000 while loading samples
06-11 11:00:43.449 2623-10103/system_process E/NdkMediaExtractor: sf error code: -1010
06-11 11:00:43.449 2623-10103/system_process E/SoundPool: Unable to load sample
06-11 11:00:43.453 1786-5021/? D/awplayer: <ComponentThread:4175>:  flush command.
06-11 11:00:43.453 1786-5021/? D/awplayer: <ComponentThread:4184>:  flush all port! we reset decoder!
06-11 11:00:43.453 1786-5021/? D/awplayer: <ComponentThread:4186>: (f:ComponentThread, l:4186) wait for OMX_VdrvCommand_FlushVdecLib
06-11 11:00:43.453 1786-5022/? D/awplayer: <ComponentVdrvThread:4516>: (f:ComponentVdrvThread, l:4516) vdrvThread receive cmd[0x2]
06-11 11:00:43.453 1786-5022/? D/awplayer: <ComponentVdrvThread:4611>: (f:ComponentVdrvThread, l:4611)(OMX_VdrvCommand_FlushVdecLib)
06-11 11:00:43.453 1786-5021/? D/awplayer: <ComponentThread:4188>: (f:ComponentThread, l:4188) wait for OMX_VdrvCommand_FlushVdecLib done!
06-11 11:00:43.453 1786-5021/? W/awplayer: <ComponentThread:4251>: ** return pic when flush,i[0],pPic[0xf18614e8]
06-11 11:00:43.453 1786-5021/? W/awplayer: <ComponentThread:4251>: ** return pic when flush,i[1],pPic[0xf1861018]
06-11 11:00:43.453 1786-5021/? W/awplayer: <ComponentThread:4251>: ** return pic when flush,i[2],pPic[0xf18610c8]
06-11 11:00:43.453 1786-5021/? W/awplayer: <ComponentThread:4251>: ** return pic when flush,i[3],pPic[0xf1861178]
06-11 11:00:43.453 1786-5021/? W/awplayer: <ComponentThread:4251>: ** return pic when flush,i[4],pPic[0xf1861228]
06-11 11:00:43.453 1786-5021/? D/awplayer: <ComponentThread:4242>: fillBufferDone when flush: i[5],pHeadBufInfo[0xf5cdf3b0],mStatus[2]
06-11 11:00:43.455 1786-5021/? W/awplayer: <ComponentThread:4251>: ** return pic when flush,i[6],pPic[0xf1861388]
06-11 11:00:43.455 1786-5021/? W/awplayer: <ComponentThread:4251>: ** return pic when flush,i[7],pPic[0xf1861438]
06-11 11:00:43.455 1786-5021/? W/awplayer: <ComponentThread:4251>: ** return pic when flush,i[8],pPic[0xf1861598]
06-11 11:00:43.455 1786-5021/? W/awplayer: <ComponentThread:4251>: ** return pic when flush,i[9],pPic[0xf1861648]
06-11 11:00:43.455 1786-5021/? D/awplayer: <ComponentThread:4270>: ion_free: handle_ion[0x1],i[0]
06-11 11:00:43.455 1786-5021/? D/awplayer: <ComponentThread:4270>: ion_free: handle_ion[0x2],i[1]
06-11 11:00:43.455 1786-5021/? D/awplayer: <ComponentThread:4270>: ion_free: handle_ion[0x3],i[2]
06-11 11:00:43.455 1786-5021/? D/awplayer: <ComponentThread:4270>: ion_free: handle_ion[0x4],i[3]
06-11 11:00:43.455 1786-5021/? D/awplayer: <ComponentThread:4270>: ion_free: handle_ion[0x5],i[4]
06-11 11:00:43.455 1786-5021/? D/awplayer: <ComponentThread:4270>: ion_free: handle_ion[0x6],i[5]
06-11 11:00:43.455 1786-5021/? D/awplayer: <ComponentThread:4270>: ion_free: handle_ion[0x7],i[6]
06-11 11:00:43.455 1786-5021/? D/awplayer: <ComponentThread:4270>: ion_free: handle_ion[0x8],i[7]
06-11 11:00:43.455 1786-5021/? D/awplayer: <ComponentThread:4270>: ion_free: handle_ion[0x9],i[8]
06-11 11:00:43.455 1786-5021/? D/awplayer: <ComponentThread:4270>: ion_free: handle_ion[0xa],i[9]
06-11 11:00:46.471 1786-2799/? D/audio_hw_primary: out_standby
06-11 11:00:47.698 10327-10327/? I/pppd: Starting pppd 
06-11 11:00:48.067 1786-2799/? V/audio_hw_primary: out_set_parameters: routing=2
06-11 11:00:48.068 1786-2799/? V/audio_hw_primary: out_set_parameters, line: 2425
06-11 11:00:48.068 1786-2799/? V/audio_hw_primary: >>>>>>>> adev->mode:0,adev->out_device is: 2, val is : 2
06-11 11:00:48.081 1786-2799/? V/audio_hw_primary: start_output_stream, line: 1929
06-11 11:00:48.081 1786-2799/? V/audio_hw_primary: start_output_stream, line: 1971
06-11 11:00:48.081 1786-2799/? D/audio_hw_primary: >>>>>> select_device
06-11 11:00:48.084 1786-2799/? V/audio_hw_primary: active_output, ****LINE:1749,FUNC:select_device, adev->out_device:2
06-11 11:00:48.084 1786-2799/? V/audio_hw_primary: ****LINE:1755,FUNC:select_device, headset_on:0, headphone_on:0, speaker_on:2, earpiece_on:0, bt_on:0
06-11 11:00:48.084 1786-2799/? V/audio_hw_primary: ****LINE:1782,FUNC:select_device, output_device_id:0
06-11 11:00:48.084 1786-2799/? V/audio_hw_primary: NORMAL mode, ****LINE:1786,FUNC:select_device, adev->out_device:2
06-11 11:00:48.086 1786-2799/? V/audio_hw_primary: use AUDIO_CODEC to playback audio
06-11 11:00:48.086 1786-2799/? V/audio_hw_primary: do not use out resampler
06-11 11:00:48.208 1786-2799/? D/AudioFlinger: mixer(0xf1e40000) throttle end: throttle time(33)
06-11 11:00:52.786 10673-10673/? I/pppd: Starting pppd 
06-11 11:00:57.863 10977-10977/? I/pppd: Starting pppd 
06-11 11:01:02.935 11306-11306/? I/pppd: Starting pppd 
06-11 11:01:08.019 11600-11600/? I/pppd: Starting pppd 
06-11 11:01:13.126 11905-11905/? I/pppd: Starting pppd 
06-11 11:01:13.418 1786-5022/? D/awplayer: <ComponentVdrvThread:4750>: set nResolutionChangeVdrvThreadFlag to 1, decode detect resolution change
06-11 11:01:13.418 1786-5021/? D/awplayer: <ComponentThread:4370>:  set nResolutionChangeNativeThreadFlag to 1
06-11 11:01:13.419 1786-5021/? D/awplayer: <ComponentThread:4454>: set pSelf->mResolutionChangeFlag to 1
06-11 11:01:13.438 1786-5022/? D/awplayer: <ComponentVdrvThread:4772>: ***ReopenVideoEngine!
06-11 11:01:13.439 1786-5022/? D/awplayer: <CreateSpecificDecoder:962>: Create decoder '115:h264'
06-11 11:01:13.441 1786-5021/? W/awplayer: <ComponentThread:4408>: *** get video fbm buf!
06-11 11:01:13.446 1786-5021/? W/awplayer: <ComponentThread:4408>: *** get video fbm buf!
06-11 11:01:13.452 1786-5021/? W/awplayer: <ComponentThread:4408>: *** get video fbm buf!
06-11 11:01:13.457 1786-5021/? W/awplayer: <ComponentThread:4408>: *** get video fbm buf!
06-11 11:01:13.461 1786-5022/? D/awplayer: <H264DecVuiParameters:270>: nFrameRate=29
06-11 11:01:13.461 1786-5022/? D/fbm.c: <FbmCreateBuffer:105>: FbmCreate, total fbm number: 11, decoder needed: 4,   nWidth=960, nHeight=544 
06-11 11:01:13.463 1786-5021/? W/awplayer: <ComponentThread:4408>: *** get video fbm buf!
06-11 11:01:13.463 1786-5021/? D/awplayer: <OmxGetVideoFbmBufInfo:3351>: video buffer info: nWidth[960],nHeight[544],nBufferCount[11],ePixelFormat[4]
06-11 11:01:13.463 1786-5021/? D/awplayer: <OmxGetVideoFbmBufInfo:3354>: video buffer info: nAlignValue[32],bProgressiveFlag[1],bIsSoftDecoderFlag[0]
06-11 11:01:13.466 1786-5021/? D/awplayer: <ComponentThread:3979>:  stop port command, cmddata = 1.
06-11 11:01:13.466 1786-5021/? D/awplayer: <ComponentThread:4036>: fillBufferDone when stop: i[9],pHeadBufInfo[0xf5cdf400],mStatus[2]
06-11 11:01:13.468 1786-5021/? D/awplayer: <ComponentThread:4055>: ion_free: handle_ion[0x1],i[0]
06-11 11:01:13.468 1786-5021/? D/awplayer: <ComponentThread:4055>: ion_free: handle_ion[0x2],i[1]
06-11 11:01:13.468 1786-5021/? D/awplayer: <ComponentThread:4055>: ion_free: handle_ion[0x3],i[2]
06-11 11:01:13.468 1786-5021/? D/awplayer: <ComponentThread:4055>: ion_free: handle_ion[0x4],i[3]
06-11 11:01:13.468 1786-5021/? D/awplayer: <ComponentThread:4055>: ion_free: handle_ion[0x5],i[4]
06-11 11:01:13.469 1786-5021/? D/awplayer: <ComponentThread:4055>: ion_free: handle_ion[0x6],i[5]
06-11 11:01:13.469 1786-5021/? D/awplayer: <ComponentThread:4055>: ion_free: handle_ion[0x7],i[6]
06-11 11:01:13.469 1786-5021/? D/awplayer: <ComponentThread:4055>: ion_free: handle_ion[0x8],i[7]
06-11 11:01:13.469 1786-5021/? D/awplayer: <ComponentThread:4055>: ion_free: handle_ion[0x9],i[8]
06-11 11:01:13.469 1786-5021/? D/awplayer: <ComponentThread:4055>: ion_free: handle_ion[0xa],i[9]
06-11 11:01:13.664 1786-5021/? D/awplayer: <ComponentThread:4127>:  restart port command.pSelf->m_state[3]
06-11 11:01:13.666 29752-5019/com.flexline D/SurfaceUtils: set up nativeWindow 0x7f7bab5010 for 960x544, color 0x32315659, rotation 0, usage 0x2d00
06-11 11:01:13.667 1786-9659/? D/awplayer: <set_parameter:1349>: the semVal[1] of m_semExtraOutBufferNum is not 0
06-11 11:01:13.667 1786-9659/? D/awplayer: <set_parameter:1375>: (omx_vdec, f:set_parameter, l:1375) OMX_IndexParamPortDefinition, width = 960, height = 544, nPortIndex[1], nBufferCountActual[11], nBufferCountMin[7], nBufferSize[783360],AlloSize[0]
06-11 11:01:13.689 1740-3472/? I/[Gralloc]: ion_alloc from ion_client:18 via heap type DMA(mask:16) for 783360 Bytes uncached buffer successfully, usage = 0x00002d00
06-11 11:01:13.697 1786-5021/? D/awplayer: <OmxReturnBufferToDecoder:3543>: *** call SetVideoFbmBufAddress: pVideoPicture(0xf1861018)
06-11 11:01:13.701 1740-2322/? I/[Gralloc]: ion_alloc from ion_client:18 via heap type DMA(mask:16) for 783360 Bytes uncached buffer successfully, usage = 0x00002d00
06-11 11:01:13.710 1786-5021/? D/awplayer: <OmxReturnBufferToDecoder:3543>: *** call SetVideoFbmBufAddress: pVideoPicture(0xf18610c8)
06-11 11:01:13.712 1786-2802/? W/awplayer: <get_config:1827>: +++++ get display crop: top[0],left[0],width[960],height[540]
06-11 11:01:13.723 1740-2320/? I/[Gralloc]: ion_alloc from ion_client:18 via heap type DMA(mask:16) for 783360 Bytes uncached buffer successfully, usage = 0x00002d00
06-11 11:01:13.737 1786-5021/? D/awplayer: <OmxReturnBufferToDecoder:3543>: *** call SetVideoFbmBufAddress: pVideoPicture(0xf1861178)
06-11 11:01:13.747 1740-2322/? I/[Gralloc]: ion_alloc from ion_client:18 via heap type DMA(mask:16) for 783360 Bytes uncached buffer successfully, usage = 0x00002d00
06-11 11:01:13.757 1786-5021/? D/awplayer: <OmxReturnBufferToDecoder:3543>: *** call SetVideoFbmBufAddress: pVideoPicture(0xf1861228)
06-11 11:01:13.769 1740-2320/? I/[Gralloc]: ion_alloc from ion_client:18 via heap type DMA(mask:16) for 783360 Bytes uncached buffer successfully, usage = 0x00002d00
06-11 11:01:13.777 1786-5021/? D/awplayer: <OmxReturnBufferToDecoder:3543>: *** call SetVideoFbmBufAddress: pVideoPicture(0xf18612d8)
06-11 11:01:13.780 1740-3570/? I/[Gralloc]: ion_alloc from ion_client:18 via heap type DMA(mask:16) for 783360 Bytes uncached buffer successfully, usage = 0x00002d00
06-11 11:01:13.789 1786-5021/? D/awplayer: <OmxReturnBufferToDecoder:3543>: *** call SetVideoFbmBufAddress: pVideoPicture(0xf1861388)
06-11 11:01:13.792 1740-2320/? I/[Gralloc]: ion_alloc from ion_client:18 via heap type DMA(mask:16) for 783360 Bytes uncached buffer successfully, usage = 0x00002d00
06-11 11:01:13.797 1786-5021/? D/awplayer: <OmxReturnBufferToDecoder:3543>: *** call SetVideoFbmBufAddress: pVideoPicture(0xf1861438)
06-11 11:01:15.350 1740-2322/? I/[Gralloc]: ion_alloc from ion_client:18 via heap type DMA(mask:16) for 783360 Bytes uncached buffer successfully, usage = 0x00002d00
06-11 11:01:15.357 1786-5021/? D/awplayer: <OmxReturnBufferToDecoder:3543>: *** call SetVideoFbmBufAddress: pVideoPicture(0xf18614e8)
06-11 11:01:15.397 1740-2320/? I/[Gralloc]: ion_alloc from ion_client:18 via heap type DMA(mask:16) for 783360 Bytes uncached buffer successfully, usage = 0x00002d00
06-11 11:01:15.403 1786-5021/? D/awplayer: <OmxReturnBufferToDecoder:3543>: *** call SetVideoFbmBufAddress: pVideoPicture(0xf1861598)
06-11 11:01:15.419 1740-3570/? I/[Gralloc]: ion_alloc from ion_client:18 via heap type DMA(mask:16) for 783360 Bytes uncached buffer successfully, usage = 0x00002d00
06-11 11:01:15.426 1786-5021/? D/awplayer: <OmxReturnBufferToDecoder:3543>: *** call SetVideoFbmBufAddress: pVideoPicture(0xf1861648)
06-11 11:01:18.223 12218-12218/? I/pppd: Starting pppd 
06-11 11:01:23.308 12535-12535/? I/pppd: Starting pppd 
06-11 11:01:23.867 2623-2751/system_process D/ActivityManager: readMemInfo: freeMem=957972480,threshold=226492416
06-11 11:01:23.867 2623-2751/system_process D/ActivityManager: NOTIFY_CHECK_BACKGROUND_TASK_MSG spends 25ms
06-11 11:01:28.389 12841-12841/? I/pppd: Starting pppd 
06-11 11:01:33.495 13144-13144/? I/pppd: Starting pppd 
06-11 11:01:38.596 13471-13471/? I/pppd: Starting pppd 
06-11 11:01:43.707 13790-13790/? I/pppd: Starting pppd 
06-11 11:01:47.098 1786-5022/? D/awplayer: <ComponentVdrvThread:4750>: set nResolutionChangeVdrvThreadFlag to 1, decode detect resolution change
06-11 11:01:47.098 1786-5021/? D/awplayer: <ComponentThread:4370>:  set nResolutionChangeNativeThreadFlag to 1
06-11 11:01:47.099 1786-5021/? D/awplayer: <ComponentThread:4454>: set pSelf->mResolutionChangeFlag to 1
06-11 11:01:47.118 1786-5022/? D/awplayer: <ComponentVdrvThread:4772>: ***ReopenVideoEngine!
06-11 11:01:47.119 1786-5022/? D/awplayer: <CreateSpecificDecoder:962>: Create decoder '115:h264'
06-11 11:01:47.120 1786-5021/? W/awplayer: <ComponentThread:4408>: *** get video fbm buf!
06-11 11:01:47.125 1786-5021/? W/awplayer: <ComponentThread:4408>: *** get video fbm buf!
06-11 11:01:47.131 1786-5021/? W/awplayer: <ComponentThread:4408>: *** get video fbm buf!
06-11 11:01:47.140 1786-5022/? D/awplayer: <H264DecVuiParameters:270>: nFrameRate=29
06-11 11:01:47.140 1786-5022/? D/fbm.c: <FbmCreateBuffer:105>: FbmCreate, total fbm number: 11, decoder needed: 4,   nWidth=416, nHeight=240 
06-11 11:01:47.141 1786-5021/? W/awplayer: <ComponentThread:4408>: *** get video fbm buf!
06-11 11:01:47.141 1786-5021/? D/awplayer: <OmxGetVideoFbmBufInfo:3351>: video buffer info: nWidth[416],nHeight[256],nBufferCount[11],ePixelFormat[4]
06-11 11:01:47.141 1786-5021/? D/awplayer: <OmxGetVideoFbmBufInfo:3354>: video buffer info: nAlignValue[32],bProgressiveFlag[1],bIsSoftDecoderFlag[0]
06-11 11:01:47.142 1786-5021/? D/awplayer: <ComponentThread:3979>:  stop port command, cmddata = 1.
06-11 11:01:47.143 1786-5021/? D/awplayer: <ComponentThread:4036>: fillBufferDone when stop: i[5],pHeadBufInfo[0xf5cdf310],mStatus[2]
06-11 11:01:47.144 1786-5021/? D/awplayer: <ComponentThread:4055>: ion_free: handle_ion[0x1],i[0]
06-11 11:01:47.144 1786-5021/? D/awplayer: <ComponentThread:4055>: ion_free: handle_ion[0x2],i[1]
06-11 11:01:47.144 1786-5021/? D/awplayer: <ComponentThread:4055>: ion_free: handle_ion[0x3],i[2]
06-11 11:01:47.144 1786-5021/? D/awplayer: <ComponentThread:4055>: ion_free: handle_ion[0x4],i[3]
06-11 11:01:47.144 1786-5021/? D/awplayer: <ComponentThread:4055>: ion_free: handle_ion[0x5],i[4]
06-11 11:01:47.144 1786-5021/? D/awplayer: <ComponentThread:4055>: ion_free: handle_ion[0x6],i[5]
06-11 11:01:47.144 1786-5021/? D/awplayer: <ComponentThread:4055>: ion_free: handle_ion[0x7],i[6]
06-11 11:01:47.144 1786-5021/? D/awplayer: <ComponentThread:4055>: ion_free: handle_ion[0x8],i[7]
06-11 11:01:47.144 1786-5021/? D/awplayer: <ComponentThread:4055>: ion_free: handle_ion[0x9],i[8]
06-11 11:01:47.144 1786-5021/? D/awplayer: <ComponentThread:4055>: ion_free: handle_ion[0xa],i[9]
06-11 11:01:47.292 1786-5021/? D/awplayer: <ComponentThread:4127>:  restart port command.pSelf->m_state[3]
06-11 11:01:47.293 29752-5019/com.flexline D/SurfaceUtils: set up nativeWindow 0x7f7bab5010 for 416x256, color 0x32315659, rotation 0, usage 0x2d00
06-11 11:01:47.293 1786-1786/? D/awplayer: <set_parameter:1349>: the semVal[1] of m_semExtraOutBufferNum is not 0
06-11 11:01:47.294 1786-1786/? D/awplayer: <set_parameter:1375>: (omx_vdec, f:set_parameter, l:1375) OMX_IndexParamPortDefinition, width = 416, height = 256, nPortIndex[1], nBufferCountActual[11], nBufferCountMin[7], nBufferSize[159744],AlloSize[0]
06-11 11:01:48.799 14116-14116/? I/pppd: Starting pppd 
06-11 11:01:50.308 1786-2799/? D/audio_hw_primary: out_standby
06-11 11:01:53.945 14442-14442/? I/pppd: Starting pppd 
06-11 11:01:57.153 2623-4993/system_process D/NetlinkSocketObserver: NeighborEvent{elapsedMs=804741, 192.168.43.1, [9268C3FACD27], RTM_NEWNEIGH, NUD_STALE}
06-11 11:01:59.062 14758-14758/? I/pppd: Starting pppd 
06-11 11:02:04.170 15097-15097/? I/pppd: Starting pppd 
06-11 11:02:09.288 15404-15404/? I/pppd: Starting pppd 
06-11 11:02:14.378 15725-15725/? I/pppd: Starting pppd 
06-11 11:02:19.546 16052-16052/? I/pppd: Starting pppd 
06-11 11:02:23.643 16324-16324/? I/pppd: Starting pppd 
06-11 11:02:23.902 2623-2751/system_process D/ActivityManager: readMemInfo: freeMem=957685760,threshold=226492416
06-11 11:02:23.903 2623-2751/system_process D/ActivityManager: NOTIFY_CHECK_BACKGROUND_TASK_MSG spends 13ms
06-11 11:02:28.748 16623-16623/? I/pppd: Starting pppd 
06-11 11:02:33.828 16938-16938/? I/pppd: Starting pppd 
06-11 11:02:38.946 17277-17277/? I/pppd: Starting pppd 
06-11 11:02:44.067 17594-17594/? I/pppd: Starting pppd 
06-11 11:02:49.211 17950-17950/? I/pppd: Starting pppd 
06-11 11:02:54.349 18239-18239/? I/pppd: Starting pppd 
06-11 11:02:59.425 18579-18579/? I/pppd: Starting pppd 
06-11 11:03:00.402 2623-4993/system_process D/NetlinkSocketObserver: NeighborEvent{elapsedMs=867990, 192.168.43.1, [9268C3FACD27], RTM_NEWNEIGH, NUD_STALE}
06-11 11:03:03.497 18811-18811/? I/pppd: Starting pppd 
06-11 11:03:08.588 19154-19154/? I/pppd: Starting pppd 
06-11 11:03:13.669 19464-19464/? I/pppd: Starting pppd 
06-11 11:03:18.736 19816-19816/? I/pppd: Starting pppd 
06-11 11:03:23.811 20137-20137/? I/pppd: Starting pppd 
06-11 11:03:23.916 2623-2751/system_process D/ActivityManager: readMemInfo: freeMem=945668096,threshold=226492416
06-11 11:03:23.916 2623-2751/system_process D/ActivityManager: NOTIFY_CHECK_BACKGROUND_TASK_MSG spends 7ms
06-11 11:03:28.906 20437-20437/? I/pppd: Starting pppd 
06-11 11:03:33.986 20767-20767/? I/pppd: Starting pppd 
06-11 11:03:39.073 21079-21079/? I/pppd: Starting pppd 
06-11 11:03:44.143 21405-21405/? I/pppd: Starting pppd 
06-11 11:03:49.240 21698-21698/? I/pppd: Starting pppd 
06-11 11:03:54.361 22019-22019/? I/pppd: Starting pppd 
06-11 11:03:59.477 22327-22327/? I/pppd: Starting pppd 
06-11 11:04:03.557 22579-22579/? I/pppd: Starting pppd 
06-11 11:04:08.672 22902-22902/? I/pppd: Starting pppd 
06-11 11:04:13.775 23229-23229/? I/pppd: Starting pppd 
06-11 11:04:18.872 23556-23556/? I/pppd: Starting pppd 
06-11 11:04:23.936 2623-2751/system_process D/ActivityManager: readMemInfo: freeMem=932810752,threshold=226492416
06-11 11:04:23.936 2623-2751/system_process D/ActivityManager: NOTIFY_CHECK_BACKGROUND_TASK_MSG spends 14ms
06-11 11:04:23.968 23863-23863/? I/pppd: Starting pppd 
06-11 11:04:29.044 24188-24188/? I/pppd: Starting pppd 
06-11 11:04:34.149 24492-24492/? I/pppd: Starting pppd 
06-11 11:04:34.722 2623-4993/system_process D/NetlinkSocketObserver: NeighborEvent{elapsedMs=962310, 192.168.43.1, [9268C3FACD27], RTM_NEWNEIGH, NUD_STALE}
06-11 11:04:39.282 24814-24814/? I/pppd: Starting pppd 
06-11 11:04:44.355 25129-25129/? I/pppd: Starting pppd 
06-11 11:04:49.411 25424-25424/? I/pppd: Starting pppd 
06-11 11:04:53.481 25685-25685/? I/pppd: Starting pppd 
06-11 11:04:57.723 2623-4993/system_process D/NetlinkSocketObserver: NeighborEvent{elapsedMs=985310, 192.168.43.1, [9268C3FACD27], RTM_NEWNEIGH, NUD_STALE}
06-11 11:04:58.565 25982-25982/? I/pppd: Starting pppd 
06-11 11:05:03.660 26298-26298/? I/pppd: Starting pppd 
06-11 11:05:08.738 26618-26618/? I/pppd: Starting pppd 
06-11 11:05:13.847 26893-26893/? I/pppd: Starting pppd 
06-11 11:05:18.967 27183-27183/? I/pppd: Starting pppd 
06-11 11:05:23.951 2623-2751/system_process D/ActivityManager: readMemInfo: freeMem=924172288,threshold=226492416
06-11 11:05:23.954 2623-2751/system_process D/ActivityManager: NOTIFY_CHECK_BACKGROUND_TASK_MSG spends 16ms
06-11 11:05:24.072 27502-27502/? I/pppd: Starting pppd 
06-11 11:05:26.244 2623-4993/system_process D/NetlinkSocketObserver: NeighborEvent{elapsedMs=1013831, 192.168.43.1, [9268C3FACD27], RTM_NEWNEIGH, NUD_STALE}
06-11 11:05:29.147 27824-27824/? I/pppd: Starting pppd 
06-11 11:05:34.278 28159-28159/? I/pppd: Starting pppd 
06-11 11:05:39.357 28487-28487/? I/pppd: Starting pppd 
06-11 11:05:43.237 29752-29761/com.flexline W/art: Suspending all threads took: 10.812ms
06-11 11:05:44.473 28816-28816/? I/pppd: Starting pppd 
06-11 11:05:48.556 29076-29076/? I/pppd: Starting pppd 
06-11 11:05:49.602 2623-4993/system_process D/NetlinkSocketObserver: NeighborEvent{elapsedMs=1037190, 192.168.43.1, [9268C3FACD27], RTM_NEWNEIGH, NUD_STALE}
06-11 11:05:53.627 29363-29363/? I/pppd: Starting pppd 
06-11 11:05:58.703 29639-29639/? I/pppd: Starting pppd 
06-11 11:06:03.788 29956-29956/? I/pppd: Starting pppd 
06-11 11:06:08.870 30275-30275/? I/pppd: Starting pppd 
06-11 11:06:13.979 30536-30536/? I/pppd: Starting pppd 
06-11 11:06:14.662 2623-4993/system_process D/NetlinkSocketObserver: NeighborEvent{elapsedMs=1062250, 192.168.43.1, [9268C3FACD27], RTM_NEWNEIGH, NUD_STALE}
06-11 11:06:19.080 30874-30874/? I/pppd: Starting pppd 
06-11 11:06:23.963 2623-2751/system_process D/ActivityManager: readMemInfo: freeMem=911007744,threshold=226492416
06-11 11:06:23.963 2623-2751/system_process D/ActivityManager: NOTIFY_CHECK_BACKGROUND_TASK_MSG spends 6ms
06-11 11:06:24.147 31211-31211/? I/pppd: Starting pppd 
06-11 11:06:29.263 31524-31524/? I/pppd: Starting pppd 
06-11 11:06:34.375 31825-31825/? I/pppd: Starting pppd 
06-11 11:06:39.470 32126-32126/? I/pppd: Starting pppd 
06-11 11:06:43.561 32380-32380/? I/pppd: Starting pppd 
06-11 11:06:48.679 32677-32677/? I/pppd: Starting pppd 
06-11 11:06:53.774 525-525/? I/pppd: Starting pppd 
06-11 11:06:58.872 840-840/? I/pppd: Starting pppd 
06-11 11:07:03.965 1170-1170/? I/pppd: Starting pppd 
06-11 11:07:06.162 2623-4993/system_process D/NetlinkSocketObserver: NeighborEvent{elapsedMs=1113750, 192.168.43.1, [9268C3FACD27], RTM_NEWNEIGH, NUD_STALE}
06-11 11:07:09.042 1490-1490/? I/pppd: Starting pppd 
06-11 11:07:14.207 1822-1822/? I/pppd: Starting pppd 
06-11 11:07:19.339 2144-2144/? I/pppd: Starting pppd 
06-11 11:07:23.975 2623-2751/system_process D/ActivityManager: readMemInfo: freeMem=896770048,threshold=226492416
06-11 11:07:23.975 2623-2751/system_process D/ActivityManager: NOTIFY_CHECK_BACKGROUND_TASK_MSG spends 6ms
06-11 11:07:24.451 2472-2472/? I/pppd: Starting pppd 
06-11 11:07:28.544 2777-2777/? I/pppd: Starting pppd 
06-11 11:07:33.626 3161-3161/? I/pppd: Starting pppd 
06-11 11:07:38.723 3578-3578/? I/pppd: Starting pppd 
06-11 11:07:43.841 4069-4069/? I/pppd: Starting pppd 
06-11 11:07:48.946 4470-4470/? I/pppd: Starting pppd 
06-11 11:07:49.802 2623-4993/system_process D/NetlinkSocketObserver: NeighborEvent{elapsedMs=1157390, 192.168.43.1, [9268C3FACD27], RTM_NEWNEIGH, NUD_STALE}
06-11 11:07:51.520 3417-3417/? I/wpa_supplicant: wlan0: WPA: Group rekeying completed with 92:68:c3:fa:cd:27 [GTK=CCMP]
06-11 11:07:51.547 2623-2975/system_process D/IpReachabilityMonitor: probing ip=192.168.43.1%9
06-11 11:07:54.048 4778-4778/? I/pppd: Starting pppd 
06-11 11:07:59.115 5127-5127/? I/pppd: Starting pppd 
06-11 11:08:04.273 5460-5460/? I/pppd: Starting pppd 
06-11 11:08:07.951 29752-29761/com.flexline W/art: Suspending all threads took: 5.846ms
06-11 11:08:09.378 5770-5770/? I/pppd: Starting pppd 
06-11 11:08:11.522 2623-4993/system_process D/NetlinkSocketObserver: NeighborEvent{elapsedMs=1179110, 192.168.43.1, [9268C3FACD27], RTM_NEWNEIGH, NUD_STALE}
06-11 11:08:14.482 6086-6086/? I/pppd: Starting pppd 
06-11 11:08:18.587 6289-6289/? I/pppd: Starting pppd 
06-11 11:08:23.705 6638-6638/? I/pppd: Starting pppd 
06-11 11:08:23.998 2623-2751/system_process D/ActivityManager: readMemInfo: freeMem=881307648,threshold=226492416
06-11 11:08:23.998 2623-2751/system_process D/ActivityManager: NOTIFY_CHECK_BACKGROUND_TASK_MSG spends 15ms
06-11 11:08:28.776 6938-6938/? I/pppd: Starting pppd 
06-11 11:08:33.868 7255-7255/? I/pppd: Starting pppd 
06-11 11:08:38.974 7571-7571/? I/pppd: Starting pppd 
06-11 11:08:44.168 7852-7852/? I/pppd: Starting pppd 
06-11 11:08:48.276 2623-2749/system_process I/UsageStatsService: User[0] Flushing usage stats to disk
06-11 11:08:49.424 8165-8165/? I/pppd: Starting pppd 
06-11 11:08:53.545 8417-8417/? I/pppd: Starting pppd 
06-11 11:08:58.622 8783-8783/? I/pppd: Starting pppd 
06-11 11:09:03.706 9155-9155/? I/pppd: Starting pppd 
06-11 11:09:08.791 9496-9496/? I/pppd: Starting pppd 
06-11 11:09:13.873 9801-9801/? I/pppd: Starting pppd 
06-11 11:09:18.972 10128-10128/? I/pppd: Starting pppd 
06-11 11:09:24.036 2623-2751/system_process D/ActivityManager: readMemInfo: freeMem=865820672,threshold=226492416
06-11 11:09:24.036 2623-2751/system_process D/ActivityManager: NOTIFY_CHECK_BACKGROUND_TASK_MSG spends 34ms
06-11 11:09:24.064 10459-10459/? I/pppd: Starting pppd 
06-11 11:09:25.460 29752-29765/com.flexline I/art: Background sticky concurrent mark sweep GC freed 132475(9MB) AllocSpace objects, 311(6MB) LOS objects, 18% free, 66MB/82MB, paused 2.019ms total 116.370ms
06-11 11:09:29.144 10774-10774/? I/pppd: Starting pppd 
06-11 11:09:34.226 11106-11106/? I/pppd: Starting pppd 
06-11 11:09:39.354 11395-11395/? I/pppd: Starting pppd 
06-11 11:09:44.458 11741-11741/? I/pppd: Starting pppd 
06-11 11:09:48.538 11960-11960/? I/pppd: Starting pppd 
06-11 11:09:53.600 12248-12248/? I/pppd: Starting pppd 
06-11 11:09:58.711 12554-12554/? I/pppd: Starting pppd 
06-11 11:10:03.858 12838-12838/? I/pppd: Starting pppd 
06-11 11:10:08.971 13139-13139/? I/pppd: Starting pppd 
06-11 11:10:14.083 13462-13462/? I/pppd: Starting pppd 
06-11 11:10:19.152 13783-13783/? I/pppd: Starting pppd 
06-11 11:10:24.059 2623-2751/system_process D/ActivityManager: readMemInfo: freeMem=860303360,threshold=226492416
06-11 11:10:24.059 2623-2751/system_process D/ActivityManager: NOTIFY_CHECK_BACKGROUND_TASK_MSG spends 17ms
06-11 11:10:24.277 14093-14093/? I/pppd: Starting pppd 
06-11 11:10:29.399 14437-14437/? I/pppd: Starting pppd 
06-11 11:10:33.580 14667-14667/? I/pppd: Starting pppd 
06-11 11:10:38.674 14992-14992/? I/pppd: Starting pppd 
06-11 11:10:43.758 15303-15303/? I/pppd: Starting pppd 
06-11 11:10:48.854 15625-15625/? I/pppd: Starting pppd 
06-11 11:10:53.957 15932-15932/? I/pppd: Starting pppd 
06-11 11:10:59.050 16228-16228/? I/pppd: Starting pppd 
06-11 11:11:04.119 16542-16542/? I/pppd: Starting pppd 
06-11 11:11:09.203 16854-16854/? I/pppd: Starting pppd 
06-11 11:11:14.288 17193-17193/? I/pppd: Starting pppd 
06-11 11:11:16.230 29752-29761/com.flexline W/art: Suspending all threads took: 9.397ms
06-11 11:11:19.385 17513-17513/? I/pppd: Starting pppd 
06-11 11:11:24.071 2623-2751/system_process D/ActivityManager: readMemInfo: freeMem=845783040,threshold=226492416
06-11 11:11:24.071 2623-2751/system_process D/ActivityManager: NOTIFY_CHECK_BACKGROUND_TASK_MSG spends 5ms
06-11 11:11:24.479 17828-17828/? I/pppd: Starting pppd 
06-11 11:11:28.556 18086-18086/? I/pppd: Starting pppd 
06-11 11:11:33.671 18417-18417/? I/pppd: Starting pppd 
06-11 11:11:38.784 18741-18741/? I/pppd: Starting pppd 
06-11 11:11:43.887 19055-19055/? I/pppd: Starting pppd 
06-11 11:11:48.959 19368-19368/? I/pppd: Starting pppd 
06-11 11:11:54.046 19638-19638/? I/pppd: Starting pppd 
06-11 11:11:59.149 19938-19938/? I/pppd: Starting pppd 
06-11 11:12:04.259 20256-20256/? I/pppd: Starting pppd 
06-11 11:12:09.354 20574-20574/? I/pppd: Starting pppd 
06-11 11:12:14.438 20884-20884/? I/pppd: Starting pppd 
06-11 11:12:18.530 21130-21130/? I/pppd: Starting pppd 
06-11 11:12:23.598 21435-21435/? I/pppd: Starting pppd 
06-11 11:12:24.094 2623-2751/system_process D/ActivityManager: readMemInfo: freeMem=832638976,threshold=226492416
06-11 11:12:24.094 2623-2751/system_process D/ActivityManager: NOTIFY_CHECK_BACKGROUND_TASK_MSG spends 12ms
06-11 11:12:28.708 21737-21737/? I/pppd: Starting pppd 
06-11 11:12:33.808 22066-22066/? I/pppd: Starting pppd 
06-11 11:12:38.904 22359-22359/? I/pppd: Starting pppd 
06-11 11:12:44.020 22645-22645/? I/pppd: Starting pppd 
06-11 11:12:49.145 22949-22949/? I/pppd: Starting pppd 
06-11 11:12:54.248 23272-23272/? I/pppd: Starting pppd 
06-11 11:12:59.354 23564-23564/? I/pppd: Starting pppd 
06-11 11:13:04.454 23888-23888/? I/pppd: Starting pppd 
06-11 11:13:06.264 2623-2640/system_process I/art: Background sticky concurrent mark sweep GC freed 12899(1458KB) AllocSpace objects, 54(1080KB) LOS objects, 25% free, 8MB/11MB, paused 3.989ms total 106.132ms
06-11 11:13:08.638 24144-24144/? I/pppd: Starting pppd 
06-11 11:13:13.737 24470-24470/? I/pppd: Starting pppd 
06-11 11:13:18.859 24769-24769/? I/pppd: Starting pppd 
06-11 11:13:23.961 25109-25109/? I/pppd: Starting pppd 
06-11 11:13:24.136 2623-2751/system_process D/ActivityManager: readMemInfo: freeMem=817750016,threshold=226492416
06-11 11:13:24.136 2623-2751/system_process D/ActivityManager: NOTIFY_CHECK_BACKGROUND_TASK_MSG spends 29ms
06-11 11:13:29.043 25474-25474/? I/pppd: Starting pppd 
06-11 11:13:34.103 25814-25814/? I/pppd: Starting pppd 
06-11 11:13:39.173 26174-26174/? I/pppd: Starting pppd 
06-11 11:13:44.241 26509-26509/? I/pppd: Starting pppd 
06-11 11:13:49.461 26870-26870/? I/pppd: Starting pppd 
06-11 11:13:53.525 27141-27141/? I/pppd: Starting pppd 
06-11 11:13:58.631 27483-27483/? I/pppd: Starting pppd 
06-11 11:14:03.701 27837-27837/? I/pppd: Starting pppd 
06-11 11:14:08.772 28177-28177/? I/pppd: Starting pppd 
06-11 11:14:13.870 28541-28541/? I/pppd: Starting pppd 
06-11 11:14:18.964 28865-28865/? I/pppd: Starting pppd 
06-11 11:14:24.063 29227-29227/? I/pppd: Starting pppd 
06-11 11:14:24.157 2623-2751/system_process D/ActivityManager: readMemInfo: freeMem=809504768,threshold=226492416
06-11 11:14:24.157 2623-2751/system_process D/ActivityManager: NOTIFY_CHECK_BACKGROUND_TASK_MSG spends 9ms
06-11 11:14:29.151 29563-29563/? I/pppd: Starting pppd 
06-11 11:14:34.407 29954-29954/? I/pppd: Starting pppd 
06-11 11:14:38.528 30239-30239/? I/pppd: Starting pppd 
06-11 11:14:43.628 30585-30585/? I/pppd: Starting pppd 
06-11 11:14:48.709 30932-30932/? I/pppd: Starting pppd 
06-11 11:14:53.816 31272-31272/? I/pppd: Starting pppd 
06-11 11:14:58.886 31629-31629/? I/pppd: Starting pppd 
06-11 11:15:03.958 31966-31966/? I/pppd: Starting pppd 
06-11 11:15:09.048 32328-32328/? I/pppd: Starting pppd 
06-11 11:15:14.149 32665-32665/? I/pppd: Starting pppd 
06-11 11:15:19.337 558-558/? I/pppd: Starting pppd 
06-11 11:15:24.181 2623-2751/system_process D/ActivityManager: readMemInfo: freeMem=795922432,threshold=226492416
06-11 11:15:24.181 2623-2751/system_process D/ActivityManager: NOTIFY_CHECK_BACKGROUND_TASK_MSG spends 9ms
06-11 11:15:24.423 917-917/? I/pppd: Starting pppd 
06-11 11:15:28.288 29752-29761/com.flexline W/art: Suspending all threads took: 6.501ms
06-11 11:15:28.487 1214-1214/? I/pppd: Starting pppd 
06-11 11:15:33.588 1561-1561/? I/pppd: Starting pppd 
06-11 11:15:38.668 1961-1961/? I/pppd: Starting pppd 
06-11 11:15:43.742 2325-2325/? I/pppd: Starting pppd 
06-11 11:15:48.840 2715-2715/? I/pppd: Starting pppd 
06-11 11:15:53.909 3142-3142/? I/pppd: Starting pppd 
06-11 11:15:58.989 3574-3574/? I/pppd: Starting pppd 
06-11 11:16:04.092 4111-4111/? I/pppd: Starting pppd 
06-11 11:16:09.172 4510-4510/? I/pppd: Starting pppd 
06-11 11:16:14.267 4851-4851/? I/pppd: Starting pppd 
06-11 11:16:19.445 5251-5251/? I/pppd: Starting pppd 
06-11 11:16:23.534 5533-5533/? I/pppd: Starting pppd 
06-11 11:16:24.211 2623-2751/system_process D/ActivityManager: readMemInfo: freeMem=778489856,threshold=226492416
06-11 11:16:24.211 2623-2751/system_process D/ActivityManager: NOTIFY_CHECK_BACKGROUND_TASK_MSG spends 16ms
06-11 11:16:28.642 5907-5907/? I/pppd: Starting pppd 
06-11 11:16:33.758 6248-6248/? I/pppd: Starting pppd 
06-11 11:16:38.867 6613-6613/? I/pppd: Starting pppd 
06-11 11:16:44.054 6968-6968/? I/pppd: Starting pppd 
06-11 11:16:49.168 7323-7323/? I/pppd: Starting pppd 
06-11 11:16:54.257 7669-7669/? I/pppd: Starting pppd 
06-11 11:16:59.344 8015-8015/? I/pppd: Starting pppd 
06-11 11:17:04.427 8379-8379/? I/pppd: Starting pppd 
06-11 11:17:08.644 8657-8657/? I/pppd: Starting pppd 
06-11 11:17:13.789 9099-9099/? I/pppd: Starting pppd 
06-11 11:17:18.928 9445-9445/? I/pppd: Starting pppd 
06-11 11:17:23.992 9805-9805/? I/pppd: Starting pppd 
06-11 11:17:24.218 2623-2751/system_process D/ActivityManager: readMemInfo: freeMem=769495040,threshold=226492416
06-11 11:17:24.218 2623-2751/system_process D/ActivityManager: NOTIFY_CHECK_BACKGROUND_TASK_MSG spends 6ms
06-11 11:17:29.085 10145-10145/? I/pppd: Starting pppd 
06-11 11:17:34.170 10497-10497/? I/pppd: Starting pppd 
06-11 11:17:39.264 10852-10852/? I/pppd: Starting pppd 
06-11 11:17:44.351 11176-11176/? I/pppd: Starting pppd 
06-11 11:17:49.428 11534-11534/? I/pppd: Starting pppd 
06-11 11:17:51.533 3417-3417/? I/wpa_supplicant: wlan0: WPA: Group rekeying completed with 92:68:c3:fa:cd:27 [GTK=CCMP]
06-11 11:17:51.537 2623-2975/system_process D/IpReachabilityMonitor: probing ip=192.168.43.1%9
06-11 11:17:53.515 11798-11798/? I/pppd: Starting pppd 
06-11 11:17:58.598 12129-12129/? I/pppd: Starting pppd 
06-11 11:18:03.669 12452-12452/? I/pppd: Starting pppd 
06-11 11:18:08.202 2623-4993/system_process D/NetlinkSocketObserver: NeighborEvent{elapsedMs=1775790, 192.168.43.1, [9268C3FACD27], RTM_NEWNEIGH, NUD_STALE}
06-11 11:18:08.813 12804-12804/? I/pppd: Starting pppd 
06-11 11:18:09.998 3417-3417/? E/wpa_supplicant: wpa_driver_nl80211_driver_cmd: zwww skip failed to issue private command: BTCOEXMODE 1
06-11 11:18:09.999 2623-2975/system_process E/native: do suspend false
06-11 11:18:09.999 3417-3417/? E/wpa_supplicant: wpa_driver_nl80211_driver_cmd: zwww skip failed to issue private command: SETSUSPENDMODE 0
06-11 11:18:10.033 12866-12866/? I/dhcpcd: sending signal 14 to pid 4997
06-11 11:18:10.034 4997-4997/? I/dhcpcd: received SIGALRM, renewing
06-11 11:18:10.034 4997-4997/? I/dhcpcd: wlan0: renewing lease of 192.168.43.64
06-11 11:18:10.161 4997-4997/? I/dhcpcd: wlan0: acknowledged 192.168.43.64 from 192.168.43.1
06-11 11:18:10.192 4997-4997/? I/dhcpcd: wlan0: leased 192.168.43.64 for 3600 seconds
06-11 11:18:10.613 3417-3417/? E/wpa_supplicant: wpa_driver_nl80211_driver_cmd: zwww skip failed to issue private command: BTCOEXMODE 2
06-11 11:18:10.616 2623-2983/system_process D/ConnectivityService: notifyType CAP_CHANGED for NetworkAgentInfo [WIFI () - 100]
06-11 11:18:10.617 2623-2975/system_process D/WifiConfigStore: No blacklist allowed without epno enabled
06-11 11:18:10.623 2623-2975/system_process D/WifiStateMachine: WifiStateMachine: Leaving Connected state
06-11 11:18:13.659 13103-13103/? I/pppd: Starting pppd 
06-11 11:18:18.768 13371-13371/? I/pppd: Starting pppd 
06-11 11:18:19.224 2623-2975/system_process D/WifiStateMachine: L2Connected CMD_START_SCAN source -2 91, 92 -> obsolete
06-11 11:18:23.876 13634-13634/? I/pppd: Starting pppd 
06-11 11:18:24.237 2623-2751/system_process D/ActivityManager: readMemInfo: freeMem=755650560,threshold=226492416
06-11 11:18:24.237 2623-2751/system_process D/ActivityManager: NOTIFY_CHECK_BACKGROUND_TASK_MSG spends 15ms
06-11 11:18:28.988 13880-13880/? I/pppd: Starting pppd 
06-11 11:18:30.628 2623-2975/system_process D/WifiStateMachine: starting scan for "AndroidAP"WPA_PSK with 2437
06-11 11:18:31.802 2623-4993/system_process D/NetlinkSocketObserver: NeighborEvent{elapsedMs=1799390, 192.168.43.1, [9268C3FACD27], RTM_NEWNEIGH, NUD_STALE}
06-11 11:18:32.473 2623-2754/system_process E/KernelUidCpuTimeReader: Failed to read uid_cputime: /proc/uid_cputime/show_uid_stat: open failed: ENOENT (No such file or directory)
06-11 11:18:34.090 14124-14124/? I/pppd: Starting pppd 
06-11 11:18:39.188 14368-14368/? I/pppd: Starting pppd 
06-11 11:18:44.301 14634-14634/? I/pppd: Starting pppd 
06-11 11:18:49.390 14936-14936/? I/pppd: Starting pppd 
06-11 11:18:50.975 2623-2751/system_process I/ProcessStatsService: Prepared write state in 2ms
06-11 11:18:54.480 15229-15229/? I/pppd: Starting pppd 
06-11 11:18:59.560 15540-15540/? I/pppd: Starting pppd 
06-11 11:19:00.003 2623-2623/system_process I/ActivityManager: Killing 3473:com.android.printspooler/u0a40 (adj 11): empty for 1803s
06-11 11:19:00.088 2623-2623/system_process I/ActivityManager: Killing 3460:system:ui/1000 (adj 13): empty for 1803s
06-11 11:19:00.161 2623-2623/system_process I/ActivityManager: Killing 3819:com.smdt.test.basic/1000 (adj 13): empty for 1803s
06-11 11:19:00.232 2623-2623/system_process I/ActivityManager: Killing 4026:com.floriandraschbacher.deskdock.free/u0a52 (adj 13): empty for 1803s
06-11 11:19:00.298 2623-2623/system_process I/ActivityManager: Killing 4004:com.android.onetimeinitializer/u0a9 (adj 13): empty for 1804s
06-11 11:19:00.388 2623-2623/system_process I/ActivityManager: Killing 3978:com.android.managedprovisioning/u0a7 (adj 13): empty for 1804s
06-11 11:19:00.436 2623-2668/system_process W/art: Long monitor contention event with owner method=void com.android.server.am.ActivityManagerService.trimApplications() from ActivityManagerService.java:19734 waiters=0 for 432ms
06-11 11:19:00.437 2623-2711/system_process W/art: Long monitor contention event with owner method=void com.android.server.am.ActivityManagerService.trimApplications() from ActivityManagerService.java:19734 waiters=1 for 432ms
06-11 11:19:00.438 2623-3019/system_process W/art: Long monitor contention event with owner method=void com.android.server.am.ActivityManagerService.trimApplications() from ActivityManagerService.java:19734 waiters=2 for 356ms
06-11 11:19:00.438 2623-2731/system_process W/art: Long monitor contention event with owner method=void com.android.server.am.ActivityManagerService.trimApplications() from ActivityManagerService.java:19734 waiters=3 for 283ms
06-11 11:19:04.656 15834-15834/? I/pppd: Starting pppd 
06-11 11:19:09.748 16139-16139/? I/pppd: Starting pppd 
06-11 11:19:14.833 16451-16451/? I/pppd: Starting pppd 
06-11 11:19:19.946 16760-16760/? I/pppd: Starting pppd 
06-11 11:19:24.269 2623-2751/system_process D/ActivityManager: readMemInfo: freeMem=778371072,threshold=226492416
06-11 11:19:24.270 2623-2751/system_process D/ActivityManager: NOTIFY_CHECK_BACKGROUND_TASK_MSG spends 18ms
06-11 11:19:25.084 17057-17057/? I/pppd: Starting pppd 
06-11 11:19:30.182 17396-17396/? I/pppd: Starting pppd 
06-11 11:19:35.276 17704-17704/? I/pppd: Starting pppd 
06-11 11:19:40.344 18021-18021/? I/pppd: Starting pppd 
06-11 11:19:45.455 18332-18332/? I/pppd: Starting pppd 
06-11 11:19:49.558 18573-18573/? I/pppd: Starting pppd 
06-11 11:19:54.648 18881-18881/? I/pppd: Starting pppd 
06-11 11:19:59.711 19206-19206/? I/pppd: Starting pppd