--------- beginning of /dev/log/system 02-08 13:34:53.134 I/LAB126 ( 1444): tap on collapsed status bar we eat 02-08 13:34:53.618 I/LAB126 ( 1444): tap on collapsed status bar we eat 02-08 13:34:54.204 I/LAB126 ( 1444): open notifications from tap in the status bar, child count: 2 02-08 13:34:54.618 D/LAB126 ( 1444): expand softkeys --------- beginning of /dev/log/main 02-08 13:34:55.290 I/MSV (15243): WebUtils futureTask is null 02-08 13:34:55.290 D/ActivityManager( 1372): Starting: Intent { flg=0x10000000 cmp=com.android.systemui/.usb.UsbStorageActivity bnds=[0,110][600,220] } from pid -1 02-08 13:34:55.290 D/LAB126 ( 1444): collapsing softkeys because notification closing. 02-08 13:34:55.290 D/LAB126 ( 1444): collapse softkeys 02-08 13:34:55.298 V/MediaLibraryActivity(15243): MediaLibraryActivity.Writing CACHE 02-08 13:34:55.298 V/MediaLibraryActivity(15243): MediaLibraryActivity.Count: 3 02-08 13:34:55.368 I/PackageManager( 1372): Updating external media status from mounted to unmounted 02-08 13:34:55.368 D/VoldCmdListener( 1288): volume shared /mnt/sdcard ums 02-08 13:34:55.368 D/VoldCmdListener( 1288): asec list 02-08 13:34:55.368 I/LAB126 ( 1444): StatusBarService hears intent. action: com.lab126.intent.TOP_WINDOW_CHANGED 02-08 13:34:55.368 I/LAB126 ( 1444): window changed 02-08 13:34:55.368 I/LAB126 ( 1444): don't set softkey config for 'Starting...' window 02-08 13:34:55.376 I/PackageManager( 1372): No secure containers on sdcard 02-08 13:34:55.392 D/VoldCmdListener( 1288): volume shared /mnt/sdcard ums 02-08 13:34:55.423 V/MediaLibraryActivity(15243): MediaLibraryActivity.DONE Writing 02-08 13:34:55.501 D/dalvikvm( 1372): GC_CONCURRENT freed 1885K, 52% free 6308K/13127K, external 6837K/8538K, paused 2ms+5ms 02-08 13:34:55.985 D/VoldCmdListener( 1288): storage users /mnt/sdcard 02-08 13:34:55.993 D/LAB126 ( 1444): collapse softkeys 02-08 13:34:56.048 I/ActivityManager( 1372): Displayed com.android.systemui/.usb.UsbStorageActivity: +741ms 02-08 13:34:56.079 I/LAB126 ( 1444): StatusBarService hears intent. action: com.lab126.intent.TOP_WINDOW_CHANGED 02-08 13:34:56.079 I/LAB126 ( 1444): window changed 02-08 13:34:56.095 D/vzAnalytics(15243): Scheduling db and net save task 02-08 13:34:56.149 W/ActivityManager( 1372): Killing processes unmount media at adjustment 7 02-08 13:34:56.149 W/ActivityManager( 1372): Killing ProcessRecord{40527b60 15227:com.cooliris.media/10018} (adj 7): unmount media 02-08 13:34:56.149 D/VoldCmdListener( 1288): storage users /mnt/sdcard 02-08 13:34:56.259 I/MountService( 1372): Retrying to kill storage users again 02-08 13:34:56.259 D/VoldCmdListener( 1288): storage users /mnt/sdcard 02-08 13:34:56.360 W/ActivityManager( 1372): Killing processes unmount media at adjustment 4 02-08 13:34:56.360 W/ActivityManager( 1372): Killing ProcessRecord{406c0798 15243:com.verizon.ams.tesla/10034} (adj 4): unmount media 02-08 13:34:56.360 D/VoldCmdListener( 1288): storage users /mnt/sdcard 02-08 13:34:56.384 I/WindowManager( 1372): WIN DEATH: Window{4098f760 com.verizon.ams.tesla/com.verizon.ams.tesla.ui.MainActivity paused=false} 02-08 13:34:56.384 W/ActivityManager( 1372): Scheduling restart of crashed service com.verizon.ams.tesla/.service.download.MetaDataSyncService in 5000ms 02-08 13:34:56.384 W/ActivityManager( 1372): Scheduling restart of crashed service com.verizon.ams.tesla/.service.download.DownloadService in 15000ms 02-08 13:34:56.384 I/WindowManager( 1372): WIN DEATH: Window{4071c5f8 com.verizon.ams.tesla/com.verizon.ams.tesla.ui.TabDownloadsActivity paused=false} 02-08 13:34:56.462 D/VoldCmdListener( 1288): storage users /mnt/sdcard 02-08 13:34:56.610 D/dalvikvm( 1372): GC_EXPLICIT freed 171K, 53% free 6219K/13127K, external 6837K/8538K, paused 57ms 02-08 13:34:56.610 I/PackageManager( 1372): Updating external media status from unmounted to unmounted 02-08 13:34:56.610 D/VoldCmdListener( 1288): volume unmount /mnt/sdcard force 02-08 13:34:56.610 D/Vold ( 1288): Volume sdcard state changing 4 (Mounted) -> 5 (Unmounting) 02-08 13:34:56.618 I/AmazonEmail(11087): storage path "/mnt/sdcard" unmounting 02-08 13:34:56.618 V/MediaProvider(11097): Detached volume: external 02-08 13:34:56.618 I/ActivityManager( 1372): Start proc com.cooliris.media for broadcast com.cooliris.media/com.cooliris.cache.BootReceiver: pid=15355 uid=10018 gids={1015, 3003} 02-08 13:34:56.657 I/ActivityThread(15355): Pub com.cooliris.picasa.contentprovider: com.cooliris.picasa.PicasaContentProvider 02-08 13:34:56.657 D/ContentProvider(15355): Videos folder exist 02-08 13:34:56.657 D/ContentProvider(15355): Pictures folder exists 02-08 13:34:56.696 I/com.amazon.dcp.metrics.CrashDetectionHelper(15355): Setting up crash detector. 02-08 13:34:56.696 I/BootReceiver(15355): Got intent with action android.intent.action.MEDIA_EJECT 02-08 13:34:57.610 I/Vold ( 1288): /mnt/secure/staging/.android_secure sucessfully unmounted 02-08 13:34:57.610 I/Vold ( 1288): /mnt/secure/asec sucessfully unmounted 02-08 13:34:58.423 I/Vold ( 1288): /mnt/secure/staging sucessfully unmounted 02-08 13:34:58.423 I/Vold ( 1288): /mnt/sdcard unmounted sucessfully 02-08 13:34:58.423 I/PackageManager( 1372): Updating external media status from unmounted to unmounted 02-08 13:34:58.423 D/Vold ( 1288): Volume sdcard state changing 5 (Unmounting) -> 1 (Idle-Unmounted) 02-08 13:34:58.423 I/StorageNotification( 1444): Media state changed from {mounted} -> {unmounted} 02-08 13:34:58.431 I/AmazonEmail(11087): storage path "/mnt/sdcard" unmounted 02-08 13:34:58.431 D/VoldCmdListener( 1288): volume share /mnt/sdcard ums 02-08 13:34:58.431 D/Vold ( 1288): Volume sdcard state changing 1 (Idle-Unmounted) -> 7 (Shared-Unmounted) 02-08 13:34:58.438 W/MountService( 1372): Duplicate state transition (unmounted -> unmounted) 02-08 13:34:58.454 D/Tethering( 1372): InitialState.processMessage what=4 02-08 13:34:58.454 D/Tethering( 1372): sendTetherStateChangedBroadcast 0, 0, 0 02-08 13:34:58.462 I/AmazonEmail(11087): storage path "/mnt/sdcard" unmounted 02-08 13:34:58.501 D/VoldCmdListener( 1288): volume shared /mnt/sdcard ums 02-08 13:34:58.501 D/VoldCmdListener( 1288): volume share /mnt/sdcard ums 02-08 13:34:58.501 I/StorageNotification( 1444): Media state changed from {unmounted} -> {shared} 02-08 13:34:58.501 E/MountService( 1372): Failed to share/unshare 02-08 13:34:58.501 E/MountService( 1372): com.android.server.NativeDaemonConnectorException: Cmd {volume share /mnt/sdcard ums} failed with code 405 : {volume operation failed (Device or resource busy)} 02-08 13:34:58.501 E/MountService( 1372): at com.android.server.NativeDaemonConnector.doCommand(NativeDaemonConnector.java:259) 02-08 13:34:58.501 E/MountService( 1372): at com.android.server.MountService.doShareUnshareVolume(MountService.java:508) 02-08 13:34:58.501 E/MountService( 1372): at com.android.server.MountService.access$200(MountService.java:76) 02-08 13:34:58.501 E/MountService( 1372): at com.android.server.MountService$UmsEnableCallBack.handleFinished(MountService.java:309) 02-08 13:34:58.501 E/MountService( 1372): at com.android.server.MountService$MountServiceHandler.handleMessage(MountService.java:412) 02-08 13:34:58.501 E/MountService( 1372): at android.os.Handler.dispatchMessage(Handler.java:99) 02-08 13:34:58.501 E/MountService( 1372): at android.os.Looper.loop(Looper.java:130) 02-08 13:34:58.501 E/MountService( 1372): at android.os.HandlerThread.run(HandlerThread.java:60) 02-08 13:34:58.509 D/IconMerger( 1444): sum of notifications: 0 02-08 13:34:58.595 D/dalvikvm( 1444): GC_CONCURRENT freed 1847K, 52% free 4063K/8391K, external 9027K/10545K, paused 2ms+5ms 02-08 13:35:00.321 D/VoldCmdListener( 1288): volume unshare /mnt/sdcard ums 02-08 13:35:00.321 I/PackageManager( 1372): Updating external media status from unmounted to unmounted 02-08 13:35:00.321 D/Tethering( 1372): sendTetherStateChangedBroadcast 1, 0, 0 02-08 13:35:00.321 D/Tethering( 1372): interfaceAdded :usb0 02-08 13:35:00.321 D/Vold ( 1288): Volume sdcard state changing 7 (Shared-Unmounted) -> 1 (Idle-Unmounted) 02-08 13:35:00.337 I/AmazonEmail(11087): storage path "/mnt/sdcard" unmounted 02-08 13:35:00.337 I/ActivityManager( 1372): Start proc com.verizon.ams.tesla for activity com.verizon.ams.tesla/.ui.TabDownloadsActivity: pid=15371 uid=10034 gids={3003, 1015} 02-08 13:35:00.345 I/StorageNotification( 1444): Media state changed from {shared} -> {unmounted} 02-08 13:35:00.360 D/VoldCmdListener( 1288): volume shared /mnt/sdcard ums 02-08 13:35:00.360 D/VoldCmdListener( 1288): volume mount /mnt/sdcard 02-08 13:35:00.360 I/Vold ( 1288): /dev/block/vold/179:12 being considered for volume sdcard 02-08 13:35:00.360 D/Vold ( 1288): Volume sdcard state changing 1 (Idle-Unmounted) -> 3 (Checking) 02-08 13:35:00.392 I//system/bin/fsck_msdos( 1288): ** /dev/block/vold/179:12 02-08 13:35:00.392 I//system/bin/fsck_msdos( 1288): ** Phase 1 - Read FAT (compare skipped) 02-08 13:35:00.392 I//system/bin/fsck_msdos( 1288): Attempting to allocate 10242 KB for FAT 02-08 13:35:00.392 I/StorageNotification( 1444): Media state changed from {unmounted} -> {checking} 02-08 13:35:00.438 I/LAB126 ( 1444): StatusBarService hears intent. action: com.lab126.intent.TOP_WINDOW_CHANGED 02-08 13:35:00.438 I/LAB126 ( 1444): window changed 02-08 13:35:00.438 I/LAB126 ( 1444): don't set softkey config for 'Starting...' window 02-08 13:35:00.470 I/ActivityThread(15371): Pub com.verizon.mediamanager.SearchSuggestion: com.verizon.mediamanager.SearchSuggestion 02-08 13:35:00.470 D/ContentProvider(15371): Error creating Video folder. 02-08 13:35:00.470 D/ContentProvider(15371): Error creating Pictures folder. 02-08 13:35:00.478 I/MSV (15371): FiosTV Remote DVR Configuration 02-08 13:35:00.478 I/MSV (15371): =============================== 02-08 13:35:00.478 I/MSV (15371): Config: production 02-08 13:35:00.478 I/MSV (15371): Device Model: Kindle Fire 02-08 13:35:00.478 I/MSV (15371): Proxy URL: https://www32.verizon.com/vvmapp2/VVM_LAB.aspx?URL= 02-08 13:35:00.478 I/MSV (15371): Network Timeout: 25000 ms 02-08 13:35:00.478 I/MSV (15371): =============================== 02-08 13:35:00.493 V/MSVAppData(15371): init MSV data 02-08 13:35:00.493 V/MSVAppData(15371): ********* DEVICEID:173adb4e339c16e7 02-08 13:35:00.501 D/mediamanager(15371): APP CONTEXT RECYCLING 02-08 13:35:00.501 D/mediamanager(15371): setting environment for mmol : PRODUCTION 02-08 13:35:00.595 D/dalvikvm(15371): GC_EXTERNAL_ALLOC freed 338K, 48% free 3097K/5895K, external 847K/1181K, paused 32ms 02-08 13:35:00.673 D/dalvikvm(15371): GC_EXTERNAL_ALLOC freed 31K, 49% free 3065K/5895K, external 4597K/5741K, paused 29ms 02-08 13:35:00.759 W/System.err(15371): java.io.IOException: No such file or directory 02-08 13:35:00.767 W/System.err(15371): at java.io.File.createNewFileImpl(Native Method) 02-08 13:35:00.767 W/System.err(15371): at java.io.File.createNewFile(File.java:1257) 02-08 13:35:00.767 W/System.err(15371): at com.verizon.ams.tesla.msv.MediaLibraryCacheController.saveCacheToPersist(MediaLibraryCacheController.java:166) 02-08 13:35:00.767 W/System.err(15371): at com.verizon.ams.tesla.msv.MediaLibraryCacheController.performCacheCleanup(MediaLibraryCacheController.java:455) 02-08 13:35:00.767 W/System.err(15371): at com.verizon.ams.tesla.msv.MediaLibraryCacheController.(MediaLibraryCacheController.java:59) 02-08 13:35:00.767 W/System.err(15371): at com.verizon.ams.tesla.msv.MediaLibraryCacheController.getInstance(MediaLibraryCacheController.java:81) 02-08 13:35:00.767 W/System.err(15371): at com.verizon.ams.tesla.msv.MediaLibraryActivity.onCreate(MediaLibraryActivity.java:128) 02-08 13:35:00.767 W/System.err(15371): at android.app.Instrumentation.callActivityOnCreate(Instrumentation.java:1047) 02-08 13:35:00.767 W/System.err(15371): at android.app.ActivityThread.performLaunchActivity(ActivityThread.java:1611) 02-08 13:35:00.767 W/System.err(15371): at android.app.ActivityThread.startActivityNow(ActivityThread.java:1487) 02-08 13:35:00.767 W/System.err(15371): at android.app.LocalActivityManager.moveToState(LocalActivityManager.java:127) 02-08 13:35:00.767 W/System.err(15371): at android.app.LocalActivityManager.startActivity(LocalActivityManager.java:339) 02-08 13:35:00.767 W/System.err(15371): at android.widget.TabHost$IntentContentStrategy.getContentView(TabHost.java:654) 02-08 13:35:00.767 W/System.err(15371): at android.widget.TabHost.setCurrentTab(TabHost.java:326) 02-08 13:35:00.767 W/System.err(15371): at android.widget.TabHost.addTab(TabHost.java:216) 02-08 13:35:00.767 W/System.err(15371): at com.verizon.ams.tesla.ui.TabDownloadsActivity.definingTabs(TabDownloadsActivity.java:166) 02-08 13:35:00.767 W/System.err(15371): at com.verizon.ams.tesla.ui.TabDownloadsActivity.onCreate(TabDownloadsActivity.java:116) 02-08 13:35:00.767 W/System.err(15371): at android.app.Instrumentation.callActivityOnCreate(Instrumentation.java:1047) 02-08 13:35:00.767 W/System.err(15371): at android.app.ActivityThread.performLaunchActivity(ActivityThread.java:1611) 02-08 13:35:00.767 W/System.err(15371): at android.app.ActivityThread.handleLaunchActivity(ActivityThread.java:1663) 02-08 13:35:00.767 W/System.err(15371): at android.app.ActivityThread.access$1500(ActivityThread.java:117) 02-08 13:35:00.767 W/System.err(15371): at android.app.ActivityThread$H.handleMessage(ActivityThread.java:931) 02-08 13:35:00.767 W/System.err(15371): at android.os.Handler.dispatchMessage(Handler.java:99) 02-08 13:35:00.767 W/System.err(15371): at android.os.Looper.loop(Looper.java:130) 02-08 13:35:00.767 W/System.err(15371): at android.app.ActivityThread.main(ActivityThread.java:3683) 02-08 13:35:00.767 W/System.err(15371): at java.lang.reflect.Method.invokeNative(Native Method) 02-08 13:35:00.767 W/System.err(15371): at java.lang.reflect.Method.invoke(Method.java:507) 02-08 13:35:00.767 W/System.err(15371): at com.android.internal.os.ZygoteInit$MethodAndArgsCaller.run(ZygoteInit.java:850) 02-08 13:35:00.767 W/System.err(15371): at com.android.internal.os.ZygoteInit.main(ZygoteInit.java:608) 02-08 13:35:00.767 W/System.err(15371): at dalvik.system.NativeStart.main(Native Method) 02-08 13:35:00.774 W/ResourceType(15371): Skipping entry 0x7f02015d in package table 0 because it is not complex! 02-08 13:35:00.774 W/ResourceType(15371): Skipping entry 0x7f02015d in package table 0 because it is not complex! 02-08 13:35:00.774 W/ResourceType(15371): Skipping entry 0x7f02015d in package table 0 because it is not complex! 02-08 13:35:00.774 W/ResourceType(15371): Skipping entry 0x7f02015d in package table 0 because it is not complex! 02-08 13:35:00.774 W/ResourceType(15371): Skipping entry 0x7f02015d in package table 0 because it is not complex! 02-08 13:35:00.774 W/ResourceType(15371): Skipping entry 0x7f02015d in package table 0 because it is not complex! 02-08 13:35:00.782 D/vzAnalytics(15371): WiFi:true, CellData:false 02-08 13:35:00.782 D/vzAnalytics(15371): Initializing session 1328729700793-5GJM9CC 02-08 13:35:00.790 D/vzAnalytics(15371): Started network thread 1328729700802 02-08 13:35:00.790 D/vzAnalytics(15371): Scheduling db and net save task 02-08 13:35:00.790 I/vzAnalytics(15371): Initialized vz analytics. version 2.0.2 02-08 13:35:00.806 W/PhoneWindow(15371): Previously focused view reported id 16908298 during save, but can't be found during restore. 02-08 13:35:00.907 D/dalvikvm(15371): GC_CONCURRENT freed 232K, 46% free 3242K/5959K, external 2976K/6263K, paused 2ms+3ms 02-08 13:35:00.938 V/DesEncrypter(15371): error while decrypt: javax.crypto.IllegalBlockSizeException: last block incomplete in decryption 02-08 13:35:00.938 I/System.out(15371): Account Migration Exception : java.lang.NullPointerException 02-08 13:35:01.009 V/DRMManager(15371): PV Lib not linked 02-08 13:35:01.009 E/FVApplication(15371): TRY MOTO DRM 02-08 13:35:01.024 I/ActivityManager( 1372): Displayed com.verizon.ams.tesla/.ui.TabDownloadsActivity: +698ms 02-08 13:35:01.032 I/LAB126 ( 1444): StatusBarService hears intent. action: com.lab126.intent.TOP_WINDOW_CHANGED 02-08 13:35:01.032 I/LAB126 ( 1444): window changed 02-08 13:35:01.032 E/FVApplication(15371): SUCCESS bind to Janus DRM API 02-08 13:35:01.032 V/DeviceDRMInfoManager(15371): fetchDeviceDRMInfo() :: url = http://www98-west.verizon.com/mobilesvc/adLibSvc/adLibRestSvc/Devicelist?Model=Kindle+Fire&firmware=default&ostype=android&osversion=10&appname=MM&appversion=1.5.0&format=xml 02-08 13:35:01.032 W/ActivityManager( 1372): Unable to start service Intent { act=com.motorola.android.wmdrm.IWmdrmService }: not found 02-08 13:35:01.048 V/MSV (15371): WebUtils url:http://www98-west.verizon.com/mobilesvc/adLibSvc/adLibRestSvc/Devicelist?Model=Kindle+Fire&firmware=default&ostype=android&osversion=10&appname=MM&appversion=1.5.0&format=xml 02-08 13:35:01.079 V/MetaDataSyncService(15371): getMediaIds(): 02-08 13:35:01.095 V/MSV (15371): WebUtils finished http get call 02-08 13:35:01.267 I//system/bin/fsck_msdos( 1288): ** Phase 2 - Check Cluster Chains 02-08 13:35:01.267 V/MetaDataSyncService(15371): downloadPurchaseList() :: url = https://www36.verizon.com/MSVAppServices/Authenticated/POX/AccountMgrXmlServices.asmx/MSVAppRequest? 02-08 13:35:01.274 V/MetaDataSyncService(15371): downloadPurchaseList() :: postData = strInputXML=GETPURCHASEDTITLESironman1058DNETtrans_12BB001YDESCproduct_purchasedDateALLAverageRatingALLALL 02-08 13:35:01.274 V/MSV (15371): WebUtils url:https://www36.verizon.com/MSVAppServices/Authenticated/POX/AccountMgrXmlServices.asmx/MSVAppRequest? 02-08 13:35:01.274 V/MSV (15371): WebUtils body:strInputXML=GETPURCHASEDTITLESironman1058DNETtrans_12BB001YDESCproduct_purchasedDateALLAverageRatingALLALL 02-08 13:35:01.298 D/vzAnalytics(15371): Loaded 0 config keys 02-08 13:35:01.306 V/MetaDataSyncService(15371): downloadWatchList() :: url = https://www36.verizon.com/MSVAppServices/Authenticated/POX/AccountMgrXmlServices.asmx/MSVAppRequest? 02-08 13:35:01.306 V/MetaDataSyncService(15371): downloadWatchList() :: postData = strInputXML=GETWATCHLISTironman1058DNETtrans_12BB001DESCproduct_dtmcreateDateALLAverageRatingALLALL 02-08 13:35:01.306 V/MSV (15371): WebUtils url:https://www36.verizon.com/MSVAppServices/Authenticated/POX/AccountMgrXmlServices.asmx/MSVAppRequest? 02-08 13:35:01.306 V/MSV (15371): WebUtils body:strInputXML=GETWATCHLISTironman1058DNETtrans_12BB001DESCproduct_dtmcreateDateALLAverageRatingALLALL 02-08 13:35:01.360 V/MSV (15371): WebUtils url:https://www36.verizon.com/MSVAppServices/Authenticated/POX/CatalogXmlServices.asmx/MSVAppRequest? 02-08 13:35:01.360 V/MSV (15371): WebUtils body:strInputXML=GETUSERRATINGSironman1058DNETtrans_12BB001 02-08 13:35:01.360 V/MetaDataSyncService(15371): getContentItemIds(): 02-08 13:35:01.360 I/DeviceDRMInfoManager(15371): deviceDRMHandler.. 02-08 13:35:01.563 V/DeviceDRMInfoManager(15371): DRM FOR THE DEVICE: [SM] 02-08 13:35:01.899 I//system/bin/fsck_msdos( 1288): ** Phase 3 - Checking Directories 02-08 13:35:02.087 I//system/bin/fsck_msdos( 1288): ** Phase 4 - Checking for Lost Files 02-08 13:35:02.243 I//system/bin/fsck_msdos( 1288): Next free cluster in FSInfo block (109273) not free 02-08 13:35:02.243 I//system/bin/fsck_msdos( 1288): FIXED 02-08 13:35:02.243 I//system/bin/fsck_msdos( 1288): 999 files, 2075026 free (1037513 clusters) 02-08 13:35:02.282 I/Vold ( 1288): Filesystem check completed OK 02-08 13:35:02.290 I/Vold ( 1288): Device /dev/block/vold/179:12, target /mnt/sdcard mounted @ /mnt/secure/staging 02-08 13:35:02.290 I/PackageManager( 1372): Updating external media status from unmounted to mounted 02-08 13:35:02.290 D/Vold ( 1288): Volume sdcard state changing 3 (Checking) -> 4 (Mounted) 02-08 13:35:02.298 I/StorageNotification( 1444): Media state changed from {checking} -> {mounted} 02-08 13:35:02.298 D/VoldCmdListener( 1288): volume unshare /mnt/sdcard ums 02-08 13:35:02.298 W/Vold ( 1288): Returning OperationFailed - no handler for errno 22 02-08 13:35:02.306 E/MountService( 1372): Failed to share/unshare 02-08 13:35:02.306 E/MountService( 1372): com.android.server.NativeDaemonConnectorException: Cmd {volume unshare /mnt/sdcard ums} failed with code 400 : {volume operation failed (Invalid argument)} 02-08 13:35:02.306 E/MountService( 1372): at com.android.server.NativeDaemonConnector.doCommand(NativeDaemonConnector.java:259) 02-08 13:35:02.306 E/MountService( 1372): at com.android.server.MountService.doShareUnshareVolume(MountService.java:508) 02-08 13:35:02.306 E/MountService( 1372): at com.android.server.MountService.setUsbMassStorageEnabled(MountService.java:1231) 02-08 13:35:02.306 E/MountService( 1372): at android.os.storage.IMountService$Stub.onTransact(IMountService.java:683) 02-08 13:35:02.306 E/MountService( 1372): at android.os.Binder.execTransact(Binder.java:320) 02-08 13:35:02.306 E/MountService( 1372): at dalvik.system.NativeStart.run(Native Method) 02-08 13:35:02.306 D/VoldCmdListener( 1288): volume mount /mnt/sdcard 02-08 13:35:02.313 I/BootReceiver(15355): Got intent with action android.intent.action.MEDIA_MOUNTED 02-08 13:35:02.313 E/MountService( 1372): Failed to remount /mnt/sdcard after disabling share method ums 02-08 13:35:02.321 I/PackageManager( 1372): No secure containers on sdcard 02-08 13:35:02.321 D/VoldCmdListener( 1288): asec list 02-08 13:35:02.321 W/PackageManager( 1372): Not granting permission com.amazon.dcp.metrics.permission.METRICS_PERMISSION to package com.imdb.mobile (protectionLevel=2 flags=0x8be45) 02-08 13:35:02.321 W/PackageManager( 1372): Unknown permission com.google.android.googleapps.permission.GOOGLE_AUTH.mail in package com.android.contacts 02-08 13:35:02.329 W/PackageManager( 1372): Unknown permission android.permission.DEVICE_ADMIN in package com.lab126.otter 02-08 13:35:02.329 W/PackageManager( 1372): Unknown permission com.google.android.googleapps.permission.GOOGLE_AUTH in package com.android.providers.calendar 02-08 13:35:02.329 W/PackageManager( 1372): Unknown permission com.google.android.googleapps.permission.GOOGLE_AUTH.cl in package com.android.providers.calendar 02-08 13:35:02.329 W/PackageManager( 1372): Unknown permission org.thialfihar.android.apg.permission.READ_KEY_DETAILS in package com.amazon.email 02-08 13:35:02.329 W/PackageManager( 1372): Not granting permission com.amazon.dcp.metrics.permission.METRICS_PERMISSION to package com.cooliris.media (protectionLevel=2 flags=0x8bc45) 02-08 13:35:02.329 W/PackageManager( 1372): Unknown permission com.android.launcher.permission.INSTALL_SHORTCUT in package com.farproc.wifi.analyzer 02-08 13:35:02.329 W/PackageManager( 1372): Not granting permission android.permission.ACCESS_DRM to package com.verizon.ams.tesla (protectionLevel=2 flags=0x8be46) 02-08 13:35:02.329 W/PackageManager( 1372): Not granting permission android.permission.WRITE_SECURE_SETTINGS to package com.verizon.ams.tesla (protectionLevel=3 flags=0x8be46) 02-08 13:35:02.329 W/PackageManager( 1372): Unknown permission com.google.android.googleapps.permission.GOOGLE_AUTH in package com.android.providers.contacts 02-08 13:35:02.329 W/PackageManager( 1372): Unknown permission com.google.android.googleapps.permission.GOOGLE_AUTH.cp in package com.android.providers.contacts 02-08 13:35:02.329 W/PackageManager( 1372): Unknown permission com.google.android.gm.permission.WRITE_GMAIL in package com.android.settings 02-08 13:35:02.329 W/PackageManager( 1372): Unknown permission com.google.android.gm.permission.READ_GMAIL in package com.android.settings 02-08 13:35:02.337 W/PackageManager( 1372): Unknown permission com.google.android.googleapps.permission.GOOGLE_AUTH in package com.android.settings 02-08 13:35:02.337 W/PackageManager( 1372): Unknown permission com.android.launcher.permission.READ_SETTINGS in package com.android.settings 02-08 13:35:02.337 W/PackageManager( 1372): Unknown permission com.android.launcher.permission.WRITE_SETTINGS in package com.android.settings 02-08 13:35:02.337 W/PackageManager( 1372): Unknown permission com.android.launcher.permission.INSTALL_SHORTCUT in package com.amazon.cloud9 02-08 13:35:02.337 W/PackageManager( 1372): Unknown permission android.webkit.permission.PLUGIN in package com.adobe.flashplayer 02-08 13:35:02.337 W/PackageManager( 1372): Unknown permission com.google.android.providers.gmail.permission.WRITE_GMAIL in package com.qo.android.oemvc 02-08 13:35:02.337 W/PackageManager( 1372): Unknown permission com.google.android.providers.gmail.permission.READ_GMAIL in package com.qo.android.oemvc 02-08 13:35:02.337 W/PackageManager( 1372): Unknown permission com.google.android.gm.permission.READ_GMAIL in package com.qo.android.oemvc 02-08 13:35:02.337 W/PackageManager( 1372): Unknown permission com.google.android.gm.permission.WRITE_GMAIL in package com.qo.android.oemvc 02-08 13:35:02.337 W/PackageManager( 1372): Unknown permission android.permission.READ_OWNER_DATA in package com.qo.android.oemvc 02-08 13:35:02.337 W/PackageManager( 1372): Unknown permission com.android.email.permission.ACCESS_PROVIDER in package com.qo.android.oemvc 02-08 13:35:02.337 W/PackageManager( 1372): Unknown permission com.android.launcher.permission.INSTALL_SHORTCUT in package com.estrongs.android.pop 02-08 13:35:02.337 W/PackageManager( 1372): Unknown permission com.android.launcher.permission.UNINSTALL_SHORTCUT in package com.estrongs.android.pop 02-08 13:35:02.337 W/PackageManager( 1372): Unknown permission com.estrongs.android.pop.PERMISSION in package com.estrongs.android.pop 02-08 13:35:02.337 W/PackageManager( 1372): Unknown permission com.android.launcher.permission.INSTALL_SHORTCUT in package com.anydo 02-08 13:35:02.337 W/PackageManager( 1372): Unknown permission android.permission.ACCESS_LOCATION in package com.anydo 02-08 13:35:02.337 W/PackageManager( 1372): Unknown permission com.google.android.c2dm.permission.RECEIVE in package com.anydo 02-08 13:35:02.337 W/PackageManager( 1372): Not granting permission com.amazon.dcp.metrics.permission.METRICS_PERMISSION to package com.alphonso.pulse (protectionLevel=2 flags=0x8be45) 02-08 13:35:02.337 W/PackageManager( 1372): Unknown permission android.permission.ADD_SYSTEM_SERVICE in package com.pandora.android 02-08 13:35:02.337 W/PackageManager( 1372): Unknown permission android.permission.CHANGE_CHANGE_STATE in package com.pinapps.amped 02-08 13:35:02.415 I/MetaDataSyncService(15371): getting purchaselist successful! 02-08 13:35:02.415 D/MediaScannerService(11097): start scanning volume external 02-08 13:35:02.790 D/dalvikvm(15371): GC_CONCURRENT freed 506K, 44% free 3804K/6791K, external 2976K/6263K, paused 1ms+2ms 02-08 13:35:02.821 I/MetaDataSyncService(15371): purchaselistIndex size bobby:0 02-08 13:35:02.821 I/MetaDataSyncService(15371): purchaselistIndex size bobby:0 02-08 13:35:02.821 I/MetaDataSyncService(15371): Purchasehistory list Parsing successfully 02-08 13:35:03.376 V/MSV (15371): WebUtils finished http post call 02-08 13:35:03.392 I/AmazonEmail(11087): storage path "/mnt/sdcard" mounted readOnly=false 02-08 13:35:03.431 D/LAB126 ( 1444): got back button softkey click! 02-08 13:35:03.431 D/LAB126 ( 1444): collapse because of back button 02-08 13:35:03.431 D/LAB126 ( 1444): collapse softkeys 02-08 13:35:03.438 D/LAB126 ( 1444): got back button softkey click! 02-08 13:35:03.438 D/LAB126 ( 1444): collapse because of back button 02-08 13:35:03.438 D/LAB126 ( 1444): collapse softkeys 02-08 13:35:03.446 W/KeyCharacterMap(15371): No keyboard for id 0 02-08 13:35:03.446 W/KeyCharacterMap(15371): Using default keymap: /system/usr/keychars/qwerty.kcm.bin 02-08 13:35:03.454 I/MSV (15371): WebUtils futureTask is null 02-08 13:35:03.470 W/System.err(15371): java.lang.NullPointerException 02-08 13:35:03.470 W/System.err(15371): at com.verizon.ams.tesla.msv.MediaLibraryActivity$WriteCacheTask.doInBackground(MediaLibraryActivity.java:970) 02-08 13:35:03.470 W/System.err(15371): at com.verizon.ams.tesla.msv.MediaLibraryActivity$WriteCacheTask.doInBackground(MediaLibraryActivity.java:1) 02-08 13:35:03.470 W/System.err(15371): at android.os.AsyncTask$2.call(AsyncTask.java:185) 02-08 13:35:03.470 W/System.err(15371): at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:306) 02-08 13:35:03.470 W/System.err(15371): at java.util.concurrent.FutureTask.run(FutureTask.java:138) 02-08 13:35:03.470 W/System.err(15371): at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1088) 02-08 13:35:03.478 W/System.err(15371): at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:581) 02-08 13:35:03.478 W/System.err(15371): at java.lang.Thread.run(Thread.java:1019) 02-08 13:35:03.478 V/MediaLibraryActivity(15371): MediaLibraryActivity.DONE Writing 02-08 13:35:03.509 I/LAB126 ( 1444): StatusBarService hears intent. action: com.lab126.intent.TOP_WINDOW_CHANGED 02-08 13:35:03.509 I/LAB126 ( 1444): window changed 02-08 13:35:03.509 I/LAB126 ( 1444): don't set softkey config for 'Starting...' window 02-08 13:35:03.524 V/MediaProvider(11097): /mnt/sdcard volume ID: 252718362 02-08 13:35:03.563 D/dalvikvm(15371): GC_EXTERNAL_ALLOC freed 239K, 47% free 3652K/6791K, external 3061K/6263K, paused 49ms 02-08 13:35:03.657 V/MediaProvider(11097): Attached volume: external 02-08 13:35:03.704 D/MediaScanner(11097): found .nomedia, skipping directory 02-08 13:35:03.829 D/MediaScanner(11097): found .nomedia, skipping directory 02-08 13:35:03.853 D/MediaScanner(11097): found .nomedia, skipping directory 02-08 13:35:03.876 D/MediaScanner(11097): prescan time: 18ms 02-08 13:35:03.876 D/MediaScanner(11097): scan time: 196ms 02-08 13:35:03.876 D/MediaScanner(11097): postscan time: 0ms 02-08 13:35:03.876 D/MediaScanner(11097): total time: 214ms 02-08 13:35:03.876 D/MediaScannerService(11097): done scanning volume external 02-08 13:35:03.876 I/BootReceiver(15355): Got intent with action android.intent.action.MEDIA_SCANNER_FINISHED 02-08 13:35:04.056 I/ActivityManager( 1372): Displayed com.verizon.ams.tesla/.ui.MainActivity: +602ms 02-08 13:35:04.063 I/LAB126 ( 1444): StatusBarService hears intent. action: com.lab126.intent.TOP_WINDOW_CHANGED 02-08 13:35:04.063 I/LAB126 ( 1444): window changed 02-08 13:35:04.220 D/dalvikvm(15371): GC_CONCURRENT freed 1107K, 47% free 4081K/7623K, external 5928K/6446K, paused 2ms+2ms 02-08 13:35:04.563 D/IconMerger( 1444): notification for com.android.systemui (1) 02-08 13:35:04.563 D/IconMerger( 1444): sum of notifications: 1 02-08 13:35:04.618 V/MSV (15371): finished http post call 02-08 13:35:04.743 V/MSV (15371): Silent request done... 02-08 13:35:04.743 V/MSV (15371): getting watchlist successful! 02-08 13:35:04.743 V/MSV (15371): WebUtils finished http post call 02-08 13:35:04.743 V/MSV (15371): WebUtils finished http post call 02-08 13:35:05.087 D/ActivityManager( 1372): Starting: Intent { act=android.intent.action.VIEW dat=app://com.verizon.ams.tesla/mydownloads cmp=com.verizon.ams.tesla/.ui.TabDownloadsActivity } from pid 15371 02-08 13:35:05.157 V/MSV (15371): watchlist index created!9 02-08 13:35:05.157 I/MSV (15371): Watchlist Parsing successfully 02-08 13:35:05.540 D/skia (15355): Loading ARM decoder... 02-08 13:35:05.556 D/skia (15355): Loading ARM encoder... 02-08 13:35:05.579 D/skia (15355): Loading ARM decoder... 02-08 13:35:05.618 D/dalvikvm(15355): GC_EXTERNAL_ALLOC freed 418K, 51% free 2826K/5703K, external 1181K/1181K, paused 29ms 02-08 13:35:05.626 D/skia (15355): Loading ARM encoder... 02-08 13:35:05.704 D/skia (15355): Loading ARM decoder... 02-08 13:35:05.720 D/skia (15355): Loading ARM encoder... 02-08 13:35:05.720 W/ResourceType(15371): Skipping entry 0x7f02015d in package table 0 because it is not complex! 02-08 13:35:05.720 W/ResourceType(15371): Skipping entry 0x7f02015d in package table 0 because it is not complex! 02-08 13:35:05.720 W/ResourceType(15371): Skipping entry 0x7f02015d in package table 0 because it is not complex! 02-08 13:35:05.720 W/ResourceType(15371): Skipping entry 0x7f02015d in package table 0 because it is not complex! 02-08 13:35:05.720 W/ResourceType(15371): Skipping entry 0x7f02015d in package table 0 because it is not complex! 02-08 13:35:05.720 W/ResourceType(15371): Skipping entry 0x7f02015d in package table 0 because it is not complex! 02-08 13:35:05.728 V/MediaLibraryActivity(15371): getMediaIds(): 02-08 13:35:05.728 V/MediaLibraryActivity(15371): MediaLibraryActivity.Done Waiting. READ NOW 02-08 13:35:05.743 D/skia (15371): Loading ARM decoder... 02-08 13:35:05.751 D/skia (15355): Loading ARM decoder... 02-08 13:35:05.767 D/skia (15355): Loading ARM encoder... 02-08 13:35:05.782 D/skia (15355): Loading ARM decoder... 02-08 13:35:05.798 D/dalvikvm(15371): GC_EXTERNAL_ALLOC freed 1136K, 46% free 4277K/7879K, external 6444K/6446K, paused 41ms 02-08 13:35:05.813 D/skia (15355): Loading ARM encoder... 02-08 13:35:05.946 V/MediaLibraryActivity(15371): getContentItemIds(): 02-08 13:35:06.024 I/CommonUtils(15371): updatePurchaseStatusForMediaCache .... 02-08 13:35:06.040 V/MediaLibraryActivity(15371): downloadPurchaseList() :: url = https://www36.verizon.com/MSVAppServices/Authenticated/POX/AccountMgrXmlServices.asmx/MSVAppRequest? 02-08 13:35:06.040 V/MediaLibraryActivity(15371): downloadPurchaseList() :: postData = strInputXML=GETPURCHASEDTITLESDNETtrans_12BB001Y 02-08 13:35:06.063 V/MSV (15371): WebUtils url:https://www36.verizon.com/MSVAppServices/Authenticated/POX/AccountMgrXmlServices.asmx/MSVAppRequest? 02-08 13:35:06.063 V/MSV (15371): WebUtils body:strInputXML=GETPURCHASEDTITLESDNETtrans_12BB001Y 02-08 13:35:06.087 D/skia (15371): Loading ARM decoder... 02-08 13:35:06.095 D/MSC_V3.2.7(15371): API MSC_V3.2.7 : getRemainingRightsInfo - START 02-08 13:35:06.095 D/MSC_V3.2.7(15371): initialize sm. 02-08 13:35:06.095 D/MSC_V3.2.7(15371): absolute path : /data/data/com.verizon.ams.tesla/app_msc_home 02-08 13:35:06.095 D/MSC_V3.2.7(15371): asset exists in msc home, NOT copying 02-08 13:35:06.095 D/MSC_V3.2.7(15371): absolute path : /data/data/com.verizon.ams.tesla/app_msc_home 02-08 13:35:06.095 D/MSC_V3.2.7(15371): asset exists in msc home, NOT copying 02-08 13:35:06.095 D/MSC_V3.2.7(15371): MSC CERTS to MSC HOME done 02-08 13:35:06.095 D/dalvikvm(15371): Trying to load lib /data/data/com.verizon.ams.tesla/lib/libSMutility.so 0x40513858 02-08 13:35:06.095 D/dalvikvm(15371): Added shared lib /data/data/com.verizon.ams.tesla/lib/libSMutility.so 0x40513858 02-08 13:35:06.095 D/dalvikvm(15371): No JNI_OnLoad found in /data/data/com.verizon.ams.tesla/lib/libSMutility.so 0x40513858, skipping init 02-08 13:35:06.095 D/dalvikvm(15371): Trying to load lib /data/data/com.verizon.ams.tesla/lib/libsmsimpleclient.so 0x40513858 02-08 13:35:06.103 D/dalvikvm(15371): Added shared lib /data/data/com.verizon.ams.tesla/lib/libsmsimpleclient.so 0x40513858 02-08 13:35:06.103 D/dalvikvm(15371): No JNI_OnLoad found in /data/data/com.verizon.ams.tesla/lib/libsmsimpleclient.so 0x40513858, skipping init 02-08 13:35:06.103 D/MSC_V3.2.7(15371): MSChome : /data/data/com.verizon.ams.tesla/app_msc_home 02-08 13:35:06.103 D/MSC_V3.2.7(15371): SMhome : /data/data/com.verizon.ams.tesla/app_msc_home/sm_home 02-08 13:35:06.103 D/MSC_V3.2.7(15371): DeviceRooted: Genuine 02-08 13:35:06.103 I/SecureMedia(15371): 2012-02-08 13:35:06,smsimpleclient.c(739),INFO,Thread SimpleClient initing with: -l 4 -webapi.allowremote -https.insecure -webapi.port 0 -https.cainfo /data/data/com.verizon.ams.tesla/app_msc_home/MSC_Client_RootCA.pem -https.cert /data/data/com.verizon.ams.tesla/app_msc_home/MSC_Client_chain.pem -https.key /data/data/com.verizon.ams.tesla/app_msc_home/MSC_Client_chain.pem -ps.dirname /data/data/com.verizon.ams.tesla/app_msc_home/sm_home 02-08 13:35:06.103 I/SecureMedia(15371): . 02-08 13:35:06.103 D/SecureMedia(15371): 2012-02-08 13:35:06,smplatform.c(154),TRACE,Main SecureMedia v2.1.0.5699 (Android) 02-08 13:35:06.634 D/SecureMedia(15371): 2012-02-08 13:35:06,smx.c(318),TRACE,Main SMX: No SN for EMM listener in the persistent store. 02-08 13:35:06.634 D/SecureMedia(15371): 2012-02-08 13:35:06,smclient.c(578),TRACE,Main No plugins to communicate with 02-08 13:35:06.634 D/SecureMedia(15371): 2012-02-08 13:35:06,smclient.c(578),TRACE,Main No plugins to communicate with 02-08 13:35:06.634 D/SecureMedia(15371): 2012-02-08 13:35:06,smclient.c(578),TRACE,Main No plugins to communicate with 02-08 13:35:06.649 D/SecureMedia(15371): 2012-02-08 13:35:06,smdecryptor_tk.c(232),TRACE,Main Setting protection scheme: SHARK 02-08 13:35:06.649 D/SecureMedia(15371): 2012-02-08 13:35:06,smdecryptor_tk.c(237),TRACE,Main _SetSpec: spec=SHARK 02-08 13:35:06.649 D/SecureMedia(15371): 2012-02-08 13:35:06,smdecryptor_tk.c(232),TRACE,Main Setting protection scheme: SHARK 02-08 13:35:06.649 D/SecureMedia(15371): 2012-02-08 13:35:06,smdecryptor_tk.c(237),TRACE,Main _SetSpec: spec=SHARK 02-08 13:35:06.649 D/SecureMedia(15371): 2012-02-08 13:35:06,smclientplugin.c(359),TRACE,Main Protection scheme: SHARK 02-08 13:35:06.649 D/SecureMedia(15371): 2012-02-08 13:35:06,smm2dec.c(87),TRACE,Main Scramble specification is AES-ECB-BEG 02-08 13:35:06.649 D/SecureMedia(15371): 2012-02-08 13:35:06,smaes.c(428),TRACE,Main AES Copyright: AES Cipher Copyright (c) 2001, Dr Brian Gladman 02-08 13:35:06.649 D/SecureMedia(15371): 2012-02-08 13:35:06,smm2dec.c(87),TRACE,Main Scramble specification is AES-ECB-BEG 02-08 13:35:06.649 D/SecureMedia(15371): 2012-02-08 13:35:06,smclient.c(618),TRACE,Main Note that plugin session has not been initialized yet and thus no encryption will be done on the message id = 10 02-08 13:35:06.657 I/SecureMedia(15371): 2012-02-08 13:35:06,smclient.c(404),INFO,Main Establishing session with 'smclient' 02-08 13:35:06.657 I/SecureMedia(15371): 2012-02-08 13:35:06,smclient.c(658),INFO,Main Establishing session with daemon 02-08 13:35:06.657 D/SecureMedia(15371): 2012-02-08 13:35:06,smclient.c(659),TRACE,Main SMC 02-08 13:35:06.657 I/SecureMedia(15371): 2012-02-08 13:35:06,smsimpleclient.c(741),INFO,Main SimpleClient started. 02-08 13:35:06.657 I/SecureMedia(15371): 2012-02-08 13:35:06,smsimpleclient.c(750),INFO,Main Result = 0x0 02-08 13:35:06.657 D/MSC_V3.2.7(15371): initing SM DONE GOOD 02-08 13:35:06.657 I/System.out(15371): BEFORE starting to loadlibraries 02-08 13:35:06.657 D/dalvikvm(15371): Trying to load lib /data/data/com.verizon.ams.tesla/lib/libIPRM_Common.so 0x40513858 02-08 13:35:06.657 D/dalvikvm(15371): Added shared lib /data/data/com.verizon.ams.tesla/lib/libIPRM_Common.so 0x40513858 02-08 13:35:06.657 D/dalvikvm(15371): No JNI_OnLoad found in /data/data/com.verizon.ams.tesla/lib/libIPRM_Common.so 0x40513858, skipping init 02-08 13:35:06.657 D/dalvikvm(15371): Trying to load lib /data/data/com.verizon.ams.tesla/lib/libIPRM_Transport.so 0x40513858 02-08 13:35:06.657 D/dalvikvm(15371): Added shared lib /data/data/com.verizon.ams.tesla/lib/libIPRM_Transport.so 0x40513858 02-08 13:35:06.665 D/dalvikvm(15371): No JNI_OnLoad found in /data/data/com.verizon.ams.tesla/lib/libIPRM_Transport.so 0x40513858, skipping init 02-08 13:35:06.665 D/dalvikvm(15371): Trying to load lib /data/data/com.verizon.ams.tesla/lib/libIPRM_CryptoLibraryRobust.so 0x40513858 02-08 13:35:06.665 D/dalvikvm(15371): Added shared lib /data/data/com.verizon.ams.tesla/lib/libIPRM_CryptoLibraryRobust.so 0x40513858 02-08 13:35:06.665 D/dalvikvm(15371): No JNI_OnLoad found in /data/data/com.verizon.ams.tesla/lib/libIPRM_CryptoLibraryRobust.so 0x40513858, skipping init 02-08 13:35:06.665 D/dalvikvm(15371): Trying to load lib /data/data/com.verizon.ams.tesla/lib/libIPRM_CertLibrary.so 0x40513858 02-08 13:35:06.665 D/dalvikvm(15371): Added shared lib /data/data/com.verizon.ams.tesla/lib/libIPRM_CertLibrary.so 0x40513858 02-08 13:35:06.665 D/dalvikvm(15371): No JNI_OnLoad found in /data/data/com.verizon.ams.tesla/lib/libIPRM_CertLibrary.so 0x40513858, skipping init 02-08 13:35:06.665 D/dalvikvm(15371): Trying to load lib /data/data/com.verizon.ams.tesla/lib/libIPRM_SecurityProcessor.so 0x40513858 02-08 13:35:06.665 D/dalvikvm(15371): Added shared lib /data/data/com.verizon.ams.tesla/lib/libIPRM_SecurityProcessor.so 0x40513858 02-08 13:35:06.665 D/dalvikvm(15371): No JNI_OnLoad found in /data/data/com.verizon.ams.tesla/lib/libIPRM_SecurityProcessor.so 0x40513858, skipping init 02-08 13:35:06.665 D/dalvikvm(15371): Trying to load lib /data/data/com.verizon.ams.tesla/lib/libIPRM_ESBThin.so 0x40513858 02-08 13:35:06.665 D/dalvikvm(15371): Added shared lib /data/data/com.verizon.ams.tesla/lib/libIPRM_ESBThin.so 0x40513858 02-08 13:35:06.665 D/dalvikvm(15371): No JNI_OnLoad found in /data/data/com.verizon.ams.tesla/lib/libIPRM_ESBThin.so 0x40513858, skipping init 02-08 13:35:06.665 D/dalvikvm(15371): Trying to load lib /data/data/com.verizon.ams.tesla/lib/libIPRM_RightManagement.so 0x40513858 02-08 13:35:06.673 D/dalvikvm(15371): Added shared lib /data/data/com.verizon.ams.tesla/lib/libIPRM_RightManagement.so 0x40513858 02-08 13:35:06.673 D/dalvikvm(15371): No JNI_OnLoad found in /data/data/com.verizon.ams.tesla/lib/libIPRM_RightManagement.so 0x40513858, skipping init 02-08 13:35:06.673 D/dalvikvm(15371): Trying to load lib /data/data/com.verizon.ams.tesla/lib/libIPRM_DRMRulesConverter.so 0x40513858 02-08 13:35:06.673 D/dalvikvm(15371): Added shared lib /data/data/com.verizon.ams.tesla/lib/libIPRM_DRMRulesConverter.so 0x40513858 02-08 13:35:06.673 D/dalvikvm(15371): No JNI_OnLoad found in /data/data/com.verizon.ams.tesla/lib/libIPRM_DRMRulesConverter.so 0x40513858, skipping init 02-08 13:35:06.673 D/dalvikvm(15371): Trying to load lib /data/data/com.verizon.ams.tesla/lib/libIPRM_ThinAgent.so 0x40513858 02-08 13:35:06.673 I/SecureMedia(15371): 2012-02-08 13:35:06,smclientdaemon.c(699),INFO,PingWorker-0 Pinging SecureMedia Broadcast Subscription Service... 02-08 13:35:06.681 I/SecureMedia(15371): 2012-02-08 13:35:06,smsc.c(680),INFO,PingWorker-0 Ping state for 'BSS': SN = 173adb4e339c16e7, current kids are: 02-08 13:35:06.688 D/dalvikvm(15371): Added shared lib /data/data/com.verizon.ams.tesla/lib/libIPRM_ThinAgent.so 0x40513858 02-08 13:35:06.688 D/dalvikvm(15371): No JNI_OnLoad found in /data/data/com.verizon.ams.tesla/lib/libIPRM_ThinAgent.so 0x40513858, skipping init 02-08 13:35:06.696 D/dalvikvm(15371): Trying to load lib /data/data/com.verizon.ams.tesla/lib/libIPRM_JNI.so 0x40513858 02-08 13:35:06.696 D/dalvikvm(15371): Added shared lib /data/data/com.verizon.ams.tesla/lib/libIPRM_JNI.so 0x40513858 02-08 13:35:06.696 D/dalvikvm(15371): No JNI_OnLoad found in /data/data/com.verizon.ams.tesla/lib/libIPRM_JNI.so 0x40513858, skipping init 02-08 13:35:06.696 I/System.out(15371): loadlibraries DONE 02-08 13:35:06.696 D/jAgentHN(15371): before calling native iprmInit 02-08 13:35:06.712 D/------ Activity(15371): iprm init DONE GOOD 02-08 13:35:06.712 D/MSC_V3.2.7(15371): before starting CRMonitorT 02-08 13:35:06.712 D/CRMonitorT(15371): newing CRMonitorT 02-08 13:35:06.712 D/MSC_V3.2.7(15371): Done starting the thread 02-08 13:35:06.720 D/MSC_V3.2.7(15371): END of initilizeSM 02-08 13:35:06.720 D/CRMonitorT(15371): am AWAKE Again ! 02-08 13:35:06.720 D/skia (15371): Loading ARM decoder... 02-08 13:35:06.720 D/CRMonitorT(15371): LKGT : 1328729643 02-08 13:35:06.720 D/CRMonitorT(15371): TIME service : service=RightsInfoInt&ver=1.0&scmd=timequery 02-08 13:35:06.728 D/skia (15371): Loading ARM decoder... 02-08 13:35:06.946 V/MSV (15371): WebUtils finished http post call 02-08 13:35:07.438 D/skia (15371): Loading ARM decoder... 02-08 13:35:10.235 W/ProcessStats( 1372): Skipping unknown process pid 15417 02-08 13:35:10.532 D/CRMonitorT(15371): LKST RESPONSE: 0Success2012-02-08T14:35:10-05:00 02-08 13:35:10.532 D/RMTimeQueryProcessor(15371): Parsing : 0Success2012-02-08T14:35:10-05:00 02-08 13:35:10.540 D/-------MyUtils(15371): out : time in seconds:1328729710 02-08 13:35:10.540 D/CRMonitorT(15371): LKST VALUE : 2012-02-08T13:35:10-06:00 02-08 13:35:10.540 D/CRMonitorT(15371): updating lkgt with server time 02-08 13:35:10.540 D/CRMonitorT(15371): cdt is less than lkgt 02-08 13:35:10.540 D/CRMonitorT(15371): save LKGT, result = true 02-08 13:35:10.540 D/CRMonitorT(15371): going to SLEEP, next wakeup in [338471] milliseconds 02-08 13:35:10.571 D/MSC_V3.2.7(15371): device id : 173adb4e339c16e7 02-08 13:35:10.571 D/MSC_V3.2.7(15371): Getting Rights info from LOCAL for : /mnt/sdcard/.video/Afric_342339.mp4 02-08 13:35:10.571 D/MSC_V3.2.7(15371): getRemainingRights for :/mnt/sdcard/.video/Afric_342339.mp4 02-08 13:35:10.571 D/MSC_V3.2.7(15371): BEFORE calling iprmHandle.getSMVODRule for content: Afric_342339.mp4 02-08 13:35:10.579 D/SecureMedia(15371): 2012-02-08 13:35:10,smwatermark.c(309),TRACE,PingWorker-0 SessionID set to 63109 02-08 13:35:10.579 I/SecureMedia(15371): 2012-02-08 13:35:10,smclientdaemon.c(711),INFO,PingWorker-0 Next BSS ping in 2053 seconds 02-08 13:35:10.579 I/SecureMedia(15371): 2012-02-08 13:35:10,smclientdaemon.c(718),INFO,PingWorker-0 Pinging SecureMedia On-Demand Subscription Service... 02-08 13:35:10.579 I/SecureMedia(15371): 2012-02-08 13:35:10,smclientdaemon.c(730),INFO,PingWorker-0 Next ODSS ping in 86400 seconds 02-08 13:35:10.595 D/MSC_V3.2.7(15371): AFTER getSMVODRule 02-08 13:35:10.595 D/MSC_V3.2.7(15371): rentalflag = true 02-08 13:35:10.595 D/MSC_V3.2.7(15371): start time = 33554552 02-08 13:35:10.595 D/MSC_V3.2.7(15371): play window = -431947776 02-08 13:35:10.595 D/MSC_V3.2.7(15371): expiry time = -1610612504 02-08 13:35:10.595 D/MSC_V3.2.7(15371): local PERSISTENT RIGHTS file not accessible 02-08 13:35:10.595 D/MSC_V3.2.7(15371): API MSC_V3.2.7 : getRemainingRightsInfo - END 02-08 13:35:10.595 V/SMDRMManager(15371): getRemainingRightsInfo() FAILLOCAL PERSISTENT RIGHTS NOT ACCESSIBLE 02-08 13:35:10.603 D/skia (15371): Loading ARM decoder... 02-08 13:35:10.603 D/MSC_V3.2.7(15371): API MSC_V3.2.7 : getRemainingRightsInfo - START 02-08 13:35:11.204 I/InputDispatcher( 1372): Application is not responding: Window{407fd168 com.verizon.ams.tesla/com.verizon.ams.tesla.ui.TabDownloadsActivity paused=false}. 5009.7ms since event, 5000.6ms since wait started 02-08 13:35:11.204 I/Process ( 1372): Sending signal. PID: 15371 SIG: 3 02-08 13:35:11.204 I/dalvikvm(15371): threadid=4: reacting to signal 3 02-08 13:35:11.204 I/WindowManager( 1372): Input event dispatching timed out sending to com.verizon.ams.tesla/com.verizon.ams.tesla.ui.TabDownloadsActivity 02-08 13:35:11.212 I/dalvikvm(15371): Wrote stack traces to '/data/anr/traces.txt' 02-08 13:35:11.212 I/Process ( 1372): Sending signal. PID: 1372 SIG: 3 02-08 13:35:11.212 I/dalvikvm( 1372): threadid=4: reacting to signal 3 02-08 13:35:11.235 I/dalvikvm( 1372): Wrote stack traces to '/data/anr/traces.txt' 02-08 13:35:11.235 I/Process ( 1372): Sending signal. PID: 1444 SIG: 3 02-08 13:35:11.235 I/dalvikvm( 1444): threadid=4: reacting to signal 3 02-08 13:35:11.243 I/dalvikvm( 1444): Wrote stack traces to '/data/anr/traces.txt' 02-08 13:35:11.337 D/SecureMedia(15371): 2012-02-08 13:35:11,smesam2.c(253),TRACE,PingWorker-0 Service not configured: IDETECT(0x6000A): Unknown service 02-08 13:35:11.337 D/SecureMedia(15371): 2012-02-08 13:35:11,smesam2.c(529),TRACE,PingWorker-0 SMC 02-08 13:35:11.337 D/SecureMedia(15371): 2012-02-08 13:35:11,smesam2.c(872),TRACE,PingWorker-0 SMC 02-08 13:35:11.337 D/SecureMedia(15371): 2012-02-08 13:35:11,smesam2.c(905),TRACE,PingWorker-0 SME(0x6000A) 02-08 13:35:11.337 D/SecureMedia(15371): 2012-02-08 13:35:11,smesam2.c(1135),TRACE,PingWorker-0 SMC 02-08 13:35:11.337 D/SecureMedia(15371): 2012-02-08 13:35:11,smclientidetect.c(83),TRACE,PingWorker-0 SMC 02-08 13:35:11.337 D/SecureMedia(15371): 2012-02-08 13:35:11,smclientidetect.c(145),TRACE,PingWorker-0 SMC 02-08 13:35:11.337 D/SecureMedia(15371): 2012-02-08 13:35:11,smmisc.c(466),TRACE,PingWorker-0 Initializing SRNG. 02-08 13:35:11.337 I/SecureMedia(15371): 2012-02-08 13:35:11,smclientidetect.c(157),INFO,PingWorker-0 Next iDetect server check in 84306 seconds 02-08 13:35:11.337 E/SecureMedia(15371): 2012-02-08 13:35:11,smclientidetect.c(161),ERROR,PingWorker-0 iDetect (0x6000A): Unknown service 02-08 13:35:11.337 D/SecureMedia(15371): 2012-02-08 13:35:11,smclientidetect.c(173),TRACE,PingWorker-0 SMC 02-08 13:35:11.360 D/MSC_V3.2.7(15371): device id : 173adb4e339c16e7 02-08 13:35:11.360 D/MSC_V3.2.7(15371): Getting Rights info from LOCAL for : /mnt/sdcard/.video/Frien_380104.mp4 02-08 13:35:11.360 D/MSC_V3.2.7(15371): getRemainingRights for :/mnt/sdcard/.video/Frien_380104.mp4 02-08 13:35:11.360 D/MSC_V3.2.7(15371): BEFORE calling iprmHandle.getSMVODRule for content: Frien_380104.mp4 02-08 13:35:11.368 D/MSC_V3.2.7(15371): AFTER getSMVODRule 02-08 13:35:11.376 D/MSC_V3.2.7(15371): rentalflag = true 02-08 13:35:11.376 D/MSC_V3.2.7(15371): start time = 0 02-08 13:35:11.376 D/MSC_V3.2.7(15371): play window = 0 02-08 13:35:11.376 D/MSC_V3.2.7(15371): expiry time = 1328767889 02-08 13:35:11.376 D/MSC_V3.2.7(15371): returning RightsInfo 02-08 13:35:11.376 D/MSC_V3.2.7(15371): API MSC_V3.2.7 : getRemainingRightsInfo - END 02-08 13:35:11.384 D/skia (15371): Loading ARM decoder... 02-08 13:35:11.392 D/dalvikvm( 1372): GC_CONCURRENT freed 1616K, 50% free 6650K/13127K, external 6837K/8538K, paused 2ms+8ms 02-08 13:35:11.509 D/dalvikvm( 1372): GC_EXPLICIT freed 513K, 51% free 6461K/13127K, external 6837K/8538K, paused 70ms 02-08 13:35:11.517 I/LAB126 ( 1444): StatusBarService hears intent. action: com.lab126.intent.TOP_WINDOW_CHANGED 02-08 13:35:11.517 E/FVApplication(15371): Updating Storage Info 02-08 13:35:11.517 I/LAB126 ( 1444): window changed 02-08 13:35:11.548 I/MediaLibraryActivity(15371): getting purchaselist successful! 02-08 13:35:11.610 I/MediaLibraryActivity(15371): purchaselistIndex size bobby:20 02-08 13:35:11.610 I/MediaLibraryActivity(15371): purchaselistIndex size bobby:20 02-08 13:35:11.610 I/MediaLibraryActivity(15371): Purchasehistory list Parsing successfully 02-08 13:35:11.610 V/MediaLibraryActivity(15371): MediaLibraryActivity.Done Waiting. READ NOW 02-08 13:35:11.782 D/skia (15371): Loading ARM decoder... 02-08 13:35:11.853 D/dalvikvm(15371): GC_CONCURRENT freed 1509K, 47% free 4525K/8519K, external 7759K/8318K, paused 2ms+3ms 02-08 13:35:11.884 I/CommonUtils(15371): updatePurchaseStatusForMediaCache .... 02-08 13:35:11.892 W/InputManagerService( 1372): Window already focused, ignoring focus gain of: com.android.internal.view.IInputMethodClient$Stub$Proxy@40851660 02-08 13:35:11.899 D/skia (15371): Loading ARM decoder... 02-08 13:35:11.899 D/MSC_V3.2.7(15371): API MSC_V3.2.7 : getRemainingRightsInfo - START 02-08 13:35:11.907 D/MSC_V3.2.7(15371): device id : 173adb4e339c16e7 02-08 13:35:11.907 D/MSC_V3.2.7(15371): Getting Rights info from LOCAL for : /mnt/sdcard/.video/Afric_342339.mp4 02-08 13:35:11.907 D/MSC_V3.2.7(15371): getRemainingRights for :/mnt/sdcard/.video/Afric_342339.mp4 02-08 13:35:11.907 D/MSC_V3.2.7(15371): BEFORE calling iprmHandle.getSMVODRule for content: Afric_342339.mp4 02-08 13:35:11.915 D/MSC_V3.2.7(15371): AFTER getSMVODRule 02-08 13:35:11.915 D/MSC_V3.2.7(15371): rentalflag = true 02-08 13:35:11.915 D/MSC_V3.2.7(15371): start time = -1895530448 02-08 13:35:11.915 D/MSC_V3.2.7(15371): play window = -1409286016 02-08 13:35:11.915 D/MSC_V3.2.7(15371): expiry time = 1866006752 02-08 13:35:11.915 D/MSC_V3.2.7(15371): local PERSISTENT RIGHTS file not accessible 02-08 13:35:11.915 D/MSC_V3.2.7(15371): API MSC_V3.2.7 : getRemainingRightsInfo - END 02-08 13:35:11.915 V/SMDRMManager(15371): getRemainingRightsInfo() FAILLOCAL PERSISTENT RIGHTS NOT ACCESSIBLE 02-08 13:35:11.923 D/skia (15371): Loading ARM decoder... 02-08 13:35:11.923 D/MSC_V3.2.7(15371): API MSC_V3.2.7 : getRemainingRightsInfo - START 02-08 13:35:11.931 D/MSC_V3.2.7(15371): device id : 173adb4e339c16e7 02-08 13:35:11.931 D/MSC_V3.2.7(15371): Getting Rights info from LOCAL for : /mnt/sdcard/.video/Frien_380104.mp4 02-08 13:35:11.931 D/MSC_V3.2.7(15371): getRemainingRights for :/mnt/sdcard/.video/Frien_380104.mp4 02-08 13:35:11.931 D/MSC_V3.2.7(15371): BEFORE calling iprmHandle.getSMVODRule for content: Frien_380104.mp4 02-08 13:35:11.931 D/MSC_V3.2.7(15371): AFTER getSMVODRule 02-08 13:35:11.931 D/MSC_V3.2.7(15371): rentalflag = true 02-08 13:35:11.931 D/MSC_V3.2.7(15371): start time = 0 02-08 13:35:11.931 D/MSC_V3.2.7(15371): play window = 0 02-08 13:35:11.931 D/MSC_V3.2.7(15371): expiry time = 1328767889 02-08 13:35:11.931 D/MSC_V3.2.7(15371): returning RightsInfo 02-08 13:35:11.938 D/MSC_V3.2.7(15371): API MSC_V3.2.7 : getRemainingRightsInfo - END 02-08 13:35:11.938 D/skia (15371): Loading ARM decoder... 02-08 13:35:12.063 I/Process ( 1372): Sending signal. PID: 1565 SIG: 3 02-08 13:35:12.063 I/Process ( 1372): Sending signal. PID: 1595 SIG: 3 02-08 13:35:12.071 I/Process ( 1372): Sending signal. PID: 1457 SIG: 3 02-08 13:35:12.087 I/Process ( 1372): Sending signal. PID: 1537 SIG: 3 02-08 13:35:12.103 D/InputDispatcher( 1372): channel '407fd168 com.verizon.ams.tesla/com.verizon.ams.tesla.ui.TabDownloadsActivity (server)' ~ Synthesized 1 cancelation events to bring channel back in sync with reality: application not responding, options=0. 02-08 13:35:12.103 I/InputDispatcher( 1372): Dropping event because the pointer is not down. 02-08 13:35:12.103 I/InputDispatcher( 1372): Dropping event because the pointer is not down. 02-08 13:35:12.103 I/InputDispatcher( 1372): Dropping event because the pointer is not down. 02-08 13:35:12.103 E/ActivityManager( 1372): ANR in com.verizon.ams.tesla (com.verizon.ams.tesla/.ui.TabDownloadsActivity) 02-08 13:35:12.103 E/ActivityManager( 1372): Reason: keyDispatchingTimedOut 02-08 13:35:12.103 E/ActivityManager( 1372): Load: 1.81 / 1.96 / 1.29 02-08 13:35:12.103 E/ActivityManager( 1372): CPU usage from 6001ms to 981ms ago: 02-08 13:35:12.103 E/ActivityManager( 1372): 33% 15371/com.verizon.ams.tesla: 25% user + 8.3% kernel / faults: 2657 minor 02-08 13:35:12.103 E/ActivityManager( 1372): 8.5% 3/ksoftirqd/0: 0% user + 8.5% kernel 02-08 13:35:12.103 E/ActivityManager( 1372): 3.5% 1444/com.android.systemui: 2.3% user + 1.1% kernel / faults: 3249 minor 02-08 13:35:12.103 E/ActivityManager( 1372): 3.1% 1372/system_server: 2.1% user + 0.9% kernel / faults: 60 minor 02-08 13:35:12.103 E/ActivityManager( 1372): 2.1% 15355/com.cooliris.media: 1.7% user + 0.3% kernel / faults: 1005 minor 02-08 13:35:12.103 E/ActivityManager( 1372): 1.3% 5153/tiwlan_wq: 0% user + 1.3% kernel 02-08 13:35:12.103 E/ActivityManager( 1372): 1.1% 771/mmcqd: 0% user + 1.1% kernel 02-08 13:35:12.103 E/ActivityManager( 1372): 0.9% 1457/com.amazon.kindle.otter: 0.9% user + 0% kernel / faults: 3 minor 02-08 13:35:12.103 E/ActivityManager( 1372): 0.3% 7/events/0: 0% user + 0.3% kernel 02-08 13:35:12.103 E/ActivityManager( 1372): 0.3% 1302/adbd: 0% user + 0.3% kernel 02-08 13:35:12.103 E/ActivityManager( 1372): 0.3% 11097/android.process.media: 0% user + 0.3% kernel / faults: 23 minor 02-08 13:35:12.103 E/ActivityManager( 1372): 0.1% 1310/pvr_workqueue: 0% user + 0.1% kernel 02-08 13:35:12.103 E/ActivityManager( 1372): 0.1% 1537/com.amazon.gardemanger: 0.1% user + 0% kernel 02-08 13:35:12.103 E/ActivityManager( 1372): 0% 12401/ksoftirqd/1: 0% user + 0% kernel 02-08 13:35:12.103 E/ActivityManager( 1372): 0.1% 12414/events/1: 0% user + 0.1% kernel 02-08 13:35:12.103 E/ActivityManager( 1372): 0.1% 15292/logcat: 0.1% user + 0% kernel 02-08 13:35:12.103 E/ActivityManager( 1372): 0.1% 15345/logcat: 0% user + 0.1% kernel 02-08 13:35:12.103 E/ActivityManager( 1372): 39% TOTAL: 17% user + 15% kernel + 7.1% iowait + 0% softirq 02-08 13:35:12.103 E/ActivityManager( 1372): CPU usage from 307ms to 836ms later: 02-08 13:35:12.103 E/ActivityManager( 1372): 100% 15371/com.verizon.ams.tesla: 78% user + 21% kernel / faults: 1498 minor 02-08 13:35:12.103 E/ActivityManager( 1372): 69% 15371/rizon.ams.tesla: 57% user + 11% kernel 02-08 13:35:12.103 E/ActivityManager( 1372): 9.6% 15374/GC: 7.6% user + 1.9% kernel 02-08 13:35:12.103 E/ActivityManager( 1372): 3.8% 15378/Compiler: 0% user + 3.8% kernel 02-08 13:35:12.103 E/ActivityManager( 1372): 33% 1372/system_server: 22% user + 11% kernel / faults: 676 minor 02-08 13:35:12.103 E/ActivityManager( 1372): 9.4% 1379/SurfaceFlinger: 9.4% user + 0% kernel 02-08 13:35:12.103 E/ActivityManager( 1372): 7.5% 1413/WindowManager: 7.5% user + 0% kernel 02-08 13:35:12.103 E/ActivityManager( 1372): 3.7% 1415/InputDispatcher: 0% user + 3.7% kernel 02-08 13:35:12.103 E/ActivityManager( 1372): 1.8% 1377/Binder Thread #: 0% user + 1.8% kernel 02-08 13:35:12.103 E/ActivityManager( 1372): 1.8% 1378/Binder Thread #: 0% user + 1.8% kernel 02-08 13:35:12.103 E/ActivityManager( 1372): 1.8% 1392/ActivityManager: 1.8% user + 0% kernel 02-08 13:35:12.103 E/ActivityManager( 1372): 1.8% 1468/Binder Thread #: 1.8% user + 0% kernel 02-08 13:35:12.103 E/ActivityManager( 1372): 1.8% 1545/Binder Thread #: 0% user + 1.8% kernel 02-08 13:35:12.103 E/ActivityManager( 1372): 1.8% 1552/Binder Thread #: 1.8% user + 0% kernel 02-08 13:35:12.103 E/ActivityManager( 1372): 1.8% 1562/Binder Thread #: 0% user + 1.8% kernel 02-08 13:35:12.103 E/ActivityManager( 1372): 7.6% 1565/com.amazon.dcp: 6.1% user + 1.5% kernel / faults: 66 minor 02-08 13:35:12.103 E/ActivityManager( 1372): 4.6% 1568/GC: 4.6% user + 0% kernel 02-08 13:35:12.103 E/ActivityManager( 1372): 3% 1635/pool-1-thread-1: 1.5% user + 1.5% kernel 02-08 13:35:12.103 E/ActivityManager( 1372): 4.6% 1595/com.amazon.venezia: 4.6% user + 0% kernel / faults: 17 minor 02-08 13:35:12.103 E/ActivityManager( 1372): 3% 1603/Binder Thread #: 3% user + 0% kernel 02-08 13:35:12.103 E/ActivityManager( 1372): 1.5% 9401/Binder Thread #: 1.5% user + 0% kernel 02-08 13:35:12.103 E/ActivityManager( 1372): 3.8% 1457/com.amazon.kindle.otter: 1.9% user + 1.9% kernel / faults: 20 minor 02-08 13:35:12.103 E/ActivityManager( 1372): 1.9% 3310/pool-16-thread-: 0% user + 1.9% kernel 02-08 13:35:12.103 E/ActivityManager( 1372): 1.2% 1278/jbd2/mmcblk0p10: 0% user + 1.2% kernel 02-08 13:35:12.103 E/ActivityManager( 1372): 1.3% 1302/adbd: 0% user + 1.3% kernel 02-08 13:35:12.103 E/ActivityManager( 1372): 1.3% 1302/adbd: 0% user + 1.3% kernel 02-08 13:35:12.103 E/ActivityManager( 1372): 1.3% 1310/pvr_workqueue: 0% user + 1.3% kernel 02-08 13:35:12.103 E/ActivityManager( 1372): 1.9% 1537/com.amazon.gardemanger: 1.9% user + 0% kernel / faults: 15 minor 02-08 13:35:12.103 E/ActivityManager( 1372): 1.9% 1582/Binder Thread #: 1.9% user + 0% kernel 02-08 13:35:12.103 E/ActivityManager( 1372): 1.9% 1584/pool-1-thread-2: 1.9% user + 0% kernel 02-08 13:35:12.103 E/ActivityManager( 1372): 1.9% 1593/Binder Thread #: 1.9% user + 0% kernel 02-08 13:35:12.103 E/ActivityManager( 1372): 1.5% 1646/logcat: 0% user + 1.5% kernel 02-08 13:35:12.103 E/ActivityManager( 1372): 1.6% 15345/logcat: 0% user + 1.6% kernel 02-08 13:35:12.103 E/ActivityManager( 1372): 85% TOTAL: 62% user + 19% kernel + 3.8% iowait 02-08 13:35:12.446 D/dalvikvm( 1372): GC_FOR_MALLOC freed 1586K, 50% free 6691K/13127K, external 7055K/8538K, paused 54ms 02-08 13:35:12.556 D/dalvikvm( 1372): GC_FOR_MALLOC freed 455K, 48% free 6892K/13127K, external 7051K/8538K, paused 51ms 02-08 13:35:12.610 D/dalvikvm( 1372): GC_FOR_MALLOC freed 627K, 46% free 7201K/13127K, external 7051K/8538K, paused 48ms 02-08 13:35:12.610 I/dalvikvm-heap( 1372): Grow heap (frag case) to 16.958MB for 693062-byte allocation 02-08 13:35:12.673 D/dalvikvm( 1372): GC_FOR_MALLOC freed 0K, 44% free 7878K/13831K, external 7051K/8538K, paused 51ms 02-08 13:35:14.142 W/ActivityManager( 1372): Force finishing activity com.verizon.ams.tesla/.ui.TabDownloadsActivity 02-08 13:35:14.142 I/ActivityManager( 1372): Killing com.verizon.ams.tesla (pid=15371): user's request 02-08 13:35:14.149 I/Process ( 1372): Sending signal. PID: 15371 SIG: 9 02-08 13:35:14.181 I/ActivityManager( 1372): Process com.verizon.ams.tesla (pid 15371) has died. 02-08 13:35:14.181 W/ActivityManager( 1372): Service crashed 2 times, stopping: ServiceRecord{40741f90 com.verizon.ams.tesla/.service.download.MetaDataSyncService} 02-08 13:35:14.181 W/ActivityManager( 1372): Service crashed 2 times, stopping: ServiceRecord{40741588 com.verizon.ams.tesla/.service.download.DownloadService} 02-08 13:35:14.181 E/InputDispatcher( 1372): channel '40903ee8 com.verizon.ams.tesla/com.verizon.ams.tesla.ui.MainActivity (server)' ~ Consumer closed input channel or an error occurred. events=0x8 02-08 13:35:14.181 E/InputDispatcher( 1372): channel '40903ee8 com.verizon.ams.tesla/com.verizon.ams.tesla.ui.MainActivity (server)' ~ Channel is unrecoverably broken and will be disposed! 02-08 13:35:14.188 E/InputDispatcher( 1372): channel '407fd168 com.verizon.ams.tesla/com.verizon.ams.tesla.ui.TabDownloadsActivity (server)' ~ Consumer closed input channel or an error occurred. events=0x8 02-08 13:35:14.188 E/InputDispatcher( 1372): channel '407fd168 com.verizon.ams.tesla/com.verizon.ams.tesla.ui.TabDownloadsActivity (server)' ~ Channel is unrecoverably broken and will be disposed! 02-08 13:35:14.188 I/WindowManager( 1372): WINDOW DIED Window{40903ee8 com.verizon.ams.tesla/com.verizon.ams.tesla.ui.MainActivity paused=false} 02-08 13:35:14.188 I/WindowManager( 1372): WIN DEATH: Window{407fd168 com.verizon.ams.tesla/com.verizon.ams.tesla.ui.TabDownloadsActivity paused=true} 02-08 13:35:14.196 W/WindowManager( 1372): Failed looking up window 02-08 13:35:14.196 W/WindowManager( 1372): java.lang.IllegalArgumentException: Requested window android.os.BinderProxy@4093e598 does not exist 02-08 13:35:14.196 W/WindowManager( 1372): at com.android.server.WindowManagerService.windowForClientLocked(WindowManagerService.java:8177) 02-08 13:35:14.196 W/WindowManager( 1372): at com.android.server.WindowManagerService.windowForClientLocked(WindowManagerService.java:8168) 02-08 13:35:14.196 W/WindowManager( 1372): at com.android.server.WindowManagerService$WindowState$DeathRecipient.binderDied(WindowManagerService.java:7027) 02-08 13:35:14.196 W/WindowManager( 1372): at android.os.BinderProxy.sendDeathNotice(Binder.java:381) 02-08 13:35:14.196 W/WindowManager( 1372): at dalvik.system.NativeStart.run(Native Method) 02-08 13:35:14.196 I/WindowManager( 1372): WIN DEATH: null 02-08 13:35:14.204 I/ActivityManager( 1372): Start proc com.verizon.ams.tesla for activity com.verizon.ams.tesla/.ui.MainActivity: pid=15424 uid=10034 gids={3003, 1015} 02-08 13:35:14.235 E/jdwp (15424): Failed sending reply to debugger: Broken pipe 02-08 13:35:14.235 D/dalvikvm(15424): Debugger has detached; object registry had 1 entries 02-08 13:35:14.251 I/LAB126 ( 1444): StatusBarService hears intent. action: com.lab126.intent.TOP_WINDOW_CHANGED 02-08 13:35:14.251 I/LAB126 ( 1444): window changed 02-08 13:35:14.251 I/LAB126 ( 1444): don't set softkey config for 'Starting...' window 02-08 13:35:14.267 I/ActivityManager( 1372): Displayed com.verizon.ams.tesla/.ui.TabDownloadsActivity: +9s178ms 02-08 13:35:14.306 I/ActivityThread(15424): Pub com.verizon.mediamanager.SearchSuggestion: com.verizon.mediamanager.SearchSuggestion 02-08 13:35:14.306 D/ContentProvider(15424): Videos folder exist 02-08 13:35:14.306 D/ContentProvider(15424): Pictures folder exists 02-08 13:35:14.313 I/MSV (15424): FiosTV Remote DVR Configuration 02-08 13:35:14.313 I/MSV (15424): =============================== 02-08 13:35:14.313 I/MSV (15424): Config: production 02-08 13:35:14.313 I/MSV (15424): Device Model: Kindle Fire 02-08 13:35:14.313 I/MSV (15424): Proxy URL: https://www32.verizon.com/vvmapp2/VVM_LAB.aspx?URL= 02-08 13:35:14.313 I/MSV (15424): Network Timeout: 25000 ms 02-08 13:35:14.313 I/MSV (15424): =============================== 02-08 13:35:14.329 V/MSVAppData(15424): init MSV data 02-08 13:35:14.329 V/MSVAppData(15424): ********* DEVICEID:173adb4e339c16e7 02-08 13:35:14.337 D/mediamanager(15424): APP CONTEXT RECYCLING 02-08 13:35:14.337 D/mediamanager(15424): setting environment for mmol : PRODUCTION 02-08 13:35:14.431 D/dalvikvm(15424): GC_CONCURRENT freed 357K, 48% free 3111K/5895K, external 933K/1181K, paused 1ms+2ms 02-08 13:35:14.462 D/dalvikvm(15424): GC_EXTERNAL_ALLOC freed 2K, 48% free 3109K/5895K, external 933K/1181K, paused 26ms 02-08 13:35:14.532 D/dalvikvm(15424): GC_EXTERNAL_ALLOC freed 30K, 48% free 3079K/5895K, external 4683K/5848K, paused 25ms 02-08 13:35:14.688 D/vzAnalytics(15424): WiFi:true, CellData:false 02-08 13:35:14.688 D/vzAnalytics(15424): Started network thread 1328729714699 02-08 13:35:14.688 D/vzAnalytics(15424): Initializing session 1328729714698-MM5MJRS 02-08 13:35:14.696 D/vzAnalytics(15424): Scheduling db and net save task 02-08 13:35:14.696 I/vzAnalytics(15424): Initialized vz analytics. version 2.0.2 02-08 13:35:14.751 W/InputManagerService( 1372): Got RemoteException sending setActive(false) notification to pid 15371 uid 10034 02-08 13:35:14.829 D/dalvikvm(15424): GC_CONCURRENT freed 224K, 45% free 3334K/6023K, external 3799K/4317K, paused 2ms+3ms 02-08 13:35:14.915 I/ActivityManager( 1372): Displayed com.verizon.ams.tesla/.ui.MainActivity: +717ms 02-08 13:35:14.915 I/LAB126 ( 1444): StatusBarService hears intent. action: com.lab126.intent.TOP_WINDOW_CHANGED 02-08 13:35:14.915 I/LAB126 ( 1444): window changed 02-08 13:35:15.063 D/dalvikvm(15424): GC_CONCURRENT freed 955K, 47% free 3913K/7303K, external 3799K/4317K, paused 4ms+3ms 02-08 13:35:15.110 D/vzAnalytics(15424): Loaded 0 config keys 02-08 13:35:15.274 V/DesEncrypter(15424): error while decrypt: javax.crypto.IllegalBlockSizeException: last block incomplete in decryption 02-08 13:35:15.274 I/System.out(15424): Account Migration Exception : java.lang.NullPointerException 02-08 13:35:15.290 V/DRMManager(15424): PV Lib not linked 02-08 13:35:15.290 E/FVApplication(15424): TRY MOTO DRM 02-08 13:35:15.298 E/FVApplication(15424): SUCCESS bind to Janus DRM API 02-08 13:35:15.298 V/DeviceDRMInfoManager(15424): fetchDeviceDRMInfo() :: url = http://www98-west.verizon.com/mobilesvc/adLibSvc/adLibRestSvc/Devicelist?Model=Kindle+Fire&firmware=default&ostype=android&osversion=10&appname=MM&appversion=1.5.0&format=xml 02-08 13:35:15.298 V/MSV (15424): WebUtils url:http://www98-west.verizon.com/mobilesvc/adLibSvc/adLibRestSvc/Devicelist?Model=Kindle+Fire&firmware=default&ostype=android&osversion=10&appname=MM&appversion=1.5.0&format=xml 02-08 13:35:15.298 V/MetaDataSyncService(15424): getMediaIds(): 02-08 13:35:15.298 W/ActivityManager( 1372): Unable to start service Intent { act=com.motorola.android.wmdrm.IWmdrmService }: not found 02-08 13:35:15.353 V/MetaDataSyncService(15424): downloadPurchaseList() :: url = https://www36.verizon.com/MSVAppServices/Authenticated/POX/AccountMgrXmlServices.asmx/MSVAppRequest? 02-08 13:35:15.353 V/MetaDataSyncService(15424): downloadPurchaseList() :: postData = strInputXML=GETPURCHASEDTITLESironman1058DNETtrans_12BB001YDESCproduct_purchasedDateALLAverageRatingALLALL 02-08 13:35:15.353 V/MSV (15424): WebUtils url:https://www36.verizon.com/MSVAppServices/Authenticated/POX/AccountMgrXmlServices.asmx/MSVAppRequest? 02-08 13:35:15.353 V/MSV (15424): WebUtils body:strInputXML=GETPURCHASEDTITLESironman1058DNETtrans_12BB001YDESCproduct_purchasedDateALLAverageRatingALLALL 02-08 13:35:15.353 V/MetaDataSyncService(15424): downloadWatchList() :: url = https://www36.verizon.com/MSVAppServices/Authenticated/POX/AccountMgrXmlServices.asmx/MSVAppRequest? 02-08 13:35:15.353 V/MetaDataSyncService(15424): downloadWatchList() :: postData = strInputXML=GETWATCHLISTironman1058DNETtrans_12BB001DESCproduct_dtmcreateDateALLAverageRatingALLALL 02-08 13:35:15.353 V/MSV (15424): WebUtils url:https://www36.verizon.com/MSVAppServices/Authenticated/POX/AccountMgrXmlServices.asmx/MSVAppRequest? 02-08 13:35:15.353 V/MSV (15424): WebUtils body:strInputXML=GETWATCHLISTironman1058DNETtrans_12BB001DESCproduct_dtmcreateDateALLAverageRatingALLALL 02-08 13:35:15.376 V/MSV (15424): WebUtils url:https://www36.verizon.com/MSVAppServices/Authenticated/POX/CatalogXmlServices.asmx/MSVAppRequest? 02-08 13:35:15.376 V/MSV (15424): WebUtils body:strInputXML=GETUSERRATINGSironman1058DNETtrans_12BB001 02-08 13:35:15.376 V/MetaDataSyncService(15424): getContentItemIds(): 02-08 13:35:15.501 V/MSV (15424): finished http post call 02-08 13:35:15.884 V/MSV (15424): WebUtils finished http get call 02-08 13:35:15.884 I/DeviceDRMInfoManager(15424): deviceDRMHandler.. 02-08 13:35:15.923 V/DeviceDRMInfoManager(15424): DRM FOR THE DEVICE: [SM] 02-08 13:35:16.181 D/dalvikvm(15424): GC_CONCURRENT freed 1311K, 47% free 4287K/8071K, external 3799K/4317K, paused 1ms+3ms 02-08 13:35:16.806 I/MetaDataSyncService(15424): getting purchaselist successful! 02-08 13:35:16.806 V/MSV (15424): WebUtils finished http post call 02-08 13:35:17.149 D/skia (15424): Loading ARM decoder... 02-08 13:35:17.165 D/skia (15424): Loading ARM decoder... 02-08 13:35:17.165 D/skia (15424): sk_fill_input_buffer():54:: !!!Premature end of JPEG file. Sending dummy EOI. 02-08 13:35:17.165 D/skia (15424): --- decoder->decode returned false 02-08 13:35:17.165 I/System.out(15424): resolveUri failed on bad bitmap uri: /data/data/com.verizon.ams.tesla/cache/169971789.cachefile 02-08 13:35:17.220 D/skia (15424): Loading ARM decoder... 02-08 13:35:17.267 D/dalvikvm(15424): GC_EXTERNAL_ALLOC freed 811K, 50% free 4202K/8327K, external 4309K/4317K, paused 35ms 02-08 13:35:17.313 D/skia (15424): Loading ARM decoder... 02-08 13:35:17.313 D/skia (15424): sk_fill_input_buffer():54:: !!!Premature end of JPEG file. Sending dummy EOI. 02-08 13:35:17.313 D/skia (15424): --- decoder->decode returned false 02-08 13:35:17.313 I/System.out(15424): resolveUri failed on bad bitmap uri: /data/data/com.verizon.ams.tesla/cache/169971789.cachefile 02-08 13:35:17.321 I/MetaDataSyncService(15424): purchaselistIndex size bobby:0 02-08 13:35:17.321 I/MetaDataSyncService(15424): purchaselistIndex size bobby:0 02-08 13:35:17.321 I/MetaDataSyncService(15424): Purchasehistory list Parsing successfully 02-08 13:35:17.399 D/skia (15424): Loading ARM decoder... 02-08 13:35:17.407 D/skia (15424): --- decoder->decode returned false 02-08 13:35:17.579 V/MSV (15424): WebUtils finished http post call 02-08 13:35:17.579 V/MSV (15424): Silent request done... 02-08 13:35:17.579 V/MSV (15424): WebUtils finished http post call 02-08 13:35:17.579 V/MSV (15424): getting watchlist successful! 02-08 13:35:17.587 D/skia (15424): Loading ARM decoder... 02-08 13:35:17.657 V/MSV (15424): watchlist index created!9 02-08 13:35:17.657 I/MSV (15424): Watchlist Parsing successfully