• After 15+ years, we've made a big change: Android Forums is now Early Bird Club. Learn more here.

[Verizon] Excessive RAM usage by "System"

PhilosoRaptor

PhilosoRaptor

Android Enthusiast
I'm just pasting this thread here from my original one on XDA so I can get some additional help on this.

-----------------------------------------------------------------------

Ok, so check this out and tell me if you've experienced the same:

After fresh reboot:



After a hours/days of usage:



Just took this one on Liquid 1.25 (4.0.4 based)


After reboot


You're looking at "System" RAM usage and its affect on total RAM usage at the top pie graph. Is this normal? It's very annoying because it runs considerably slower after a while, so I check the RAM usage and "System" is taking 4-5 times more RAM than it does after a fresh reboot. I wish I could just kill/restart the service without rebooting the phone. Or better yet; keep it from getting that high in the first place. It causes constant OG Droid-like launcher redraws almost EVERY time I hit the home button, taking at least 5-10 secs for the icons to even start appearing and probably 20+ secs for it to be fully loaded and scrolling to be smooth again (using Apex at the moment because Nova seemed to be worse than that). I wish we could see what the remaining ~300 MBs of RAM were being allocated to too.

As long as I have around 150MB+ free, the phone seems to run great. But that "System" seems to slowly creep up in RAM usage until I have 100MBs or less free, bringing things almost to a halt. I have noticed this across a couple 4.0.3 (shown pics I'm running Codename Android 1.5.0) ROMs and just saw it again for the first time on a 4.0.4-based ROM (Liquid 1.25). I was hoping the update would address this :(. Also, no amount of killing apps or disabling Google services brings this number back down. The only way I've found to fix it is to reboot, which I find unacceptable. I don't want to reboot my phone multiple times a day to keep it running fast.

TIA for any insight, suggestions or help.

FYI: The app I'm using to display that info is called "SystemPanel" (free and paid version in the Play Store).

Update: Here's a video I made to show what I've found that causes this. If you're running this ROM or have a ROM with a lockscreen like that (as I did with Codename 1.5.0), please see if you can replicate this. It seems that once I enable that octo lockscreen, the memory leak will be permanent after I enable that because I can revert it back to the stock lockscreen and it will still increase System memory usage after ever unlock.

Update 2: I've replicated this on AOKP now using the same lock screen setup. Now you can see me crashing the phone after only locking/unlocking the phone.

http://www.youtube.com/watch?v=Z9a91heHVNI
 
Strange. I don't even have such a process in my list. Here's what I see (and I've had my phone up for a week or more at a time and never had the lag you describe :().


Screenshot_2012-04-06-09-56-14.png
 
Can you collect some info via terminal emulator or adb shell? This should help us figure out where the memory is going. I'll need the output of the following:

cat /proc/meminfo
cat /proc/zoneinfo

If you have busybox, it might be good to see the ps output:

/system/xbin/ps w
 
jkc120 said:
Can you collect some info via terminal emulator or adb shell? This should help us figure out where the memory is going. I'll need the output of the following:

cat /proc/meminfo
cat /proc/zoneinfo

If you have busybox, it might be good to see the ps output:

/system/xbin/ps w
Click to expand...

Ok, I'll paste that stuff in here next time I see it happen. I rebooted last night (when that last screen was taken) and "System" is up to 95.1MB effective usage right now. I'll wait until it gets up to 250+ like it did in those pics and post it here.

Thanks!
 
You've got a app that's not working correctly or is incompatible with ICS. Unlike GB, app battery usage is recorded in batt stats under "system" in ICS.

That said, what isn't working properly? That's a good place to start freezing in TIBU or uninstalling. :D
 
Ok, it hasn't got quite as bad as the pics in my OP, but right now my system usage is about 220MBs. I've uninstalled/froze any apps and services that I would suspect to be causing these issues (haven't been updated since ICS debuted).

Here's what I get when I punch in those commands:

Code: 
app_70@android:/ $ export PATH=/data/local/bin:$PATH
app_70@android:/ $ su
app_70@android:/ # cat /proc/meminfo
MemTotal:         711416 kB
MemFree:           50720 kB
Buffers:            1504 kB
Cached:            95792 kB
SwapCached:            0 kB
Active:           450992 kB
Inactive:          59636 kB
Active(anon):     414456 kB
Inactive(anon):      284 kB
Active(file):      36536 kB
Inactive(file):    59352 kB
Unevictable:        1084 kB
Mlocked:               0 kB
HighTotal:        598016 kB
HighFree:          15156 kB
LowTotal:         113400 kB
LowFree:           35564 kB
SwapTotal:             0 kB
SwapFree:              0 kB
Dirty:                 0 kB
Writeback:             0 kB
AnonPages:        414504 kB
Mapped:           143868 kB
Shmem:               320 kB
Slab:              15084 kB
SReclaimable:       4452 kB
SUnreclaim:        10632 kB
KernelStack:        4584 kB
PageTables:         6616 kB
NFS_Unstable:          0 kB
Bounce:                0 kB
WritebackTmp:          0 kB
CommitLimit:      355708 kB
Committed_AS:    4110196 kB
VmallocTotal:     778240 kB
VmallocUsed:       49748 kB
VmallocChunk:     664516 kB
app_70@android:/ # cat /proc/zoneinfo
Node 0, zone   Normal
  pages free     8884
        min      360
        low      450
        high     540
        scanned  0
        spanned  32768
        present  32512
    nr_free_pages 8884
    nr_inactive_anon 3
    nr_active_anon 7529
    nr_inactive_file 424
    nr_active_file 50
    nr_unevictable 0
    nr_mlock     0
    nr_anon_pages 7529
    nr_mapped    3556
    nr_file_pages 477
    nr_dirty     0
    nr_writeback 0
    nr_slab_reclaimable 1113
    nr_slab_unreclaimable 2658
    nr_page_table_pages 1654
    nr_kernel_stack 573
    nr_unstable  0
    nr_bounce    0
    nr_vmscan_write 22937
    nr_vmscan_immediate_reclaim 173412
    nr_writeback_temp 0
    nr_isolated_anon 0
    nr_isolated_file 0
    nr_shmem     3
    nr_dirtied   116172
    nr_written   92747
    nr_anon_transparent_hugepages 0
        protection: (0, 4616, 4616)
  pagesets
    cpu: 0
              count: 28
              high:  42
              batch: 7
  vm stats threshold: 4
    cpu: 1
              count: 14
              high:  42
              batch: 7
  vm stats threshold: 4
  all_unreclaimable: 0
  start_pfn:         524288
  inactive_ratio:    1
Node 0, zone  HighMem
  pages free     3665
        min      128
        low      536
        high     945
        scanned  0
        spanned  229376
        present  147712
    nr_free_pages 3665
    nr_inactive_anon 68
    nr_active_anon 96236
    nr_inactive_file 14401
    nr_active_file 9099
    nr_unevictable 271
    nr_mlock     0
    nr_anon_pages 96242
    nr_mapped    32412
    nr_file_pages 23848
    nr_dirty     0
    nr_writeback 0
    nr_slab_reclaimable 0
    nr_slab_unreclaimable 0
    nr_page_table_pages 0
    nr_kernel_stack 0
    nr_unstable  0
    nr_bounce    0
    nr_vmscan_write 0
    nr_vmscan_immediate_reclaim 5
    nr_writeback_temp 0
    nr_isolated_anon 0
    nr_isolated_file 0
    nr_shmem     77
    nr_dirtied   254477
    nr_written   198142
    nr_anon_transparent_hugepages 0
        protection: (0, 0, 0)
  pagesets
    cpu: 0
              count: 43
              high:  186
              batch: 31
  vm stats threshold: 16
    cpu: 1
              count: 27
              high:  186
              batch: 31
  vm stats threshold: 16
  all_unreclaimable: 0
  start_pfn:         557056
  inactive_ratio:    1
app_70@android:/ #

I don't have busy box on my phone yet. I'll try and get that later. I'm posting this from my phone, or I would post a screen shot from system panel too. Right now it's been keeping about 140MBs total available, but "system" staying over 200MBs now and it's causing lots of launcher redraws and other apps being purged from memory constantly, so it's still quite annoying.

Thanks for the help!
 
colchiro said:
You've got a app that's not working correctly or is incompatible with ICS. Unlike GB, app battery usage is recorded in batt stats under "system" in ICS.

That said, what isn't working properly? That's a good place to start freezing in TIBU or uninstalling. :D
Click to expand...

I'm not having an issue with battery life or usage though, just slow performance after a few days use. I did just try to disable any apps/services that weren't necessary though (k-9 mail, Verizon, business calendar, Key Ring) to see if that might be the issue and it doesn't seem to be because I'm sitting at about 240 MBs "effective" System usage and 90-100 MBs free at the moment with those apps disabled. Almost everything else that's still running is a stock/native service. The only notable exception is Swype and that's been updated specifically for ICS and I haven't heard of it causing issues with anyone else, so I doubt that's it.

There has to be a way to break down "system" usage to see what exact service or feature is causing this excessive usage.
 
PhilosoRaptor said:
Ok, it hasn't got quite as bad as the pics in my OP, but right now my system usage is about 220MBs. I've uninstalled/froze any apps and services that I would suspect to be causing these issues (haven't been updated since ICS debuted).

Thanks for the help!
Click to expand...

Thanks! I'm dumping my stats and will compare them and see if anything stands out. I'll reply back in a bit.
 
Here's another dump since its gotten worse since lady night (my last post/dump). I'll post screen shots when I get to my PC and can upload them.

Code: 
export PATH=/data/local/bin:$PATH
app_70@android:/ $ export PATH=/data/local/bin:$PATH
app_70@android:/ $ su
app_70@android:/ # cat /proc/meminfo
MemTotal:         711416 kB
MemFree:           24460 kB
Buffers:            1368 kB
Cached:           127740 kB
SwapCached:            0 kB
Active:           446844 kB
Inactive:          94608 kB
Active(anon):     413528 kB
Inactive(anon):      276 kB
Active(file):      33316 kB
Inactive(file):    94332 kB
Unevictable:        1084 kB
Mlocked:               0 kB
HighTotal:        598016 kB
HighFree:           2532 kB
LowTotal:         113400 kB
LowFree:           21928 kB
SwapTotal:             0 kB
SwapFree:              0 kB
Dirty:                 0 kB
Writeback:             0 kB
AnonPages:        413540 kB
Mapped:           136176 kB
Shmem:               320 kB
Slab:              14836 kB
SReclaimable:       4300 kB
SUnreclaim:        10536 kB
KernelStack:        4392 kB
PageTables:         6332 kB
NFS_Unstable:          0 kB
Bounce:                0 kB
WritebackTmp:          0 kB
CommitLimit:      355708 kB
Committed_AS:    3928832 kB
VmallocTotal:     778240 kB
VmallocUsed:       48164 kB
VmallocChunk:     665820 kB
app_70@android:/ # cat /proc/zoneinfo
Node 0, zone   Normal
  pages free     5160
        min      360
        low      450
        high     540
        scanned  0
        spanned  32768
        present  32512
    nr_free_pages 5160
    nr_inactive_anon 3
    nr_active_anon 6865
    nr_inactive_file 3169
    nr_active_file 160
    nr_unevictable 0
    nr_mlock     0
    nr_anon_pages 6882
    nr_mapped    5413
    nr_file_pages 3332
    nr_dirty     0
    nr_writeback 0
    nr_slab_reclaimable 1075
    nr_slab_unreclaimable 2634
    nr_page_table_pages 1583
    nr_kernel_stack 549
    nr_unstable  0
    nr_bounce    0
    nr_vmscan_write 34507
    nr_vmscan_immediate_reclaim 232323
    nr_writeback_temp 0
    nr_isolated_anon 0
    nr_isolated_file 0
    nr_shmem     3
    nr_dirtied   147839
    nr_written   119310
    nr_anon_transparent_hugepages 0
        protection: (0, 4616, 4616)
  pagesets
    cpu: 0
              count: 32
              high:  42
              batch: 7
  vm stats threshold: 4
    cpu: 1
              count: 16
              high:  42
              batch: 7
  vm stats threshold: 4
  all_unreclaimable: 0
  start_pfn:         524288
  inactive_ratio:    1
Node 0, zone  HighMem
  pages free     509
        min      128
        low      536
        high     945
        scanned  0
        spanned  229376
        present  147712
    nr_free_pages 509
    nr_inactive_anon 66
    nr_active_anon 96566
    nr_inactive_file 20587
    nr_active_file 8194
    nr_unevictable 271
    nr_mlock     0
    nr_anon_pages 96555
    nr_mapped    28816
    nr_file_pages 29129
    nr_dirty     0
    nr_writeback 0
    nr_slab_reclaimable 0
    nr_slab_unreclaimable 0
    nr_page_table_pages 0
    nr_kernel_stack 0
    nr_unstable  0
    nr_bounce    0
    nr_vmscan_write 0
    nr_vmscan_immediate_reclaim 5
    nr_writeback_temp 0
    nr_isolated_anon 0
    nr_isolated_file 0
    nr_shmem     77
    nr_dirtied   291768
    nr_written   231436
    nr_anon_transparent_hugepages 0
        protection: (0, 0, 0)
  pagesets
    cpu: 0
              count: 91
              high:  186
              batch: 31
  vm stats threshold: 16
    cpu: 1
              count: 115
              high:  186
              batch: 31
  vm stats threshold: 16
  all_unreclaimable: 0
  start_pfn:         557056
  inactive_ratio:    1
app_70@android:/ #

That's with it pretty much identical to the screenshots in my first post. System using about 260MBs and only having around 95MBs of RAM available. The phone is running horribly..not much better than my old Droid normally ran. Anything I open purges the launcher from memory causing redraws even if I just open something for a second.
 
I'll get the zoneinfo comparison shortly, but here's a comparison of my meminfo and yours. At the time, my system memory usage as reported in that app was ~30 MB.

Code: 
         Active  +53.40  +13.94  382.97  436.37
   Active(anon) +102.95  +34.21  300.89  403.84
   Active(file)  -49.55  -60.36   82.08   32.54
      AnonPages +102.98  +34.23  300.87  403.85
        Buffers  -13.11  -90.75   14.45    1.34
         Cached  -32.73  -20.78  157.47  124.75
       HighFree   -8.02  -76.42   10.49    2.47
       Inactive   +3.65   +4.12   88.74   92.39
 Inactive(anon)   -0.24  -47.33    0.51    0.27
 Inactive(file)   +3.89   +4.41   88.23   92.12
    KernelStack   -2.28  -34.72    6.57    4.29
        LowFree   -3.77  -14.96   25.18   21.41
         Mapped  -68.98  -34.15  201.96  132.98
        MemFree  -11.78  -33.03   35.67   23.89
     PageTables   -3.41  -35.57    9.60    6.18
   SReclaimable   -4.67  -52.66    8.87    4.20
     SUnreclaim   +0.45   +4.61    9.84   10.29
          Shmem   -0.24  -43.26    0.55    0.31
           Slab   -4.22  -22.55   18.71   14.49
   VmallocChunk   -0.93   -0.14  651.14  650.21
    VmallocUsed  -14.75  -23.87   61.79   47.04
The first column is the entry in the meminfo file, the 2nd is the change (in MB) from mine to yours, the 3rd is the % change from mine to yours and the last 2 columns are my value (in MB) and your value respectively.

The only ones that really stand out are the Active and Active(anon). Doesn't really tell us much unfortunately. I'll see if the zoneinfo comparison sheds any light.

If you can, get busybox on that sucker so you can get the ps output :)
 
jkc120 said:
I'll get the zoneinfo comparison shortly, but here's a comparison of my meminfo and yours. At the time, my system memory usage as reported in that app was ~30 MB.

Code: 
         Active  +53.40  +13.94  382.97  436.37
   Active(anon) +102.95  +34.21  300.89  403.84
   Active(file)  -49.55  -60.36   82.08   32.54
      AnonPages +102.98  +34.23  300.87  403.85
        Buffers  -13.11  -90.75   14.45    1.34
         Cached  -32.73  -20.78  157.47  124.75
       HighFree   -8.02  -76.42   10.49    2.47
       Inactive   +3.65   +4.12   88.74   92.39
 Inactive(anon)   -0.24  -47.33    0.51    0.27
 Inactive(file)   +3.89   +4.41   88.23   92.12
    KernelStack   -2.28  -34.72    6.57    4.29
        LowFree   -3.77  -14.96   25.18   21.41
         Mapped  -68.98  -34.15  201.96  132.98
        MemFree  -11.78  -33.03   35.67   23.89
     PageTables   -3.41  -35.57    9.60    6.18
   SReclaimable   -4.67  -52.66    8.87    4.20
     SUnreclaim   +0.45   +4.61    9.84   10.29
          Shmem   -0.24  -43.26    0.55    0.31
           Slab   -4.22  -22.55   18.71   14.49
   VmallocChunk   -0.93   -0.14  651.14  650.21
    VmallocUsed  -14.75  -23.87   61.79   47.04
The first column is the entry in the meminfo file, the 2nd is the change (in MB) from mine to yours, the 3rd is the % change from mine to yours and the last 2 columns are my value (in MB) and your value respectively.

The only ones that really stand out are the Active and Active(anon). Doesn't really tell us much unfortunately. I'll see if the zoneinfo comparison sheds any light.

If you can, get busybox on that sucker so you can get the ps output :)
Click to expand...

Thanks a lot for taking the time to compare and write that out. I think I may have somewhat figured it out though.

I went into my "Superuser" app to see what apps had root permissions and "system" was among them. So I toggled it to "deny" and rebooted the phone. Upon reboot I got a prompt that "System" was requesting root permissions. I denied that too and left the box unchecked so it will prompt me again if it tries to access root again (to see what actions trigger it later). So far "System" has stayed in the 50-60 MB range for usage, but it's only been a few hours since that reboot. I'll have to monitor it for the next day or so to see if that keeps it down.

I installed Busybox though, but I'm not sure how to run that command or use Busybox. All I know is that it's installed to my /system/xbin directory and that it doesn't appear in my app drawer (just the installer by Jrummy that I used to install it). I tried running that command in my terminal emulator and it just spit out a whole lot of gibberish that didn't seem coherent or legible at all. Is that what I'm supposed to do?

I've noticed something since monitoring this "system" usage for the past week too. Once it reaches around the 300 MB spot in RAM usage, it causes my phone to reboot. I've seen it consistently do this 3 times in the past week or two. So there's definitely like a memory leak or something because it slowly but surely increases. Every time I went back to check it after a few mins of usage, it would have raised by at least 1-2 MBs. I don't think I've ever seen it lower in usage.

So could anyone that's reading this thread (that's rooted) check their Superuser app and see if this "system" app is listed and what permissions it has? I'm hoping that I've found out that it's not supposed to have root access. But then that begs the question; what is "system" and why is it requesting root access? If it's a legit service; should it still have root access?

I'm hoping to see that no one else here has it listed in their Superuser app so that I can leave it as it. I just hope it's not something that requires root access and will interfere with the OS at some point if it don't have the permissions it requires.

Thanks for all the help so far!
 
Ok, denying "System" root permissions doesn't seem to have helped any. But I just figured something else out that is causing "system" RAM usage to increase.

EVERY time I unlock/wake up my phone, it increases by ~1-1.5 MB. I just did it like 15 times in a row and it went from ~120 MBs to ~135 MBs in usage. I would leave System Panel open, turn off my screen, immediately turn it back on, unlock the phone and observe "System" RAM usage increase by around 1 MB. I left System Panel open so when I unlocked my phone, I would see it first thing when it unlocks and every time it raises "System" RAM usage by around 1 MB.

WTF.. someone please point me to a dev or help me out with this. I would just flash it back to stock or try a new ROM, but I would like to actually see what's causing this first if possible so I can avoid it from happening in the future.

Thanks again!

Edit: Alright, I just crashed my phone by constantly locking/unlocking it. It crashed right around when "System" got to 286 MBs of usage, then did a soft reboot (back to the boot animation as opposed to the Google screen with the lock at the bottom), then upon unlocking it went through the "Activation" setup where it just tells you to wait for a few seconds, then I hit the finish button to return to my launcher. After the crash it starts out at 48.9 MBs usage. Even right after a fresh reboot, System is exhibiting the exact same behavior of increasing in RAM usage after every unlock. Good stuff, eh?
 
PhilosoRaptor said:
Ok, denying "System" root permissions doesn't seem to have helped any. But I just figured something else out that is causing "system" RAM usage to increase.

EVERY time I unlock/wake up my phone, it increases by ~1-1.5 MB. I just did it like 15 times in a row and it went from ~120 MBs to ~135 MBs in usage. I would leave System Panel open, turn off my screen, immediately turn it back on, unlock the phone and observe "System" RAM usage increase by around 1 MB. I left System Panel open so when I unlocked my phone, I would see it first thing when it unlocks and every time it raises "System" RAM usage by around 1 MB.

WTF.. someone please point me to a dev or help me out with this. I would just flash it back to stock or try a new ROM, but I would like to actually see what's causing this first if possible so I can avoid it from happening in the future.

Thanks again!

Edit: Alright, I just crashed my phone by constantly locking/unlocking it. It crashed right around when "System" got to 286 MBs of usage, then did a soft reboot (back to the boot animation as opposed to the Google screen with the lock at the bottom), then upon unlocking it went through the "Activation" setup where it just tells you to wait for a few seconds, then I hit the finish button to return to my launcher. After the crash it starts out at 48.9 MBs usage. Even right after a fresh reboot, System is exhibiting the exact same behavior of increasing in RAM usage after every unlock. Good stuff, eh?
Click to expand...

What ROM is this? I would contact the dev(s) and at least report the memory leak/bug.
 
jkc120 said:
What ROM is this? I would contact the dev(s) and at least report the memory leak/bug.
Click to expand...

Well, like I said in my OP, I've seen it across at least two different ROMs based on two different versions of ICS. I saw it on Codename Android (4.0.3 based) and now I'm seeing it on Liquid 1.25. I don't think it's the ROM because it's a pretty popular ROM and I'm sure other people would be reporting this too. I wouldn't think this bug would coincidentally be in both of those ROMs either. I have posted this in the Liquid thread here and on XDA though just to get more visibility.

So I think this comes down to being a bad or malicious app like Colchirosaid above. I wanted to diagnose this before I wiped the phone, but it seems like it's not possible at this point. I think I will just have to wipe the phone and then only install Titanium and System Panel, then individually install every app/service I have until I can replicate the bug by constantly locking/unlocking my phone. I'm just glad I found a way to trigger it like that so I can (more) rapidly observe the memory usage.

I have a feeling it's something stupid like my Weatherbug service or some other obscure app that I've had on my phone forever. I haven't installed any new apps in the last couple months that I can think of outside of games.

Thanks again for your input :).
 
Get OS Monitor from the market. Go into its settings and enable root mode. You can press on various instances of "system" and see what is running. There's also a real time logcat that's built in to get a flavor for what's going on.
 
iowabowtech said:
Get OS Monitor from the market. Go into its settings and enable root mode. You can press on various instances of "system" and see what is running. There's also a real time logcat that's built in to get a flavor for what's going on.
Click to expand...

Here's what I get when I export the log with OS Monitor:

Code: 
04/16/2012 05:16:11 [WARNING] Freezing remaining freezable tasks ... (elapsed 0.02 seconds) done.
04/16/2012 05:16:11 [DEBUG] PM: Entering mem sleep
04/16/2012 05:16:11 [INFORMATION] twl_rtc_set_alarm(): 2012-04-16 17:52:48 on
04/16/2012 05:16:11 [INFORMATION] PM: suspend of devices complete after 19.745 msecs
04/16/2012 05:16:11 [INFORMATION] PM: late suspend of devices complete after 1.403 msecs
04/16/2012 05:16:11 [WARNING] Disabling non-boot CPUs ...
04/16/2012 05:16:11 [INFORMATION] Resume caused by wakeup I/O pad: CONTROL_WKUP_PADCONF_WAKEUPEVENT_0[9]
04/16/2012 05:16:11 [INFORMATION] Resume caused by wakeup I/O pad: CONTROL_WKUP_PADCONF_WAKEUPEVENT_0[12
04/16/2012 05:16:11 [ERROR] Successfully put all powerdomains to target state
04/16/2012 05:16:11 [INFORMATION] Suspended for 0.047 seconds
04/16/2012 05:16:11 [INFORMATION] Enabling non-boot CPUs ...
04/16/2012 05:16:11 [INFORMATION] [imoseyon] cpufreq policy max set to 1.2Ghz at boot
04/16/2012 05:16:11 [INFORMATION] CPU1 is up
04/16/2012 05:16:11 [INFORMATION] PM: early resume of devices complete after 1.800 msecs
04/16/2012 05:16:11 [INFORMATION] wakeup wake lock: gpio_input
04/16/2012 05:16:11 [ERROR] twl_rtc_alarm_verify Now=2012-04-16 17:50:20 Alarm=2012-04-16 17:52:48 Delta=-148 sec
04/16/2012 05:16:11 [INFORMATION] PM: resume of devices complete after 214.141 msecs
04/16/2012 05:16:11 [DEBUG] PM: Finishing wakeup.
04/16/2012 05:16:11 [WARNING] Restarting tasks ... 
04/16/2012 05:16:11 [ERROR] hub 2-0:1.0: activate --> -22
04/16/2012 05:16:11 [WARNING] done.
04/16/2012 05:16:11 [INFORMATION] suspend: exit suspend, ret = 0 (2012-04-16 17:50:19.767624473 UTC)
04/16/2012 05:16:11 [INFORMATION] active wake lock modem_usb_suspend_block
04/16/2012 05:16:11 [INFORMATION] active wake lock modem_usb_gpio_wake, time left 55
04/16/2012 05:16:11 [INFORMATION] PM: Syncing filesystems ... done.
04/16/2012 05:16:11 [DEBUG] PM: Preparing system for mem slee
04/16/2012 05:16:11 [INFORMATION] twl6030_irq_pm_notifier(): wakeirqs=1 irq_wake_enabled=1
04/16/2012 05:16:11 [WARNING] Freezing user space processes ... (elapsed 0.02 seconds) done.
04/16/2012 05:16:11 [WARNING] Freezing remaining freezable tasks ... (elapsed 0.02 seconds) done
04/16/2012 05:16:11 [DEBUG] PM: Entering mem sleep
04/16/2012 05:16:11 [INFORMATION] twl_rtc_set_alarm(): 2012-04-16 17:52:47 on
04/16/2012 05:16:11 [INFORMATION] PM: suspend of devices complete after 19.683 msecs
04/16/2012 05:16:11 [INFORMATION] PM: late suspend of devices complete after 1.464 msecs
04/16/2012 05:16:11 [WARNING] Disabling non-boot CPUs ...
04/16/2012 05:16:11 [INFORMATION] Resume caused by wakeup I/O pad: CONTROL_WKUP_PADCONF_WAKEUPEVENT_0[9]
04/16/2012 05:16:11 [INFORMATION] Resume caused by wakeup I/O pad: CONTROL_WKUP_PADCONF_WAKEUPEVENT_0[12
04/16/2012 05:16:11 [ERROR] Successfully put all powerdomains to target state
04/16/2012 05:16:11 [INFORMATION] Suspended for 0.073 seconds
04/16/2012 05:16:11 [INFORMATION] Enabling non-boot CPUs ...
04/16/2012 05:16:11 [INFORMATION] [imoseyon] cpufreq policy max set to 1.2Ghz at boot
04/16/2012 05:16:11 [INFORMATION] CPU1 is up
04/16/2012 05:16:11 [INFORMATION] PM: early resume of devices complete after 1.464 msecs
04/16/2012 05:16:11 [INFORMATION] wakeup wake lock: gpio_input
04/16/2012 05:16:12 [ERROR] twl_rtc_alarm_verify Now=2012-04-16 17:50:21 Alarm=2012-04-16 17:52:47 Delta=-146 sec
04/16/2012 05:16:12 [INFORMATION] PM: resume of devices complete after 226.928 msecs
04/16/2012 05:16:12 [DEBUG] PM: Finishing wakeup.
04/16/2012 05:16:12 [WARNING] Restarting tasks ... 
04/16/2012 05:16:12 [ERROR] hub 2-0:1.0: activate --> -22
04/16/2012 05:16:12 [WARNING] done.
04/16/2012 05:16:12 [INFORMATION] suspend: exit suspend, ret = 0 (2012-04-16 17:50:20.726486769 UTC)
04/16/2012 05:16:12 [INFORMATION] active wake lock KeyEvents
04/16/2012 05:16:12 [INFORMATION] active wake lock modem_usb_suspend_block
04/16/2012 05:16:12 [INFORMATION] active wake lock modem_usb_gpio_wake, time left 54
04/16/2012 05:16:12 [INFORMATION] PM: Syncing filesystems ... done.
04/16/2012 05:16:12 [DEBUG] PM: Preparing system for mem slee
04/16/2012 05:16:12 [INFORMATION] twl6030_irq_pm_notifier(): wakeirqs=1 irq_wake_enabled=1
04/16/2012 05:16:12 [WARNING] Freezing user space processes ... (elapsed 0.02 seconds) done.
04/16/2012 05:16:12 [WARNING] Freezing remaining freezable tasks ... (elapsed 0.02 seconds) done
04/16/2012 05:16:12 [DEBUG] PM: Entering mem sleep
04/16/2012 05:16:12 [INFORMATION] twl_rtc_set_alarm(): 2012-04-16 17:52:47 on
04/16/2012 05:16:12 [INFORMATION] PM: suspend of devices complete after 19.592 msecs
04/16/2012 05:16:12 [INFORMATION] active wake lock gpio_input
04/16/2012 05:16:12 [ERROR] pm_noirq_op(): platform_pm_suspend_noirq+0x0/0x40 returns -11
04/16/2012 05:16:12 [ERROR] PM: Device power.0 failed to suspend late: error -11
04/16/2012 05:16:12 [INFORMATION] PM: early resume of devices complete after 1.739 msecs
04/16/2012 05:16:12 [ERROR] PM: Some devices failed to power down
04/16/2012 05:16:12 [ERROR] twl_rtc_alarm_verify Now=2012-04-16 17:50:22 Alarm=2012-04-16 17:52:47 Delta=-145 sec
04/16/2012 05:16:13 [INFORMATION] PM: resume of devices complete after 230.072 msecs
04/16/2012 05:16:13 [DEBUG] PM: Finishing wakeup.
04/16/2012 05:16:13 [WARNING] Restarting tasks ... done.
04/16/2012 05:16:13 [INFORMATION] suspend: exit suspend, ret = -11 (2012-04-16 17:50:21.582504836 UTC)
04/16/2012 05:16:13 [INFORMATION] active wake lock KeyEvents
04/16/2012 05:16:13 [INFORMATION] active wake lock modem_usb_suspend_block
04/16/2012 05:16:13 [INFORMATION] active wake lock musb_autosuspend_wake_lock
04/16/2012 05:16:13 [INFORMATION] active wake lock modem_usb_gpio_wake, time left 55
04/16/2012 05:16:13 [ERROR] hub 2-0:1.0: activate --> -22
04/16/2012 05:16:13 [INFORMATION] PM: Syncing filesystems ... done.
04/16/2012 05:16:13 [DEBUG] PM: Preparing system for mem slee
04/16/2012 05:16:13 [INFORMATION] twl6030_irq_pm_notifier(): wakeirqs=1 irq_wake_enabled=1
04/16/2012 05:16:13 [WARNING] Freezing user space processes ... (elapsed 0.02 seconds) done.
04/16/2012 05:16:13 [WARNING] Freezing remaining freezable tasks ... 
04/16/2012 05:16:13 [INFORMATION] active wake lock gpio_input
04/16/2012 05:16:13 [WARNING] 
04/16/2012 05:16:13 [ERROR] Freezing of tasks  aborted
04/16/2012 05:16:13 [WARNING] 
04/16/2012 05:16:13 [WARNING] Restarting tasks ... done.
04/16/2012 05:16:13 [INFORMATION] suspend: exit suspend, ret = -16 (2012-04-16 17:50:22.147263136 UTC)
04/16/2012 05:16:13 [INFORMATION] PM: Syncing filesystems ... done.
04/16/2012 05:16:13 [DEBUG] PM: Preparing system for mem slee
04/16/2012 05:16:13 [INFORMATION] twl6030_irq_pm_notifier(): wakeirqs=1 irq_wake_enabled=1
04/16/2012 05:16:13 [WARNING] Freezing user space processes ... (elapsed 0.02 seconds) done.
04/16/2012 05:16:13 [WARNING] Freezing remaining freezable tasks ... (elapsed 0.02 seconds) done.
04/16/2012 05:16:13 [DEBUG] PM: Entering mem sleep
04/16/2012 05:16:13 [INFORMATION] twl_rtc_set_alarm(): 2012-04-16 17:52:47 on
04/16/2012 05:16:13 [INFORMATION] PM: suspend of devices complete after 19.317 msecs
04/16/2012 05:16:13 [INFORMATION] PM: late suspend of devices complete after 1.373 msecs
04/16/2012 05:16:13 [WARNING] Disabling non-boot CPUs ...
04/16/2012 05:16:13 [INFORMATION] Resume caused by wakeup I/O pad: CONTROL_WKUP_PADCONF_WAKEUPEVENT_0[9]
04/16/2012 05:16:13 [INFORMATION] Resume caused by wakeup I/O pad: CONTROL_WKUP_PADCONF_WAKEUPEVENT_0[12
04/16/2012 05:16:13 [ERROR] Successfully put all powerdomains to target state
04/16/2012 05:16:13 [INFORMATION] Suspended for 0.091 seconds
04/16/2012 05:16:13 [INFORMATION] Enabling non-boot CPUs ...
04/16/2012 05:16:13 [INFORMATION] [imoseyon] cpufreq policy max set to 1.2Ghz at boot
04/16/2012 05:16:13 [INFORMATION] CPU1 is up
04/16/2012 05:16:13 [INFORMATION] PM: early resume of devices complete after 1.770 msecs
04/16/2012 05:16:13 [INFORMATION] wakeup wake lock: gpio_input
04/16/2012 05:16:13 [ERROR] twl_rtc_alarm_verify Now=2012-04-16 17:50:23 Alarm=2012-04-16 17:52:47 Delta=-144 sec
04/16/2012 05:16:14 [INFORMATION] PM: resume of devices complete after 227.294 msecs
04/16/2012 05:16:14 [DEBUG] PM: Finishing wakeup.
04/16/2012 05:16:14 [WARNING] Restarting tasks ... 
04/16/2012 05:16:14 [ERROR] hub 2-0:1.0: activate --> -22
04/16/2012 05:16:14 [WARNING] done.
04/16/2012 05:16:14 [INFORMATION] suspend: exit suspend, ret = 0 (2012-04-16 17:50:22.629410346 UTC)
04/16/2012 05:16:14 [INFORMATION] active wake lock KeyEvents
04/16/2012 05:16:14 [INFORMATION] active wake lock modem_usb_suspend_block
04/16/2012 05:16:14 [INFORMATION] active wake lock modem_usb_gpio_wake, time left 54
04/16/2012 05:16:14 [INFORMATION] PM: Syncing filesystems ... done.
04/16/2012 05:16:14 [DEBUG] PM: Preparing system for mem slee
04/16/2012 05:16:14 [INFORMATION] twl6030_irq_pm_notifier(): wakeirqs=1 irq_wake_enabled=1
04/16/2012 05:16:14 [WARNING] Freezing user space processes ... (elapsed 0.02 seconds) done.
04/16/2012 05:16:14 [WARNING] Freezing remaining freezable tasks ... (elapsed 0.02 seconds) done
04/16/2012 05:16:14 [DEBUG] PM: Entering mem sleep
04/16/2012 05:16:14 [INFORMATION] twl_rtc_set_alarm(): 2012-04-16 17:52:47 on
04/16/2012 05:16:14 [INFORMATION] PM: suspend of devices complete after 19.683 msecs
04/16/2012 05:16:14 [INFORMATION] PM: late suspend of devices complete after 1.403 msecs
04/16/2012 05:16:14 [WARNING] Disabling non-boot CPUs ...
04/16/2012 05:16:14 [INFORMATION] Resume caused by wakeup I/O pad: CONTROL_WKUP_PADCONF_WAKEUPEVENT_0[9]
04/16/2012 05:16:14 [ERROR] Successfully put all powerdomains to target state
04/16/2012 05:16:14 [INFORMATION] Suspended for 0.282 seconds
04/16/2012 05:16:14 [INFORMATION] Enabling non-boot CPUs ...
04/16/2012 05:16:14 [INFORMATION] [imoseyon] cpufreq policy max set to 1.2Ghz at boot
04/16/2012 05:16:14 [INFORMATION] CPU1 is up
04/16/2012 05:16:14 [INFORMATION] PM: early resume of devices complete after 1.983 msecs
04/16/2012 05:16:14 [INFORMATION] wakeup wake lock: gpio_input
04/16/2012 05:16:14 [ERROR] twl_rtc_alarm_verify Now=2012-04-16 17:50:24 Alarm=2012-04-16 17:52:47 Delta=-143 sec
04/16/2012 05:16:14 [INFORMATION] PM: resume of devices complete after 216.094 msecs
04/16/2012 05:16:14 [DEBUG] PM: Finishing wakeup.
04/16/2012 05:16:14 [WARNING] Restarting tasks ... 
04/16/2012 05:16:14 [ERROR] hub 2-0:1.0: activate --> -22
04/16/2012 05:16:14 [WARNING] done.
04/16/2012 05:16:14 [INFORMATION] suspend: exit suspend, ret = 0 (2012-04-16 17:50:23.770523618 UTC)
04/16/2012 05:16:14 [INFORMATION] active wake lock modem_usb_suspend_block
04/16/2012 05:16:14 [INFORMATION] active wake lock modem_usb_gpio_wake, time left 54
04/16/2012 05:16:15 [INFORMATION] PM: Syncing filesystems ... done.
04/16/2012 05:16:15 [DEBUG] PM: Preparing system for mem slee
04/16/2012 05:16:15 [INFORMATION] twl6030_irq_pm_notifier(): wakeirqs=1 irq_wake_enabled=1
04/16/2012 05:16:15 [WARNING] Freezing user space processes ... (elapsed 0.02 seconds) done.
04/16/2012 05:16:15 [WARNING] Freezing remaining freezable tasks ... (elapsed 0.02 seconds) done
04/16/2012 05:16:15 [DEBUG] PM: Entering mem sleep
04/16/2012 05:16:15 [INFORMATION] twl_rtc_set_alarm(): 2012-04-16 17:52:47 on
04/16/2012 05:16:15 [INFORMATION] PM: suspend of devices complete after 20.172 msecs
04/16/2012 05:16:15 [INFORMATION] PM: late suspend of devices complete after 1.403 msecs
04/16/2012 05:16:15 [WARNING] Disabling non-boot CPUs ...
04/16/2012 05:16:15 [INFORMATION] Resume caused by wakeup I/O pad: CONTROL_WKUP_PADCONF_WAKEUPEVENT_0[9]
04/16/2012 05:16:15 [INFORMATION] Resume caused by wakeup I/O pad: CONTROL_WKUP_PADCONF_WAKEUPEVENT_0[12
04/16/2012 05:16:15 [ERROR] Successfully put all powerdomains to target state
04/16/2012 05:16:15 [INFORMATION] Suspended for 0.898 seconds
04/16/2012 05:16:15 [INFORMATION] Enabling non-boot CPUs ...
04/16/2012 05:16:15 [INFORMATION] [imoseyon] cpufreq policy max set to 1.2Ghz at boot
04/16/2012 05:16:15 [INFORMATION] CPU1 is up
04/16/2012 05:16:15 [INFORMATION] PM: early resume of devices complete after 1.464 msecs
04/16/2012 05:16:15 [INFORMATION] wakeup wake lock: gpio_input
04/16/2012 05:16:15 [ERROR] twl_rtc_alarm_verify Now=2012-04-16 17:50:25 Alarm=2012-04-16 17:52:47 Delta=-142 sec
04/16/2012 05:16:15 [INFORMATION] PM: resume of devices complete after 217.224 msecs
04/16/2012 05:16:15 [DEBUG] PM: Finishing wakeup.
04/16/2012 05:16:15 [WARNING] Restarting tasks ... 
04/16/2012 05:16:15 [ERROR] hub 2-0:1.0: activate --> -22
04/16/2012 05:16:15 [WARNING] done.
04/16/2012 05:16:15 [INFORMATION] suspend: exit suspend, ret = 0 (2012-04-16 17:50:25.551346363 UTC)
04/16/2012 05:16:15 [INFORMATION] active wake lock gpio_input
04/16/2012 05:16:15 [INFORMATION] active wake lock KeyEvents
04/16/2012 05:16:15 [INFORMATION] active wake lock modem_usb_suspend_block
04/16/2012 05:16:15 [INFORMATION] active wake lock modem_usb_gpio_wake, time left 55
04/16/2012 05:16:16 [INFORMATION] PM: Syncing filesystems ... done.
04/16/2012 05:16:16 [DEBUG] PM: Preparing system for mem slee
04/16/2012 05:16:16 [INFORMATION] twl6030_irq_pm_notifier(): wakeirqs=1 irq_wake_enabled=1
04/16/2012 05:16:16 [WARNING] Freezing user space processes ... (elapsed 0.03 seconds) done.
04/16/2012 05:16:16 [WARNING] Freezing remaining freezable tasks ... (elapsed 0.02 seconds) done
04/16/2012 05:16:16 [DEBUG] PM: Entering mem sleep
04/16/2012 05:16:16 [INFORMATION] twl_rtc_set_alarm(): 2012-04-16 17:52:47 on
04/16/2012 05:16:16 [INFORMATION] PM: suspend of devices complete after 19.836 msecs
04/16/2012 05:16:16 [INFORMATION] PM: late suspend of devices complete after 1.373 msecs
04/16/2012 05:16:16 [WARNING] Disabling non-boot CPUs ...
04/16/2012 05:16:16 [INFORMATION] Resume caused by wakeup I/O pad: CONTROL_WKUP_PADCONF_WAKEUPEVENT_0[9]
04/16/2012 05:16:16 [INFORMATION] Resume caused by wakeup I/O pad: CONTROL_WKUP_PADCONF_WAKEUPEVENT_0[12
04/16/2012 05:16:16 [ERROR] Successfully put all powerdomains to target state
04/16/2012 05:16:16 [INFORMATION] Suspended for 21.251 seconds
04/16/2012 05:16:16 [INFORMATION] Enabling non-boot CPUs ...
04/16/2012 05:16:16 [INFORMATION] [imoseyon] cpufreq policy max set to 1.2Ghz at boot
04/16/2012 05:16:16 [INFORMATION] CPU1 is up
04/16/2012 05:16:16 [INFORMATION] PM: early resume of devices complete after 1.983 msecs
04/16/2012 05:16:16 [INFORMATION] wakeup wake lock: gpio_input
04/16/2012 05:16:16 [ERROR] twl_rtc_alarm_verify Now=2012-04-16 17:50:48 Alarm=2012-04-16 17:52:47 Delta=-119 sec
04/16/2012 05:16:16 [INFORMATION] PM: resume of devices complete after 217.010 msecs
04/16/2012 05:16:16 [DEBUG] PM: Finishing wakeup.
04/16/2012 05:16:16 [WARNING] Restarting tasks ... 
04/16/2012 05:16:16 [ERROR] hub 2-0:1.0: activate --> -22
04/16/2012 05:16:16 [WARNING] done.
04/16/2012 05:16:16 [INFORMATION] suspend: exit suspend, ret = 0 (2012-04-16 17:50:47.678329996 UTC)
04/16/2012 05:16:16 [INFORMATION] active wake lock KeyEvents
04/16/2012 05:16:16 [INFORMATION] active wake lock modem_usb_suspend_block
04/16/2012 05:16:16 [INFORMATION] active wake lock modem_usb_gpio_wake, time left 55
04/16/2012 05:16:17 [INFORMATION] PM: Syncing filesystems ... done.
04/16/2012 05:16:17 [DEBUG] PM: Preparing system for mem slee
04/16/2012 05:16:17 [INFORMATION] twl6030_irq_pm_notifier(): wakeirqs=1 irq_wake_enabled=1
04/16/2012 05:16:17 [WARNING] Freezing user space processes ... (elapsed 0.02 seconds) done.
04/16/2012 05:16:17 [WARNING] Freezing remaining freezable tasks ... (elapsed 0.02 seconds) done
04/16/2012 05:16:17 [DEBUG] PM: Entering mem sleep
04/16/2012 05:16:17 [INFORMATION] twl_rtc_set_alarm(): 2012-04-16 17:52:47 on
04/16/2012 05:16:17 [INFORMATION] PM: suspend of devices complete after 19.775 msecs
04/16/2012 05:16:17 [INFORMATION] PM: late suspend of devices complete after 1.403 msecs
04/16/2012 05:16:17 [WARNING] Disabling non-boot CPUs ...
04/16/2012 05:16:17 [INFORMATION] Wakeup IRQ 168 gpio_keys pending, suspend aborted
04/16/2012 05:16:17 [INFORMATION] Enabling non-boot CPUs ...
04/16/2012 05:16:17 [INFORMATION] [imoseyon] cpufreq policy max set to 1.2Ghz at boot
04/16/2012 05:16:17 [INFORMATION] CPU1 is up
04/16/2012 05:16:17 [INFORMATION] PM: early resume of devices complete after 1.647 msecs
04/16/2012 05:16:17 [INFORMATION] wakeup wake lock: gpio_input
04/16/2012 05:16:17 [ERROR] twl_rtc_alarm_verify Now=2012-04-16 17:50:48 Alarm=2012-04-16 17:52:47 Delta=-119 sec
04/16/2012 05:16:17 [INFORMATION] PM: resume of devices complete after 214.202 msecs
04/16/2012 05:16:17 [DEBUG] PM: Finishing wakeup.
04/16/2012 05:16:17 [WARNING] Restarting tasks ... done.
04/16/2012 05:16:17 [INFORMATION] suspend: exit suspend, ret = -16 (2012-04-16 17:50:48.546677164 UTC)
04/16/2012 05:16:17 [INFORMATION] active wake lock KeyEvents
04/16/2012 05:16:17 [INFORMATION] active wake lock modem_usb_suspend_block
04/16/2012 05:16:17 [INFORMATION] active wake lock musb_autosuspend_wake_lock
04/16/2012 05:16:17 [INFORMATION] active wake lock modem_usb_gpio_wake, time left 53
04/16/2012 05:16:17 [ERROR] hub 2-0:1.0: activate --> -22
04/16/2012 05:16:18 [INFORMATION] PM: Syncing filesystems ... done.
04/16/2012 05:16:18 [DEBUG] PM: Preparing system for mem slee
04/16/2012 05:16:18 [INFORMATION] twl6030_irq_pm_notifier(): wakeirqs=1 irq_wake_enabled=1
04/16/2012 05:16:18 [WARNING] Freezing user space processes ... (elapsed 0.02 seconds) done.
04/16/2012 05:16:18 [WARNING] Freezing remaining freezable tasks ... (elapsed 0.02 seconds) done.
04/16/2012 05:16:18 [DEBUG] PM: Entering mem sleep
04/16/2012 05:16:18 [INFORMATION] twl_rtc_set_alarm(): 2012-04-16 17:52:47 on
04/16/2012 05:16:18 [INFORMATION] PM: suspend of devices complete after 19.256 msecs
04/16/2012 05:16:18 [INFORMATION] PM: late suspend of devices complete after 1.373 msecs
04/16/2012 05:16:18 [WARNING] Disabling non-boot CPUs ...
04/16/2012 05:16:18 [INFORMATION] Resume caused by wakeup I/O pad: CONTROL_WKUP_PADCONF_WAKEUPEVENT_0[9]
04/16/2012 05:16:18 [INFORMATION] Resume caused by wakeup I/O pad: CONTROL_WKUP_PADCONF_WAKEUPEVENT_0[12
04/16/2012 05:16:18 [ERROR] Successfully put all powerdomains to target state
04/16/2012 05:16:18 [INFORMATION] Suspended for 0.112 seconds
04/16/2012 05:16:18 [INFORMATION] Enabling non-boot CPUs ...
04/16/2012 05:16:18 [INFORMATION] [imoseyon] cpufreq policy max set to 1.2Ghz at boot
04/16/2012 05:16:18 [INFORMATION] CPU1 is up
04/16/2012 05:16:18 [INFORMATION] PM: early resume of devices complete after 1.861 msecs
04/16/2012 05:16:18 [INFORMATION] wakeup wake lock: gpio_input
04/16/2012 05:16:18 [ERROR] twl_rtc_alarm_verify Now=2012-04-16 17:50:49 Alarm=2012-04-16 17:52:47 Delta=-118 sec
04/16/2012 05:16:18 [INFORMATION] PM: resume of devices complete after 230.773 msecs
04/16/2012 05:16:18 [DEBUG] PM: Finishing wakeup.
04/16/2012 05:16:18 [WARNING] Restarting tasks ... 
04/16/2012 05:16:18 [ERROR] hub 2-0:1.0: activate --> -22
04/16/2012 05:16:18 [WARNING] done.
04/16/2012 05:16:18 [INFORMATION] suspend: exit suspend, ret = 0 (2012-04-16 17:50:49.522720856 UTC)
04/16/2012 05:16:18 [INFORMATION] active wake lock KeyEvents
04/16/2012 05:16:18 [INFORMATION] active wake lock modem_usb_suspend_block
04/16/2012 05:16:18 [INFORMATION] active wake lock musb_autosuspend_wake_lock
04/16/2012 05:16:18 [INFORMATION] active wake lock modem_usb_gpio_wake, time left 55
04/16/2012 05:16:18 [INFORMATION] PM: Syncing filesystems ... done.
04/16/2012 05:16:18 [DEBUG] PM: Preparing system for mem slee
04/16/2012 05:16:18 [INFORMATION] twl6030_irq_pm_notifier(): wakeirqs=1 irq_wake_enabled=1
04/16/2012 05:16:18 [WARNING] Freezing user space processes ... (elapsed 0.02 seconds) done.
04/16/2012 05:16:18 [WARNING] Freezing remaining freezable tasks ... 
04/16/2012 05:16:18 [INFORMATION] active wake lock gpio_input
04/16/2012 05:16:18 [WARNING] 
04/16/2012 05:16:18 [ERROR] Freezing of tasks  aborted
04/16/2012 05:16:18 [WARNING] 
04/16/2012 05:16:18 [WARNING] Restarting tasks ... done.
04/16/2012 05:16:18 [INFORMATION] suspend: exit suspend, ret = -16 (2012-04-16 17:50:50.101059479 UTC)
04/16/2012 05:16:18 [INFORMATION] PM: Syncing filesystems ... done.
04/16/2012 05:16:19 [DEBUG] PM: Preparing system for mem slee
04/16/2012 05:16:19 [INFORMATION] twl6030_irq_pm_notifier(): wakeirqs=1 irq_wake_enabled=1
04/16/2012 05:16:19 [WARNING] Freezing user space processes ... (elapsed 0.02 seconds) done.
04/16/2012 05:16:19 [WARNING] Freezing remaining freezable tasks ... (elapsed 0.02 seconds) done.
04/16/2012 05:16:19 [DEBUG] PM: Entering mem sleep
04/16/2012 05:16:19 [INFORMATION] twl_rtc_set_alarm(): 2012-04-16 17:52:47 on
04/16/2012 05:16:19 [INFORMATION] PM: suspend of devices complete after 19.500 msecs
04/16/2012 05:16:19 [INFORMATION] PM: late suspend of devices complete after 1.403 msecs
04/16/2012 05:16:19 [WARNING] Disabling non-boot CPUs ...
04/16/2012 05:16:19 [INFORMATION] Resume caused by IRQ 163, gpio_keys
04/16/2012 05:16:19 [ERROR] Successfully put all powerdomains to target state
04/16/2012 05:16:19 [INFORMATION] Suspended for 21.337 seconds
04/16/2012 05:16:19 [INFORMATION] wakeup wake lock: alarm
04/16/2012 05:16:19 [INFORMATION] Enabling non-boot CPUs ...
04/16/2012 05:16:19 [INFORMATION] [imoseyon] cpufreq policy max set to 1.2Ghz at boot
04/16/2012 05:16:19 [INFORMATION] CPU1 is up
04/16/2012 05:16:19 [INFORMATION] PM: early resume of devices complete after 1.800 msecs
04/16/2012 05:16:19 [ERROR] twl_rtc_alarm_verify Now=2012-04-16 17:51:12 Alarm=2012-04-16 17:52:47 Delta=-95 sec
04/16/2012 05:16:19 [INFORMATION] PM: resume of devices complete after 215.209 msecs
04/16/2012 05:16:19 [DEBUG] PM: Finishing wakeup.
04/16/2012 05:16:19 [WARNING] Restarting tasks ... 
04/16/2012 05:16:19 [ERROR] hub 2-0:1.0: activate --> -22
04/16/2012 05:16:19 [INFORMATION] request_suspend_state: wakeup (3->0) at 115725255493751 (2012-04-16 17:51:11.805801903 UTC
04/16/2012 05:16:19 [WARNING] done.
04/16/2012 05:16:19 [INFORMATION] suspend: exit suspend, ret = 0 (2012-04-16 17:51:11.827286279 UTC)
04/16/2012 05:16:19 [INFORMATION] active wake lock max17040-battery
04/16/2012 05:16:19 [INFORMATION] active wake lock PowerManagerService
04/16/2012 05:16:19 [INFORMATION] active wake lock main
04/16/2012 05:16:19 [INFORMATION] active wake lock modem_usb_suspend_block
04/16/2012 05:16:19 [INFORMATION] active wake lock modem_usb_gpio_wake, time left 52
04/16/2012 05:16:19 [INFORMATION] max17040 4-0036: online = 1 vcell = 4153750 soc = 99 status = 2 health = 1 temp = 330 charger status = 0
04/16/2012 05:16:19 [INFORMATION] DSSCOMP: dsscomp_late_resume
04/16/2012 05:16:19 [INFORMATION] [imoseyon] Powered on touchscreen
04/16/2012 05:16:34 [INFORMATION] request_suspend_state: sleep (0->3) at 115739970215431 (2012-04-16 17:51:26.520523583 UTC)
04/16/2012 05:16:34 [INFORMATION] [imoseyon] Powered off touchscreen
04/16/2012 05:16:34 [INFORMATION] DSSCOMP: dsscomp_early_suspend
04/16/2012 05:16:34 [INFORMATION] DSSCOMP: blanked screen
04/16/2012 05:16:36 [INFORMATION] PM: Syncing filesystems ... done.
04/16/2012 05:16:36 [DEBUG] PM: Preparing system for mem slee
04/16/2012 05:16:36 [INFORMATION] twl6030_irq_pm_notifier(): wakeirqs=1 irq_wake_enabled=1
04/16/2012 05:16:36 [WARNING] Freezing user space processes ... (elapsed 0.02 seconds) done.
04/16/2012 05:16:36 [WARNING] Freezing remaining freezable tasks ... (elapsed 0.02 seconds) done.
04/16/2012 05:16:36 [DEBUG] PM: Entering mem sleep
04/16/2012 05:16:36 [INFORMATION] twl_rtc_set_alarm(): 2012-04-16 17:51:57 on
04/16/2012 05:16:36 [INFORMATION] PM: suspend of devices complete after 15.899 msecs
04/16/2012 05:16:36 [INFORMATION] PM: late suspend of devices complete after 0.976 msecs
04/16/2012 05:16:36 [WARNING] Disabling non-boot CPUs ...
04/16/2012 05:16:36 [INFORMATION] omap2_gpio_set_edge_wakeup: aborted suspend due to gpio 62
04/16/2012 05:16:36 [INFORMATION] GIC returns spurious interrupt for resume IRQ
04/16/2012 05:16:36 [INFORMATION] PD_CORE curr=ON prev=ON logic=ON
04/16/2012 05:16:36 [INFORMATION] PD_L4_PER curr=ON prev=ON logic=ON
04/16/2012 05:16:36 [INFORMATION] PD_MPU curr=ON prev=ON logic=ON
04/16/2012 05:16:36 [INFORMATION] Powerdomain (core_pwrdm) didn't enter target state 1 Vs achieved state 3. current state 3
04/16/2012 05:16:36 [INFORMATION] Powerdomain (mpu_pwrdm) didn't enter target state 1 Vs achieved state 3. current state 3
04/16/2012 05:16:36 [INFORMATION] Powerdomain (l4per_pwrdm) didn't enter target state 1 Vs achieved state 3. current state 
04/16/2012 05:16:36 [ERROR] Could not enter target state in pm_suspend
04/16/2012 05:16:36 [INFORMATION] Suspended for 0.001 seconds
04/16/2012 05:16:36 [INFORMATION] Enabling non-boot CPUs ...
04/16/2012 05:16:36 [INFORMATION] [imoseyon] cpufreq policy max set to 1.2Ghz at boot
04/16/2012 05:16:36 [INFORMATION] CPU1 is up
04/16/2012 05:16:36 [INFORMATION] PM: early resume of devices complete after 1.068 msecs
04/16/2012 05:16:36 [INFORMATION] wakeup wake lock: musb_autosuspend_wake_lock
04/16/2012 05:16:36 [ERROR] twl_rtc_alarm_verify Now=2012-04-16 17:51:29 Alarm=2012-04-16 17:51:57 Delta=-28 sec
04/16/2012 05:16:36 [INFORMATION] PM: resume of devices complete after 226.654 msecs
04/16/2012 05:16:36 [DEBUG] PM: Finishing wakeup.
04/16/2012 05:16:36 [WARNING] Restarting tasks ... done.
04/16/2012 05:16:36 [INFORMATION] suspend: exit suspend, ret = 0 (2012-04-16 17:51:28.997238667 UTC)
04/16/2012 05:16:36 [ERROR] hub 2-0:1.0: activate --> -22
04/16/2012 05:16:37 [INFORMATION] PM: Syncing filesystems ... done.
04/16/2012 05:16:37 [DEBUG] PM: Preparing system for mem slee
04/16/2012 05:16:37 [INFORMATION] twl6030_irq_pm_notifier(): wakeirqs=1 irq_wake_enabled=1
04/16/2012 05:16:37 [WARNING] Freezing user space processes ... (elapsed 0.02 seconds) done.
04/16/2012 05:16:37 [WARNING] Freezing remaining freezable tasks ... (elapsed 0.02 seconds) done
04/16/2012 05:16:37 [DEBUG] PM: Entering mem sleep
04/16/2012 05:16:37 [INFORMATION] twl_rtc_set_alarm(): 2012-04-16 17:51:57 on
04/16/2012 05:16:37 [INFORMATION] PM: suspend of devices complete after 18.585 msecs
04/16/2012 05:16:37 [INFORMATION] PM: late suspend of devices complete after 1.403 msecs
04/16/2012 05:16:37 [WARNING] Disabling non-boot CPUs ...
04/16/2012 05:16:37 [INFORMATION] Resume caused by I/O pad: CONTROL_PADCONF_WAKEUPEVENT_0[9]
04/16/2012 05:16:37 [INFORMATION] Resume caused by I/O pad: CONTROL_PADCONF_WAKEUPEVENT_1[6]
04/16/2012 05:16:37 [ERROR] Successfully put all powerdomains to target state
04/16/2012 05:16:37 [INFORMATION] Suspended for 4.719 seconds
04/16/2012 05:16:37 [INFORMATION] Enabling non-boot CPUs ...
04/16/2012 05:16:37 [INFORMATION] [imoseyon] cpufreq policy max set to 1.2Ghz at boot
04/16/2012 05:16:37 [INFORMATION] CPU1 is up
04/16/2012 05:16:37 [INFORMATION] PM: early resume of devices complete after 1.342 msecs
04/16/2012 05:16:37 [INFORMATION] wakeup wake lock: musb_autosuspend_wake_lock
04/16/2012 05:16:37 [ERROR] twl_rtc_alarm_verify Now=2012-04-16 17:51:35 Alarm=2012-04-16 17:51:57 Delta=-22 sec
04/16/2012 05:16:37 [INFORMATION] PM: resume of devices complete after 227.325 msecs
04/16/2012 05:16:37 [DEBUG] PM: Finishing wakeup.
04/16/2012 05:16:37 [WARNING] Restarting tasks ... 
04/16/2012 05:16:37 [ERROR] hub 2-0:1.0: activate --> -22
04/16/2012 05:16:37 [WARNING] done.
04/16/2012 05:16:37 [INFORMATION] suspend: exit suspend, ret = 0 (2012-04-16 17:51:34.590195202 UTC)
04/16/2012 05:16:37 [INFORMATION] PM: Syncing filesystems ... done.
04/16/2012 05:16:37 [DEBUG] PM: Preparing system for mem slee
04/16/2012 05:16:37 [INFORMATION] twl6030_irq_pm_notifier(): wakeirqs=1 irq_wake_enabled=1
04/16/2012 05:16:37 [WARNING] Freezing user space processes ... (elapsed 0.02 seconds) done.
04/16/2012 05:16:37 [WARNING] Freezing remaining freezable tasks ... (elapsed 0.02 seconds) done
04/16/2012 05:16:37 [DEBUG] PM: Entering mem sleep
04/16/2012 05:16:37 [INFORMATION] twl_rtc_set_alarm(): 2012-04-16 17:51:56 on
04/16/2012 05:16:38 [INFORMATION] PM: suspend of devices complete after 19.775 msecs
04/16/2012 05:16:38 [INFORMATION] PM: late suspend of devices complete after 1.403 msecs
04/16/2012 05:16:38 [WARNING] Disabling non-boot CPUs ...
04/16/2012 05:16:38 [INFORMATION] Resume caused by I/O pad: CONTROL_PADCONF_WAKEUPEVENT_0[9]
04/16/2012 05:16:38 [INFORMATION] Resume caused by I/O pad: CONTROL_PADCONF_WAKEUPEVENT_1[6]
04/16/2012 05:16:38 [ERROR] Successfully put all powerdomains to target state
04/16/2012 05:16:38 [INFORMATION] Suspended for 9.104 seconds
04/16/2012 05:16:38 [INFORMATION] Enabling non-boot CPUs ...
04/16/2012 05:16:38 [INFORMATION] [imoseyon] cpufreq policy max set to 1.2Ghz at boot
04/16/2012 05:16:38 [INFORMATION] CPU1 is up
04/16/2012 05:16:38 [INFORMATION] PM: early resume of devices complete after 1.464 msecs
04/16/2012 05:16:38 [INFORMATION] wakeup wake lock: musb_autosuspend_wake_lock
04/16/2012 05:16:38 [ERROR] twl_rtc_alarm_verify Now=2012-04-16 17:51:44 Alarm=2012-04-16 17:51:56 Delta=-12 sec
04/16/2012 05:16:38 [INFORMATION] PM: resume of devices complete after 214.874 msecs
04/16/2012 05:16:38 [DEBUG] PM: Finishing wakeup.
04/16/2012 05:16:38 [WARNING] Restarting tasks ... 
04/16/2012 05:16:38 [ERROR] hub 2-0:1.0: activate --> -22
04/16/2012 05:16:38 [WARNING] done.
04/16/2012 05:16:38 [INFORMATION] suspend: exit suspend, ret = 0 (2012-04-16 17:51:44.552475466 UTC)
04/16/2012 05:16:38 [INFORMATION] PM: Syncing filesystems ... done.
04/16/2012 05:16:38 [DEBUG] PM: Preparing system for mem slee
04/16/2012 05:16:38 [INFORMATION] twl6030_irq_pm_notifier(): wakeirqs=1 irq_wake_enabled=1
04/16/2012 05:16:38 [WARNING] Freezing user space processes ... (elapsed 0.02 seconds) done.
04/16/2012 05:16:38 [WARNING] Freezing remaining freezable tasks ... (elapsed 0.02 seconds) done
04/16/2012 05:16:38 [DEBUG] PM: Entering mem sleep
04/16/2012 05:16:38 [INFORMATION] twl_rtc_set_alarm(): 2012-04-16 17:51:56 on
04/16/2012 05:16:38 [INFORMATION] PM: suspend of devices complete after 20.172 msecs
04/16/2012 05:16:38 [INFORMATION] PM: late suspend of devices complete after 1.373 msecs
04/16/2012 05:16:38 [WARNING] Disabling non-boot CPUs ...
04/16/2012 05:16:38 [INFORMATION] Resume caused by I/O pad: CONTROL_PADCONF_WAKEUPEVENT_0[9]
04/16/2012 05:16:38 [INFORMATION] Resume caused by I/O pad: CONTROL_PADCONF_WAKEUPEVENT_1[6]
04/16/2012 05:16:38 [ERROR] Successfully put all powerdomains to target state
04/16/2012 05:16:38 [INFORMATION] Suspended for 4.346 seconds
04/16/2012 05:16:38 [INFORMATION] Enabling non-boot CPUs ...
04/16/2012 05:16:38 [INFORMATION] [imoseyon] cpufreq policy max set to 1.2Ghz at boot
04/16/2012 05:16:38 [INFORMATION] CPU1 is up
04/16/2012 05:16:38 [INFORMATION] PM: early resume of devices complete after 2.044 msecs
04/16/2012 05:16:39 [INFORMATION] wakeup wake lock: musb_autosuspend_wake_lock
04/16/2012 05:16:39 [ERROR] twl_rtc_alarm_verify Now=2012-04-16 17:51:50 Alarm=2012-04-16 17:51:56 Delta=-6 sec
04/16/2012 05:16:39 [INFORMATION] PM: resume of devices complete after 233.276 msecs
04/16/2012 05:16:39 [DEBUG] PM: Finishing wakeup.
04/16/2012 05:16:39 [WARNING] Restarting tasks ... 
04/16/2012 05:16:39 [ERROR] hub 2-0:1.0: activate --> -22
04/16/2012 05:16:39 [WARNING] done.
04/16/2012 05:16:39 [INFORMATION] suspend: exit suspend, ret = 0 (2012-04-16 17:51:49.814285759 UTC)
04/16/2012 05:16:40 [INFORMATION] PM: Syncing filesystems ... done.
04/16/2012 05:16:40 [DEBUG] PM: Preparing system for mem slee
04/16/2012 05:16:40 [INFORMATION] twl6030_irq_pm_notifier(): wakeirqs=1 irq_wake_enabled=1
04/16/2012 05:16:40 [WARNING] Freezing user space processes ... (elapsed 0.02 seconds) done.
04/16/2012 05:16:40 [WARNING] Freezing remaining freezable tasks ... (elapsed 0.02 seconds) done
04/16/2012 05:16:40 [DEBUG] PM: Entering mem sleep
04/16/2012 05:16:40 [INFORMATION] twl_rtc_set_alarm(): 2012-04-16 17:51:57 on
04/16/2012 05:16:40 [INFORMATION] PM: suspend of devices complete after 20.202 msecs
04/16/2012 05:16:40 [INFORMATION] PM: late suspend of devices complete after 1.373 msecs
04/16/2012 05:16:40 [WARNING] Disabling non-boot CPUs ...
04/16/2012 05:16:40 [INFORMATION] Resume caused by IRQ 39, TWL6030-PIH
04/16/2012 05:16:40 [ERROR] Successfully put all powerdomains to target state
04/16/2012 05:16:40 [INFORMATION] Suspended for 5.535 seconds
04/16/2012 05:16:40 [INFORMATION] Enabling non-boot CPUs ...
04/16/2012 05:16:40 [INFORMATION] [imoseyon] cpufreq policy max set to 1.2Ghz at boot
04/16/2012 05:16:40 [INFORMATION] CPU1 is up
04/16/2012 05:16:40 [INFORMATION] PM: early resume of devices complete after 2.136 msecs
04/16/2012 05:16:40 [INFORMATION] wakeup wake lock: musb_autosuspend_wake_lock
04/16/2012 05:16:40 [ERROR] twl_rtc_alarm_verify Now=2012-04-16 17:51:57 Alarm=2012-04-16 17:51:57 Delta=0 sec
04/16/2012 05:16:40 [INFORMATION] PM: resume of devices complete after 214.996 msecs
04/16/2012 05:16:40 [DEBUG] PM: Finishing wakeup.
04/16/2012 05:16:40 [WARNING] Restarting tasks ... 
04/16/2012 05:16:40 [ERROR] hub 2-0:1.0: activate --> -22
04/16/2012 05:16:40 [WARNING] done.
04/16/2012 05:16:40 [INFORMATION] suspend: exit suspend, ret = 0 (2012-04-16 17:51:56.715927594 UTC)
04/16/2012 05:16:41 [INFORMATION] PM: Syncing filesystems ... done.
04/16/2012 05:16:41 [DEBUG] PM: Preparing system for mem slee
04/16/2012 05:16:41 [INFORMATION] twl6030_irq_pm_notifier(): wakeirqs=1 irq_wake_enabled=1
04/16/2012 05:16:41 [WARNING] Freezing user space processes ... (elapsed 0.02 seconds) done.
04/16/2012 05:16:41 [WARNING] Freezing remaining freezable tasks ... (elapsed 0.02 seconds) done
04/16/2012 05:16:41 [DEBUG] PM: Entering mem sleep
04/16/2012 05:16:41 [INFORMATION] twl_rtc_set_alarm(): 2012-04-16 17:52:47 on
04/16/2012 05:16:41 [INFORMATION] PM: suspend of devices complete after 19.531 msecs
04/16/2012 05:16:41 [INFORMATION] PM: late suspend of devices complete after 1.373 msecs
04/16/2012 05:16:41 [WARNING] Disabling non-boot CPUs ...
04/16/2012 05:16:41 [INFORMATION] Resume caused by I/O pad: CONTROL_PADCONF_WAKEUPEVENT_0[9]
04/16/2012 05:16:41 [INFORMATION] Resume caused by I/O pad: CONTROL_PADCONF_WAKEUPEVENT_1[6]
04/16/2012 05:16:41 [ERROR] Successfully put all powerdomains to target state
04/16/2012 05:16:41 [INFORMATION] Suspended for 1.322 seconds
04/16/2012 05:16:41 [INFORMATION] Enabling non-boot CPUs ...
04/16/2012 05:16:41 [INFORMATION] [imoseyon] cpufreq policy max set to 1.2Ghz at boot
04/16/2012 05:16:41 [INFORMATION] CPU1 is up
04/16/2012 05:16:41 [INFORMATION] PM: early resume of devices complete after 1.770 msecs
04/16/2012 05:16:41 [INFORMATION] wakeup wake lock: musb_autosuspend_wake_lock
04/16/2012 05:16:41 [ERROR] twl_rtc_alarm_verify Now=2012-04-16 17:51:59 Alarm=2012-04-16 17:52:47 Delta=-48 se
04/16/2012 05:16:41 [INFORMATION] PM: resume of devices complete after 232.879 msecs
04/16/2012 05:16:41 [DEBUG] PM: Finishing wakeup.
04/16/2012 05:16:41 [WARNING] Restarting tasks ... 
04/16/2012 05:16:41 [ERROR] hub 2-0:1.0: activate --> -22
04/16/2012 05:16:41 [WARNING] done.
04/16/2012 05:16:41 [INFORMATION] suspend: exit suspend, ret = 0 (2012-04-16 17:51:58.929581153 UTC)
04/16/2012 05:16:41 [INFORMATION] active wake lock secril_fmt-interface
04/16/2012 05:16:41 [INFORMATION] active wake lock modem_usb_suspend_block
04/16/2012 05:16:41 [INFORMATION] active wake lock modem_usb_gpio_wake, time left 55
04/16/2012 05:16:42 [INFORMATION] PM: Syncing filesystems ... done.
04/16/2012 05:16:42 [DEBUG] PM: Preparing system for mem slee
04/16/2012 05:16:42 [INFORMATION] twl6030_irq_pm_notifier(): wakeirqs=1 irq_wake_enabled=1
04/16/2012 05:16:42 [WARNING] Freezing user space processes ... (elapsed 0.03 seconds) done.
04/16/2012 05:16:42 [WARNING] Freezing remaining freezable tasks ... 
04/16/2012 05:16:42 [INFORMATION] active wake lock alarm, time left 639
04/16/2012 05:16:42 [WARNING] 
04/16/2012 05:16:42 [ERROR] Freezing of tasks  aborted
04/16/2012 05:16:42 [WARNING] 
04/16/2012 05:16:42 [WARNING] Restarting tasks ... done.
04/16/2012 05:16:42 [INFORMATION] suspend: exit suspend, ret = -16 (2012-04-16 17:52:00.023483747 UTC)
04/16/2012 05:16:42 [INFORMATION] active wake lock PowerManagerService
04/16/2012 05:16:42 [INFORMATION] PM: Syncing filesystems ... done.
04/16/2012 05:16:42 [DEBUG] PM: Preparing system for mem slee
04/16/2012 05:16:42 [INFORMATION] twl6030_irq_pm_notifier(): wakeirqs=1 irq_wake_enabled=1
04/16/2012 05:16:42 [WARNING] Freezing user space processes ... (elapsed 0.02 seconds) done.
04/16/2012 05:16:42 [WARNING] Freezing remaining freezable tasks ... (elapsed 0.02 seconds) done.
04/16/2012 05:16:42 [DEBUG] PM: Entering mem sleep
04/16/2012 05:16:42 [INFORMATION] twl_rtc_set_alarm(): 2012-04-16 17:52:47 on
04/16/2012 05:16:42 [INFORMATION] PM: suspend of devices complete after 15.899 msecs
04/16/2012 05:16:42 [INFORMATION] PM: late suspend of devices complete after 0.976 msecs
04/16/2012 05:16:42 [WARNING] Disabling non-boot CPUs ...
04/16/2012 05:16:42 [INFORMATION] Resume caused by I/O pad: CONTROL_PADCONF_WAKEUPEVENT_0[9]
04/16/2012 05:16:42 [INFORMATION] Resume caused by I/O pad: CONTROL_PADCONF_WAKEUPEVENT_1[6]
04/16/2012 05:16:42 [ERROR] Successfully put all powerdomains to target state
04/16/2012 05:16:42 [INFORMATION] Suspended for 0.559 seconds
04/16/2012 05:16:42 [INFORMATION] Enabling non-boot CPUs ...
04/16/2012 05:16:42 [INFORMATION] [imoseyon] cpufreq policy max set to 1.2Ghz at boot
04/16/2012 05:16:42 [INFORMATION] CPU1 is up
04/16/2012 05:16:42 [INFORMATION] PM: early resume of devices complete after 2.319 msecs
04/16/2012 05:16:42 [INFORMATION] wakeup wake lock: musb_autosuspend_wake_lock
04/16/2012 05:16:42 [ERROR] twl_rtc_alarm_verify Now=2012-04-16 17:52:01 Alarm=2012-04-16 17:52:47 Delta=-46 se
04/16/2012 05:16:43 [INFORMATION] PM: resume of devices complete after 229.553 msecs
04/16/2012 05:16:43 [DEBUG] PM: Finishing wakeup.
04/16/2012 05:16:43 [WARNING] Restarting tasks ... 
04/16/2012 05:16:43 [ERROR] hub 2-0:1.0: activate --> -22
04/16/2012 05:16:43 [WARNING] done.
04/16/2012 05:16:43 [INFORMATION] suspend: exit suspend, ret = 0 (2012-04-16 17:52:01.152084808 UTC)
04/16/2012 05:16:43 [INFORMATION] active wake lock modem_usb_suspend_block
04/16/2012 05:16:43 [INFORMATION] active wake lock musb_autosuspend_wake_lock
04/16/2012 05:16:43 [INFORMATION] active wake lock modem_usb_gpio_wake, time left 55
04/16/2012 05:16:43 [INFORMATION] max17040 4-0036: online = 1 vcell = 4017500 soc = 98 status = 2 health = 1 temp = 330 charger status = 0
04/16/2012 05:16:46 [INFORMATION] PM: Syncing filesystems ... done.
04/16/2012 05:16:46 [DEBUG] PM: Preparing system for mem slee
04/16/2012 05:16:46 [INFORMATION] twl6030_irq_pm_notifier(): wakeirqs=1 irq_wake_enabled=1
04/16/2012 05:16:46 [WARNING] Freezing user space processes ... (elapsed 0.03 seconds) done.
04/16/2012 05:16:46 [WARNING] Freezing remaining freezable tasks ... (elapsed 0.02 seconds) done.
04/16/2012 05:16:46 [DEBUG] PM: Entering mem sleep
04/16/2012 05:16:46 [INFORMATION] twl_rtc_set_alarm(): 2012-04-16 17:52:31 on
04/16/2012 05:16:46 [INFORMATION] PM: suspend of devices complete after 20.141 msecs
04/16/2012 05:16:46 [INFORMATION] PM: late suspend of devices complete after 1.403 msecs
04/16/2012 05:16:46 [WARNING] Disabling non-boot CPUs ...
04/16/2012 05:16:46 [INFORMATION] Resume caused by I/O pad: CONTROL_PADCONF_WAKEUPEVENT_0[9]
04/16/2012 05:16:46 [INFORMATION] Resume caused by I/O pad: CONTROL_PADCONF_WAKEUPEVENT_1[6]
04/16/2012 05:16:46 [ERROR] Successfully put all powerdomains to target state
04/16/2012 05:16:46 [INFORMATION] Suspended for 3.599 seconds
04/16/2012 05:16:46 [INFORMATION] Enabling non-boot CPUs ...
04/16/2012 05:16:46 [WARNING] gic_timer_retrigger: lost localtimer interrupt
04/16/2012 05:16:46 [INFORMATION] [imoseyon] cpufreq policy max set to 1.2Ghz at boot
04/16/2012 05:16:46 [INFORMATION] CPU1 is up
04/16/2012 05:16:46 [INFORMATION] PM: early resume of devices complete after 1.800 msecs
04/16/2012 05:16:46 [INFORMATION] wakeup wake lock: musb_autosuspend_wake_lock
04/16/2012 05:16:46 [ERROR] twl_rtc_alarm_verify Now=2012-04-16 17:52:09 Alarm=2012-04-16 17:52:31 Delta=-22 sec
04/16/2012 05:16:47 [INFORMATION] PM: resume of devices complete after 213.287 msecs
04/16/2012 05:16:47 [DEBUG] PM: Finishing wakeup.
04/16/2012 05:16:47 [WARNING] Restarting tasks ... 
04/16/2012 05:16:47 [ERROR] hub 2-0:1.0: activate --> -22
04/16/2012 05:16:47 [WARNING] done.
04/16/2012 05:16:47 [INFORMATION] suspend: exit suspend, ret = 0 (2012-04-16 17:52:08.767532798 UTC)
04/16/2012 05:16:48 [INFORMATION] PM: Syncing filesystems ... done.
04/16/2012 05:16:48 [DEBUG] PM: Preparing system for mem slee
04/16/2012 05:16:48 [INFORMATION] twl6030_irq_pm_notifier(): wakeirqs=1 irq_wake_enabled=1
04/16/2012 05:16:48 [WARNING] Freezing user space processes ... (elapsed 0.02 seconds) done.
04/16/2012 05:16:48 [WARNING] Freezing remaining freezable tasks ... (elapsed 0.02 seconds) done
04/16/2012 05:16:48 [DEBUG] PM: Entering mem sleep
04/16/2012 05:16:48 [INFORMATION] twl_rtc_set_alarm(): 2012-04-16 17:52:31 on
04/16/2012 05:16:48 [INFORMATION] PM: suspend of devices complete after 20.233 msecs
04/16/2012 05:16:48 [INFORMATION] PM: late suspend of devices complete after 1.373 msecs
04/16/2012 05:16:48 [WARNING] Disabling non-boot CPUs ...
04/16/2012 05:16:48 [INFORMATION] Resume caused by IRQ 39, TWL6030-PIH
04/16/2012 05:16:48 [ERROR] Successfully put all powerdomains to target state
04/16/2012 05:16:48 [INFORMATION] Suspended for 20.580 seconds
04/16/2012 05:16:48 [INFORMATION] Enabling non-boot CPUs ...
04/16/2012 05:16:48 [INFORMATION] [imoseyon] cpufreq policy max set to 1.2Ghz at boot
04/16/2012 05:16:48 [INFORMATION] CPU1 is up
04/16/2012 05:16:48 [INFORMATION] PM: early resume of devices complete after 1.800 msecs
04/16/2012 05:16:48 [INFORMATION] wakeup wake lock: musb_autosuspend_wake_lock
04/16/2012 05:16:48 [ERROR] twl_rtc_alarm_verify Now=2012-04-16 17:52:31 Alarm=2012-04-16 17:52:31 Delta=0 sec
04/16/2012 05:16:48 [INFORMATION] PM: resume of devices complete after 229.125 msecs
04/16/2012 05:16:48 [DEBUG] PM: Finishing wakeup.
04/16/2012 05:16:48 [WARNING] Restarting tasks ... 
04/16/2012 05:16:48 [ERROR] hub 2-0:1.0: activate --> -22
04/16/2012 05:16:48 [WARNING] done.
04/16/2012 05:16:48 [INFORMATION] suspend: exit suspend, ret = 0 (2012-04-16 17:52:30.754196599 UTC)
04/16/2012 05:16:49 [INFORMATION] PM: Syncing filesystems ... done.
04/16/2012 05:16:49 [DEBUG] PM: Preparing system for mem slee
04/16/2012 05:16:49 [INFORMATION] twl6030_irq_pm_notifier(): wakeirqs=1 irq_wake_enabled=1
04/16/2012 05:16:49 [WARNING] Freezing user space processes ... (elapsed 0.02 seconds) done.
04/16/2012 05:16:49 [WARNING] Freezing remaining freezable tasks ... (elapsed 0.02 seconds) done
04/16/2012 05:16:49 [DEBUG] PM: Entering mem sleep
04/16/2012 05:16:49 [INFORMATION] twl_rtc_set_alarm(): 2012-04-16 17:52:48 on
04/16/2012 05:16:49 [INFORMATION] PM: suspend of devices complete after 19.714 msecs
04/16/2012 05:16:49 [INFORMATION] PM: late suspend of devices complete after 1.403 msecs
04/16/2012 05:16:49 [WARNING] Disabling non-boot CPUs ...
04/16/2012 05:16:49 [INFORMATION] Resume caused by IRQ 39, TWL6030-PIH
04/16/2012 05:16:49 [ERROR] Successfully put all powerdomains to target state
04/16/2012 05:16:49 [INFORMATION] Suspended for 15.953 seconds
04/16/2012 05:16:49 [INFORMATION] Enabling non-boot CPUs ...
04/16/2012 05:16:49 [INFORMATION] [imoseyon] cpufreq policy max set to 1.2Ghz at boot
04/16/2012 05:16:49 [INFORMATION] CPU1 is up
04/16/2012 05:16:49 [INFORMATION] PM: early resume of devices complete after 1.983 msecs

Before I exported that, I locked/unlocked my phone several times so you could see what's happening. I'm at work right now so I don't have time to look at it thoroughly.

Thanks again!
 
If you get a chance, would you mind also exporting one from alogcat? Thanks.

edit: should have added...scan through that log more carefully before posting. If there's anything you don't want to post publicly, PM it or keep it private altogether if you wish. At minimum, use it as a secondary tool.
 
iowabowtech said:
If you get a chance, would you mind also exporting one from alogcat? Thanks.

edit: should have added...scan through that log more carefully before posting. If there's anything you don't want to post publicly, PM it or keep it private altogether if you wish. At minimum, use it as a secondary tool.
Click to expand...

Alrighty, just pulled the log. I couldn't see or ctrl+F any sensitive info that I wouldn't want in there. Please let me know if you see anything that I may have obviously missed.

I can't make heads or tails of wtf is in this log, lol. I hope you know what you're looking for ;). I locked and unlocked the phone a few times before I saved the log. Also, I had to take some of the text out of the top so the .txt file would be small enough to attach.

Thanks a lot for the help.

I was planning on doing a full wipe tonight, but I'm wore out from work and school today (full time worker and student with a family of 4 at the moment :o), so I might try that Wednesday afternoon when I don't have class and have time to set my phone up again while combing through my apps trying to find the bad one.
 

Attachments

That log is odd. And not just because the .txt format stacked all the data. It just seems to be almost looping the same info and there's log info I expect to see that's not there. PM sent.
 
Last night I decided to do a full wipe in recovery, then troubleshoot from there. When I got back into the phone, I didn't install/change a damn thing except install System Panel and aLogCat (I didn't even sign into my Google account). After I did that, System didn't budge much from around 50MBs regardless of how many times I unlocked the phone. So after that I went into Liquid Controls and set up my custom lock screen (there's an option to have 8 points/icons on the lock screen instead of the stock 2 points/icons to unlock and for the camera). After I did that, I didn't really notice System increasing in memory usage. I think it did a tiny bit after every use, but not by near as much as it was before (maybe 0.1MB after every unlock instead of around 1MB after every unlock like it was doing before). So then I went to customize my Nav bar to add the search button. After I did that, I definitely noticed an increase in System memory usage after every unlock, but again, it was much smaller increments than it was before I wiped; maybe 0.2-.0.3MBs increase after every unlock. I got it up to about 90MBs before I got tired and fell asleep last night, lol. So this morning I restored my backup from before I wiped the phone and tried to disable all Nav bar and lock screen custimizations to see if it fixed the issue. It didn't though. But now it only goes up by about 0.5MB as opposed to 1MB before I changed the nav bar and unlock screen back to stock.

So this is very perplexing to me. So this morning before I restored my backup from before the wipe, I e-mailed myself the log from alogcat so I could post this. I still see some of the same messages about the status bar and Torch showing up in the log even though I didn't change anything with the status bar and didn't touch the Torch setting after the wipe.

Thanks again!

Code: 
D/Statusbar :Clock(  474): mClockFormat: {h:mm a}
D/Statusbar :Clock(   474): MAGIC1: {} MAGIC2: {} MAGIC3: {} MAGIC4: {}
D/Statusbar :Clock(   474): mClockFormat: {h:mm a}
D/Statusbar :Clock(  474): MAGIC1: {} MAGIC2:  {} MAGIC3: {} MAGIC4: {}
D/Statusbar :Clock(  474): mClockFormat: {h:mm  a}
D/Statusbar :Clock(  474): MAGIC1: {} MAGIC2: {} MAGIC3: {} MAGIC4:  {}
D/Statusbar :Clock(  474): mClockFormat: {h:mm a}
D/Statusbar  :Clock(  474): MAGIC1: {} MAGIC2: {} MAGIC3: {} MAGIC4: {}
D/Statusbar  :Clock(  474): mClockFormat: {h:mm a}
D/Statusbar :Clock(  474): MAGIC1:  {} MAGIC2: {} MAGIC3: {} MAGIC4: {}
D/Statusbar :Clock(  474):  mClockFormat: {h:mm a}
D/Statusbar :Clock(  474): MAGIC1: {} MAGIC2: {}  MAGIC3: {} MAGIC4: {}
D/Statusbar :Clock(  474): mClockFormat: {h:mm  a}
D/Statusbar :Clock(  474): MAGIC1: {} MAGIC2: {} MAGIC3: {} MAGIC4:  {}
D/Statusbar :Clock(  474): mClockFormat: {h:mm a}
D/Statusbar  :Clock(  474): MAGIC1: {} MAGIC2: {} MAGIC3: {} MAGIC4: {}
D/Statusbar  :Clock(  474): mClockFormat: {h:mm a}
D/Statusbar :Clock(  474): MAGIC1:  {} MAGIC2: {} MAGIC3: {} MAGIC4: {}
D/Statusbar :Clock(  474):  mClockFormat: {h:mm a}
D/Statusbar :Clock(  474): MAGIC1: {} MAGIC2: {}  MAGIC3: {} MAGIC4: {}
D/Statusbar :Clock(  474): mClockFormat: {h:mm  a}
D/Statusbar :Clock(  474): MAGIC1: {} MAGIC2: {} MAGIC3: {} MAGIC4:  {}
D/Statusbar :Clock(  474): mClockFormat: {h:mm a}
D/Statusbar  :Clock(  474): MAGIC1: {} MAGIC2: {} MAGIC3: {} MAGIC4: {}
D/Statusbar  :Clock(  474): mClockFormat: {h:mm a}
D/Statusbar :Clock(  474): MAGIC1:  {} MAGIC2: {} MAGIC3: {} MAGIC4: {}
D/Statusbar :Clock(  474):  mClockFormat: {h:mm a}
D/Statusbar :Clock(  474): MAGIC1: {} MAGIC2: {}  MAGIC3: {} MAGIC4: {}
W/ThrottleService(  240): unable to find stats for  iface rmnet0
D/Statusbar :Clock(  474): mClockFormat: {h:mm  a}
D/Statusbar :Clock(  474): MAGIC1: {} MAGIC2: {} MAGIC3: {} MAGIC4:  {}
D/Statusbar :Clock(  474): mClockFormat: {h:mm a}
D/Statusbar  :Clock(  474): MAGIC1: {} MAGIC2: {} MAGIC3: {} MAGIC4: {}
D/Statusbar  :Clock(  474): mClockFormat: {h:mm a}
D/Statusbar :Clock(  474): MAGIC1:  {} MAGIC2: {} MAGIC3: {} MAGIC4: {}
D/Statusbar :Clock(  474):  mClockFormat: {h:mm a}
D/Statusbar :Clock(  474): MAGIC1: {} MAGIC2: {}  MAGIC3: {} MAGIC4: {}
D/Statusbar :Clock(  474): mClockFormat: {h:mm  a}
D/Statusbar :Clock(  474): MAGIC1: {} MAGIC2: {} MAGIC3: {} MAGIC4:  {}
D/Statusbar :Clock(  474): mClockFormat: {h:mm a}
D/Statusbar  :Clock(  474): MAGIC1: {} MAGIC2: {} MAGIC3: {} MAGIC4: {}
D/Statusbar  :Clock(  474): mClockFormat: {h:mm a}
D/Statusbar :Clock(  474): MAGIC1:  {} MAGIC2: {} MAGIC3: {} MAGIC4: {}
D/Statusbar :Clock(  474):  mClockFormat: {h:mm a}
D/Statusbar :Clock(  474): MAGIC1: {} MAGIC2: {}  MAGIC3: {} MAGIC4: {}
D/Statusbar :Clock(  474): mClockFormat: {h:mm  a}
D/Statusbar :Clock(  474): MAGIC1: {} MAGIC2: {} MAGIC3: {} MAGIC4:  {}
D/Statusbar :Clock(  474): mClockFormat: {h:mm a}
D/Statusbar  :Clock(  474): MAGIC1: {} MAGIC2: {} MAGIC3: {} MAGIC4: {}
D/Statusbar  :Clock(  474): mClockFormat: {h:mm a}
D/Statusbar :Clock(  474): MAGIC1:  {} MAGIC2: {} MAGIC3: {} MAGIC4: {}
D/Statusbar :Clock(  474):  mClockFormat: {h:mm a}
D/Statusbar :Clock(  474): MAGIC1: {} MAGIC2: {}  MAGIC3: {} MAGIC4: {}
D/Statusbar :Clock(  474): mClockFormat: {h:mm  a}
D/Statusbar :Clock(  474): MAGIC1: {} MAGIC2: {} MAGIC3: {} MAGIC4:  {}
D/Statusbar :Clock(  474): mClockFormat: {h:mm a}
D/Statusbar  :Clock(  474): MAGIC1: {} MAGIC2: {} MAGIC3: {} MAGIC4: {}
D/Statusbar  :Clock(  474): mClockFormat: {h:mm a}
D/Statusbar :Clock(  474): MAGIC1:  {} MAGIC2: {} MAGIC3: {} MAGIC4: {}
D/Statusbar :Clock(  474):  mClockFormat: {h:mm a}
D/Statusbar :Clock(  474): MAGIC1: {} MAGIC2: {}  MAGIC3: {} MAGIC4: {}
D/dalvikvm(  240): JIT code cache reset in 10 ms  (1048380 bytes 5/0)
D/dalvikvm(  240): GC_CONCURRENT freed 1836K, 4% free  65480K/68167K, paused 7ms+78ms
D/Statusbar :Clock(  474): mClockFormat:  {h:mm a}
D/Statusbar :Clock(  474): MAGIC1: {} MAGIC2: {} MAGIC3: {}  MAGIC4: {}
D/Statusbar :Clock(  474): mClockFormat: {h:mm  a}
D/Statusbar :Clock(  474): MAGIC1: {} MAGIC2: {} MAGIC3: {} MAGIC4:  {}
D/Statusbar :Clock(  474): mClockFormat: {h:mm a}
D/Statusbar  :Clock(  474): MAGIC1: {} MAGIC2: {} MAGIC3: {} MAGIC4: {}
D/Statusbar  :Clock(  474): mClockFormat: {h:mm a}
D/Statusbar :Clock(  474): MAGIC1:  {} MAGIC2: {} MAGIC3: {} MAGIC4: {}
W/ThrottleService(  240): unable to  find stats for iface rmnet0
D/Statusbar :Clock(  474): mClockFormat: {h:mm  a}
D/Statusbar :Clock(  474): MAGIC1: {} MAGIC2: {} MAGIC3: {} MAGIC4:  {}
D/Statusbar :Clock(  474): mClockFormat: {h:mm a}
D/Statusbar  :Clock(  474): MAGIC1: {} MAGIC2: {} MAGIC3: {} MAGIC4: {}
D/Statusbar  :Clock(  474): mClockFormat: {h:mm a}
D/Statusbar :Clock(  474): MAGIC1:  {} MAGIC2: {} MAGIC3: {} MAGIC4: {}
D/Statusbar :Clock(  474):  mClockFormat: {h:mm a}
D/Statusbar :Clock(  474): MAGIC1: {} MAGIC2: {}  MAGIC3: {} MAGIC4: {}
D/Statusbar :Clock(  474): mClockFormat: {h:mm  a}
D/Statusbar :Clock(  474): MAGIC1: {} MAGIC2: {} MAGIC3: {} MAGIC4:  {}
D/Statusbar :Clock(  474): mClockFormat: {h:mm a}
D/Statusbar  :Clock(  474): MAGIC1: {} MAGIC2: {} MAGIC3: {} MAGIC4: {}
D/Statusbar  :Clock(  474): mClockFormat: {h:mm a}
D/Statusbar :Clock(  474): MAGIC1:  {} MAGIC2: {} MAGIC3: {} MAGIC4: {}
D/Statusbar :Clock(  474):  mClockFormat: {h:mm a}
D/Statusbar :Clock(  474): MAGIC1: {} MAGIC2: {}  MAGIC3: {} MAGIC4: {}
D/dalvikvm(  240): GC_CONCURRENT freed 1900K, 4% free  65502K/68167K, paused 7ms+68ms
D/Statusbar :Clock(  474): mClockFormat:  {h:mm a}
D/Statusbar :Clock(  474): MAGIC1: {} MAGIC2: {} MAGIC3: {}  MAGIC4: {}
D/Statusbar :Clock(  474): mClockFormat: {h:mm  a}
D/Statusbar :Clock(  474): MAGIC1: {} MAGIC2: {} MAGIC3: {} MAGIC4:  {}
D/Statusbar :Clock(  474): mClockFormat: {h:mm a}
D/Statusbar  :Clock(  474): MAGIC1: {} MAGIC2: {} MAGIC3: {} MAGIC4: {}
D/Statusbar  :Clock(  474): mClockFormat: {h:mm a}
D/Statusbar :Clock(  474): MAGIC1:  {} MAGIC2: {} MAGIC3: {} MAGIC4: {}
D/Statusbar :Clock(  474):  mClockFormat: {h:mm a}
D/Statusbar :Clock(  474): MAGIC1: {} MAGIC2: {}  MAGIC3: {} MAGIC4: {}
D/Statusbar :Clock(  474): mClockFormat: {h:mm  a}
D/Statusbar :Clock(  474): MAGIC1: {} MAGIC2: {} MAGIC3: {} MAGIC4:  {}
D/Statusbar :Clock(  474): mClockFormat: {h:mm a}
D/Statusbar  :Clock(  474): MAGIC1: {} MAGIC2: {} MAGIC3: {} MAGIC4: {}
D/Statusbar  :Clock(  474): mClockFormat: {h:mm a}
D/Statusbar :Clock(  474): MAGIC1:  {} MAGIC2: {} MAGIC3: {} MAGIC4: {}
D/Statusbar :Clock(  474):  mClockFormat: {h:mm a}
D/Statusbar :Clock(  474): MAGIC1: {} MAGIC2: {}  MAGIC3: {} MAGIC4: {}
D/Statusbar :Clock(  474): mClockFormat: {h:mm  a}
D/Statusbar :Clock(  474): MAGIC1: {} MAGIC2: {} MAGIC3: {} MAGIC4:  {}
D/Statusbar :Clock(  474): mClockFormat: {h:mm a}
D/Statusbar  :Clock(  474): MAGIC1: {} MAGIC2: {} MAGIC3: {} MAGIC4: {}
D/Statusbar  :Clock(  474): mClockFormat: {h:mm a}
D/Statusbar :Clock(  474): MAGIC1:  {} MAGIC2: {} MAGIC3: {} MAGIC4: {}
W/ThrottleService(  240): unable to  find stats for iface rmnet0
I/power   (  240): *** set_screen_state  1
D/SystemPanel( 1692): Request stop: disable.
I/WindowManager(  240):  Lock screen displayed!
D/dalvikvm( 1692): GC_CONCURRENT freed 806K, 31% free  14555K/20807K, paused 2ms+6ms
D/dalvikvm( 1692): GC_CONCURRENT freed 545K,  30% free 14667K/20807K, paused 3ms+6ms
D/SurfaceFlinger(  118): Screen about  to return, flinger = 0x1989918
D/AccelerometerListener(  536):  enable(false)
D/dalvikvm( 1692): GC_CONCURRENT freed 789K, 31% free  14536K/20807K, paused 1ms+7ms
D/PhoneStatusBar(  474): disable: 0x01e00000  -> 0x00e00000 (diff: 0x01000000)
D/PhoneStatusBar(  474): disable: <  expand icons alerts ticker system_info BACK HOME recent* CLOCK  >
D/NfcService(  545): NFC-C ON
D/OpenGLRenderer(  240): Flushing  caches (mode 0)
W/InputManagerService(  240): Window already focused,  ignoring focus gain of:  com.android.internal.view.IInputMethodClient$Stub$Proxy@421ece58
D/PhoneStatusBar(   474): disable: 0x00e00000 -> 0x00000000 (diff:  0x00e00000)
D/PhoneStatusBar(  474): disable: < expand icons alerts ticker  system_info back* home* recent clock* >
V/PhoneStatusBar(  474):  setLightsOn(true)
D/PhoneStatusBar(  474): showing the MENU  button
D/Statusbar :Clock(  474): mClockFormat: {h:mm&#61184; a&#61185;}
D/Statusbar  :Clock(  474): MAGIC1: {&#61184;} MAGIC2: {&#61185;} MAGIC3: {&#61186;} MAGIC4: {&#61187;}
D/Statusbar  :Clock(  474): mClockFormat: {h:mm&#61184; a&#61185;}
D/Statusbar :Clock(  474): MAGIC1:  {&#61184;} MAGIC2: {&#61185;} MAGIC3: {&#61186;} MAGIC4: {&#61187;}
D/dalvikvm(  240): GC_EXPLICIT freed  1887K, 5% free 65411K/68167K, paused 9ms+55ms
I/ActivityManager(  240): START  {act=com.android.systemui.INTENT_TORCH_OFF flg=0x10000000  cmp=com.android.systemui/.Torch} from pid 240
I/power   (  240): ***  set_screen_state 0
E/LockScreen(  240): NameNotFoundException:  [**null**]
I/Torch   (  474): onCreate
V/TransportControlView(  240):  Create TCV  com.android.internal.widget.TransportControlView@44967a78
E/HELP    (   240): ERROR UNLOCKWIDGET2 IS NULL
E/LockScreen(  240): NameNotFoundException:  [**null**]
E/LockScreen(  240): NameNotFoundException:  [**null**]
D/SurfaceFlinger(  118): About to give-up screen, flinger =  0x1989918
W/InputManagerService(  240): Window already focused, ignoring  focus gain of:  com.android.internal.view.IInputMethodClient$Stub$Proxy@41a5e590
D/NfcService(   545): NFC-C OFF, disconnect
D/PhoneStatusBar(  474): closing recents  panel
D/AccelerometerListener(  536): enable(false)
D/PhoneStatusBar(   474): disable: 0x00000000 -> 0x01000000 (diff:  0x01000000)
D/PhoneStatusBar(  474): disable: < expand icons alerts ticker  system_info back home RECENT* clock >
D/SystemPanel( 1692): Request stop:  disable.
D/android.widget.GridLayout(  240): vertical constraints: y1 - y0  > 177, y2 - y1 > 38, y3 - y2 > 38, y5 - y4 > 32, y6 - y5 > 604,  y7 - y6 > 0, y7 - y0 < 1134, y5 - y4 < 0 are inconsistent; permanently  removing: y7 - y0 < 1134, y5 - y4 < 0. 
D/PhoneStatusBar(  474):  closing recents panel
D/OpenGLRenderer(  474): Flushing caches (mode  1)
D/PhoneStatusBar(  474): closing recents panel
D/dalvikvm( 1692):  GC_CONCURRENT freed 599K, 31% free 14491K/20807K, paused  2ms+6ms
D/PhoneStatusBar(  474): disable: 0x01000000 -> 0x01e00000 (diff:  0x00e00000)
D/PhoneStatusBar(  474): disable: < expand icons alerts ticker  system_info BACK* HOME* RECENT CLOCK* >
D/PhoneStatusBar(  474): hiding  the MENU button
D/PhoneStatusBar(  474): closing recents panel
I/power    (  240): *** set_screen_state 1
D/AccelerometerListener(  536):  enable(false)
I/WindowManager(  240): Lock screen displayed!
D/dalvikvm(  1692): GC_CONCURRENT freed 415K, 30% free 14647K/20807K, paused  2ms+4ms
D/SystemPanel( 1692): Request stop: disable.
I/ActivityManager(   240): START {act=com.android.systemui.INTENT_TORCH_OFF flg=0x10000000  cmp=com.android.systemui/.Torch} from pid 240
D/SurfaceFlinger(  118):  Screen about to return, flinger = 0x1989918
D/dalvikvm(  240): GC_FOR_ALLOC  freed 710K, 5% free 65378K/68167K, paused 264ms
I/Torch   (  474):  onCreate
D/PhoneStatusBar(  474): disable: 0x01e00000 -> 0x00e00000 (diff:  0x01000000)
D/PhoneStatusBar(  474): disable: < expand icons alerts ticker  system_info BACK HOME recent* CLOCK >
D/NfcService(  545): NFC-C  ON
D/OpenGLRenderer(  240): Flushing caches (mode 0)
D/SystemPanel( 1692):  Request stop: disable.
V/PhoneStatusBar(  474):  setLightsOn(true)
D/PhoneStatusBar(  474): disable: 0x00e00000 ->  0x00000000 (diff: 0x00e00000)
D/PhoneStatusBar(  474): disable: < expand  icons alerts ticker system_info back* home* recent clock*  >
D/PhoneStatusBar(  474): showing the MENU button
D/dalvikvm( 1692):  GC_CONCURRENT freed 782K, 31% free 14554K/20807K, paused 2ms+8ms
D/dalvikvm(  1692): GC_CONCURRENT freed 494K, 30% free 14717K/20807K, paused  2ms+4ms
W/InputManagerService(  240): Window already focused, ignoring focus  gain of:  com.android.internal.view.IInputMethodClient$Stub$Proxy@44280970
D/dalvikvm(  1692): GC_CONCURRENT freed 715K, 30% free 14712K/20807K, paused  2ms+5ms
D/OpenGLRenderer(  474): Flushing caches (mode 1)
D/dalvikvm(   240): GC_EXPLICIT freed 362K, 5% free 65372K/68167K, paused  2ms+61ms
I/ActivityManager(  240): START  {act=com.android.systemui.INTENT_TORCH_OFF flg=0x10000000  cmp=com.android.systemui/.Torch} from pid 240
I/power   (  240): ***  set_screen_state 0
E/LockScreen(  240): NameNotFoundException:  [**null**]
I/Torch   (  474): onCreate
V/TransportControlView(  240):  Create TCV  com.android.internal.widget.TransportControlView@448fc1f0
D/SurfaceFlinger(   118): About to give-up screen, flinger = 0x1989918
E/HELP    (  240): ERROR  UNLOCKWIDGET2 IS NULL
E/LockScreen(  240): NameNotFoundException:  [**null**]
E/LockScreen(  240): NameNotFoundException:  [**null**]
W/InputManagerService(  240): Window already focused, ignoring  focus gain of:  com.android.internal.view.IInputMethodClient$Stub$Proxy@4188cf58
D/PhoneStatusBar(   474): closing recents panel
D/PhoneStatusBar(  474): disable: 0x00000000  -> 0x01000000 (diff: 0x01000000)
D/PhoneStatusBar(  474): disable: <  expand icons alerts ticker system_info back home RECENT* clock  >
D/AccelerometerListener(  536): enable(false)
D/NfcService(  545):  NFC-C OFF, disconnect
D/android.widget.GridLayout(  240): vertical  constraints: y1 - y0 > 177, y2 - y1 > 38, y3 - y2 > 38, y5 - y4 >  32, y6 - y5 > 604, y7 - y6 > 0, y7 - y0 < 1134, y5 - y4 < 0 are  inconsistent; permanently removing: y7 - y0 < 1134, y5 - y4 < 0.  
D/PhoneStatusBar(  474): closing recents panel
D/PhoneStatusBar(  474):  closing recents panel
D/PhoneStatusBar(  474): disable: 0x01000000 ->  0x01e00000 (diff: 0x00e00000)
D/PhoneStatusBar(  474): disable: < expand  icons alerts ticker system_info BACK* HOME* RECENT CLOCK*  >
D/PhoneStatusBar(  474): hiding the MENU button
D/PhoneStatusBar(   474): closing recents panel
I/power   (  240): *** set_screen_state  1
D/SystemPanel( 1692): Request stop: disable.
D/AccelerometerListener(   536): enable(false)
I/WindowManager(  240): Lock screen  displayed!
D/dalvikvm( 1692): GC_CONCURRENT freed 785K, 31% free  14540K/20807K, paused 3ms+6ms
I/ActivityManager(  240): START  {act=com.android.systemui.INTENT_TORCH_OFF flg=0x10000000  cmp=com.android.systemui/.Torch} from pid 240
D/dalvikvm( 1692):  GC_CONCURRENT freed 506K, 30% free 14686K/20807K, paused  2ms+6ms
D/SurfaceFlinger(  118): Screen about to return, flinger =  0x1989918
I/Torch   (  474): onCreate
D/SystemPanel( 1692): Request stop:  disable.
D/PhoneStatusBar(  474): disable: 0x01e00000 -> 0x00e00000 (diff:  0x01000000)
D/PhoneStatusBar(  474): disable: < expand icons alerts ticker  system_info BACK HOME recent* CLOCK >
D/OpenGLRenderer(  240): Flushing  caches (mode 0)
D/NfcService(  545): NFC-C ON
D/dalvikvm( 1692):  GC_CONCURRENT freed 760K, 30% free 14627K/20807K, paused  3ms+5ms
D/PhoneStatusBar(  474): disable: 0x00e00000 -> 0x00000000 (diff:  0x00e00000)
D/PhoneStatusBar(  474): disable: < expand icons alerts ticker  system_info back* home* recent clock* >
D/dalvikvm(  474): GC_CONCURRENT  freed 1273K, 57% free 16992K/39303K, paused 2ms+7ms
D/PhoneStatusBar(  474):  showing the MENU button
V/PhoneStatusBar(  474):  setLightsOn(true)
D/dalvikvm( 1692): GC_CONCURRENT freed 508K, 29% free  14799K/20807K, paused 2ms+5ms
D/OpenGLRenderer(  474): Flushing caches (mode  1)
W/InputManagerService(  240): Window already focused, ignoring focus gain  of:  com.android.internal.view.IInputMethodClient$Stub$Proxy@418e9c20
D/dalvikvm(   240): GC_EXPLICIT freed 706K, 4% free 65445K/68167K, paused  7ms+66ms
I/ActivityManager(  240): START  {act=com.android.systemui.INTENT_TORCH_OFF flg=0x10000000  cmp=com.android.systemui/.Torch} from pid 240
I/power   (  240): ***  set_screen_state 0
E/LockScreen(  240): NameNotFoundException:  [**null**]
I/Torch   (  474): onCreate
D/SurfaceFlinger(  118): About to  give-up screen, flinger = 0x1989918
V/TransportControlView(  240): Create TCV  com.android.internal.widget.TransportControlView@4491fc78
D/AccelerometerListener(   536): enable(false)
D/PhoneStatusBar(  474): closing recents  panel
E/HELP    (  240): ERROR UNLOCKWIDGET2 IS  NULL
W/InputManagerService(  240): Window already focused, ignoring focus  gain of:  com.android.internal.view.IInputMethodClient$Stub$Proxy@41bf7d98
E/LockScreen(   240): NameNotFoundException: [**null**]
E/LockScreen(  240):  NameNotFoundException: [**null**]
D/NfcService(  545): NFC-C OFF,  disconnect
D/PhoneStatusBar(  474): disable: 0x00000000 -> 0x01000000  (diff: 0x01000000)
D/PhoneStatusBar(  474): disable: < expand icons alerts  ticker system_info back home RECENT* clock >
D/android.widget.GridLayout(   240): vertical constraints: y1 - y0 > 177, y2 - y1 > 38, y3 - y2 > 38,  y5 - y4 > 32, y6 - y5 > 604, y7 - y6 > 0, y7 - y0 < 1134, y5 - y4  < 0 are inconsistent; permanently removing: y7 - y0 < 1134, y5 - y4 <  0. 
D/PhoneStatusBar(  474): closing recents panel
I/power   (  240): ***  set_screen_state 1
D/PhoneStatusBar(  474): disable: 0x01000000 ->  0x01e00000 (diff: 0x00e00000)
D/PhoneStatusBar(  474): disable: < expand  icons alerts ticker system_info BACK* HOME* RECENT CLOCK* >
D/SystemPanel(  1692): Request stop: disable.
D/PhoneStatusBar(  474): hiding the MENU  button
D/AccelerometerListener(  536): enable(false)
I/WindowManager(   240): Lock screen displayed!
D/PhoneStatusBar(  474): closing recents  panel
I/ActivityManager(  240): START  {act=com.android.systemui.INTENT_TORCH_OFF flg=0x10000000  cmp=com.android.systemui/.Torch} from pid 240
D/dalvikvm( 1692):  GC_CONCURRENT freed 833K, 30% free 14649K/20807K, paused 2ms+6ms
D/dalvikvm(  1692): GC_CONCURRENT freed 537K, 29% free 14780K/20807K, paused  2ms+5ms
D/SurfaceFlinger(  118): Screen about to return, flinger =  0x1989918
I/Torch   (  474): onCreate
D/SystemPanel( 1692): Request stop:  disable.
D/OpenGLRenderer(  240): Flushing caches (mode  0)
D/PhoneStatusBar(  474): disable: 0x01e00000 -> 0x00e00000 (diff:  0x01000000)
D/PhoneStatusBar(  474): disable: < expand icons alerts ticker  system_info BACK HOME recent* CLOCK >
D/NfcService(  545): NFC-C  ON
D/OpenGLRenderer(  474): Flushing caches (mode  1)
W/InputManagerService(  240): Window already focused, ignoring focus gain  of:  com.android.internal.view.IInputMethodClient$Stub$Proxy@44941aa0
D/PhoneStatusBar(   474): disable: 0x00e00000 -> 0x00000000 (diff:  0x00e00000)
D/PhoneStatusBar(  474): disable: < expand icons alerts ticker  system_info back* home* recent clock* >
V/PhoneStatusBar(  474):  setLightsOn(true)
D/PhoneStatusBar(  474): showing the MENU  button
D/dalvikvm( 1692): GC_CONCURRENT freed 764K, 30% free 14740K/20807K,  paused 1ms+4ms
D/dalvikvm(  240): GC_EXPLICIT freed 712K, 4% free  65509K/68167K, paused 9ms+57ms
I/ActivityManager(  240): START  {act=com.android.systemui.INTENT_TORCH_OFF flg=0x10000000  cmp=com.android.systemui/.Torch} from pid 240
I/power   (  240): ***  set_screen_state 0
E/LockScreen(  240): NameNotFoundException:  [**null**]
I/Torch   (  474): onCreate
D/SurfaceFlinger(  118): About to  give-up screen, flinger = 0x1989918
D/PhoneStatusBar(  474): closing recents  panel
D/NfcService(  545): NFC-C OFF, disconnect
V/TransportControlView(   240): Create TCV  com.android.internal.widget.TransportControlView@4420df38
D/AccelerometerListener(   536): enable(false)
W/InputManagerService(  240): Window already focused,  ignoring focus gain of:  com.android.internal.view.IInputMethodClient$Stub$Proxy@419df598
E/HELP     (  240): ERROR UNLOCKWIDGET2 IS NULL
E/LockScreen(  240):  NameNotFoundException: [**null**]
E/LockScreen(  240): NameNotFoundException:  [**null**]
D/PhoneStatusBar(  474): disable: 0x00000000 -> 0x01000000  (diff: 0x01000000)
D/PhoneStatusBar(  474): disable: < expand icons alerts  ticker system_info back home RECENT* clock >
D/android.widget.GridLayout(   240): vertical constraints: y1 - y0 > 177, y2 - y1 > 38, y3 - y2 > 38,  y5 - y4 > 32, y6 - y5 > 604, y7 - y6 > 0, y7 - y0 < 1134, y5 - y4  < 0 are inconsistent; permanently removing: y7 - y0 < 1134, y5 - y4 <  0. 
D/PhoneStatusBar(  474): closing recents panel
I/power   (  240): ***  set_screen_state 1
D/PhoneStatusBar(  474): disable: 0x01000000 ->  0x01e00000 (diff: 0x00e00000)
D/PhoneStatusBar(  474): disable: < expand  icons alerts ticker system_info BACK* HOME* RECENT CLOCK* >
D/SystemPanel(  1692): Request stop: disable.
D/AccelerometerListener(  536):  enable(false)
D/PhoneStatusBar(  474): hiding the MENU  button
D/PhoneStatusBar(  474): closing recents panel
I/WindowManager(   240): Lock screen displayed!
I/ActivityManager(  240): START  {act=com.android.systemui.INTENT_TORCH_OFF flg=0x10000000  cmp=com.android.systemui/.Torch} from pid 240
D/dalvikvm( 1692):  GC_CONCURRENT freed 808K, 31% free 14556K/20807K, paused 2ms+8ms
D/dalvikvm(  1692): GC_CONCURRENT freed 488K, 30% free 14666K/20807K, paused  2ms+4ms
D/SurfaceFlinger(  118): Screen about to return, flinger =  0x1989918
I/Torch   (  474): onCreate
D/SystemPanel( 1692): Request stop:  disable.
D/OpenGLRenderer(  474): Flushing caches (mode 1)
D/NfcService(   545): NFC-C ON
D/OpenGLRenderer(  240): Flushing caches (mode  0)
D/PhoneStatusBar(  474): disable: 0x01e00000 -> 0x00e00000 (diff:  0x01000000)
D/PhoneStatusBar(  474): disable: < expand icons alerts ticker  system_info BACK HOME recent* CLOCK >
D/PhoneStatusBar(  474): disable:  0x00e00000 -> 0x00000000 (diff: 0x00e00000)
D/PhoneStatusBar(  474):  disable: < expand icons alerts ticker system_info back* home* recent clock*  >
V/PhoneStatusBar(  474): setLightsOn(true)
D/PhoneStatusBar(  474):  showing the MENU button
D/dalvikvm( 1692): GC_CONCURRENT freed 733K, 30% free  14611K/20807K, paused 1ms+6ms
D/dalvikvm( 1692): GC_CONCURRENT freed 495K,  30% free 14761K/20807K, paused 2ms+4ms
W/InputManagerService(  240): Window  already focused, ignoring focus gain of:  com.android.internal.view.IInputMethodClient$Stub$Proxy@4456d768
D/dalvikvm(   240): GC_EXPLICIT freed 720K, 4% free 65569K/68167K, paused  3ms+77ms
I/ActivityManager(  240): START  {act=com.android.systemui.INTENT_TORCH_OFF flg=0x10000000  cmp=com.android.systemui/.Torch} from pid 240
I/power   (  240): ***  set_screen_state 0
E/LockScreen(  240): NameNotFoundException:  [**null**]
I/Torch   (  474): onCreate
D/SurfaceFlinger(  118): About to  give-up screen, flinger = 0x1989918
V/TransportControlView(  240): Create TCV  com.android.internal.widget.TransportControlView@449c7a88
D/PhoneStatusBar(   474): closing recents panel
D/AccelerometerListener(  536):  enable(false)
W/InputManagerService(  240): Window already focused, ignoring  focus gain of:  com.android.internal.view.IInputMethodClient$Stub$Proxy@448b49c8
E/HELP     (  240): ERROR UNLOCKWIDGET2 IS NULL
E/LockScreen(  240):  NameNotFoundException: [**null**]
D/NfcService(  545): NFC-C OFF,  disconnect
E/LockScreen(  240): NameNotFoundException:  [**null**]
D/PhoneStatusBar(  474): disable: 0x00000000 -> 0x01000000  (diff: 0x01000000)
D/PhoneStatusBar(  474): disable: < expand icons alerts  ticker system_info back home RECENT* clock >
D/android.widget.GridLayout(   240): vertical constraints: y1 - y0 > 177, y2 - y1 > 38, y3 - y2 > 38,  y5 - y4 > 32, y6 - y5 > 604, y7 - y6 > 0, y7 - y0 < 1134, y5 - y4  < 0 are inconsistent; permanently removing: y7 - y0 < 1134, y5 - y4 <  0. 
D/PhoneStatusBar(  474): closing recents panel
I/power   (  240): ***  set_screen_state 1
D/SystemPanel( 1692): Request stop:  disable.
D/PhoneStatusBar(  474): disable: 0x01000000 -> 0x01e00000 (diff:  0x00e00000)
D/PhoneStatusBar(  474): disable: < expand icons alerts ticker  system_info BACK* HOME* RECENT CLOCK* >
D/AccelerometerListener(  536):  enable(false)
D/PhoneStatusBar(  474): hiding the MENU  button
I/WindowManager(  240): Lock screen displayed!
I/ActivityManager(   240): START {act=com.android.systemui.INTENT_TORCH_OFF flg=0x10000000  cmp=com.android.systemui/.Torch} from pid 240
D/PhoneStatusBar(  474):  closing recents panel
D/dalvikvm( 1692): GC_CONCURRENT freed 841K, 30% free  14645K/20807K, paused 3ms+6ms
D/SurfaceFlinger(  118): Screen about to  return, flinger = 0x1989918
D/dalvikvm( 1692): GC_CONCURRENT freed 550K, 29%  free 14783K/20807K, paused 2ms+5ms
D/PhoneStatusBar(  474): disable:  0x01e00000 -> 0x00e00000 (diff: 0x01000000)
D/PhoneStatusBar(  474):  disable: < expand icons alerts ticker system_info BACK HOME recent* CLOCK  >
I/Torch   (  474): onCreate
D/OpenGLRenderer(  240): Flushing caches  (mode 0)
D/NfcService(  545): NFC-C ON
D/PhoneStatusBar(  474): disable:  0x00e00000 -> 0x00000000 (diff: 0x00e00000)
D/PhoneStatusBar(  474):  disable: < expand icons alerts ticker system_info back* home* recent clock*  >
V/PhoneStatusBar(  474): setLightsOn(true)
D/PhoneStatusBar(  474):  showing the MENU button
D/SystemPanel( 1692): Request stop:  disable.
W/InputManagerService(  240): Window already focused, ignoring focus  gain of:  com.android.internal.view.IInputMethodClient$Stub$Proxy@416d8168
D/dalvikvm(  1692): GC_CONCURRENT freed 777K, 30% free 14726K/20807K, paused  2ms+4ms
D/OpenGLRenderer(  474): Flushing caches (mode 1)
D/dalvikvm(   240): GC_EXPLICIT freed 710K, 4% free 65631K/68167K, paused  2ms+53ms
I/ActivityManager(  240): START  {act=com.android.systemui.INTENT_TORCH_OFF flg=0x10000000  cmp=com.android.systemui/.Torch} from pid 240
I/power   (  240): ***  set_screen_state 0
E/LockScreen(  240): NameNotFoundException:  [**null**]
I/Torch   (  474): onCreate
D/SurfaceFlinger(  118): About to  give-up screen, flinger = 0x1989918
V/TransportControlView(  240): Create TCV  com.android.internal.widget.TransportControlView@441d5a60
W/InputManagerService(   240): Window already focused, ignoring focus gain of:  com.android.internal.view.IInputMethodClient$Stub$Proxy@441e71e0
D/PhoneStatusBar(   474): closing recents panel
E/HELP    (  240): ERROR UNLOCKWIDGET2 IS  NULL
E/LockScreen(  240): NameNotFoundException: [**null**]
E/LockScreen(   240): NameNotFoundException: [**null**]
D/PhoneStatusBar(  474): disable:  0x00000000 -> 0x01000000 (diff: 0x01000000)
D/PhoneStatusBar(  474):  disable: < expand icons alerts ticker system_info back home RECENT* clock  >
D/NfcService(  545): NFC-C OFF,  disconnect
D/android.widget.GridLayout(  240): vertical constraints: y1 - y0  > 177, y2 - y1 > 38, y3 - y2 > 38, y5 - y4 > 32, y6 - y5 > 604,  y7 - y6 > 0, y7 - y0 < 1134, y5 - y4 < 0 are inconsistent; permanently  removing: y7 - y0 < 1134, y5 - y4 < 0. 
D/AccelerometerListener(  536):  enable(false)
D/PhoneStatusBar(  474): closing recents  panel
D/PhoneStatusBar(  474): disable: 0x01000000 -> 0x01e00000 (diff:  0x00e00000)
D/PhoneStatusBar(  474): disable: < expand icons alerts ticker  system_info BACK* HOME* RECENT CLOCK* >
D/PhoneStatusBar(  474): hiding  the MENU button
D/PhoneStatusBar(  474): closing recents panel
I/power    (  240): *** set_screen_state 1
D/AccelerometerListener(  536):  enable(false)
D/SystemPanel( 1692): Request stop:  disable.
I/WindowManager(  240): Lock screen  displayed!
I/ActivityManager(  240): START  {act=com.android.systemui.INTENT_TORCH_OFF flg=0x10000000  cmp=com.android.systemui/.Torch} from pid 240
D/dalvikvm( 1692):  GC_CONCURRENT freed 812K, 31% free 14558K/20807K, paused 3ms+8ms
D/dalvikvm(  1692): GC_CONCURRENT freed 536K, 30% free 14680K/20807K, paused  2ms+6ms
D/SurfaceFlinger(  118): Screen about to return, flinger =  0x1989918
I/Torch   (  474): onCreate
D/PhoneStatusBar(  474): disable:  0x01e00000 -> 0x00e00000 (diff: 0x01000000)
D/PhoneStatusBar(  474):  disable: < expand icons alerts ticker system_info BACK HOME recent* CLOCK  >
D/NfcService(  545): NFC-C ON
D/OpenGLRenderer(  240): Flushing  caches (mode 0)
D/PhoneStatusBar(  474): disable: 0x00e00000 -> 0x00000000  (diff: 0x00e00000)
D/PhoneStatusBar(  474): disable: < expand icons alerts  ticker system_info back* home* recent clock* >
D/SystemPanel( 1692):  Request stop: disable.
V/PhoneStatusBar(  474):  setLightsOn(true)
D/PhoneStatusBar(  474): showing the MENU  button
W/InputManagerService(  240): Window already focused, ignoring focus  gain of:  com.android.internal.view.IInputMethodClient$Stub$Proxy@449a2048
D/dalvikvm(  1692): GC_CONCURRENT freed 745K, 30% free 14634K/20807K, paused  3ms+5ms
D/dalvikvm( 1692): GC_CONCURRENT freed 519K, 29% free 14791K/20807K,  paused 2ms+3ms
D/OpenGLRenderer(  474): Flushing caches (mode  1)
D/dalvikvm(  240): GC_EXPLICIT freed 712K, 4% free 65693K/68167K, paused  6ms+48ms
I/ActivityManager(  240): START  {act=com.android.systemui.INTENT_TORCH_OFF flg=0x10000000  cmp=com.android.systemui/.Torch} from pid 240
I/power   (  240): ***  set_screen_state 0
E/LockScreen(  240): NameNotFoundException:  [**null**]
I/Torch   (  474): onCreate
D/SurfaceFlinger(  118): About to  give-up screen, flinger = 0x1989918
V/TransportControlView(  240): Create TCV  com.android.internal.widget.TransportControlView@4498cf38
D/AccelerometerListener(   536): enable(false)
D/PhoneStatusBar(  474): closing recents  panel
D/NfcService(  545): NFC-C OFF, disconnect
W/InputManagerService(   240): Window already focused, ignoring focus gain of:  com.android.internal.view.IInputMethodClient$Stub$Proxy@442053e0
E/HELP     (  240): ERROR UNLOCKWIDGET2 IS NULL
E/LockScreen(  240):  NameNotFoundException: [**null**]
E/LockScreen(  240): NameNotFoundException:  [**null**]
D/PhoneStatusBar(  474): disable: 0x00000000 -> 0x01000000  (diff: 0x01000000)
D/PhoneStatusBar(  474): disable: < expand icons alerts  ticker system_info back home RECENT* clock >
D/android.widget.GridLayout(   240): vertical constraints: y1 - y0 > 177, y2 - y1 > 38, y3 - y2 > 38,  y5 - y4 > 32, y6 - y5 > 604, y7 - y6 > 0, y7 - y0 < 1134, y5 - y4  < 0 are inconsistent; permanently removing: y7 - y0 < 1134, y5 - y4 <  0. 
D/PhoneStatusBar(  474): closing recents panel
I/power   (  240): ***  set_screen_state 1
D/SystemPanel( 1692): Request stop:  disable.
D/AccelerometerListener(  536): enable(false)
D/PhoneStatusBar(   474): disable: 0x01000000 -> 0x01e00000 (diff:  0x00e00000)
D/PhoneStatusBar(  474): disable: < expand icons alerts ticker  system_info BACK* HOME* RECENT CLOCK* >
D/PhoneStatusBar(  474): hiding  the MENU button
I/WindowManager(  240): Lock screen  displayed!
D/PhoneStatusBar(  474): closing recents  panel
I/ActivityManager(  240): START  {act=com.android.systemui.INTENT_TORCH_OFF flg=0x10000000  cmp=com.android.systemui/.Torch} from pid 240
D/dalvikvm( 1692):  GC_CONCURRENT freed 832K, 30% free 14673K/20807K, paused 2ms+8ms
D/dalvikvm(  1692): GC_CONCURRENT freed 507K, 29% free 14787K/20807K, paused  2ms+7ms
D/SurfaceFlinger(  118): Screen about to return, flinger =  0x1989918
I/Torch   (  474): onCreate
D/SystemPanel( 1692): Request stop:  disable.
D/dalvikvm( 1692): GC_CONCURRENT freed 769K, 30% free 14734K/20807K,  paused 2ms+6ms
D/OpenGLRenderer(  474): Flushing caches (mode  1)
D/NfcService(  545): NFC-C ON
D/OpenGLRenderer(  240): Flushing caches  (mode 0)
D/PhoneStatusBar(  474): disable: 0x01e00000 -> 0x00e00000 (diff:  0x01000000)
D/PhoneStatusBar(  474): disable: < expand icons alerts ticker  system_info BACK HOME recent* CLOCK >
W/InputManagerService(  240): Window  already focused, ignoring focus gain of:  com.android.internal.view.IInputMethodClient$Stub$Proxy@44991470
D/PhoneStatusBar(   474): disable: 0x00e00000 -> 0x00000000 (diff:  0x00e00000)
D/PhoneStatusBar(  474): disable: < expand icons alerts ticker  system_info back* home* recent clock* >
V/PhoneStatusBar(  474):  setLightsOn(true)
D/PhoneStatusBar(  474): showing the MENU  button
D/dalvikvm(  240): GC_EXPLICIT freed 728K, 4% free 65756K/68167K,  paused 7ms+51ms
D/PhoneStatusBar(  474): opening recents  panel
V/RecentTasksLoader(  474): creating activity desc for id=42,  label=Settings
V/RecentTasksLoader(  474): creating activity desc for id=507,  label=aLogcat
V/RecentTasksLoader(  474): creating activity desc for id=11,  label=Titanium Backup
V/RecentTasksLoader(  474): creating activity desc for  id=6, label=Apex Launcher
V/RecentTasksLoader(  474): creating activity desc  for id=10, label=Play Store
V/RecentTasksLoader(  474): creating activity  desc for id=7, label=Wi-Fi
V/RecentTasksLoader(  474): Showing 5  tasks
D/dalvikvm(  474): GC_FOR_ALLOC freed 1262K, 57% free 17059K/39303K,  paused 32ms
V/RecentTasksLoader(  474): Loaded bitmap for task  com.android.systemui.recent.TaskDescription@41c0f378:  android.graphics.Bitmap@42279ad0
V/RecentTasksLoader(  474): Loaded  bitmap for task com.android.systemui.recent.TaskDescription@41c10b18:  android.graphics.Bitmap@42242948
V/RecentsPanelView(  474):  onVisibilityChanged(com.android.systemui.recent.RecentsPanelView@4224b800,  0)
D/RecentsPanelView(  474):  startAnimation(appearing=true)
D/RecentsPanelView(  474):  panelHeight=1184
D/PhoneStatusBar(  474): hiding the MENU  button
V/RecentTasksLoader(  474): Loaded bitmap for task  com.android.systemui.recent.TaskDescription@41c8d508:  android.graphics.Bitmap@41bfbf40
V/RecentTasksLoader(  474): Loaded  bitmap for task com.android.systemui.recent.TaskDescription@41c3e9d0:  android.graphics.Bitmap@42266490
V/RecentTasksLoader(  474): Loaded  bitmap for task com.android.systemui.recent.TaskDescription@41c3f2e0:  android.graphics.Bitmap@41c0cde0
D/RecentsPanelView(  474):  onAnimationEnd
V/RecentsPanelView(  474):  onInterceptTouchEvent()
V/RecentsPanelView(  474):  onInterceptTouchEvent()
V/RecentsPanelView(  474):  onInterceptTouchEvent()
V/RecentsPanelView(  474):  onInterceptTouchEvent()
V/RecentsPanelView(  474):  onInterceptTouchEvent()
V/RecentsPanelView(  474):  onInterceptTouchEvent()
V/RecentsPanelView(  474):  onInterceptTouchEvent()
D/dalvikvm( 1692): GC_CONCURRENT freed 901K, 31% free  14548K/20807K, paused 5ms+5ms
D/PhoneStatusBar(  474): closing recents  panel
D/RecentsPanelView(  474):  startAnimation(appearing=false)
D/dalvikvm( 4404): GC_CONCURRENT freed  4965K, 37% free 12919K/20231K, paused 2ms+4ms
V/RecentsPanelView(  474):  onVisibilityChanged(com.android.systemui.recent.RecentsPanelView@4224b800,  8)
D/OpenGLRenderer(  474): Flushing caches (mode 0)
D/RecentsPanelView(   474): onAnimationEnd
D/dalvikvm( 4404): GC_CONCURRENT freed 138K, 35% free  13195K/20231K, paused 2ms+1ms
D/RecentsPanelView(  474):  panelHeight=0
D/PhoneStatusBar(  474): hiding the MENU button
D/dalvikvm(  4404): GC_CONCURRENT freed 84K, 34% free 13546K/20231K, paused  2ms+2ms
D/dalvikvm( 4404): GC_CONCURRENT freed 85K, 31% free 13972K/20231K,  paused 1ms+4ms
D/dalvikvm( 4404): GC_CONCURRENT freed 73K, 30% free  14306K/20231K, paused 2ms+6ms
D/dalvikvm( 4404): GC_CONCURRENT freed 347K,  29% free 14444K/20231K, paused 2ms+6ms
D/dalvikvm( 4404): GC_CONCURRENT freed  1379K, 33% free 13602K/20231K, paused 1ms+11ms
W/InputManagerService(  240):  Window already focused, ignoring focus gain of:  com.android.internal.view.IInputMethodClient$Stub$Proxy@416c3c18
D/dalvikvm(  4404): GC_CONCURRENT freed 770K, 35% free 13238K/20231K, paused  2ms+3ms
I/ActivityManager(  240): START  {act=android.intent.action.CHOOSER  cmp=android/com.android.internal.app.ChooserActivity (has extras)} from pid  4404
D/dalvikvm( 4404): GC_CONCURRENT freed 468K, 35% free 13240K/20231K,  paused 8ms+3ms
D/libEGL  ( 4404): loaded  /system/lib/egl/libGLES_android.so
D/PhoneStatusBar(  474): hiding the  MENU button
D/libEGL  ( 4404): loaded  /vendor/lib/egl/libEGL_POWERVR_SGX540_120.so
D/libEGL  ( 4404): loaded  /vendor/lib/egl/libGLESv1_CM_POWERVR_SGX540_120.so
D/libEGL  ( 4404):  loaded /vendor/lib/egl/libGLESv2_POWERVR_SGX540_120.so
D/OpenGLRenderer(  4404): Enabling debug mode 0
I/ActivityManager(  240): Displayed  android/com.android.internal.app.ChooserActivity: +303ms (total  +24m36s500ms)
I/ActivityManager(  240): START  {act=android.intent.action.SEND typ=text/plain flg=0x3000000 cmp=[URL="http://com.google.android.gm/.ComposeActivityGmail"]com.google.android.gm/.ComposeActivityGmail[/URL] (has extras)}  from pid 4404
D/dalvikvm(  240): GC_FOR_ALLOC freed 527K, 4% free  65774K/68167K, paused 197ms
I/ActivityManager(  240): Start proc [URL="http://com.google.android.gm/"]com.google.android.gm[/URL] for  activity [URL="http://com.google.android.gm/.ComposeActivityGmail"]com.google.android.gm/.ComposeActivityGmail[/URL]: pid=9172  uid=10020 gids={3003, 1015}
I/ActivityThread( 9172): Pub gmail-ls:  com.google.android.gm.provider.MailProvider
I/ActivityThread( 9172): Pub  com.google.android.gmail.SuggestionProvider:  com.google.android.gm.SuggestionsProvider
I/ActivityThread( 9172): Pub  [URL="http://com.google.android.gm/"]com.google.android.gm[/URL]:  com.google.android.gm.provider.PublicContentProvider
I/ActivityManager(   240): START {act=android.intent.action.SEND typ=text/plain flg=0x2000000  cmp=[URL="http://com.google.android.gm/.ComposeActivity"]com.google.android.gm/.ComposeActivity[/URL] (has extras)} from  pid 9172
D/dalvikvm( 9172): GC_FOR_ALLOC freed 242K, 3% free 12771K/13127K,  paused 32ms
D/dalvikvm( 9172): GC_FOR_ALLOC freed 138K, 5% free  12770K/13319K, paused 17ms
I/ActivityManager(  240): START {flg=0x2000000  cmp=[URL="http://com.google.android.gm/.WaitActivity"]com.google.android.gm/.WaitActivity[/URL] (has extras)} from  pid 9172
I/ActivityManager(  240): Start proc com.google.android.gsf.login  for service  com.google.android.gsf.login/com.google.android.gsf.loginservice.GoogleLoginService:  pid=9190 uid=10011 gids={3003, 1015, 1007, 2001, 3006}
D/dalvikvm(  240):  GC_EXPLICIT freed 290K, 4% free 65768K/68167K, paused  6ms+48ms
D/PhoneStatusBar(  474): hiding the MENU button
D/libEGL  (  9172): loaded /system/lib/egl/libGLES_android.so
D/libEGL  ( 9172):  loaded /vendor/lib/egl/libEGL_POWERVR_SGX540_120.so
D/libEGL  ( 9172):  loaded /vendor/lib/egl/libGLESv1_CM_POWERVR_SGX540_120.so
D/libEGL  (  9172): loaded  /vendor/lib/egl/libGLESv2_POWERVR_SGX540_120.so
I/ActivityManager(   240): No longer want com.google.android.gallery3d (pid 1174): hidden  #16
D/OpenGLRenderer( 9172): Enabling debug mode 0
W/InputManagerService(   240): Starting input on non-focused client  com.android.internal.view.IInputMethodClient$Stub$Proxy@43a171c0  (uid=10051 pid=4404)
I/ActivityManager(  240): START {flg=0x80000  cmp=com.google.android.gsf.login/.AccountIntroActivity (has extras)} from  pid 9172
I/GLSActivity( 9190): Starting account intro Intent { flg=0x80000  cmp=com.google.android.gsf.login/.AccountIntroActivity (has extras)  }
I/ActivityManager(  240): START  {cmp=com.google.android.gsf.login/.AccountIntroUIActivity (has extras)}  from pid 9190
I/ActivityManager(  240): Displayed [URL="http://com.google.android.gm/.WaitActivity"]com.google.android.gm/.WaitActivity[/URL]: +598ms (total  +817ms)
D/OpenGLRenderer( 9172): Flushing caches (mode  1)
D/PhoneStatusBar(  474): hiding the MENU button
D/libEGL  ( 9190):  loaded /system/lib/egl/libGLES_android.so
D/libEGL  ( 9190): loaded  /vendor/lib/egl/libEGL_POWERVR_SGX540_120.so
D/libEGL  ( 9190): loaded  /vendor/lib/egl/libGLESv1_CM_POWERVR_SGX540_120.so
D/libEGL  ( 9190):  loaded /vendor/lib/egl/libGLESv2_POWERVR_SGX540_120.so
D/OpenGLRenderer(  9190): Enabling debug mode 0
I/ActivityManager(  240): Displayed  com.google.android.gsf.login/.AccountIntroUIActivity:  +299ms
D/OpenGLRenderer( 4404): Flushing caches (mode 0)
D/OpenGLRenderer(  9172): Flushing caches (mode 0)
D/OpenGLRenderer( 4404): Flushing caches  (mode 1)
I/ActivityManager(  240): No longer want  com.google.android.partnersetup (pid 1444): hidden  #16
D/PhoneStatusBar(  474): hiding the MENU button
D/OpenGLRenderer(  9190): Flushing caches (mode 0)
D/OpenGLRenderer( 9172): Flushing caches  (mode 0)
D/dalvikvm( 4404): GC_CONCURRENT freed 545K, 36% free 13108K/20231K,  paused 3ms+2ms
I/ActivityManager(  240): No longer want com.android.musicfx  (pid 1621): hidden #16
D/OpenGLRenderer( 9190): Flushing caches (mode  1)
D/OpenGLRenderer( 9172): Flushing caches (mode 1)
D/dalvikvm( 4404):  GC_CONCURRENT freed 316K, 35% free 13195K/20231K, paused 2ms+2ms
D/dalvikvm(  4404): GC_CONCURRENT freed 103K, 33% free 13604K/20231K, paused  1ms+2ms
D/dalvikvm( 4404): GC_CONCURRENT freed 102K, 31% free 14012K/20231K,  paused 1ms+2ms
D/dalvikvm( 4404): GC_CONCURRENT freed 72K, 30% free  14343K/20231K, paused 1ms+7ms
D/dalvikvm( 4404): GC_CONCURRENT freed 348K,  29% free 14474K/20231K, paused 2ms+11ms
D/dalvikvm( 4404): GC_CONCURRENT  freed 1399K, 33% free 13624K/20231K, paused 2ms+10ms
 
iowabowtech said:
So how did you end up man? Install a new rom/kernel?
Click to expand...

Just dealing with it so far, hah. I really want to switch ROMs, but I don't have much time to compare and look at them all to decide what I should switch to. I was just about to flash BAMF 1.2, but then I saw that it doesn't support fast charge (which I really like at work where I have a weak signal and don't have a wall adapter or outlet around) or nav bar customization :(. Is there another ROM that has pretty much all of the Liquid features?

Thanks again for all the help :).
 
I have no clue bro. I use BAMF because I'm a kernel freak moreso than a rom freak. And I would bet the farm that Adrynalyne had a major hand in the supplied kernel for BAMF. So far as I'm concerned, he makes the best kernels bar none so I never have to worry about running a custom kernel when on BAMF. That said though, I don't worry about fastcharge b/c I have a wall charger at work.

And I don't care for nav bar mods other than the addition of a search icon. I like mine hidden in the status bar. That's where all the BAMF settings toggles reside which is my preference. Somewhat the same with AOKP I think although it may have some nav bar stuff, not sure. I know you can add extra nav icons with it.

Just depends on what you're used to I suppose. I like to keep my homescreen super clean so BAMF, AOKP and stock derivatives are all that I've used on the Gnex. :dontknow:
 
You must log in or register to reply here.
Back
Top Bottom