--------- beginning of /dev/log/system
02-08 13:19:34.511 D/LAB126 ( 1441): got back button softkey click!
02-08 13:19:34.511 D/LAB126 ( 1441): collapse because of back button
--------- beginning of /dev/log/main
02-08 13:19:34.511 W/KeyCharacterMap(31753): No keyboard for id 0
02-08 13:19:34.511 W/KeyCharacterMap(31753): Using default keymap: /system/usr/keychars/qwerty.kcm.bin
02-08 13:19:34.511 D/LAB126 ( 1441): collapse softkeys
02-08 13:19:34.519 I/MSV (31753): WebUtils futureTask is null
02-08 13:19:34.519 W/System.err(31753): java.lang.NullPointerException
02-08 13:19:34.519 W/System.err(31753): at com.verizon.ams.tesla.msv.MediaLibraryActivity$WriteCacheTask.doInBackground(MediaLibraryActivity.java:970)
02-08 13:19:34.519 W/System.err(31753): at com.verizon.ams.tesla.msv.MediaLibraryActivity$WriteCacheTask.doInBackground(MediaLibraryActivity.java:1)
02-08 13:19:34.527 W/System.err(31753): at android.os.AsyncTask$2.call(AsyncTask.java:185)
02-08 13:19:34.527 W/System.err(31753): at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:306)
02-08 13:19:34.527 W/System.err(31753): at java.util.concurrent.FutureTask.run(FutureTask.java:138)
02-08 13:19:34.527 W/System.err(31753): at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1088)
02-08 13:19:34.527 W/System.err(31753): at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:581)
02-08 13:19:34.527 W/System.err(31753): at java.lang.Thread.run(Thread.java:1019)
02-08 13:19:34.527 V/MediaLibraryActivity(31753): MediaLibraryActivity.DONE Writing
02-08 13:19:34.558 I/LAB126 ( 1441): StatusBarService hears intent. action: com.lab126.intent.TOP_WINDOW_CHANGED
02-08 13:19:34.558 I/LAB126 ( 1441): window changed
02-08 13:19:34.558 I/LAB126 ( 1441): don't set softkey config for 'Starting...' window
02-08 13:19:34.879 I/ActivityManager( 1373): Displayed com.verizon.ams.tesla/.ui.MainActivity: +358ms
02-08 13:19:34.886 I/LAB126 ( 1441): StatusBarService hears intent. action: com.lab126.intent.TOP_WINDOW_CHANGED
02-08 13:19:34.886 I/LAB126 ( 1441): window changed
02-08 13:19:34.933 D/dalvikvm(31753): GC_CONCURRENT freed 1118K, 44% free 4645K/8199K, external 4042K/4728K, paused 2ms+4ms
02-08 13:19:36.246 V/MSV (31753): finished http post call
02-08 13:19:37.402 D/ActivityManager( 1373): Starting: Intent { act=android.intent.action.VIEW dat=app://com.verizon.ams.tesla/mydownloads cmp=com.verizon.ams.tesla/.ui.TabDownloadsActivity } from pid 31753
02-08 13:19:37.457 W/ResourceType(31753): Skipping entry 0x7f02015d in package table 0 because it is not complex!
02-08 13:19:37.457 W/ResourceType(31753): Skipping entry 0x7f02015d in package table 0 because it is not complex!
02-08 13:19:37.457 W/ResourceType(31753): Skipping entry 0x7f02015d in package table 0 because it is not complex!
02-08 13:19:37.457 W/ResourceType(31753): Skipping entry 0x7f02015d in package table 0 because it is not complex!
02-08 13:19:37.457 W/ResourceType(31753): Skipping entry 0x7f02015d in package table 0 because it is not complex!
02-08 13:19:37.457 W/ResourceType(31753): Skipping entry 0x7f02015d in package table 0 because it is not complex!
02-08 13:19:37.464 V/MediaLibraryActivity(31753): getMediaIds():
02-08 13:19:37.464 V/MediaLibraryActivity(31753): MediaLibraryActivity.Done Waiting. READ NOW
02-08 13:19:37.597 V/MediaLibraryActivity(31753): getContentItemIds():
02-08 13:19:37.636 I/CommonUtils(31753): updatePurchaseStatusForMediaCache ....
02-08 13:19:37.738 V/MediaLibraryActivity(31753): downloadPurchaseList() :: url = https://www36.verizon.com/MSVAppServices/Authenticated/POX/AccountMgrXmlServices.asmx/MSVAppRequest?
02-08 13:19:37.738 V/MediaLibraryActivity(31753): downloadPurchaseList() :: postData = strInputXML=GETPURCHASEDTITLESDNETtrans_12BB001Y
02-08 13:19:37.753 V/MSV (31753): WebUtils url:https://www36.verizon.com/MSVAppServices/Authenticated/POX/AccountMgrXmlServices.asmx/MSVAppRequest?
02-08 13:19:37.753 V/MSV (31753): WebUtils body:strInputXML=GETPURCHASEDTITLESDNETtrans_12BB001Y
02-08 13:19:37.777 V/MSV (31753): WebUtils url:http://www36.verizon.com/MSVAppServices/anonymous/Images/Smurfs_The_2011_new_128x192_bc.jpg
02-08 13:19:37.871 I/LAB126 ( 1441): StatusBarService hears intent. action: com.lab126.intent.TOP_WINDOW_CHANGED
02-08 13:19:37.871 I/LAB126 ( 1441): window changed
02-08 13:19:37.886 E/FVApplication(31753): Updating Storage Info
02-08 13:19:37.894 D/vzAnalytics(31753): Moved 4 events from thread 1 to bg thread. bg thread pending events 4
02-08 13:19:37.894 D/vzAnalytics(31753): Saving log to db on thread 14 for 4 events
02-08 13:19:38.207 D/vzAnalytics(31753): WiFi:true, CellData:false
02-08 13:19:38.207 D/vzAnalytics(31753): Enqueuing server log task.
02-08 13:19:38.207 D/vzAnalytics(31753): Running server log task on thread 14 for 1 events. url :http://www22.verizon.com/clickStream/Tracker.svc/xml/async/postmetrics
02-08 13:19:38.222 I/ActivityManager( 1373): Displayed com.verizon.ams.tesla/.ui.TabDownloadsActivity: +795ms
02-08 13:19:38.324 D/LAB126 ( 1441): got menu button softkey click!
02-08 13:19:38.324 D/LAB126 ( 1441): collapse because of menu
02-08 13:19:38.324 D/LAB126 ( 1441): collapse softkeys
02-08 13:19:38.332 W/KeyCharacterMap(31753): No keyboard for id 0
02-08 13:19:38.332 W/KeyCharacterMap(31753): Using default keymap: /system/usr/keychars/qwerty.kcm.bin
02-08 13:19:38.597 D/dalvikvm(31753): GC_CONCURRENT freed 1834K, 48% free 4737K/9095K, external 4122K/4728K, paused 2ms+4ms
02-08 13:19:39.105 I/MediaLibraryActivity(31753): getting purchaselist successful!
02-08 13:19:39.105 V/MSV (31753): WebUtils finished http post call
02-08 13:19:39.105 V/MSV (31753): WebUtils finished http get call
02-08 13:19:40.332 I/MediaLibraryActivity(31753): purchaselistIndex size bobby:20
02-08 13:19:40.332 I/MediaLibraryActivity(31753): purchaselistIndex size bobby:20
02-08 13:19:40.332 I/MediaLibraryActivity(31753): Purchasehistory list Parsing successfully
02-08 13:19:40.332 V/MediaLibraryActivity(31753): MediaLibraryActivity.Done Waiting. READ NOW
02-08 13:19:40.347 D/skia (31753): --- SkImageDecoder::Factory returned null
02-08 13:19:40.347 W/InputManagerService( 1373): Window already focused, ignoring focus gain of: com.android.internal.view.IInputMethodClient$Stub$Proxy@40781cc0
02-08 13:19:40.457 I/CommonUtils(31753): updatePurchaseStatusForMediaCache ....
02-08 13:19:40.464 V/MSV (31753): WebUtils url:http://www36.verizon.com/MSVAppServices/anonymous/Images/Smurfs_The_2011_new_128x192_bc.jpg
02-08 13:19:40.464 V/MSV (31753): WebUtils finished http get call
02-08 13:19:40.535 D/skia (31753): --- SkImageDecoder::Factory returned null
02-08 13:19:40.668 D/vzAnalytics(31753): deleting events after server log.
02-08 13:19:40.777 I/vzAnalytics(31753): complete server log task. success :true
02-08 13:19:40.777 D/vzAnalytics(31753): Scheduling db and net save task
02-08 13:19:41.043 I/MSV (31753): send downloading event
02-08 13:19:41.066 D/IconMerger( 1441): notification for com.verizon.ams.tesla (1)
02-08 13:19:41.066 D/IconMerger( 1441): notification for com.android.systemui (1)
02-08 13:19:41.066 D/IconMerger( 1441): sum of notifications: 2
02-08 13:19:41.472 D/skia (31753): Loading ARM decoder...
02-08 13:19:45.402 D/dalvikvm( 1373): GC_CONCURRENT freed 2076K, 51% free 6409K/12999K, external 6814K/8510K, paused 3ms+5ms
02-08 13:19:45.543 D/dalvikvm( 1373): GC_CONCURRENT freed 2053K, 51% free 6404K/12999K, external 6814K/8510K, paused 2ms+5ms
02-08 13:19:45.714 D/dalvikvm( 1373): GC_CONCURRENT freed 2106K, 52% free 6345K/12999K, external 6814K/8510K, paused 2ms+4ms
02-08 13:19:45.879 D/dalvikvm( 1373): GC_CONCURRENT freed 2029K, 52% free 6364K/12999K, external 6814K/8510K, paused 3ms+4ms
02-08 13:19:46.050 D/dalvikvm( 1373): GC_CONCURRENT freed 2066K, 52% free 6346K/12999K, external 6814K/8510K, paused 2ms+4ms
02-08 13:19:46.207 D/dalvikvm( 1373): GC_CONCURRENT freed 2047K, 52% free 6347K/12999K, external 6814K/8510K, paused 2ms+4ms
02-08 13:19:46.347 D/dalvikvm( 1373): GC_CONCURRENT freed 2011K, 51% free 6383K/12999K, external 6814K/8510K, paused 2ms+4ms
02-08 13:19:46.433 D/dalvikvm( 1373): GC_CONCURRENT freed 2052K, 52% free 6368K/12999K, external 6814K/8510K, paused 3ms+5ms
02-08 13:19:46.714 D/MSC_V3.2.7(31753): API MSC_V3.2.7 : getRemainingRightsInfo - START
02-08 13:19:46.714 D/MSC_V3.2.7(31753): initialize sm.
02-08 13:19:46.714 D/MSC_V3.2.7(31753): absolute path : /data/data/com.verizon.ams.tesla/app_msc_home
02-08 13:19:46.714 D/MSC_V3.2.7(31753): asset exists in msc home, NOT copying
02-08 13:19:46.714 D/MSC_V3.2.7(31753): absolute path : /data/data/com.verizon.ams.tesla/app_msc_home
02-08 13:19:46.714 D/MSC_V3.2.7(31753): asset exists in msc home, NOT copying
02-08 13:19:46.714 D/MSC_V3.2.7(31753): MSC CERTS to MSC HOME done
02-08 13:19:46.714 D/dalvikvm(31753): Trying to load lib /data/data/com.verizon.ams.tesla/lib/libSMutility.so 0x40513f10
02-08 13:19:46.714 D/dalvikvm(31753): Added shared lib /data/data/com.verizon.ams.tesla/lib/libSMutility.so 0x40513f10
02-08 13:19:46.714 D/dalvikvm(31753): No JNI_OnLoad found in /data/data/com.verizon.ams.tesla/lib/libSMutility.so 0x40513f10, skipping init
02-08 13:19:46.714 D/dalvikvm(31753): Trying to load lib /data/data/com.verizon.ams.tesla/lib/libsmsimpleclient.so 0x40513f10
02-08 13:19:46.722 D/dalvikvm(31753): Added shared lib /data/data/com.verizon.ams.tesla/lib/libsmsimpleclient.so 0x40513f10
02-08 13:19:46.722 D/dalvikvm(31753): No JNI_OnLoad found in /data/data/com.verizon.ams.tesla/lib/libsmsimpleclient.so 0x40513f10, skipping init
02-08 13:19:46.722 D/MSC_V3.2.7(31753): MSChome : /data/data/com.verizon.ams.tesla/app_msc_home
02-08 13:19:46.722 D/MSC_V3.2.7(31753): SMhome : /data/data/com.verizon.ams.tesla/app_msc_home/sm_home
02-08 13:19:46.722 D/MSC_V3.2.7(31753): DeviceRooted: Genuine
02-08 13:19:46.722 I/SecureMedia(31753): 2012-02-08 13:19:46,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:19:46.722 I/SecureMedia(31753): .
02-08 13:19:46.722 D/SecureMedia(31753): 2012-02-08 13:19:46,smplatform.c(154),TRACE,Main SecureMedia v2.1.0.5699 (Android)
02-08 13:19:46.746 D/SecureMedia(31753): 2012-02-08 13:19:46,smx.c(318),TRACE,Main SMX: No SN for EMM listener in the persistent store.
02-08 13:19:46.746 D/SecureMedia(31753): 2012-02-08 13:19:46,smclient.c(578),TRACE,Main No plugins to communicate with
02-08 13:19:46.746 D/SecureMedia(31753): 2012-02-08 13:19:46,smclient.c(578),TRACE,Main No plugins to communicate with
02-08 13:19:46.746 D/SecureMedia(31753): 2012-02-08 13:19:46,smclient.c(578),TRACE,Main No plugins to communicate with
02-08 13:19:46.754 I/SecureMedia(31753): 2012-02-08 13:19:46,smclientdaemon.c(699),INFO,PingWorker-0 Pinging SecureMedia Broadcast Subscription Service...
02-08 13:19:46.754 D/SecureMedia(31753): 2012-02-08 13:19:46,smdecryptor_tk.c(232),TRACE,Main Setting protection scheme: SHARK
02-08 13:19:46.754 D/SecureMedia(31753): 2012-02-08 13:19:46,smdecryptor_tk.c(237),TRACE,Main _SetSpec: spec=SHARK
02-08 13:19:46.754 D/SecureMedia(31753): 2012-02-08 13:19:46,smdecryptor_tk.c(232),TRACE,Main Setting protection scheme: SHARK
02-08 13:19:46.754 D/SecureMedia(31753): 2012-02-08 13:19:46,smdecryptor_tk.c(237),TRACE,Main _SetSpec: spec=SHARK
02-08 13:19:46.754 D/SecureMedia(31753): 2012-02-08 13:19:46,smclientplugin.c(359),TRACE,Main Protection scheme: SHARK
02-08 13:19:46.754 D/SecureMedia(31753): 2012-02-08 13:19:46,smm2dec.c(87),TRACE,Main Scramble specification is AES-ECB-BEG
02-08 13:19:46.754 D/SecureMedia(31753): 2012-02-08 13:19:46,smaes.c(428),TRACE,Main AES Copyright: AES Cipher Copyright (c) 2001, Dr Brian Gladman
02-08 13:19:46.754 D/SecureMedia(31753): 2012-02-08 13:19:46,smm2dec.c(87),TRACE,Main Scramble specification is AES-ECB-BEG
02-08 13:19:46.754 D/SecureMedia(31753): 2012-02-08 13:19:46,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:19:46.761 I/SecureMedia(31753): 2012-02-08 13:19:46,smsc.c(680),INFO,PingWorker-0 Ping state for 'BSS': SN = 4f631457dd1d2ab, current kids are:
02-08 13:19:48.472 D/skia (31753): Loading ARM decoder...
02-08 13:19:48.511 D/dalvikvm(31753): GC_EXTERNAL_ALLOC freed 749K, 44% free 5096K/9095K, external 4681K/4728K, paused 39ms
02-08 13:19:49.230 V/WifiMonitor( 1373): Event [CTRL-EVENT-STATE-CHANGE id=6 state=6 BSSID=00:00:00:00:00:00]
02-08 13:19:49.230 V/WifiStateTracker( 1373): Changing supplicant state: COMPLETED ==> GROUP_HANDSHAKE
02-08 13:19:49.230 V/WifiMonitor( 1373): Event [WPA: Group rekeying completed with 00:00:00:00:51:cb [GTK=CCMP]]
02-08 13:19:49.230 V/WifiMonitor( 1373): Event [CTRL-EVENT-STATE-CHANGE id=6 state=7 BSSID=00:00:00:00:00:00]
02-08 13:19:49.238 V/WifiStateTracker( 1373): Changing supplicant state: GROUP_HANDSHAKE ==> COMPLETED
02-08 13:19:49.238 I/MailService(31776): connected: network state is CONNECTED, is available is true
02-08 13:19:49.238 I/MailService(31776): connected: AutoSync help is available, autoSync = true
02-08 13:19:49.238 I/MailService(31776): connected: wifi -49
02-08 13:19:49.238 I/MailService(31776): connected: final hasConnectivity is true
02-08 13:19:49.238 I/MailService(31776): MailService.onStart(Intent { act=com.amazon.email.intent.action.MAIL_SERVICE_CONNECTIVITY_CHANGE cmp=com.amazon.email/.service.MailService }, 1), hasConnectivity = true, doBackground = true
02-08 13:19:49.246 I/MailService(31776): connected: network state is CONNECTED, is available is true
02-08 13:19:49.246 I/MailService(31776): connected: AutoSync help is available, autoSync = true
02-08 13:19:49.254 I/MailService(31776): connected: wifi -49
02-08 13:19:49.254 I/MailService(31776): connected: final hasConnectivity is true
02-08 13:19:49.254 I/MailService(31776): MailService.onStart(Intent { act=com.amazon.email.intent.action.MAIL_SERVICE_CONNECTIVITY_CHANGE cmp=com.amazon.email/.service.MailService }, 2), hasConnectivity = true, doBackground = true
02-08 13:19:52.230 D/skia (31753): Loading ARM decoder...
02-08 13:19:54.925 I/SecureMedia(31753): 2012-02-08 13:19:54,smclient.c(404),INFO,PingWorker-0 Establishing session with 'smclient'
02-08 13:19:54.925 I/SecureMedia(31753): 2012-02-08 13:19:54,smclient.c(658),INFO,PingWorker-0 Establishing session with daemon
02-08 13:19:54.925 D/SecureMedia(31753): 2012-02-08 13:19:54,smclient.c(659),TRACE,PingWorker-0 SMC
02-08 13:19:54.925 D/SecureMedia(31753): 2012-02-08 13:19:54,smwatermark.c(309),TRACE,PingWorker-0 SessionID set to 63094
02-08 13:19:54.925 I/SecureMedia(31753): 2012-02-08 13:19:54,smclientdaemon.c(711),INFO,PingWorker-0 Next BSS ping in 2504 seconds
02-08 13:19:54.925 I/SecureMedia(31753): 2012-02-08 13:19:54,smclientdaemon.c(718),INFO,PingWorker-0 Pinging SecureMedia On-Demand Subscription Service...
02-08 13:19:54.925 I/SecureMedia(31753): 2012-02-08 13:19:54,smclientdaemon.c(730),INFO,PingWorker-0 Next ODSS ping in 86400 seconds
02-08 13:19:55.066 I/InputDispatcher( 1373): Application is not responding: Window{4098bf98 com.verizon.ams.tesla/com.verizon.ams.tesla.ui.TabDownloadsActivity paused=false}. 5011.0ms since event, 5001.9ms since wait started
02-08 13:19:55.066 I/Process ( 1373): Sending signal. PID: 31753 SIG: 3
02-08 13:19:55.066 I/dalvikvm(31753): threadid=4: reacting to signal 3
02-08 13:19:55.066 I/WindowManager( 1373): Input event dispatching timed out sending to com.verizon.ams.tesla/com.verizon.ams.tesla.ui.TabDownloadsActivity
02-08 13:19:55.074 I/dalvikvm(31753): Wrote stack traces to '/data/anr/traces.txt'
02-08 13:19:55.074 I/Process ( 1373): Sending signal. PID: 1373 SIG: 3
02-08 13:19:55.074 I/dalvikvm( 1373): threadid=4: reacting to signal 3
02-08 13:19:55.082 I/dalvikvm( 1373): Wrote stack traces to '/data/anr/traces.txt'
02-08 13:19:55.082 I/Process ( 1373): Sending signal. PID: 1441 SIG: 3
02-08 13:19:55.082 I/dalvikvm( 1441): threadid=4: reacting to signal 3
02-08 13:19:55.082 I/dalvikvm( 1441): Wrote stack traces to '/data/anr/traces.txt'
02-08 13:19:55.160 D/dalvikvm( 1373): GC_CONCURRENT freed 2053K, 52% free 6363K/12999K, external 6814K/8510K, paused 2ms+6ms
02-08 13:19:55.308 D/dalvikvm( 1373): GC_EXPLICIT freed 621K, 50% free 6564K/12999K, external 6814K/8510K, paused 65ms
02-08 13:19:55.839 I/Process ( 1373): Sending signal. PID: 2631 SIG: 3
02-08 13:19:55.847 D/InputDispatcher( 1373): channel '4098bf98 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:19:55.847 I/InputDispatcher( 1373): Dropping event because the pointer is not down.
02-08 13:19:55.847 I/InputDispatcher( 1373): Dropping event because the pointer is not down.
02-08 13:19:55.847 I/InputDispatcher( 1373): Dropping event because the pointer is not down.
02-08 13:19:55.847 E/ActivityManager( 1373): ANR in com.verizon.ams.tesla (com.verizon.ams.tesla/.ui.TabDownloadsActivity)
02-08 13:19:55.847 E/ActivityManager( 1373): Reason: keyDispatchingTimedOut
02-08 13:19:55.847 E/ActivityManager( 1373): Load: 1.14 / 5.09 / 5.83
02-08 13:19:55.847 E/ActivityManager( 1373): CPU usage from 7535ms to 2520ms ago with 99% awake:
02-08 13:19:55.847 E/ActivityManager( 1373): 5.7% 31753/com.verizon.ams.tesla: 4.7% user + 0.9% kernel / faults: 359 minor
02-08 13:19:55.847 E/ActivityManager( 1373): 1.5% 1373/system_server: 0.9% user + 0.5% kernel / faults: 8 minor
02-08 13:19:55.847 E/ActivityManager( 1373): 0.3% 3/ksoftirqd/0: 0% user + 0.3% kernel
02-08 13:19:55.847 E/ActivityManager( 1373): 0.2% 31776/com.amazon.email: 0.2% user + 0% kernel / faults: 167 minor
02-08 13:19:55.847 E/ActivityManager( 1373): 0.7% 2631/com.amazon.kindle.otter: 0.3% user + 0.3% kernel / faults: 2 minor
02-08 13:19:55.847 E/ActivityManager( 1373): 0.5% 1432/tiwlan_wq: 0% user + 0.5% kernel
02-08 13:19:55.847 E/ActivityManager( 1373): 0.1% 7/events/0: 0% user + 0.1% kernel
02-08 13:19:55.847 E/ActivityManager( 1373): 6.6% TOTAL: 4.1% user + 2% kernel + 0.4% iowait + 0.1% softirq
02-08 13:19:55.847 E/ActivityManager( 1373): CPU usage from 239ms to 752ms later:
02-08 13:19:55.847 E/ActivityManager( 1373): 1.9% 1373/system_server: 1.9% user + 0% kernel
02-08 13:19:55.847 E/ActivityManager( 1373): 3.9% 1415/InputDispatcher: 3.9% user + 0% kernel
02-08 13:19:55.847 E/ActivityManager( 1373): 1.5% 1432/tiwlan_wq: 0% user + 1.5% kernel
02-08 13:19:55.847 E/ActivityManager( 1373): 1.6% 2631/com.amazon.kindle.otter: 1.6% user + 0% kernel / faults: 1 minor
02-08 13:19:55.847 E/ActivityManager( 1373): 1.6% 2714/pool-9-thread-1: 1.6% user + 0% kernel
02-08 13:19:55.847 E/ActivityManager( 1373): 1.6% 31753/com.verizon.ams.tesla: 1.6% user + 0% kernel / faults: 4 minor
02-08 13:19:55.847 E/ActivityManager( 1373): 1.9% TOTAL: 0.9% user + 0.9% kernel
02-08 13:19:56.324 D/dalvikvm( 1373): GC_FOR_MALLOC freed 1458K, 48% free 6771K/12999K, external 7032K/8510K, paused 57ms
02-08 13:19:56.379 D/dalvikvm( 1373): GC_FOR_MALLOC freed 422K, 47% free 6978K/12999K, external 7028K/8510K, paused 48ms
02-08 13:19:57.011 D/SecureMedia(31753): 2012-02-08 13:19:57,smesam2.c(253),TRACE,PingWorker-0 Service not configured: IDETECT(0x6000A): Unknown service
02-08 13:19:57.011 D/SecureMedia(31753): 2012-02-08 13:19:57,smesam2.c(529),TRACE,PingWorker-0 SMC
02-08 13:19:57.011 D/SecureMedia(31753): 2012-02-08 13:19:57,smesam2.c(872),TRACE,PingWorker-0 SMC
02-08 13:19:57.011 D/SecureMedia(31753): 2012-02-08 13:19:57,smesam2.c(905),TRACE,PingWorker-0 SME(0x6000A)
02-08 13:19:57.011 D/SecureMedia(31753): 2012-02-08 13:19:57,smesam2.c(1135),TRACE,PingWorker-0 SMC
02-08 13:19:57.011 D/SecureMedia(31753): 2012-02-08 13:19:57,smclientidetect.c(83),TRACE,PingWorker-0 SMC
02-08 13:19:57.011 D/SecureMedia(31753): 2012-02-08 13:19:57,smclientidetect.c(145),TRACE,PingWorker-0 SMC
02-08 13:19:57.011 D/SecureMedia(31753): 2012-02-08 13:19:57,smmisc.c(466),TRACE,PingWorker-0 Initializing SRNG.
02-08 13:19:57.011 I/SecureMedia(31753): 2012-02-08 13:19:57,smclientidetect.c(157),INFO,PingWorker-0 Next iDetect server check in 82856 seconds
02-08 13:19:57.011 E/SecureMedia(31753): 2012-02-08 13:19:57,smclientidetect.c(161),ERROR,PingWorker-0 iDetect (0x6000A): Unknown service
02-08 13:19:57.011 D/SecureMedia(31753): 2012-02-08 13:19:57,smclientidetect.c(173),TRACE,PingWorker-0 SMC
02-08 13:19:57.027 I/SecureMedia(31753): 2012-02-08 13:19:57,smsimpleclient.c(741),INFO,Main SimpleClient started.
02-08 13:19:57.027 I/SecureMedia(31753): 2012-02-08 13:19:57,smsimpleclient.c(750),INFO,Main Result = 0x0
02-08 13:19:57.027 D/MSC_V3.2.7(31753): initing SM DONE GOOD
02-08 13:19:57.027 I/System.out(31753): BEFORE starting to loadlibraries
02-08 13:19:57.027 D/dalvikvm(31753): Trying to load lib /data/data/com.verizon.ams.tesla/lib/libIPRM_Common.so 0x40513f10
02-08 13:19:57.035 D/dalvikvm(31753): Added shared lib /data/data/com.verizon.ams.tesla/lib/libIPRM_Common.so 0x40513f10
02-08 13:19:57.035 D/dalvikvm(31753): No JNI_OnLoad found in /data/data/com.verizon.ams.tesla/lib/libIPRM_Common.so 0x40513f10, skipping init
02-08 13:19:57.035 D/dalvikvm(31753): Trying to load lib /data/data/com.verizon.ams.tesla/lib/libIPRM_Transport.so 0x40513f10
02-08 13:19:57.035 D/dalvikvm(31753): Added shared lib /data/data/com.verizon.ams.tesla/lib/libIPRM_Transport.so 0x40513f10
02-08 13:19:57.035 D/dalvikvm(31753): No JNI_OnLoad found in /data/data/com.verizon.ams.tesla/lib/libIPRM_Transport.so 0x40513f10, skipping init
02-08 13:19:57.035 D/dalvikvm(31753): Trying to load lib /data/data/com.verizon.ams.tesla/lib/libIPRM_CryptoLibraryRobust.so 0x40513f10
02-08 13:19:57.035 D/dalvikvm(31753): Added shared lib /data/data/com.verizon.ams.tesla/lib/libIPRM_CryptoLibraryRobust.so 0x40513f10
02-08 13:19:57.035 D/dalvikvm(31753): No JNI_OnLoad found in /data/data/com.verizon.ams.tesla/lib/libIPRM_CryptoLibraryRobust.so 0x40513f10, skipping init
02-08 13:19:57.035 D/dalvikvm(31753): Trying to load lib /data/data/com.verizon.ams.tesla/lib/libIPRM_CertLibrary.so 0x40513f10
02-08 13:19:57.035 D/dalvikvm(31753): Added shared lib /data/data/com.verizon.ams.tesla/lib/libIPRM_CertLibrary.so 0x40513f10
02-08 13:19:57.035 D/dalvikvm(31753): No JNI_OnLoad found in /data/data/com.verizon.ams.tesla/lib/libIPRM_CertLibrary.so 0x40513f10, skipping init
02-08 13:19:57.035 D/dalvikvm(31753): Trying to load lib /data/data/com.verizon.ams.tesla/lib/libIPRM_SecurityProcessor.so 0x40513f10
02-08 13:19:57.035 D/dalvikvm(31753): Added shared lib /data/data/com.verizon.ams.tesla/lib/libIPRM_SecurityProcessor.so 0x40513f10
02-08 13:19:57.035 D/dalvikvm(31753): No JNI_OnLoad found in /data/data/com.verizon.ams.tesla/lib/libIPRM_SecurityProcessor.so 0x40513f10, skipping init
02-08 13:19:57.035 D/dalvikvm(31753): Trying to load lib /data/data/com.verizon.ams.tesla/lib/libIPRM_ESBThin.so 0x40513f10
02-08 13:19:57.035 D/dalvikvm(31753): Added shared lib /data/data/com.verizon.ams.tesla/lib/libIPRM_ESBThin.so 0x40513f10
02-08 13:19:57.035 D/dalvikvm(31753): No JNI_OnLoad found in /data/data/com.verizon.ams.tesla/lib/libIPRM_ESBThin.so 0x40513f10, skipping init
02-08 13:19:57.035 D/dalvikvm(31753): Trying to load lib /data/data/com.verizon.ams.tesla/lib/libIPRM_RightManagement.so 0x40513f10
02-08 13:19:57.035 D/dalvikvm(31753): Added shared lib /data/data/com.verizon.ams.tesla/lib/libIPRM_RightManagement.so 0x40513f10
02-08 13:19:57.035 D/dalvikvm(31753): No JNI_OnLoad found in /data/data/com.verizon.ams.tesla/lib/libIPRM_RightManagement.so 0x40513f10, skipping init
02-08 13:19:57.035 D/dalvikvm(31753): Trying to load lib /data/data/com.verizon.ams.tesla/lib/libIPRM_DRMRulesConverter.so 0x40513f10
02-08 13:19:57.035 D/dalvikvm(31753): Added shared lib /data/data/com.verizon.ams.tesla/lib/libIPRM_DRMRulesConverter.so 0x40513f10
02-08 13:19:57.035 D/dalvikvm(31753): No JNI_OnLoad found in /data/data/com.verizon.ams.tesla/lib/libIPRM_DRMRulesConverter.so 0x40513f10, skipping init
02-08 13:19:57.035 D/dalvikvm(31753): Trying to load lib /data/data/com.verizon.ams.tesla/lib/libIPRM_ThinAgent.so 0x40513f10
02-08 13:19:57.043 D/dalvikvm(31753): Added shared lib /data/data/com.verizon.ams.tesla/lib/libIPRM_ThinAgent.so 0x40513f10
02-08 13:19:57.043 D/dalvikvm(31753): No JNI_OnLoad found in /data/data/com.verizon.ams.tesla/lib/libIPRM_ThinAgent.so 0x40513f10, skipping init
02-08 13:19:57.043 D/dalvikvm(31753): Trying to load lib /data/data/com.verizon.ams.tesla/lib/libIPRM_JNI.so 0x40513f10
02-08 13:19:57.043 D/dalvikvm(31753): Added shared lib /data/data/com.verizon.ams.tesla/lib/libIPRM_JNI.so 0x40513f10
02-08 13:19:57.043 D/dalvikvm(31753): No JNI_OnLoad found in /data/data/com.verizon.ams.tesla/lib/libIPRM_JNI.so 0x40513f10, skipping init
02-08 13:19:57.043 I/System.out(31753): loadlibraries DONE
02-08 13:19:57.043 D/jAgentHN(31753): before calling native iprmInit
02-08 13:19:57.496 D/------ Activity(31753): iprm init DONE GOOD
02-08 13:19:57.496 D/MSC_V3.2.7(31753): before starting CRMonitorT
02-08 13:19:57.496 D/CRMonitorT(31753): newing CRMonitorT
02-08 13:19:57.496 D/MSC_V3.2.7(31753): Done starting the thread
02-08 13:19:57.496 D/MSC_V3.2.7(31753): END of initilizeSM
02-08 13:19:57.496 D/CRMonitorT(31753): am AWAKE Again !
02-08 13:19:57.496 D/CRMonitorT(31753): LKGT : 1328728747
02-08 13:19:57.504 D/CRMonitorT(31753): TIME service : service=RightsInfoInt&ver=1.0&scmd=timequery
02-08 13:19:57.504 D/MSC_V3.2.7(31753): device id : 4f631457dd1d2ab
02-08 13:19:57.504 D/MSC_V3.2.7(31753): Getting Rights info from LOCAL for : /mnt/sdcard/.video/Smurf_402986.mp4
02-08 13:19:57.504 D/MSC_V3.2.7(31753): getRemainingRights for :/mnt/sdcard/.video/Smurf_402986.mp4
02-08 13:19:57.504 D/MSC_V3.2.7(31753): BEFORE calling iprmHandle.getSMVODRule for content: Smurf_402986.mp4
02-08 13:19:57.511 D/MSC_V3.2.7(31753): AFTER getSMVODRule
02-08 13:19:57.511 D/MSC_V3.2.7(31753): rentalflag = false
02-08 13:19:57.511 D/MSC_V3.2.7(31753): start time = 0
02-08 13:19:57.511 D/MSC_V3.2.7(31753): play window = 0
02-08 13:19:57.511 D/MSC_V3.2.7(31753): expiry time = 0
02-08 13:19:57.511 D/MSC_V3.2.7(31753): returning RightsInfo
02-08 13:19:57.511 D/MSC_V3.2.7(31753): API MSC_V3.2.7 : getRemainingRightsInfo - END
02-08 13:19:57.519 V/SMDRMManager(31753): Invoking Play() of Secure Media :: Content = /mnt/sdcard/.video/Smurf_402986.mp4
02-08 13:19:57.519 D/MSC_V3.2.7(31753): API MSC_V3.2.7 play - START
02-08 13:19:57.519 D/MSC_V3.2.7(31753): Params : /mnt/sdcard/.video/Smurf_402986.mp4
02-08 13:19:57.519 D/MSC_V3.2.7(31753): device id : 4f631457dd1d2ab
02-08 13:19:57.519 D/MSC_V3.2.7(31753): getRemainingRights for :/mnt/sdcard/.video/Smurf_402986.mp4
02-08 13:19:57.519 D/MSC_V3.2.7(31753): BEFORE calling iprmHandle.getSMVODRule for content: Smurf_402986.mp4
02-08 13:19:57.535 D/MSC_V3.2.7(31753): AFTER getSMVODRule
02-08 13:19:57.535 D/MSC_V3.2.7(31753): rentalflag = false
02-08 13:19:57.535 D/MSC_V3.2.7(31753): start time = 0
02-08 13:19:57.535 D/MSC_V3.2.7(31753): play window = 0
02-08 13:19:57.535 D/MSC_V3.2.7(31753): expiry time = 0
02-08 13:19:57.535 D/---------SMVODRules(31753): rentalflag = false
02-08 13:19:57.535 D/---------SMVODRules(31753): start time = 0
02-08 13:19:57.535 D/---------SMVODRules(31753): play window = 0
02-08 13:19:57.535 D/---------SMVODRules(31753): expiry time = 1969-12-31T18:00:00-06:00
02-08 13:19:57.535 D/---------SMVODRules(31753): NOW time = 2012-02-08T13:19:57-06:00
02-08 13:19:57.535 D/MSC_V3.2.7(31753): now : 1328728797
02-08 13:19:57.535 D/MSC_V3.2.7(31753): expiry : 0
02-08 13:19:57.535 D/MSC_V3.2.7(31753): local PRO NOT expired
02-08 13:19:57.535 D/MSC_V3.2.7(31753): NOT rental content, so do nothing during playback
02-08 13:19:57.535 D/decryptorD(31753): newing DecryptorD
02-08 13:19:57.535 D/decryptorD(31753): INSIDE nano constructor
02-08 13:19:57.535 D/decryptorD(31753): BEFORE newing serversocket
02-08 13:19:57.535 D/decryptorD(31753): BEFORE newing and start decserver T
02-08 13:19:57.535 D/MSC_V3.2.7(31753): INVOKE player :
02-08 13:19:57.535 D/MSC_V3.2.7(31753): contentname - http://127.57.127.189:57617//mnt/sdcard/.video/Smurf_402986.mp4
02-08 13:19:57.543 D/MSC_V3.2.7(31753): API MSC_V3.2.7 : play - END
02-08 13:19:57.543 D/mediamanager(31753): intent received com.verizon.flexview.mediamanager.action_audio_pause
02-08 13:19:57.543 E/MediaPlayer(31753): pause called in state 1
02-08 13:19:57.543 E/MediaPlayer(31753): error (-38, 0)
02-08 13:19:57.543 I/MSC_API_tester(31753): MSCPlayReceiver received called
02-08 13:19:57.543 I/MSC_API_tester(31753): Broadcast receiver to play received.
02-08 13:19:57.550 D/skia (31753): Loading ARM decoder...
02-08 13:19:57.550 D/ActivityManager( 1373): Starting: Intent { flg=0x24000000 cmp=com.verizon.ams.tesla/.drm.sm.MSCPlayer (has extras) } from pid 31753
02-08 13:19:57.589 E/MediaPlayer(31753): Error (-38,0)
02-08 13:19:57.597 I/MSV (31753): WebUtils futureTask is done
02-08 13:19:57.597 V/MediaLibraryActivity(31753): MediaLibraryActivity.Writing CACHE
02-08 13:19:57.597 V/MediaLibraryActivity(31753): MediaLibraryActivity.Count: 1
02-08 13:19:57.605 I/WindowManager( 1373): Setting rotation to 1, animFlags=1
02-08 13:19:57.621 I/ActivityManager( 1373): Config changed: { scale=1.0 imsi=0/0 loc=en_US touch=3 keys=1/1/2 nav=1/1 orien=2 layout=268435491 uiMode=17 seq=33}
02-08 13:19:57.660 D/LatinKeyboard( 1449): Setting IME options
02-08 13:19:57.668 D/mediamanager(31753): audio player unknown error, player status :false
02-08 13:19:57.683 D/IconMerger( 1441): notification for com.verizon.ams.tesla (1)
02-08 13:19:57.683 D/IconMerger( 1441): notification for com.android.systemui (1)
02-08 13:19:57.683 D/IconMerger( 1441): sum of notifications: 2
02-08 13:19:57.707 I/MSCPlayer(31753): onResume() called
02-08 13:19:57.800 I/MSCPlayer(31753): Surface created.
02-08 13:19:57.800 D/MediaPlayer(31753): Couldn't open file on client side, trying server side
02-08 13:19:57.800 W/ASFDummyExtractor( 1293): ASF parser is not available
02-08 13:19:57.800 D/AwesomePlayer( 1293): setDataSource_l(http://127.57.127.189:57617//mnt/sdcard/.video/Smurf_402986.mp4)
02-08 13:19:57.800 I/MSCPlayer(31753): Surface changed: (540, 1024)
02-08 13:19:57.832 D/decryptorD(31753): INPUT REQUEST : ... START
02-08 13:19:57.832 D/decryptorD(31753): INPUT REQUEST : GET //mnt/sdcard/.video/Smurf_402986.mp4 HTTP/1.1
02-08 13:19:57.832 D/decryptorD(31753): INPUT REQUEST : Host: 127.57.127.189
02-08 13:19:57.832 D/decryptorD(31753): INPUT REQUEST : User-Agent: stagefright/1.1 (Linux;Android 2.3.4)
02-08 13:19:57.832 D/decryptorD(31753): INPUT REQUEST :
02-08 13:19:57.832 D/decryptorD(31753): GET '//mnt/sdcard/.video/Smurf_402986.mp4'
02-08 13:19:57.832 D/decryptorD(31753): HDR: 'user-agent' = 'stagefright/1.1 (Linux;Android 2.3.4)'
02-08 13:19:57.832 D/decryptorD(31753): HDR: 'host' = '127.57.127.189'
02-08 13:19:57.832 D/decryptorD(31753): BEFORE SERVING file : //mnt/sdcard/.video/Smurf_402986.mp4
02-08 13:19:57.832 D/jAgentHN(31753): before calling native iprmInit
02-08 13:19:57.832 D/decryptorD(31753): IPRM init DONE GOOD
02-08 13:19:57.832 D/decryptorD(31753): ESTABLISH params, reading RIGHTS OBJECT : Smurf_402986.mp4
02-08 13:19:57.839 I/ActivityManager( 1373): Displayed com.verizon.ams.tesla/.drm.sm.MSCPlayer: +238ms
02-08 13:19:57.839 I/LAB126 ( 1441): StatusBarService hears intent. action: com.lab126.intent.TOP_WINDOW_CHANGED
02-08 13:19:57.839 I/LAB126 ( 1441): window changed
02-08 13:19:57.863 D/decryptorD(31753): ------- IPRM : establish GOOD for content : Smurf_402986.mp4
02-08 13:19:57.863 D/decryptorD(31753): ssid : AAAURCCBans=
02-08 13:19:57.863 D/decryptorD(31753): content location is LOCAL
02-08 13:19:57.863 D/decryptorD(31753): created fis
02-08 13:19:57.863 D/decryptorD(31753): string used for range calculation, [Range] range :null
02-08 13:19:57.863 D/decryptorD(31753): serve returns, send Response.
02-08 13:19:57.871 D/decryptorD(31753): HTTP/1.1 200 OK
02-08 13:19:57.871 D/decryptorD(31753): Date: Wed, 8 Feb 2012 19:19:57 GMT
02-08 13:19:57.871 D/decryptorD(31753): Connection: Keep-Alive
02-08 13:19:57.871 D/decryptorD(31753): Content-Type: video/mp4
02-08 13:19:57.871 D/decryptorD(31753): Accept-Ranges: bytes
02-08 13:19:57.871 D/decryptorD(31753): Content-Length: 1022062685
02-08 13:19:57.871 D/decryptorD(31753): Content-Range: bytes 0-1022062684/1022062685
02-08 13:19:57.871 D/decryptorD(31753):
02-08 13:19:57.871 D/decryptorD(31753): string used for range calculation, [Content-Range] range :bytes 0-1022062684/1022062685
02-08 13:19:57.871 D/decryptorD(31753): range as string : 0
02-08 13:19:57.871 D/decryptorD(31753): header reveals.... a startFrom : 0
02-08 13:19:57.886 V/MediaLibraryActivity(31753): MediaLibraryActivity.DONE Writing
02-08 13:19:57.902 I/MSCPlayer(31753): Surface changed: (580, 1024)
02-08 13:19:58.394 D/skia (31753): Loading ARM decoder...
02-08 13:19:58.410 D/skia (31753): Loading ARM decoder...
02-08 13:19:58.668 D/decryptorD(31753): IPRM offset START3003686
02-08 13:19:58.699 D/dalvikvm(31753): GC_FOR_MALLOC freed 838K, 48% free 4778K/9095K, external 5985K/5998K, paused 29ms
02-08 13:19:58.707 I/dalvikvm-heap(31753): Grow heap (frag case) to 13.388MB for 524304-byte allocation
02-08 13:19:58.738 D/dalvikvm(31753): GC_FOR_MALLOC freed 39K, 46% free 5251K/9671K, external 5040K/5998K, paused 26ms
02-08 13:19:58.738 D/decryptorD(31753): start CLEAR push
02-08 13:19:58.761 D/decryptorD(31753): Done pushing CLEAR PART of content, current pos : 3003686
02-08 13:19:58.761 D/decryptorD(31753): START pushing ENC PART of content
02-08 13:19:58.793 D/dalvikvm(31753): GC_FOR_MALLOC freed 19K, 46% free 5232K/9671K, external 4746K/5927K, paused 26ms
02-08 13:19:58.800 I/dalvikvm-heap(31753): Grow heap (frag case) to 12.623MB for 524304-byte allocation
02-08 13:19:58.824 D/dalvikvm(31753): GC_FOR_MALLOC freed <1K, 44% free 5744K/10247K, external 4746K/5927K, paused 29ms
02-08 13:19:58.886 D/MPEG4Extractor( 1293): Atom found: ftyp with size = 18
02-08 13:19:58.886 D/MPEG4Extractor( 1293): Atom found: moov with size = 2dd1ec
02-08 13:19:58.886 D/MPEG4Extractor( 1293): Atom found: mvhd with size = 6c
02-08 13:19:58.886 D/MPEG4Extractor( 1293): Atom found: iods with size = 21
02-08 13:19:58.886 D/MPEG4Extractor( 1293): Atom found: trak with size = 1856b0
02-08 13:19:58.886 D/MPEG4Extractor( 1293): Atom found: tkhd with size = 5c
02-08 13:19:58.886 D/MPEG4Extractor( 1293): Atom found: mdia with size = 18564c
02-08 13:19:58.886 D/MPEG4Extractor( 1293): Atom found: mdhd with size = 20
02-08 13:19:58.886 D/MPEG4Extractor( 1293): Atom found: hdlr with size = 44
02-08 13:19:58.894 E/MPEG4Extractor( 1293): Chunk: ftyp
02-08 13:19:58.894 E/MPEG4Extractor( 1293): Chunk: moov
02-08 13:19:58.894 E/MPEG4Extractor( 1293): Chunk: mvhd
02-08 13:19:58.894 E/MPEG4Extractor( 1293): Chunk: iods
02-08 13:19:58.894 E/MPEG4Extractor( 1293): Chunk: trak
02-08 13:19:58.894 E/MPEG4Extractor( 1293): Chunk: tkhd
02-08 13:19:58.894 E/MPEG4Extractor( 1293): Chunk: mdia
02-08 13:19:58.894 E/MPEG4Extractor( 1293): Chunk: mdhd
02-08 13:19:58.894 E/MPEG4Extractor( 1293): Chunk: hdlr
02-08 13:19:58.894 E/MPEG4Extractor( 1293): Chunk: minf
02-08 13:19:58.894 E/MPEG4Extractor( 1293): Chunk: vmhd
02-08 13:19:58.894 E/MPEG4Extractor( 1293): Chunk: dinf
02-08 13:19:58.894 E/MPEG4Extractor( 1293): Chunk: dref
02-08 13:19:58.894 E/MPEG4Extractor( 1293): Chunk: stbl
02-08 13:19:58.902 E/MPEG4Extractor( 1293): Chunk: stsd
02-08 13:19:58.902 E/MPEG4Extractor( 1293): Chunk: avc1
02-08 13:19:58.902 E/MPEG4Extractor( 1293): Chunk: avcC
02-08 13:19:58.902 E/MPEG4Extractor( 1293): Chunk: sinf
02-08 13:19:58.902 E/MPEG4Extractor( 1293): Chunk: stts
02-08 13:19:58.902 E/MPEG4Extractor( 1293): Chunk: ctts
02-08 13:19:58.918 E/MPEG4Extractor( 1293): Chunk: stsc
02-08 13:19:58.918 E/MPEG4Extractor( 1293): Chunk: stsz
02-08 13:19:58.964 E/MPEG4Extractor( 1293): Chunk: stco
02-08 13:19:58.964 E/MPEG4Extractor( 1293): Chunk: stss
02-08 13:19:58.964 E/MPEG4Extractor( 1293): Chunk: trak
02-08 13:19:58.964 E/MPEG4Extractor( 1293): Chunk: tkhd
02-08 13:19:58.964 E/MPEG4Extractor( 1293): Chunk: mdia
02-08 13:19:58.964 E/MPEG4Extractor( 1293): Chunk: mdhd
02-08 13:19:58.964 E/MPEG4Extractor( 1293): Chunk: hdlr
02-08 13:19:58.964 E/MPEG4Extractor( 1293): Chunk: minf
02-08 13:19:58.964 E/MPEG4Extractor( 1293): Chunk: smhd
02-08 13:19:58.964 E/MPEG4Extractor( 1293): Chunk: dinf
02-08 13:19:58.964 E/MPEG4Extractor( 1293): Chunk: dref
02-08 13:19:58.964 E/MPEG4Extractor( 1293): Chunk: stbl
02-08 13:19:59.152 E/MPEG4Extractor( 1293): Chunk: stsd
02-08 13:19:59.152 E/MPEG4Extractor( 1293): Chunk: mp4a
02-08 13:19:59.152 E/MPEG4Extractor( 1293): Chunk: esds
02-08 13:19:59.152 E/MPEG4Extractor( 1293): Chunk: sinf
02-08 13:19:59.152 E/MPEG4Extractor( 1293): Chunk: stts
02-08 13:19:59.152 E/MPEG4Extractor( 1293): Chunk: stsc
02-08 13:19:59.160 E/MPEG4Extractor( 1293): Chunk: stsz
02-08 13:19:59.183 D/CRMonitorT(31753): LKST RESPONSE: 0Success2012-02-08T14:19:58-05:00
02-08 13:19:59.183 D/RMTimeQueryProcessor(31753): Parsing : 0Success2012-02-08T14:19:58-05:00
02-08 13:19:59.199 D/-------MyUtils(31753): out : time in seconds:1328728798
02-08 13:19:59.207 D/CRMonitorT(31753): LKST VALUE : 2012-02-08T13:19:58-06:00
02-08 13:19:59.207 D/CRMonitorT(31753): updating lkgt with server time
02-08 13:19:59.207 D/CRMonitorT(31753): cdt is more than lkgt
02-08 13:19:59.254 E/MPEG4Extractor( 1293): Chunk: stco
02-08 13:19:59.254 E/SEI_TI_Parser( 1293): Not Supported, SEI messages 0x0
02-08 13:19:59.254 D/AwesomePlayer( 1293): AwesomePlayer Not VPX decoder = **video/avc** and video is = 720X404
02-08 13:19:59.254 D/OMX_CORE( 1293): TIOMX_GetHandle
02-08 13:19:59.261 E/OMX_PROXYDEC( 1293): ___INSISDE VIDEO DECODER PROXY WRAPPER__
02-08 13:19:59.261 D/CRMonitorT(31753): save LKGT, result = true
02-08 13:19:59.261 D/CRMonitorT(31753): going to SLEEP, next wakeup in [323499] milliseconds
02-08 13:19:59.449 D/OMXCodec( 1293): Flash Clip: Profile: 77, Level: 31, Interlaced: 0, RefFrames: 2
02-08 13:19:59.449 D/OMXCodec( 1293): Updated. H264 Component profile 2 level 512 NRefFrames 2
02-08 13:19:59.457 D/OMXCodec( 1293): initOutputFormat WxH 720x404 Padded 896x512
02-08 13:19:59.457 D/AwesomePlayer( 1293): initRenderer_l 896x512
02-08 13:19:59.457 D/AwesomePlayer( 1293): initRenderer_l 720x404
02-08 13:19:59.457 D/OMXCodec( 1293): CodecRecommended O/P BufferCnt[9]
02-08 13:19:59.457 D/AwesomePlayer( 1293): Codec Recommended outputBuffer count 9
02-08 13:19:59.457 D/TIOverlay( 1373): Enabling the OVERLAY[0]
02-08 13:19:59.457 I/Overlay-V4L2( 1373): Openning /dev/video1
02-08 13:19:59.457 D/TIOverlay( 1373): Creating overlay from W896/H512/FMT33 ...
02-08 13:19:59.457 D/TIOverlay( 1373): mZorderUsage[0] is assigned to 0
02-08 13:19:59.457 I/TIOverlay( 1373): Setting position X0/Y0/W1024/H580
02-08 13:19:59.457 D/TIOverlay( 1373): REQUEST FOR LCD1
02-08 13:19:59.457 D/TIOverlay( 1373): found Panel Id @ [0], displayname [lcd2]
02-08 13:19:59.457 D/TIOverlay( 1373): found Display Manager @ [2], managername [2lcd]
02-08 13:19:59.457 I/TIOverlay( 1373): Position/X0/Y0/W1024/H580/R0/A255/Z1
02-08 13:19:59.457 I/TIOverlay( 1373): Adjusted Position/X0/Y0/W1024/H580
02-08 13:19:59.457 D/TIOverlay( 1293): Num of Buffers = 2
02-08 13:19:59.457 D/TIOverlay( 1293): LCD[0] NAME[lcd2]
02-08 13:19:59.457 D/TIOverlay( 1293): LCD[0] PATH[/sys/devices/platform/omapdss/display0/enabled]
02-08 13:19:59.457 D/TIOverlay( 1293): LCD[0] timings[46000,1024/160/150/10,600/12/20/3
02-08 13:19:59.457 D/TIOverlay( 1293): ]
02-08 13:19:59.457 E/TIOverlay( 1293): Can't open the file[/sys/devices/platform/omapdss/display1/name]
02-08 13:19:59.457 D/TIOverlay( 1293): lcd name get failed (maybe because device has less displays than max allowed)
02-08 13:19:59.457 D/TIOverlay( 1293): MANAGER[0] NAME[lcd]
02-08 13:19:59.457 D/TIOverlay( 1293): MANAGER[1] NAME[tv]
02-08 13:19:59.457 D/TIOverlay( 1293): MANAGER[2] NAME[2lcd]
02-08 13:19:59.457 D/TIHardwareRenderer( 1293): Overlay Buffer Count [as recommneded] 9
02-08 13:19:59.457 D/TIOverlay( 1293): overlay_resizeInput 896x512 9
02-08 13:19:59.464 I/OMXCodec( 1293): [OMX.TI.DUCATI1.VIDEO.DECODER] allocating 4 buffers of size 299520 on input port
02-08 13:19:59.496 I/OMXCodec( 1293): [OMX.TI.DUCATI1.VIDEO.DECODER] allocating 9 buffers of size 3145728 on output port
02-08 13:19:59.816 D/dalvikvm( 1373): GC_EXPLICIT freed 1757K, 52% free 6331K/12999K, external 7028K/8510K, paused 59ms
02-08 13:20:00.511 I/MSCPlayer(31753): Video size has changed: (404, 720)
02-08 13:20:00.511 I/MSCPlayer(31753): Playback has been prepared.
02-08 13:20:00.511 I/MSCPlayer(31753): Start video playback.
02-08 13:20:00.511 D/HLSVOID_API_Tester:BookMarkData(31753): GetBookMarkPos: mm_msv_sm_bookmark.xml
02-08 13:20:00.511 D/HLSVOID_API_Tester:BookMarkData(31753): GetBookMarkPos: /data/data/com.verizon.ams.tesla/files/mm_msv_sm_bookmark.xml
02-08 13:20:00.527 I/MSCPlayer(31753): GetBookMarkPos: 0
02-08 13:20:00.574 I/MSCPlayer(31753): Surface changed: (404, 720)
02-08 13:20:00.605 D/AudioResampler( 1293): forcing AudioResampler quality to 2
02-08 13:20:00.605 D/Omap4ALSA( 1293): open called for devices 00000002 in mode 0 channels 00000000...
02-08 13:20:00.738 D/TIOverlay( 1293): Crop Win/X32/Y24/W720/H404
02-08 13:20:00.738 D/TIOverlay( 1293): REQUEST FOR LCD1
02-08 13:20:00.738 D/TIOverlay( 1293): found Panel Id @ [0], displayname [lcd2]
02-08 13:20:00.738 D/TIOverlay( 1293): found Display Manager @ [2], managername [2lcd]
02-08 13:20:00.777 D/vzAnalytics(31753): Moved 3 events from thread 1 to bg thread. bg thread pending events 3
02-08 13:20:00.777 D/vzAnalytics(31753): Saving log to db on thread 14 for 3 events
02-08 13:20:01.004 I/Omap4ALSA( 1293): Using ping-pong!
02-08 13:20:01.004 I/Omap4ALSA( 1293): Buffer size: 4096
02-08 13:20:01.004 I/Omap4ALSA( 1293): Latency: 92879
02-08 13:20:01.050 I/Omap4ALSA( 1293): Initialized ALSA PLAYBACK device 'plughw:0,0'
02-08 13:20:01.050 E/AudioHardwareALSA( 1293): RE-OPEN AFTER STANDBY:: took 450 msecs
02-08 13:20:01.058 W/AudioFlinger( 1293): write blocked for 452 msecs, 1 delayed writes, thread 0x58050
02-08 13:20:01.847 D/vzAnalytics(31753): WiFi:true, CellData:false
02-08 13:20:01.847 D/vzAnalytics(31753): Enqueuing server log task.
02-08 13:20:01.847 D/vzAnalytics(31753): Running server log task on thread 14 for 4 events. url :http://www22.verizon.com/clickStream/Tracker.svc/xml/async/postmetrics
02-08 13:20:02.050 D/vzAnalytics(31753): deleting events after server log.
02-08 13:20:02.324 I/vzAnalytics(31753): complete server log task. success :true
02-08 13:20:02.324 D/vzAnalytics(31753): Scheduling db and net save task
02-08 13:20:07.871 I/Process ( 1373): Sending signal. PID: 31753 SIG: 9
02-08 13:20:07.871 W/ActivityManager( 1373): Force finishing activity com.verizon.ams.tesla/.drm.sm.MSCPlayer
02-08 13:20:07.871 I/ActivityManager( 1373): Killing com.verizon.ams.tesla (pid=31753): user's request
02-08 13:20:07.886 I/WindowManager( 1373): WIN DEATH: Window{4098bf98 com.verizon.ams.tesla/com.verizon.ams.tesla.ui.TabDownloadsActivity paused=false}
02-08 13:20:07.886 I/ActivityManager( 1373): Process com.verizon.ams.tesla (pid 31753) has died.
02-08 13:20:07.886 W/ActivityManager( 1373): Scheduling restart of crashed service com.verizon.ams.tesla/.service.download.MetaDataSyncService in 5000ms
02-08 13:20:07.886 W/ActivityManager( 1373): Scheduling restart of crashed service com.verizon.ams.tesla/.service.download.DownloadService in 14999ms
02-08 13:20:07.902 E/InputDispatcher( 1373): channel '40969068 com.verizon.ams.tesla/com.verizon.ams.tesla.ui.MainActivity (server)' ~ Consumer closed input channel or an error occurred. events=0x8
02-08 13:20:07.902 E/InputDispatcher( 1373): channel '40969068 com.verizon.ams.tesla/com.verizon.ams.tesla.ui.MainActivity (server)' ~ Channel is unrecoverably broken and will be disposed!
02-08 13:20:07.902 E/HTTPStream( 1293): recv failed, server is gone, total received: 54566 bytes
02-08 13:20:07.902 E/HTTPStream( 1293): recv failed, errno = 9 (Bad file number)
02-08 13:20:07.902 E/NuCachedSource2( 1293): source returned error -1004
02-08 13:20:07.910 W/TimedEventQueue( 1293): Event 349 was not found in the queue, already cancelled?
02-08 13:20:07.918 I/WindowManager( 1373): WIN DEATH: Window{40969068 com.verizon.ams.tesla/com.verizon.ams.tesla.ui.MainActivity paused=false}
02-08 13:20:07.918 I/WindowManager( 1373): WIN DEATH: Window{407c7578 com.verizon.ams.tesla/com.verizon.ams.tesla.drm.sm.MSCPlayer paused=true}
02-08 13:20:07.918 I/ActivityManager( 1373): Start proc com.verizon.ams.tesla for activity com.verizon.ams.tesla/.ui.TabDownloadsActivity: pid=31843 uid=10028 gids={3003, 1015}
02-08 13:20:07.918 I/WindowManager( 1373): WIN DEATH: Window{408fc970 SurfaceView paused=false}
02-08 13:20:07.941 D/OMX_CORE( 1293): TIOMX_FreeHandle
02-08 13:20:07.964 I/WindowManager( 1373): Setting rotation to 0, animFlags=1
02-08 13:20:07.972 I/TIOverlay( 1373): Destroying overlay/fd=257/obj=48adf000
02-08 13:20:07.972 D/TIOverlay( 1373): mZorderUsage[0] = 0 should be removed
02-08 13:20:07.972 I/ActivityManager( 1373): Config changed: { scale=1.0 imsi=0/0 loc=en_US touch=3 keys=1/1/2 nav=1/1 orien=1 layout=268435491 uiMode=17 seq=34}
02-08 13:20:08.027 D/LatinKeyboard( 1449): Setting IME options
02-08 13:20:08.058 I/LAB126 ( 1441): StatusBarService hears intent. action: com.lab126.intent.TOP_WINDOW_CHANGED
02-08 13:20:08.058 I/LAB126 ( 1441): window changed
02-08 13:20:08.058 I/LAB126 ( 1441): don't set softkey config for 'Starting...' window
02-08 13:20:08.105 D/IconMerger( 1441): notification for com.verizon.ams.tesla (1)
02-08 13:20:08.105 D/IconMerger( 1441): notification for com.android.systemui (1)
02-08 13:20:08.105 D/IconMerger( 1441): sum of notifications: 2
02-08 13:20:08.121 I/ActivityThread(31843): Pub com.verizon.mediamanager.SearchSuggestion: com.verizon.mediamanager.SearchSuggestion
02-08 13:20:08.121 D/ContentProvider(31843): Videos folder exist
02-08 13:20:08.121 D/ContentProvider(31843): Pictures folder exists
02-08 13:20:08.121 I/MSV (31843): FiosTV Remote DVR Configuration
02-08 13:20:08.121 I/MSV (31843): ===============================
02-08 13:20:08.129 I/MSV (31843): Config: production
02-08 13:20:08.129 I/MSV (31843): Device Model: Kindle Fire
02-08 13:20:08.129 I/MSV (31843): Proxy URL: https://www32.verizon.com/vvmapp2/VVM_LAB.aspx?URL=
02-08 13:20:08.129 I/MSV (31843): Network Timeout: 25000 ms
02-08 13:20:08.129 I/MSV (31843): ===============================
02-08 13:20:08.136 V/MSVAppData(31843): init MSV data
02-08 13:20:08.144 V/MSVAppData(31843): ********* DEVICEID:4f631457dd1d2ab
02-08 13:20:08.144 D/mediamanager(31843): APP CONTEXT RECYCLING
02-08 13:20:08.152 D/mediamanager(31843): setting environment for mmol : PRODUCTION
02-08 13:20:08.308 D/dalvikvm(31843): GC_EXTERNAL_ALLOC freed 340K, 48% free 3089K/5895K, external 847K/1181K, paused 27ms
02-08 13:20:08.379 D/dalvikvm(31843): GC_EXTERNAL_ALLOC freed 23K, 48% free 3066K/5895K, external 4597K/5741K, paused 26ms
02-08 13:20:08.464 I/MSV (31843): added Bedtime Stories
02-08 13:20:08.472 V/MSV (31843): WebUtils url:https://www36.verizon.com/MSVAppServices/Anonymous/POX/CatalogXmlServices.asmx/MSVAppRequest?
02-08 13:20:08.472 V/MSV (31843): WebUtils body:strInputXML=GETMEDIADOWNLOADURLDNETtrans_12BB001303062SD
02-08 13:20:08.535 D/dalvikvm(31843): GC_CONCURRENT freed 252K, 46% free 3270K/5959K, external 2808K/6263K, paused 2ms+2ms
02-08 13:20:08.605 W/ResourceType(31843): Skipping entry 0x7f02015d in package table 0 because it is not complex!
02-08 13:20:08.605 W/ResourceType(31843): Skipping entry 0x7f02015d in package table 0 because it is not complex!
02-08 13:20:08.605 W/ResourceType(31843): Skipping entry 0x7f02015d in package table 0 because it is not complex!
02-08 13:20:08.613 W/ResourceType(31843): Skipping entry 0x7f02015d in package table 0 because it is not complex!
02-08 13:20:08.613 W/ResourceType(31843): Skipping entry 0x7f02015d in package table 0 because it is not complex!
02-08 13:20:08.613 W/ResourceType(31843): Skipping entry 0x7f02015d in package table 0 because it is not complex!
02-08 13:20:08.613 D/vzAnalytics(31843): WiFi:true, CellData:false
02-08 13:20:08.613 D/vzAnalytics(31843): Started network thread 1328728808627
02-08 13:20:08.613 D/vzAnalytics(31843): Initializing session 1328728808625-NA9LSBH
02-08 13:20:08.621 D/vzAnalytics(31843): Scheduling db and net save task
02-08 13:20:08.621 I/vzAnalytics(31843): Initialized vz analytics. version 2.0.2
02-08 13:20:08.629 W/PhoneWindow(31843): Previously focused view reported id 16908298 during save, but can't be found during restore.
02-08 13:20:08.629 V/MediaLibraryActivity(31843): getMediaIds():
02-08 13:20:08.629 V/MediaLibraryActivity(31843): MediaLibraryActivity.Done Waiting. READ NOW
02-08 13:20:08.683 V/DesEncrypter(31843): error while decrypt: javax.crypto.IllegalBlockSizeException: last block incomplete in decryption
02-08 13:20:08.683 I/System.out(31843): Account Migration Exception : java.lang.NullPointerException
02-08 13:20:08.738 V/DRMManager(31843): PV Lib not linked
02-08 13:20:08.738 E/FVApplication(31843): TRY MOTO DRM
02-08 13:20:08.738 E/FVApplication(31843): SUCCESS bind to Janus DRM API
02-08 13:20:08.738 V/DeviceDRMInfoManager(31843): 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:20:08.738 V/MSV (31843): 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:20:08.738 W/ActivityManager( 1373): Unable to start service Intent { act=com.motorola.android.wmdrm.IWmdrmService }: not found
02-08 13:20:08.754 V/MetaDataSyncService(31843): getMediaIds():
02-08 13:20:08.777 V/MediaLibraryActivity(31843): getContentItemIds():
02-08 13:20:08.808 I/ActivityManager( 1373): Displayed com.verizon.ams.tesla/.ui.TabDownloadsActivity: +899ms
02-08 13:20:08.839 I/CommonUtils(31843): updatePurchaseStatusForMediaCache ....
02-08 13:20:08.839 V/MSV (31843): PURCHASELIST EMPTY ...
02-08 13:20:08.957 V/MediaLibraryActivity(31843): downloadPurchaseList() :: url = https://www36.verizon.com/MSVAppServices/Authenticated/POX/AccountMgrXmlServices.asmx/MSVAppRequest?
02-08 13:20:08.957 V/MediaLibraryActivity(31843): downloadPurchaseList() :: postData = strInputXML=GETPURCHASEDTITLESDNETtrans_12BB001Y
02-08 13:20:08.980 V/MSV (31843): WebUtils url:https://www36.verizon.com/MSVAppServices/Authenticated/POX/AccountMgrXmlServices.asmx/MSVAppRequest?
02-08 13:20:08.980 V/MSV (31843): WebUtils body:strInputXML=GETPURCHASEDTITLESDNETtrans_12BB001Y
02-08 13:20:08.988 W/InputManagerService( 1373): Starting input on non-focused client com.android.internal.view.IInputMethodClient$Stub$Proxy@407906e8 (uid=10028 pid=31843)
02-08 13:20:08.988 W/InputManagerService( 1373): Client not active, ignoring focus gain of: com.android.internal.view.IInputMethodClient$Stub$Proxy@4076cfe8
02-08 13:20:09.019 D/skia (31843): --- SkImageDecoder::Factory returned null
02-08 13:20:09.019 E/ProductListAdapter(31843): Error decoding poster
02-08 13:20:09.058 D/dalvikvm(31843): GC_CONCURRENT freed 770K, 48% free 3551K/6791K, external 3053K/6263K, paused 1ms+3ms
02-08 13:20:09.066 V/MetaDataSyncService(31843): downloadPurchaseList() :: url = https://www36.verizon.com/MSVAppServices/Authenticated/POX/AccountMgrXmlServices.asmx/MSVAppRequest?
02-08 13:20:09.066 V/MetaDataSyncService(31843): downloadPurchaseList() :: postData = strInputXML=GETPURCHASEDTITLESironman1058DNETtrans_12BB001YDESCproduct_purchasedDateALLAverageRatingALLALL
02-08 13:20:09.066 V/MSV (31843): WebUtils url:https://www36.verizon.com/MSVAppServices/Authenticated/POX/AccountMgrXmlServices.asmx/MSVAppRequest?
02-08 13:20:09.066 V/MSV (31843): WebUtils body:strInputXML=GETPURCHASEDTITLESironman1058DNETtrans_12BB001YDESCproduct_purchasedDateALLAverageRatingALLALL
02-08 13:20:09.074 V/MetaDataSyncService(31843): downloadWatchList() :: url = https://www36.verizon.com/MSVAppServices/Authenticated/POX/AccountMgrXmlServices.asmx/MSVAppRequest?
02-08 13:20:09.074 V/MetaDataSyncService(31843): downloadWatchList() :: postData = strInputXML=GETWATCHLISTironman1058DNETtrans_12BB001DESCproduct_dtmcreateDateALLAverageRatingALLALL
02-08 13:20:09.074 V/MSV (31843): WebUtils url:https://www36.verizon.com/MSVAppServices/Authenticated/POX/AccountMgrXmlServices.asmx/MSVAppRequest?
02-08 13:20:09.074 V/MSV (31843): WebUtils body:strInputXML=GETWATCHLISTironman1058DNETtrans_12BB001DESCproduct_dtmcreateDateALLAverageRatingALLALL
02-08 13:20:09.129 E/FVApplication(31843): Updating Storage Info
02-08 13:20:09.129 I/LAB126 ( 1441): StatusBarService hears intent. action: com.lab126.intent.TOP_WINDOW_CHANGED
02-08 13:20:09.129 I/LAB126 ( 1441): window changed
02-08 13:20:09.207 V/MSV (31843): WebUtils url:https://www36.verizon.com/MSVAppServices/Authenticated/POX/CatalogXmlServices.asmx/MSVAppRequest?
02-08 13:20:09.207 V/MSV (31843): WebUtils body:strInputXML=GETUSERRATINGSironman1058DNETtrans_12BB001
02-08 13:20:09.207 V/MetaDataSyncService(31843): getContentItemIds():
02-08 13:20:09.363 D/vzAnalytics(31843): Loaded 0 config keys
02-08 13:20:10.668 I/WifiManager(31843): WiFi lock 0 acquired for DownloadMgr wifi lock.
02-08 13:20:10.675 V/MSV (31843): WebUtils finished http post call
02-08 13:20:10.675 V/MSV (31843): WebUtils finished http get call
02-08 13:20:10.675 I/DeviceDRMInfoManager(31843): deviceDRMHandler..
02-08 13:20:10.707 V/DeviceDRMInfoManager(31843): DRM FOR THE DEVICE: [SM]
02-08 13:20:11.019 E/Omap4ALSA( 1293): called drain&close
02-08 13:20:11.191 D/dalvikvm( 1373): GC_EXPLICIT freed 444K, 50% free 6500K/12999K, external 7028K/8510K, paused 63ms
02-08 13:20:11.589 D/dalvikvm(31843): GC_CONCURRENT freed 647K, 44% free 4121K/7303K, external 3053K/6263K, paused 1ms+2ms
02-08 13:20:12.175 I/MediaLibraryActivity(31843): getting purchaselist successful!
02-08 13:20:12.175 V/MSV (31843): WebUtils finished http post call
02-08 13:20:13.043 I/MediaLibraryActivity(31843): purchaselistIndex size bobby:0
02-08 13:20:13.043 I/MediaLibraryActivity(31843): purchaselistIndex size bobby:0
02-08 13:20:13.043 I/MediaLibraryActivity(31843): Purchasehistory list Parsing successfully
02-08 13:20:13.043 V/MediaLibraryActivity(31843): MediaLibraryActivity.Done Waiting. READ NOW
02-08 13:20:13.144 I/CommonUtils(31843): updatePurchaseStatusForMediaCache ....
02-08 13:20:13.152 D/skia (31843): --- SkImageDecoder::Factory returned null
02-08 13:20:13.160 E/ProductListAdapter(31843): Error decoding poster
02-08 13:20:15.089 I/MetaDataSyncService(31843): getting purchaselist successful!
02-08 13:20:15.089 V/MSV (31843): WebUtils finished http post call
02-08 13:20:16.027 I/MetaDataSyncService(31843): purchaselistIndex size bobby:20
02-08 13:20:16.035 I/MetaDataSyncService(31843): purchaselistIndex size bobby:20
02-08 13:20:16.035 I/MetaDataSyncService(31843): Purchasehistory list Parsing successfully
02-08 13:20:16.363 D/dalvikvm(31843): GC_CONCURRENT freed 1102K, 45% free 4463K/8071K, external 3059K/6263K, paused 2ms+3ms
02-08 13:20:16.793 V/MSV (31843): Silent request done...
02-08 13:20:16.793 V/MSV (31843): getting watchlist successful!
02-08 13:20:16.793 V/MSV (31843): WebUtils finished http post call
02-08 13:20:16.793 V/MSV (31843): WebUtils finished http post call
02-08 13:20:17.324 V/MSV (31843): watchlist index created!9
02-08 13:20:17.324 I/MSV (31843): Watchlist Parsing successfully
02-08 13:20:17.668 W/ProcessStats( 1373): Skipping unknown process pid 31861
02-08 13:20:17.691 D/dalvikvm( 1794): GC_EXPLICIT freed 235K, 53% free 3071K/6407K, external 847K/1181K, paused 35ms
02-08 13:20:25.457 V/DeviceStorageMonitorService( 1373): mTotalMemory is 11734999
02-08 13:20:27.761 D/dalvikvm( 1635): GC_EXPLICIT freed 7K, 58% free 4013K/9351K, external 3610K/4508K, paused 40ms
02-08 13:20:28.629 D/vzAnalytics(31843): Moved 2 events from thread 1 to bg thread. bg thread pending events 2
02-08 13:20:28.629 D/vzAnalytics(31843): Saving log to db on thread 13 for 2 events
02-08 13:20:28.761 D/vzAnalytics(31843): WiFi:true, CellData:false
02-08 13:20:28.761 D/vzAnalytics(31843): Enqueuing server log task.
02-08 13:20:28.761 D/vzAnalytics(31843): Running server log task on thread 13 for 1 events. url :http://www22.verizon.com/clickStream/Tracker.svc/xml/async/postmetrics
02-08 13:20:29.074 D/vzAnalytics(31843): deleting events after server log.
02-08 13:20:29.129 I/vzAnalytics(31843): complete server log task. success :true
02-08 13:20:29.129 D/vzAnalytics(31843): Scheduling db and net save task
02-08 13:20:31.754 I/MSV (31843): send downloading event
02-08 13:20:31.769 D/IconMerger( 1441): notification for com.verizon.ams.tesla (1)
02-08 13:20:31.769 D/IconMerger( 1441): notification for com.android.systemui (1)
02-08 13:20:31.769 D/IconMerger( 1441): sum of notifications: 2
02-08 13:20:32.769 D/dalvikvm( 1806): GC_EXPLICIT freed 313K, 43% free 4612K/8007K, external 847K/1181K, paused 51ms
02-08 13:20:37.793 D/dalvikvm(31514): GC_EXPLICIT freed 24K, 39% free 4798K/7751K, external 847K/1359K, paused 51ms
02-08 13:20:46.699 D/dalvikvm( 1373): GC_CONCURRENT freed 2144K, 51% free 6403K/12999K, external 6814K/8510K, paused 2ms+5ms
02-08 13:20:46.832 D/dalvikvm( 1373): GC_CONCURRENT freed 2106K, 52% free 6345K/12999K, external 6814K/8510K, paused 2ms+4ms
02-08 13:20:47.011 D/dalvikvm( 1373): GC_CONCURRENT freed 2053K, 52% free 6339K/12999K, external 6814K/8510K, paused 2ms+4ms
02-08 13:20:47.175 D/dalvikvm( 1373): GC_CONCURRENT freed 2047K, 52% free 6340K/12999K, external 6814K/8510K, paused 2ms+4ms
02-08 13:20:47.347 D/dalvikvm( 1373): GC_CONCURRENT freed 2034K, 52% free 6341K/12999K, external 6814K/8510K, paused 3ms+5ms
02-08 13:20:47.511 D/dalvikvm( 1373): GC_CONCURRENT freed 2048K, 52% free 6340K/12999K, external 6814K/8510K, paused 2ms+4ms
02-08 13:20:47.652 D/dalvikvm( 1373): GC_CONCURRENT freed 2074K, 52% free 6313K/12999K, external 6814K/8510K, paused 2ms+4ms
02-08 13:20:47.738 D/dalvikvm( 1373): GC_CONCURRENT freed 2031K, 52% free 6321K/12999K, external 6814K/8510K, paused 2ms+4ms
02-08 13:20:49.129 D/vzAnalytics(31843): Moved 0 events from thread 1 to bg thread. bg thread pending events 0
02-08 13:20:49.129 D/vzAnalytics(31843): WiFi:true, CellData:false
02-08 13:20:49.136 D/vzAnalytics(31843): Enqueuing server log task.
02-08 13:20:49.136 D/vzAnalytics(31843): Running server log task on thread 13 for 6 events. url :http://www22.verizon.com/clickStream/Tracker.svc/xml/async/postmetrics
02-08 13:20:49.550 D/vzAnalytics(31843): deleting events after server log.
02-08 13:20:49.863 I/vzAnalytics(31843): complete server log task. success :true
02-08 13:20:49.863 D/vzAnalytics(31843): Scheduling db and net save task
02-08 13:20:57.839 I/ChargeProtectionService( 1373): Battery discharging, resuming charging
02-08 13:21:07.933 D/dalvikvm( 1373): GC_CONCURRENT freed 2026K, 52% free 6299K/12999K, external 6814K/8510K, paused 3ms+4ms
02-08 13:21:09.871 D/vzAnalytics(31843): Moved 0 events from thread 1 to bg thread. bg thread pending events 0
02-08 13:21:09.871 D/vzAnalytics(31843): WiFi:true, CellData:false
02-08 13:21:09.871 D/vzAnalytics(31843): Enqueuing server log task.
02-08 13:21:09.871 D/vzAnalytics(31843): Running server log task on thread 13 for 1 events. url :http://www22.verizon.com/clickStream/Tracker.svc/xml/async/postmetrics
02-08 13:21:10.175 D/vzAnalytics(31843): deleting events after server log.
02-08 13:21:10.238 I/vzAnalytics(31843): complete server log task. success :true
02-08 13:21:25.464 V/DeviceStorageMonitorService( 1373): mTotalMemory is 11734999
02-08 13:21:43.996 D/GetUserAlerts( 1806): Making GetUserAlertsRequest
02-08 13:21:43.996 D/GetUserAlerts( 1806): getRequestURL=http://clientalerts.ebay.com/ws/ecasvc/ClientAlerts?callname=GetUserAlerts&SessionID=AQAAATU7XFAAAA0xfDE5MjMwMzE2fDEyNjk4NTU5fDQ5MTIyfDEzMjg4MDY0ODMzMzRmNHf5YXxAR3%2FJEWu4t7H9nct9yQ%3D%3D&SessionData=AQAAATU7XFAAAA1TSUQ9MTkyMzAzMTZ8U1Q9MXwxfExBQ1RWPTEzMjg3Mjg2MDQ0NjZ8RUhXTT0zNjc3MzQ1MjA0fFRJRFg9MHxMSVVQPTEzMjg3MjAwODMzMTh8UExIUz1bXerht6PhmVF33w2h3ZvdEL0eaokD
02-08 13:21:44.652 D/GetUserAlerts( 1806): Parsing JSON=
02-08 13:21:44.652 D/GetUserAlerts( 1806): {"Timestamp":"2012-02-08T19:21:45.601Z","Ack":"Success","Build":"E745_CORE_BUNDLED_14006276_R1","Version":"745","SessionData":"AQAAATU7XFAAAA1TSUQ9MTkyMzAzMTZ8U1Q9MXwxfExBQ1RWPTEzMjg3Mjg5MDU2MDF8RUhXTT0zNjc3MzQ1MjA0fFRJRFg9MHxMSVVQPTEzMjg3MjAwODMzMTh8UExIUz1bXeTEIQhr7FgifQ3R3u66f3XvKpSO"}
02-08 13:21:44.652 D/GetUserAlerts( 1806): Call was success but no alerts
02-08 13:21:44.652 D/GetUserAlerts( 1806): Got response ackCode=1
02-08 13:21:44.652 D/GetUserAlerts( 1806): Ack Success, session data=AQAAATU7XFAAAA1TSUQ9MTkyMzAzMTZ8U1Q9MXwxfExBQ1RWPTEzMjg3Mjg5MDU2MDF8RUhXTT0zNjc3MzQ1MjA0fFRJRFg9MHxMSVVQPTEzMjg3MjAwODMzMTh8UExIUz1bXeTEIQhr7FgifQ3R3u66f3XvKpSO
02-08 13:21:47.949 D/dalvikvm( 1373): GC_CONCURRENT freed 1988K, 52% free 6358K/12999K, external 6814K/8510K, paused 2ms+4ms
02-08 13:21:48.097 D/dalvikvm( 1373): GC_CONCURRENT freed 2051K, 52% free 6355K/12999K, external 6814K/8510K, paused 2ms+4ms
02-08 13:21:48.261 D/dalvikvm( 1373): GC_CONCURRENT freed 2058K, 52% free 6344K/12999K, external 6814K/8510K, paused 2ms+4ms
02-08 13:21:48.441 D/dalvikvm( 1373): GC_CONCURRENT freed 2046K, 52% free 6345K/12999K, external 6814K/8510K, paused 2ms+4ms
02-08 13:21:48.605 D/dalvikvm( 1373): GC_CONCURRENT freed 2023K, 52% free 6369K/12999K, external 6814K/8510K, paused 2ms+4ms
02-08 13:21:48.777 D/dalvikvm( 1373): GC_CONCURRENT freed 2072K, 52% free 6345K/12999K, external 6814K/8510K, paused 2ms+5ms
02-08 13:21:48.918 D/dalvikvm( 1373): GC_CONCURRENT freed 2032K, 52% free 6359K/12999K, external 6814K/8510K, paused 2ms+4ms
02-08 13:21:49.011 D/dalvikvm( 1373): GC_CONCURRENT freed 2064K, 52% free 6335K/12999K, external 6814K/8510K, paused 3ms+5ms
02-08 13:21:53.082 D/dalvikvm( 1806): GC_EXPLICIT freed 226K, 43% free 4606K/8007K, external 847K/1181K, paused 44ms
02-08 13:21:58.105 D/dalvikvm(31514): GC_EXPLICIT freed 13K, 39% free 4795K/7751K, external 847K/1359K, paused 44ms
02-08 13:22:23.230 D/dalvikvm( 1373): GC_CONCURRENT freed 2012K, 52% free 6306K/12999K, external 6814K/8510K, paused 2ms+5ms
02-08 13:22:25.464 V/DeviceStorageMonitorService( 1373): mTotalMemory is 11734999
02-08 13:22:49.246 D/dalvikvm( 1373): GC_CONCURRENT freed 1982K, 51% free 6371K/12999K, external 6814K/8510K, paused 2ms+4ms
02-08 13:22:49.394 D/dalvikvm( 1373): GC_CONCURRENT freed 2060K, 52% free 6358K/12999K, external 6814K/8510K, paused 2ms+4ms
02-08 13:22:49.574 D/dalvikvm( 1373): GC_CONCURRENT freed 2053K, 52% free 6352K/12999K, external 6814K/8510K, paused 2ms+4ms
02-08 13:22:49.746 D/dalvikvm( 1373): GC_CONCURRENT freed 2047K, 52% free 6352K/12999K, external 6814K/8510K, paused 3ms+4ms
02-08 13:22:49.902 D/dalvikvm( 1373): GC_CONCURRENT freed 2047K, 52% free 6353K/12999K, external 6814K/8510K, paused 3ms+4ms
02-08 13:22:50.066 D/dalvikvm( 1373): GC_CONCURRENT freed 2059K, 52% free 6337K/12999K, external 6814K/8510K, paused 3ms+4ms
02-08 13:22:50.191 D/dalvikvm( 1373): GC_CONCURRENT freed 2016K, 52% free 6367K/12999K, external 6814K/8510K, paused 2ms+4ms
02-08 13:22:50.277 D/dalvikvm( 1373): GC_CONCURRENT freed 2032K, 51% free 6374K/12999K, external 6814K/8510K, paused 2ms+4ms
02-08 13:23:25.464 V/DeviceStorageMonitorService( 1373): mTotalMemory is 11734999
02-08 13:23:28.496 D/dalvikvm( 1373): GC_CONCURRENT freed 1990K, 52% free 6323K/12999K, external 6814K/8510K, paused 2ms+5ms
02-08 13:23:50.519 D/dalvikvm( 1373): GC_CONCURRENT freed 1968K, 51% free 6403K/12999K, external 6814K/8510K, paused 3ms+5ms
02-08 13:23:50.668 D/dalvikvm( 1373): GC_CONCURRENT freed 2050K, 51% free 6399K/12999K, external 6814K/8510K, paused 3ms+5ms
02-08 13:23:50.847 D/dalvikvm( 1373): GC_CONCURRENT freed 2087K, 52% free 6360K/12999K, external 6814K/8510K, paused 3ms+4ms
02-08 13:23:51.027 D/dalvikvm( 1373): GC_CONCURRENT freed 2047K, 52% free 6360K/12999K, external 6814K/8510K, paused 2ms+4ms
02-08 13:23:51.191 D/dalvikvm( 1373): GC_CONCURRENT freed 2040K, 52% free 6362K/12999K, external 6814K/8510K, paused 2ms+4ms
02-08 13:23:51.355 D/dalvikvm( 1373): GC_CONCURRENT freed 2036K, 52% free 6363K/12999K, external 6814K/8510K, paused 2ms+4ms
02-08 13:23:51.496 D/dalvikvm( 1373): GC_CONCURRENT freed 2073K, 52% free 6335K/12999K, external 6814K/8510K, paused 3ms+4ms
02-08 13:23:51.589 D/dalvikvm( 1373): GC_CONCURRENT freed 2025K, 52% free 6350K/12999K, external 6814K/8510K, paused 2ms+4ms
02-08 13:23:53.543 D/dalvikvm( 1806): GC_EXPLICIT freed 94K, 43% free 4604K/8007K, external 847K/1181K, paused 44ms
02-08 13:23:58.574 D/dalvikvm(31514): GC_EXPLICIT freed 12K, 39% free 4795K/7751K, external 847K/1359K, paused 45ms
02-08 13:24:25.464 V/DeviceStorageMonitorService( 1373): mTotalMemory is 11734999
02-08 13:24:43.785 D/dalvikvm( 1373): GC_CONCURRENT freed 2016K, 52% free 6328K/12999K, external 6814K/8510K, paused 2ms+4ms
02-08 13:24:51.824 D/dalvikvm( 1373): GC_CONCURRENT freed 2032K, 52% free 6337K/12999K, external 6814K/8510K, paused 3ms+4ms
02-08 13:24:51.980 D/dalvikvm( 1373): GC_CONCURRENT freed 2018K, 52% free 6367K/12999K, external 6814K/8510K, paused 2ms+5ms
02-08 13:24:52.144 D/dalvikvm( 1373): GC_CONCURRENT freed 2051K, 52% free 6363K/12999K, external 6814K/8510K, paused 2ms+4ms
02-08 13:24:52.316 D/dalvikvm( 1373): GC_CONCURRENT freed 2048K, 52% free 6363K/12999K, external 6814K/8510K, paused 3ms+4ms
02-08 13:24:52.488 D/dalvikvm( 1373): GC_CONCURRENT freed 2047K, 52% free 6364K/12999K, external 6814K/8510K, paused 3ms+5ms
02-08 13:24:52.636 D/dalvikvm( 1373): GC_CONCURRENT freed 2012K, 51% free 6399K/12999K, external 6814K/8510K, paused 3ms+4ms
02-08 13:24:52.754 D/dalvikvm( 1373): GC_CONCURRENT freed 2067K, 51% free 6379K/12999K, external 6814K/8510K, paused 3ms+4ms
02-08 13:24:52.839 D/dalvikvm( 1373): GC_CONCURRENT freed 2026K, 51% free 6385K/12999K, external 6814K/8510K, paused 3ms+4ms