07-09 12:02:24.901: D/dalvikvm(27070): Trying to load lib /data/data/com.qualcomm.QCARSamples.VideoPlayback/lib/libQCAR.so 0x427e78d8 07-09 12:02:24.924: D/dalvikvm(27070): Added shared lib /data/data/com.qualcomm.QCARSamples.VideoPlayback/lib/libQCAR.so 0x427e78d8 07-09 12:02:25.018: I/QCAR(27070): Native library libQCAR.so loaded 07-09 12:02:25.018: D/dalvikvm(27070): Trying to load lib /data/data/com.qualcomm.QCARSamples.VideoPlayback/lib/libVideoPlayback.so 0x427e78d8 07-09 12:02:25.018: D/dalvikvm(27070): Added shared lib /data/data/com.qualcomm.QCARSamples.VideoPlayback/lib/libVideoPlayback.so 0x427e78d8 07-09 12:02:25.018: I/QCAR(27070): JNI_OnLoad 07-09 12:02:25.018: I/QCAR(27070): Native library libVideoPlayback.so loaded 07-09 12:02:25.026: D/QCAR(27070): VideoPlayback::onCreate 07-09 12:02:25.081: D/dalvikvm(27070): GC_FOR_ALLOC freed 60K, 10% free 7431K/8195K, paused 38ms, total 47ms 07-09 12:02:25.081: I/dalvikvm-heap(27070): Grow heap (frag case) to 8.719MB for 921616-byte allocation 07-09 12:02:25.135: D/dalvikvm(27070): GC_CONCURRENT freed <1K, 10% free 8330K/9159K, paused 23ms+2ms, total 56ms 07-09 12:02:25.166: D/dalvikvm(27070): GC_FOR_ALLOC freed 16K, 10% free 8314K/9159K, paused 26ms, total 26ms 07-09 12:02:25.174: I/dalvikvm-heap(27070): Grow heap (frag case) to 9.582MB for 921616-byte allocation 07-09 12:02:25.206: D/dalvikvm(27070): GC_CONCURRENT freed 0K, 9% free 9214K/10119K, paused 13ms+2ms, total 33ms 07-09 12:02:25.206: D/dalvikvm(27070): WAIT_FOR_CONCURRENT_GC blocked 20ms 07-09 12:02:25.229: D/dalvikvm(27070): GC_FOR_ALLOC freed 0K, 9% free 9214K/10119K, paused 20ms, total 20ms 07-09 12:02:25.237: I/dalvikvm-heap(27070): Grow heap (frag case) to 10.460MB for 921616-byte allocation 07-09 12:02:25.276: D/dalvikvm(27070): GC_CONCURRENT freed 0K, 9% free 10114K/11079K, paused 12ms+12ms, total 40ms 07-09 12:02:25.541: D/dalvikvm(27070): GC_FOR_ALLOC freed 1808K, 25% free 8330K/11079K, paused 21ms, total 21ms 07-09 12:02:25.541: I/dalvikvm-heap(27070): Grow heap (frag case) to 9.597MB for 921616-byte allocation 07-09 12:02:25.588: D/dalvikvm(27070): GC_CONCURRENT freed <1K, 17% free 9230K/11079K, paused 12ms+2ms, total 46ms 07-09 12:02:25.666: D/dalvikvm(27070): GC_FOR_ALLOC freed 16K, 17% free 9214K/11079K, paused 21ms, total 21ms 07-09 12:02:25.674: I/dalvikvm-heap(27070): Grow heap (frag case) to 10.460MB for 921616-byte allocation 07-09 12:02:25.706: D/dalvikvm(27070): GC_CONCURRENT freed 0K, 9% free 10114K/11079K, paused 13ms+3ms, total 36ms 07-09 12:02:25.706: D/dalvikvm(27070): WAIT_FOR_CONCURRENT_GC blocked 8ms 07-09 12:02:25.729: D/dalvikvm(27070): GC_FOR_ALLOC freed 0K, 9% free 10114K/11079K, paused 19ms, total 19ms 07-09 12:02:25.729: I/dalvikvm-heap(27070): Grow heap (frag case) to 11.339MB for 921616-byte allocation 07-09 12:02:25.776: D/dalvikvm(27070): GC_CONCURRENT freed 0K, 9% free 11014K/12039K, paused 13ms+3ms, total 47ms 07-09 12:02:26.088: D/dalvikvm(27070): GC_FOR_ALLOC freed 2344K, 26% free 9487K/12679K, paused 26ms, total 26ms 07-09 12:02:26.112: D/dalvikvm(27070): GC_FOR_ALLOC freed 16K, 22% free 9982K/12679K, paused 18ms, total 18ms 07-09 12:02:26.245: D/dalvikvm(27070): GC_CONCURRENT freed 536K, 20% free 10239K/12679K, paused 2ms+2ms, total 36ms 07-09 12:02:26.245: D/dalvikvm(27070): WAIT_FOR_CONCURRENT_GC blocked 34ms 07-09 12:02:26.401: D/dalvikvm(27070): GC_FOR_ALLOC freed 521K, 22% free 9978K/12679K, paused 42ms, total 42ms 07-09 12:02:26.401: I/dalvikvm-heap(27070): Grow heap (frag case) to 11.792MB for 1536016-byte allocation 07-09 12:02:26.432: D/dalvikvm(27070): GC_CONCURRENT freed <1K, 10% free 11478K/12679K, paused 3ms+3ms, total 26ms 07-09 12:02:26.479: D/QCAR(27070): VideoPlayback::onResume 07-09 12:02:26.510: I/AR(27070): QCAR has been initialized successfully 07-09 12:02:26.510: I/AR(27070): QCAR SDK version 2.5.7 07-09 12:02:26.588: D/libEGL(27070): loaded /vendor/lib/egl/libEGL_POWERVR_SGX540_120.so 07-09 12:02:26.620: D/libEGL(27070): loaded /vendor/lib/egl/libGLESv1_CM_POWERVR_SGX540_120.so 07-09 12:02:26.620: D/libEGL(27070): loaded /vendor/lib/egl/libGLESv2_POWERVR_SGX540_120.so 07-09 12:02:26.768: D/OpenGLRenderer(27070): Enabling debug mode 0 07-09 12:02:27.541: D/QCAR(27070): InitQCARTask::onPostExecute: QCAR initialization successful 07-09 12:02:27.541: I/QCAR(27070): Java_com_qualcomm_QCARSamples_VideoPlayback_VideoPlayback_initTracker 07-09 12:02:27.557: I/QCAR(27070): Successfully initialized ImageTracker. 07-09 12:02:27.557: I/QCAR(27070): Java_com_qualcomm_QCARSamples_VideoPlayback_VideoPlayback_initApplicationNative 07-09 12:02:27.573: I/QCAR(27070): Java_com_qualcomm_QCARSamples_VideoPlayback_VideoPlayback_initApplicationNative finished 07-09 12:02:27.581: I/QCAR(27070): Using OpenGL ES 2.0 07-09 12:02:27.581: I/QCAR(27070): Using opaque GLView, depth buffer size: 16, stencil size: 0 07-09 12:02:27.604: I/QCAR(27070): Java_com_qualcomm_QCARSamples_VideoPlayback_VideoPlayback_loadTrackerData 07-09 12:02:27.604: I/AR(27070): ImageTracker: Successfully created dataset 07-09 12:02:27.659: D/dalvikvm(27070): GC_FOR_ALLOC freed 507K, 13% free 11544K/13127K, paused 21ms, total 23ms 07-09 12:02:28.651: D/dalvikvm(27070): GC_FOR_ALLOC freed 1277K, 15% free 11546K/13511K, paused 19ms, total 19ms 07-09 12:02:29.432: D/dalvikvm(27070): GC_CONCURRENT freed 1384K, 15% free 11547K/13575K, paused 12ms+2ms, total 30ms 07-09 12:02:29.432: D/dalvikvm(27070): WAIT_FOR_CONCURRENT_GC blocked 7ms 07-09 12:02:29.502: I/QCAR(27070): Successfully loaded and activated data set. 07-09 12:02:29.502: D/QCAR(27070): LoadTrackerTask::onPostExecute: execution successful 07-09 12:02:29.502: I/QCAR(27070): Java_com_qualcomm_QCARSamples_VideoPlayback_VideoPlayback_initCloudReco 07-09 12:02:29.534: D/AR(27070): ARHttpClient: Executing post! 07-09 12:02:29.557: D/AR(27070): ARHttpClient: Executing post! 07-09 12:02:31.276: D/QCAR(27070): InitCloudRecoTask::onPostExecute: execution successful 07-09 12:02:31.276: D/dalvikvm(27070): WAIT_FOR_CONCURRENT_GC blocked 0ms 07-09 12:02:31.323: D/dalvikvm(27070): GC_EXPLICIT freed 506K, 14% free 11682K/13575K, paused 5ms+4ms, total 47ms 07-09 12:02:31.362: I/QCAR(27070): Java_com_qualcomm_QCARSamples_VideoPlayback_VideoPlayback_startCamera 07-09 12:02:31.635: I/QCAR(27070): Configure Video Background : Video (640,480), Screen (1024,552), mSize (1024,768) 07-09 12:02:31.721: D/dalvikvm(27070): GC_FOR_ALLOC freed 605K, 11% free 12190K/13575K, paused 21ms, total 21ms 07-09 12:02:31.721: D/dalvikvm(27070): WAIT_FOR_CONCURRENT_GC blocked 0ms 07-09 12:02:31.752: D/dalvikvm(27070): GC_EXPLICIT freed 1K, 11% free 12643K/14087K, paused 3ms+3ms, total 32ms 07-09 12:02:32.354: I/QCAR(27070): Java_com_qualcomm_QCARSamples_VideoPlayback_VideoPlayback_setProjectionMatrix 07-09 12:02:32.370: I/Choreographer(27070): Skipped 61 frames! The application may be doing too much work on its main thread. 07-09 12:02:32.557: W/SurfaceView(27070): CHECK surface infomation creating=true formatChanged=true sizeChanged=true visible=false visibleChanged=false surfaceChanged=false realSizeChanged=true redrawNeeded=true left=false top=false 07-09 12:02:37.385: D/AR(27070): ARHttpClient: Executing post! 07-09 12:02:38.206: I/QCAR(27070): Creating OpenGL ES 2.0 context 07-09 12:02:38.252: I/QCAR(27070): Java_com_qualcomm_QCARSamples_VideoPlayback_VideoPlaybackRenderer_initRendering 07-09 12:02:38.284: I/QCAR(27070): Java_com_qualcomm_QCARSamples_VideoPlayback_VideoPlaybackRenderer_updateRendering 07-09 12:02:38.284: I/QCAR(27070): Configure Video Background : Video (640,480), Screen (1024,552), mSize (1024,768) 07-09 12:02:42.096: D/AR(27070): ARHttpClient: Executing post! 07-09 12:02:44.260: I/AR(27070): Completed CloudReco transaction with ID 'e6e69ea9e4d240a1a549aa05cb6f546d' 07-09 12:02:44.284: I/AR(27070): ImageTracker: Successfully created dataset 07-09 12:02:44.354: I/AR(27070): Successfully created ImageTarget. 07-09 12:02:44.354: I/QCAR(27070): Benz.m4v 07-09 12:02:44.354: I/QCAR(27070): Calling... 07-09 12:02:44.354: I/QCAR(27070): Cloud response obtained is: Benz.m4v 07-09 12:02:44.354: I/QCAR(27070): Called... 07-09 12:02:45.706: D/QCAR(27070): VideoPlayback::onPause 07-09 12:02:45.831: W/SurfaceView(27070): CHECK surface infomation creating=false formatChanged=false sizeChanged=false visible=false visibleChanged=true surfaceChanged=true realSizeChanged=false redrawNeeded=false left=false top=false 07-09 12:02:45.838: I/QCAR(27070): Java_com_qualcomm_QCARSamples_VideoPlayback_VideoPlayback_stopCamera 07-09 12:02:46.049: D/dalvikvm(27070): WAIT_FOR_CONCURRENT_GC blocked 0ms 07-09 12:02:46.081: D/dalvikvm(27070): GC_EXPLICIT freed 505K, 10% free 12788K/14087K, paused 2ms+3ms, total 31ms 07-09 12:02:46.081: E/MediaPlayer(27070): stop called in state 1 07-09 12:02:46.081: E/MediaPlayer(27070): error (-38, 0) 07-09 12:02:46.088: E/MediaPlayer(27070): stop called in state 1 07-09 12:02:46.088: E/MediaPlayer(27070): error (-38, 0) 07-09 12:02:46.127: W/MediaPlayer(27070): mediaplayer went away with unhandled events 07-09 12:02:46.127: W/MediaPlayer(27070): mediaplayer went away with unhandled events 07-09 12:02:46.284: W/SurfaceView(27070): CHECK surface infomation creating=false formatChanged=false sizeChanged=false visible=false visibleChanged=true surfaceChanged=true realSizeChanged=false redrawNeeded=true left=false top=false 07-09 12:02:46.370: E/SpannableStringBuilder(27070): SPAN_EXCLUSIVE_EXCLUSIVE spans cannot have a zero length 07-09 12:02:46.370: E/SpannableStringBuilder(27070): SPAN_EXCLUSIVE_EXCLUSIVE spans cannot have a zero length 07-09 12:02:47.581: E/MediaPlayer(27070): error (-38, 0) 07-09 12:02:47.635: E/MediaPlayer(27070): Error (-38,0) 07-09 12:02:47.635: E/MediaPlayer(27070): stop called in state 0 07-09 12:02:47.666: D/QCAR(27070): VideoPlayback::onResume 07-09 12:02:47.682: I/QCAR(27070): Java_com_qualcomm_QCARSamples_VideoPlayback_VideoPlayback_startCamera 07-09 12:02:47.823: I/QCAR(27070): Configure Video Background : Video (640,480), Screen (1024,552), mSize (1024,768) 07-09 12:02:47.870: D/dalvikvm(27070): WAIT_FOR_CONCURRENT_GC blocked 0ms 07-09 12:02:47.909: D/dalvikvm(27070): GC_EXPLICIT freed 1500K, 16% free 12749K/15111K, paused 3ms+3ms, total 35ms 07-09 12:02:48.479: I/QCAR(27070): Java_com_qualcomm_QCARSamples_VideoPlayback_VideoPlayback_setProjectionMatrix 07-09 12:02:48.487: I/Choreographer(27070): Skipped 48 frames! The application may be doing too much work on its main thread. 07-09 12:02:48.495: I/QCAR(27070): Creating OpenGL ES 2.0 context 07-09 12:02:48.526: I/QCAR(27070): Java_com_qualcomm_QCARSamples_VideoPlayback_VideoPlaybackRenderer_initRendering 07-09 12:02:48.565: I/QCAR(27070): Java_com_qualcomm_QCARSamples_VideoPlayback_VideoPlaybackRenderer_updateRendering 07-09 12:02:48.565: I/QCAR(27070): Configure Video Background : Video (640,480), Screen (1024,552), mSize (1024,768) 07-09 12:02:48.620: I/QCAR(27070): Java_com_qualcomm_QCARSamples_VideoPlayback_VideoPlaybackRenderer_updateRendering 07-09 12:02:48.620: I/QCAR(27070): Configure Video Background : Video (640,480), Screen (1024,552), mSize (1024,768) 07-09 12:02:48.737: W/SurfaceView(27070): CHECK surface infomation creating=false formatChanged=false sizeChanged=false visible=false visibleChanged=true surfaceChanged=true realSizeChanged=false redrawNeeded=false left=false top=false 07-09 12:02:48.776: E/SpannableStringBuilder(27070): SPAN_EXCLUSIVE_EXCLUSIVE spans cannot have a zero length 07-09 12:02:48.776: E/SpannableStringBuilder(27070): SPAN_EXCLUSIVE_EXCLUSIVE spans cannot have a zero length 07-09 12:02:51.768: D/AR(27070): ARHttpClient: Executing post! 07-09 12:02:54.932: I/AR(27070): Completed CloudReco transaction with ID '798683eb728146fdac6e937ab5fdaa91' 07-09 12:02:55.026: I/AR(27070): Successfully created ImageTarget. 07-09 12:02:55.026: I/QCAR(27070): Benz.m4v 07-09 12:02:55.026: I/QCAR(27070): Calling... 07-09 12:02:55.026: I/QCAR(27070): Cloud response obtained is: Benz.m4v 07-09 12:02:55.026: I/QCAR(27070): Called... 07-09 12:03:08.635: I/MediaPlayer(27070): sendBroadcast android.media.IMediaPlayer.videoexist 07-09 12:03:08.831: I/MediaPlayer(27070): sendBroadcast android.media.IMediaPlayer.videoexist 07-09 12:03:14.229: W/MediaPlayer(27070): info/warning (703, 349) 07-09 12:03:14.229: W/MediaPlayer(27070): info/warning (701, 0) 07-09 12:03:14.252: I/MediaPlayer(27070): Info (703,349) 07-09 12:03:14.252: I/MediaPlayer(27070): Info (701,0) 07-09 12:03:18.237: W/MediaPlayer(27070): info/warning (702, 0) 07-09 12:03:18.237: I/MediaPlayer(27070): Info (702,0) 07-09 12:03:24.870: I/MediaPlayer(27070): sendBroadcast android.media.IMediaPlayer.videoexist