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

[Verizon] Booting phone takes much longer than it should

jrock204657

Well-Known Member
I've posted a couple times about this problem about being "stuck at the google image". As time has gone on I found out that it doesn't necessarily get stuck at the google img, it just sits there for literally about 15 minutes. It started out doing it about 75% of boots and now it's doing it everytime. Tried multiple rom's and same outcome. It DOES boot everytime...just takes about 15 minutes. If ANYONE has any ideas on the problem, I would appreciate your suggestions. Thanks.
 
I've posted a couple times about this problem about being "stuck at the google image". As time has gone on I found out that it doesn't necessarily get stuck at the google img, it just sits there for literally about 15 minutes. It started out doing it about 75% of boots and now it's doing it everytime. Tried multiple rom's and same outcome. It DOES boot everytime...just takes about 15 minutes. If ANYONE has any ideas on the problem, I would appreciate your suggestions. Thanks.

I am having the exact same problem. I use CWM, I wonder if that has anything to do with it ?
It just sits there, nothing happens.. then it boots normal. Sometimes it boots right away.
Phone is unlocked and rooted.
 
What rom are you running if any? If you are running a custom rom, did you do a clean flash? If you are running a custom rom, does your bootloader version match the rom? Or basically if you are running a JB rom, did you flash the JB bootloader? I have read that this is important, however if it's the other way around, i.e. JB bootloader with ICS rom that shouldn't be the problem; at least I had no problem running that way.
 
What rom are you running if any? If you are running a custom rom, did you do a clean flash? If you are running a custom rom, does your bootloader version match the rom? Or basically if you are running a JB rom, did you flash the JB bootloader? I have read that this is important, however if it's the other way around, i.e. JB bootloader with ICS rom that shouldn't be the problem; at least I had no problem running that way.

Running stock JB 4.1.1
Just checked in CWM, the bootloader is the latest one (primelc03)
Unlocked and rooted.
Is there a log anywhere ? I wonder if it's waiting for something and then times out and continues.
 
Yeah, there are other folks reporting this, too--see before and after my post here:

http://androidforums.com/verizon-galaxy-nexus-all-things-root/469049-how-root-vzw-lte-samsung-galaxy-nexus-2.html#post5032882

It sounds like an e2fsck (file system check) utility might be being launched by Android which is scanning your partitions for issues.

You could try installing one or both of Chainfire's [root] live logcat lite and/or [root] live dmesg lite apps to view the system logs to confirm this. You'd better have a magifying glass or good reading glasses, though, since the letters are very tiny.

I've got the paid version of the live dmesg app and the text is much more legible (there's a setting to enable that).

There are also ways to do something like this yourself:

http://androidforums.com/verizon-galaxy-nexus-all-things-root/612349-still-stuicking-boot-animation-logcat-3.html#post4976385

although you'll have to route the output to something like the /cache directory, but that's probably more useful anyway since you'd like to have a trace for after-the-fact analysis anyways.

I haven't experienced this issue myself, but if I was, this is what I would do...

Cheers and I'd love to know if you guys try this and/or what you might see.
 
Gentlemen,
I believe I have figured out a way to log this whole affair by using an app called alogrec. Same guy that made alogcat btw. Anyway, it will log indefinitely to the phone's storage which can then be reviewed post reboot.

There is a slight caveat. Most log collecting apps don't work well on Jellybean unless you first convert them to a system app via Titanium Backup. I confirmed this to be the case with alogrec and it's big brother alogcat among others. It will seem to work but it'll create a 0 byte file unless this is done.

So the full monty:

1.) download alogrec
2.) open titanium and convert alogrec to system app
3.) open alogrec and select option to start log
4.) reboot phone
5.) after reboot is complete, swipe down notification bar and stop alogrec
6.) use file explorer to navigate to location...storage/sdcard0/alorgrec...and view the log. It can also be copied and posted for us to review if you want. It saves itself with a .log extention but I'd be willing to bet you can rename and save it as a .txt file and then move to the PC so it could be pasted into a viewable doc.
 
I installed dmesg lite.
It does work, and of course when I installed it , it actually booted up quickly.. Tried several times..

Tried a different approach...
I logged into the terminal emulator, and ran dmesg to a file.
This cuts of part of the log so I am wondering where/if dmesg lite logs to ?
 
Ok, I realized I was looking at the dmesg output after running the phone for a while so I did another reboot...
Sure enough, it took forever to boot up again. I went into the terminal emulator again and wrote the dmesg output to a logfile. I think I have the log now from the start.
I noticed this section;

Code:
<6>[   10.222198] omap_rpmsg_mbox_callback: received echo reply from ipu ! <6>[   10.222320] virtio_rpmsg_bus virtio0: creating channel rpmsg-client-sample addr 0x32 <6>[   10.222564] virtio_rpmsg_bus virtio0: creating channel rpmsg-client-sample addr 0x33 <6>[   10.222869] virtio_rpmsg_bus virtio0: creating channel rpmsg-omx addr 0x3c <6>[   10.223144] rpmsg_omx rpmsg-omx0: new OMX connection srv channel: 1024 -> 60! <6>[   10.233032] sr_class1p5_calib_work: mpu: Calibration complete: Voltage:Nominal=1317000,Calib=1110000,margin=0 <6>[   10.755218] max17040 4-0036: online = 1 vcell = 4107500 soc = 91 status = 1 health = 1 temp = 350 charger status = 0 <6>[   11.322021] virtio_rpmsg_bus virtio1: creating channel rpmsg-omx addr 0x3c <6>[   11.322845] rpmsg_omx rpmsg-omx1: new OMX connection srv channel: 1024 -> 60! <6>[   63.615570] max17040 4-0036: online = 1 vcell = 4140000 soc = 91 status = 1 health = 1 temp = 350 charger status = 0 <6>[  116.475769] max17040 4-0036: online = 1 vcell = 4142500 soc = 91 status = 1 health = 1 temp = 350 charger status = 0 <6>[  162.302337] EXT4-fs (mmcblk0p12): mounted filesystem with ordered data mode. Opts: nomblk_io_submit,errors=panic <3>[  162.448394] init: cannot execve('/system/etc/install-recovery.sh'): Permission denied <3>[  162.499816] android_usb: already disabled <6>[  162.500305] mtp_bind_config <6>[  162.674560] SMC: SecureCRC=0xBC7458DC <6>[  162.787780] PVR: Installing MISR with cookie c08e7208 <6>[  162.788055] PVR: Installing device LISR SGX ISR on IRQ 53 with cookie c79b1a00 <6>[  162.788146] PVR: OSUnMapPhysToLin: unmapping 65535 bytes from c8be0000 <6>[  162.788269] PVR: SysFinalise: Version string: SGX revision = 1.2.0 <6>[  162.797912] android_work: sent uevent USB_STATE=CONNECTED <6>[  162.802795] android_work: sent uevent USB_STATE=DISCONNECTED <6>[  162.821136] warning: `rild' uses 32-bit capabilities (legacy support in use)
Then a little further down I see this, which also seems weird;

Code:
<4>[  297.397247] omap_thermal_throttle: temperature too high, cpu throttle at max 350000 <4>[  299.405822] omap_thermal_unthrottle: temperature reduced, ending cpu throttling <4>[  301.405822] omap_thermal_throttle: temperature too high, cpu throttle at max 920000 <4>[  302.404937] throttle_delayed_work_fn: OMAP temp read 64600 exceeds the threshold <4>[  302.405090] omap_thermal_throttle: temperature too high, cpu throttle at max 700000 <4>[  303.404937] throttle_delayed_work_fn: OMAP temp read 64600 exceeds the threshold <4>[  303.405029] omap_thermal_throttle: temperature too high, cpu throttle at max 350000 <4>[  305.405792] omap_thermal_unthrottle: temperature reduced, ending cpu throttling <4>[  307.405792] omap_thermal_throttle: temperature too high, cpu throttle at max 920000 <4>[  308.404937] throttle_delayed_work_fn: OMAP temp read 64200 exceeds the threshold <4>[  308.405059] omap_thermal_throttle: temperature too high, cpu throttle at max 700000 <4>[  309.404907] throttle_delayed_work_fn: OMAP temp read 64200 exceeds the threshold <4>[  309.405029] omap_thermal_throttle: temperature too high, cpu throttle at max 350000 <4>[  311.405792] omap_thermal_unthrottle: temperature reduced, ending cpu throttling <4>[  315.405731] omap_thermal_throttle: temperature too high, cpu throttle at max 920000 <4>[  316.412719] throttle_delayed_work_fn: OMAP temp read 65400 exceeds the threshold <4>[  316.412872] omap_thermal_throttle: temperature too high, cpu throttle at max 700000 <4>[  319.405700] omap_thermal_unthrottle: temperature reduced, ending cpu throttling <4>[  321.405670] omap_thermal_throttle: temperature too high, cpu throttle at max 920000 <4>[  322.404907] throttle_delayed_work_fn: OMAP temp read 65400 exceeds the threshold <4>[  322.405029] omap_thermal_throttle: temperature too high, cpu throttle at max 700000 <4>[  323.404907] throttle_delayed_work_fn: OMAP temp read 65400 exceeds the threshold <4>[  323.405090] omap_thermal_throttle: temperature too high, cpu throttle at max 350000 <4>[  323.406311] omap_thermal_unthrottle: temperature reduced, ending cpu throttling <6>[  324.154785] binder: 508:2183 transaction failed 29189, size 340-0 <6>[  324.306457] binder: 508:2183 transaction failed 29189, size 340-0 <6>[  325.211730] PVR: ShrinkPagePool: Number to scan: 128 <6>[  325.211853] PVR: ShrinkPagePool: Pages in pool before scan: 4312 <6>[  325.212005] PVR: ShrinkPagePool: Pages in pool after scan: 4184 <4>[  325.405700] omap_thermal_throttle: temperature too high, cpu throttle at max 920000
 
az_r2d1,

Thanks for the output...I've reformatted it for you/us here to make it a little easier to digest:

<6>[ 10.222198] omap_rpmsg_mbox_callback: received echo reply from ipu !
<6>[ 10.222320] virtio_rpmsg_bus virtio0: creating channel rpmsg-client-sample addr 0x32
<6>[ 10.222564] virtio_rpmsg_bus virtio0: creating channel rpmsg-client-sample addr 0x33
<6>[ 10.222869] virtio_rpmsg_bus virtio0: creating channel rpmsg-omx addr 0x3c
<6>[ 10.223144] rpmsg_omx rpmsg-omx0: new OMX connection srv channel: 1024 -> 60!
<6>[ 10.233032] sr_class1p5_calib_work: mpu: Calibration complete: Voltage:Nominal=1317000,Calib=1110000,margin=0
<6>[ 10.755218] max17040 4-0036: online = 1 vcell = 4107500 soc = 91 status = 1 health = 1 temp = 350 charger status = 0
<6>[ 11.322021] virtio_rpmsg_bus virtio1: creating channel rpmsg-omx addr 0x3c
<6>[ 11.322845] rpmsg_omx rpmsg-omx1: new OMX connection srv channel: 1024 -> 60!
<6>[ 63.615570] max17040 4-0036: online = 1 vcell = 4140000 soc = 91 status = 1 health = 1 temp = 350 charger status = 0
<6>[ 116.475769] max17040 4-0036: online = 1 vcell = 4142500 soc = 91 status = 1 health = 1 temp = 350 charger status = 0
<6>[ 162.302337] EXT4-fs (mmcblk0p12): mounted filesystem with ordered data mode. Opts: nomblk_io_submit,errors=panic
<3>[ 162.448394] init: cannot execve('/system/etc/install-recovery.sh'): Permission denied
<3>[ 162.499816] android_usb: already disabled
<6>[ 162.500305] mtp_bind_config
<6>[ 162.674560] SMC: SecureCRC=0xBC7458DC
<6>[ 162.787780] PVR: Installing MISR with cookie c08e7208
<6>[ 162.788055] PVR: Installing device LISR SGX ISR on IRQ 53 with cookie c79b1a00
<6>[ 162.788146] PVR: OSUnMapPhysToLin: unmapping 65535 bytes from c8be0000
<6>[ 162.788269] PVR: SysFinalise: Version string: SGX revision = 1.2.0
<6>[ 162.797912] android_work: sent uevent USB_STATE=CONNECTED
<6>[ 162.802795] android_work: sent uevent USB_STATE=DISCONNECTED
<6>[ 162.821136] warning: `rild' uses 32-bit capabilities (legacy support in use)


<4>[ 297.397247] omap_thermal_throttle: temperature too high, cpu throttle at max 350000
<4>[ 299.405822] omap_thermal_unthrottle: temperature reduced, ending cpu throttling
<4>[ 301.405822] omap_thermal_throttle: temperature too high, cpu throttle at max 920000
<4>[ 302.404937] throttle_delayed_work_fn: OMAP temp read 64600 exceeds the threshold
<4>[ 302.405090] omap_thermal_throttle: temperature too high, cpu throttle at max 700000
<4>[ 303.404937] throttle_delayed_work_fn: OMAP temp read 64600 exceeds the threshold
<4>[ 303.405029] omap_thermal_throttle: temperature too high, cpu throttle at max 350000
<4>[ 305.405792] omap_thermal_unthrottle: temperature reduced, ending cpu throttling
<4>[ 307.405792] omap_thermal_throttle: temperature too high, cpu throttle at max 920000
<4>[ 308.404937] throttle_delayed_work_fn: OMAP temp read 64200 exceeds the threshold
<4>[ 308.405059] omap_thermal_throttle: temperature too high, cpu throttle at max 700000
<4>[ 309.404907] throttle_delayed_work_fn: OMAP temp read 64200 exceeds the threshold
<4>[ 309.405029] omap_thermal_throttle: temperature too high, cpu throttle at max 350000
<4>[ 311.405792] omap_thermal_unthrottle: temperature reduced, ending cpu throttling
<4>[ 315.405731] omap_thermal_throttle: temperature too high, cpu throttle at max 920000
<4>[ 316.412719] throttle_delayed_work_fn: OMAP temp read 65400 exceeds the threshold
<4>[ 316.412872] omap_thermal_throttle: temperature too high, cpu throttle at max 700000
<4>[ 319.405700] omap_thermal_unthrottle: temperature reduced, ending cpu throttling
<4>[ 321.405670] omap_thermal_throttle: temperature too high, cpu throttle at max 920000
<4>[ 322.404907] throttle_delayed_work_fn: OMAP temp read 65400 exceeds the threshold
<4>[ 322.405029] omap_thermal_throttle: temperature too high, cpu throttle at max 700000
<4>[ 323.404907] throttle_delayed_work_fn: OMAP temp read 65400 exceeds the threshold
<4>[ 323.405090] omap_thermal_throttle: temperature too high, cpu throttle at max 350000
<4>[ 323.406311] omap_thermal_unthrottle: temperature reduced, ending cpu throttling
<6>[ 324.154785] binder: 508:2183 transaction failed 29189, size 340-0
<6>[ 324.306457] binder: 508:2183 transaction failed 29189, size 340-0
<6>[ 325.211730] PVR: ShrinkPagePool: Number to scan: 128
<6>[ 325.211853] PVR: ShrinkPagePool: Pages in pool before scan: 4312
<6>[ 325.212005] PVR: ShrinkPagePool: Pages in pool after scan: 4184
<4>[ 325.405700] omap_thermal_throttle: temperature too high, cpu throttle at max 920000

Still looking at it...I wonder if we'll need a corresponding logcat, too...
 
This cuts of part of the log so I am wondering where/if dmesg lite logs to ?

I don't think it actually logs to anywhere except for the screen.

Tonight when I get home from work, I'll try to route / capture a dmesg from boot-up (replacing the boot animation with logging of dmesg to a file in /cache) and will see if I'm seeing messages similar what you've posted.

Those may simply be normal messages...I think I was looking for things that might reference e2fsck.

I'll report back tonight--thanks!
 
I found a reference to e2fsck

Code:
<4>[    9.723999] init (1): /proc/1/oom_adj is deprecated, please use /proc/1/oom_score_adj instead.
<6>[    9.752532] max17040 4-0036: online = 1 vcell = 4093750 soc = 91 status = 3 health = 1 temp = 350 charger status = 0
<6>[    9.805236] keychord: using input dev tuna-gpio-keypad for fevent
<6>[    9.876068] EXT4-fs (mmcblk0p10): mounted filesystem with ordered data mode. Opts: (null)
<6>[    9.884460] EXT4-fs (mmcblk0p3): mounted filesystem with ordered data mode. Opts: (null)
<6>[    9.884613] fs_mgr: Running /system/bin/e2fsck on /dev/block/platform/omap/omap_hsmmc.0/by-name/cache
<6>[    9.916687] HDCP: loaded keys
<6>[    9.943359] mms_ts 3-0048: fw version 0x65 already present
<6>[    9.943664] mms_ts 3-0048: Melfas MMS-series touch controller initialized
<6>[    9.973724] EXT4-fs (mmcblk0p11): mounted filesystem with ordered data mode. Opts: nomblk_io_submit,errors=panic
<6>[    9.974304] fs_mgr: Running /system/bin/e2fsck on /dev/block/platform/omap/omap_hsmmc.0/by-name/userdata
<6>[    9.975463] sr_class1p5_calib_work: core: Calibration complete: Voltage:Nominal=962000,Calib=886240,margin=38000
<3>[    9.993438] Invalid Device Structure
<4>[   10.022216] sr_class1p5_calib_work: mpu Stop sampling: Voltage Nominal=1025000 samples=11
<6>[   10.022705] sr_class1p5_calib_work: mpu: Calibration complete: Voltage:Nominal=1025000,Calib=840000,margin=10000
<6>[   10.171997] omap-rproc omap-rproc.1: Loaded BIOS image ducati-m3.bin, size 4515836
<6>[   10.172088] omap-rproc omap-rproc.1: BIOS image version is 2
<6>[   10.185882] omap-iommu omap-iommu.0: iommu_get: ducati qos_request
<4>[   10.199829] omap_hwmod: ipu: failed to hardreset
<6>[   10.201110] omap-iommu omap-iommu.0: ducati: version 2.1
<6>[   10.208831] omap-rproc omap-rproc.1: remote processor ipu is now up
<6>[   10.221862] omap_rpmsg_mbox_callback: received echo reply from ipu !
<6>[   10.221954] omap_rpmsg_mbox_callback: received echo reply from ipu !
<6>[   10.222076] omap_rpmsg_mbox_callback: received echo reply from ipu !
<6>[   10.222198] omap_rpmsg_mbox_callback: received echo reply from ipu !
<6>[   10.222320] virtio_rpmsg_bus virtio0: creating channel rpmsg-client-sample addr 0x32
<6>[   10.222564] virtio_rpmsg_bus virtio0: creating channel rpmsg-client-sample addr 0x33
<6>[   10.222869] virtio_rpmsg_bus virtio0: creating channel rpmsg-omx addr 0x3c
<6>[   10.223144] rpmsg_omx rpmsg-omx0: new OMX connection srv channel: 1024 -> 60!
<6>[   10.233032] sr_class1p5_calib_work: mpu: Calibration complete: Voltage:Nominal=1317000,Calib=1110000,margin=0
<6>[   10.755218] max17040 4-0036: online = 1 vcell = 4107500 soc = 91 status = 1 health = 1 temp = 350 charger status = 0
<6>[   11.322021] virtio_rpmsg_bus virtio1: creating channel rpmsg-omx addr 0x3c
<6>[   11.322845] rpmsg_omx rpmsg-omx1: new OMX connection srv channel: 1024 -> 60!
<6>[   63.615570] max17040 4-0036: online = 1 vcell = 4140000 soc = 91 status = 1 health = 1 temp = 350 charger status = 0
<6>[  116.475769] max17040 4-0036: online = 1 vcell = 4142500 soc = 91 status = 1 health = 1 temp = 350 charger status = 0
<6>[  162.302337] EXT4-fs (mmcblk0p12): mounted filesystem with ordered data mode. Opts: nomblk_io_submit,errors=panic
<3>[  162.448394] init: cannot execve('/system/etc/install-recovery.sh'): Permission denied
<3>[  162.499816] android_usb: already disabled
<6>[  162.500305] mtp_bind_config
<6>[  162.674560] SMC: SecureCRC=0xBC7458DC
<6>[  162.787780] PVR: Installing MISR with cookie c08e7208
<6>[  162.788055] PVR: Installing device LISR SGX ISR on IRQ 53 with cookie c79b1a00
<6>[  162.788146] PVR: OSUnMapPhysToLin: unmapping 65535 bytes from c8be0000
<6>[  162.788269] PVR: SysFinalise: Version string: SGX revision = 1.2.0
<6>[  162.797912] android_work: sent uevent USB_STATE=CONNECTED
<6>[  162.802795] android_work: sent uevent USB_STATE=DISCONNECTED
<6>[  162.821136] warning: `rild' uses 32-bit capabilities (legacy support in use)
<6>[  162.863891] android_work: sent uevent USB_STATE=CONNECTED
<6>[  162.870574] SMC PA: INFO 00000001 00:00:00.000 ------ ------  --------------- Booting... (t=14:13:58.643) SMCXG01.04p11.32196     /Widevine Integration
<6>[  162.871887] SMC PA: INFO 00000002 00:00:00.000 ------ ------  --------------- Booted
<6>[  163.031829] android_usb gadget: high speed config #1: android
<6>[  163.032043] android_work: sent uevent USB_STATE=CONFIGURED
<6>[  164.090942] [MODEM_IF] misc_open : lte_boot0
<6>[  164.091003] [MODEM_IF] cmc221_on()
<6>[  164.186737] s6e8aa: d/b 1, p 1, b-01390ffb
<6>[  164.186767] s6e8aa: c0, 1, b-003cff6c, got v 3439157, factory wants 3433298
<6>[  164.186798] s6e8aa: c1, 1, b-00854d87, got v 3686904, factory wants 3678369
<6>[  164.186828] s6e8aa: c2, 1, b-003798e7, got v 3400258, factory wants 3394333
 
Ah, very cool! It's nice to see this actually being recorded!

I'm guessing that the values in square brackets are a timestamp (seconds and partial seconds) of some sort and it doesn't / didn't look like those two e2fsck invocations took too long (for /cache and /userdata).

I'm thinking that a full dmesg capture of a long boot that you are experiencing might indeed reveal if it is an e2fsck being run (assuming we'd see a larger difference or gaps in the timestamp entries).

Did you look at the other thread I referenced where I showed gapi how to insert the dmesg run at boot time by (first saving and then) replacing his bootanimation file with a script?

That's what I plan to do tonight on my device to see how/when/if the e2fsck is run to compare to your device.
 
I Guess I should have just logged more.
Here is the very first part up to where I see that jump in timing;
Code:
<6>[    0.000000] Initializing cgroup subsys cpu
<5>[    0.000000] Linux version 3.0.31-g396c4df (android-build@vpbs1.mtv.corp.google.com) (gcc version 4.6.x-google 20120106 (prerelease) (GCC) ) #1 SMP PREEMPT Mon Jul
16 18:08:06 PDT 2012
<4>[    0.000000] CPU: ARMv7 Processor [412fc09a] revision 10 (ARMv7), cr=10c5387d
<4>[    0.000000] CPU: VIPT nonaliasing data cache, VIPT aliasing instruction cache
<4>[    0.000000] Machine: Tuna
<6>[    0.000000] Reserving 16777216 bytes SDRAM for VRAM
<4>[    0.000000] Memory policy: ECC disabled, Data cache writealloc
<6>[    0.000000] OMAP4460 ES1.1
<6>[    0.000000] SRAM: Mapped pa 0x4030d000 to va 0xfe40d000 size: 0x1000
<6>[    0.000000] SMC: Allocated workspace of 0x300000 Bytes at (0xbfd00000)
<7>[    0.000000] On node 0 totalpages: 182272
<7>[    0.000000] free_area_init_node: node 0, pgdat c0849700, node_mem_map c09f6000
<7>[    0.000000]   Normal zone: 256 pages used for memmap
<7>[    0.000000]   Normal zone: 0 pages reserved
<7>[    0.000000]   Normal zone: 32512 pages, LIFO batch:7
<7>[    0.000000]   HighMem zone: 1792 pages used for memmap
<7>[    0.000000]   HighMem zone: 147712 pages, LIFO batch:31
<6>[    0.000000] PERCPU: Embedded 7 pages/cpu @c11fd000 s7424 r8192 d13056 u32768
<7>[    0.000000] pcpu-alloc: s7424 r8192 d13056 u32768 alloc=8*4096
<7>[    0.000000] pcpu-alloc: [0] 0 [0] 1 
<4>[    0.000000] Built 1 zonelists in Zone order, mobility grouping on.  Total pages: 180224
<5>[    0.000000] Kernel command line: console=ttyFIQ0 androidboot.console=ttyFIQ0 mem=1G vmalloc=768M omap_wdt.timer_margin=30 no_console_suspend
androidboot.serialno=016B756E0A00601B androidboot.bootloader=PRIMELC03 androidboot.baseband=I515.FG02 lcd_bootfb=0xbea70000 mms_ts.panel_id=18 androidboot.cdma=I515.FF02
androidboot.macaddr=2C:44:01:C4:B6:5A 
<6>[    0.000000] PID hash table entries: 512 (order: -1, 2048 bytes)
<6>[    0.000000] Dentry cache hash table entries: 16384 (order: 4, 65536 bytes)
<6>[    0.000000] Inode-cache hash table entries: 8192 (order: 3, 32768 bytes)
<6>[    0.000000] Memory: 512MB 200MB = 712MB total
<5>[    0.000000] Memory: 710184k/710184k available, 338392k reserved, 598016K highmem
<5>[    0.000000] Virtual kernel memory layout:
<5>[    0.000000]     vector  : 0xffff0000 - 0xffff1000   (   4 kB)
<5>[    0.000000]     fixmap  : 0xfff00000 - 0xfffe0000   ( 896 kB)
<5>[    0.000000]     DMA     : 0xff000000 - 0xffe00000   (  14 MB)
<5>[    0.000000]     vmalloc : 0xc8800000 - 0xf8000000   ( 760 MB)
<5>[    0.000000]     lowmem  : 0xc0000000 - 0xc8000000   ( 128 MB)
<5>[    0.000000]     pkmap   : 0xbfe00000 - 0xc0000000   (   2 MB)
<5>[    0.000000]     modules : 0xbf000000 - 0xbfe00000   (  14 MB)
<5>[    0.000000]       .init : 0xc0008000 - 0xc0050000   ( 288 kB)
<5>[    0.000000]       .text : 0xc0050000 - 0xc07ea000   (7784 kB)
<5>[    0.000000]       .data : 0xc07ea000 - 0xc084b4c0   ( 390 kB)
<5>[    0.000000]        .bss : 0xc084b4e4 - 0xc09f51d0   (1704 kB)
<6>[    0.000000] SLUB: Genslabs=13, HWalign=32, Order=0-3, MinObjects=0, CPUs=2, Nodes=1
<6>[    0.000000] Preemptible hierarchical RCU implementation.
<6>[    0.000000] NR_IRQS:410
<4>[    0.000000] omap_hwmod: dpll_mpu_m2_ck: missing clockdomain for dpll_mpu_m2_ck.
<6>[    0.000000] OMAP clockevent source: GPTIMER1 at 32768 Hz
<6>[    0.000000] sched_clock: 32 bits at 32kHz, resolution 30517ns, wraps every 131071999ms
<6>[    0.000061] Calibrating delay loop... 1194.24 BogoMIPS (lpj=4665344)
<6>[    0.070404] pid_max: default: 32768 minimum: 301
<6>[    0.070404] Mount-cache hash table entries: 512
<6>[    0.070404] Initializing cgroup subsys debug
<6>[    0.071685] Initializing cgroup subsys cpuacct
<6>[    0.071685] Initializing cgroup subsys freezer
<6>[    0.071807] CPU: Testing write buffer coherency: ok
<6>[    0.071960] hw perfevents: enabled with ARMv7 Cortex-A9 PMU driver, 7 counters available
<6>[    0.072113] L310 cache controller enabled
<6>[    0.072204] l2x0: 16 ways, CACHE_ID 0x410000c7, AUX_CTRL 0x5e470000, Cache size: 1048576 B
<4>[    0.150268] CPU1: Booted secondary processor
<6>[    0.181457] Brought up 2 CPUs
<6>[    0.181518] SMP: Total of 2 processors activated (2388.48 BogoMIPS).
<4>[    0.181793] omap_hwmod: _populate_mpu_rt_base found no _mpu_rt_va for emif_fw
<4>[    0.185577] omap_hwmod: _populate_mpu_rt_base found no _mpu_rt_va for l3_instr
<4>[    0.185577] omap_hwmod: _populate_mpu_rt_base found no _mpu_rt_va for l3_main_1
<4>[    0.185607] omap_hwmod: _populate_mpu_rt_base found no _mpu_rt_va for l3_main_2
<4>[    0.185607] omap_hwmod: _populate_mpu_rt_base found no _mpu_rt_va for l4_abe
<4>[    0.185607] omap_hwmod: _populate_mpu_rt_base found no _mpu_rt_va for l4_cfg
<4>[    0.185607] omap_hwmod: _populate_mpu_rt_base found no _mpu_rt_va for l4_per
<4>[    0.185668] omap_hwmod: _populate_mpu_rt_base found no _mpu_rt_va for l4_wkup
<4>[    0.185668] omap_hwmod: _populate_mpu_rt_base found no _mpu_rt_va for mpu_private
<4>[    0.185699] omap_hwmod: _populate_mpu_rt_base found no _mpu_rt_va for sl2if
<4>[    0.185821] omap_hwmod: _populate_mpu_rt_base found no _mpu_rt_va for thermal_sensor
<4>[    0.185821] omap_hwmod: _populate_mpu_rt_base found no _mpu_rt_va for usbhs_ehci
<4>[    0.185821] omap_hwmod: l3_div_ck: missing clockdomain for l3_div_ck.
<4>[    0.185821] omap_hwmod: l4_div_ck: missing clockdomain for l4_div_ck.
<4>[    0.193969] omap_hwmod: mcpdm: cannot be enabled (3)
<6>[    0.196655] print_constraints: dummy: 
<6>[    0.196655] NET: Registered protocol family 16
<6>[    0.197113] GPMC revision 6.0
<6>[    0.197113] EMIF0 is enabled with IRQ142
<6>[    0.197113] EMIF1 is enabled with IRQ143
<6>[    0.197113] Last reset was warm software reset (PRM_RSTST=0x2)
<6>[    0.197113] 
<6>[    0.198425] HSI: device registered as omap_hwmod: hsi
<6>[    0.198852] OMAP GPIO hardware version 0.1
<6>[    0.199859] omap_mux_init: Add partition: #1: core, flags: 2
<6>[    0.199859] omap_mux_init: Add partition: #2: wkup, flags: 2
<6>[    0.201690] Tuna HW revision: 19 (Toro 8-1th Sample), cpu OMAP4460 ES1.1 
<6>[    0.204010] ram_console: got buffer at a0000000, size 200000
<6>[    0.204437] ram_console: found existing buffer, size 1834980, start 1182325
<6>[    4.726135] console [ram-1] enabled
<6>[    4.726196] Using tuna_oled_factory_info_6500k
<6>[    4.730041] _omap_mux_init_gpio: Multiple gpio paths (2) for gpio102
<3>[    4.730072] omap_mux_init_gpio: Could not set gpio102
<6>[    4.730804] _omap_mux_init_gpio: Multiple gpio paths (2) for gpio101
<3>[    4.730804] omap_mux_init_gpio: Could not set gpio101
<6>[    4.923645] _omap_mux_init_gpio: Multiple gpio paths (2) for gpio100
<3>[    4.930847] omap_mux_init_gpio: Could not set gpio100
<6>[    4.936096] usb:ehci initialized
<6>[    4.936096] hw-breakpoint: found 6 breakpoint and 1 watchpoint registers.
<6>[    4.936889] hw-breakpoint: 1 breakpoint(s) reserved for watchpoint single-step.
<6>[    4.937011] hw-breakpoint: maximum watchpoint size is 4 bytes.
<6>[    4.941986] OMAP DMA hardware revision 0.0
<4>[    4.941986] omap_rproc_get_pool: carveout memory is unavailable: 0x0, 0x0
<6>[    4.951019] bio: create slab <bio-0> at 0
<6>[    4.951782] print_constraints: vwl1271: 2000 mV 
<5>[    4.951782] SCSI subsystem initialized
<6>[    4.953826] usbcore: registered new interface driver usbfs
<6>[    4.954284] usbcore: registered new interface driver hub
<6>[    4.954589] usbcore: registered new device driver usb
<6>[    4.954589] i2c-gpio i2c-gpio.5: using pins 98 (SDA) and 99 (SCL)
<6>[    4.970581] omap_i2c omap_i2c.1: bus 1 rev4.0 at 400 kHz
<4>[    4.970581] Skipping twl internal clock init and using bootloader value (unknown osc rate)
<6>[    4.973724] twl6030: PIH (irq 39) chaining IRQs 368..387
<6>[    4.976440] print_constraints: VUSB: 3300 mV normal standby
<6>[    4.977020] print_constraints: VMMC: 1800 mV normal standby
<6>[    4.977935] print_constraints: VPP: 1800 <--> 2500 mV at 1900 mV normal standby
<6>[    4.978332] print_constraints: VUSIM: 2200 mV normal standby
<6>[    4.978332] print_constraints: VCXIO: 1800 mV normal standby
<6>[    4.978912] print_constraints: VDAC: 1800 mV normal standby
<6>[    4.980285] print_constraints: VAUX1_6030: 3000 mV normal standby
<6>[    4.981109] print_constraints: VAUX2_6030: 1200 <--> 2800 mV at 1800 mV normal standby
<6>[    4.981628] print_constraints: VAUX3_6030: 3100 mV normal standby
<6>[    4.982635] print_constraints: CLK32KG: 
<6>[    4.983612] print_constraints: CLK32KAUDIO: 
<6>[    4.984405] print_constraints: VDD3: 
<6>[    4.985290] print_constraints: VMEM: at 1250 mV 
<6>[    4.986083] print_constraints: V2V1: 2100 mV normal standby
<6>[    4.986907] print_constraints: VANA: 2100 mV normal standby
<6>[    5.001800] omap_i2c omap_i2c.2: bus 2 rev4.0 at 400 kHz
<6>[    5.017456] omap_i2c omap_i2c.3: bus 3 rev4.0 at 400 kHz
<6>[    5.033020] omap_i2c omap_i2c.4: bus 4 rev4.0 at 400 kHz
<6>[    5.033996] omap-rproc omap-rproc.0: dsp is available
<6>[    5.033996] omap-rproc omap-rproc.1: ipu is available
<6>[    5.034942] Advanced Linux Sound Architecture Driver Version 1.0.24.
<6>[    5.034942] Bluetooth: Core ver 2.16
<6>[    5.034942] NET: Registered protocol family 31
<6>[    5.036041] Bluetooth: HCI device and connection manager initialized
<6>[    5.036041] Bluetooth: HCI socket layer initialized
<6>[    5.036041] Bluetooth: L2CAP socket layer initialized
<6>[    5.036041] Bluetooth: SCO socket layer initialized
<6>[    5.037048] cfg80211: Calling CRDA to update world regulatory domain
<6>[    5.037384] Switching to clocksource 32k_counter
<6>[    5.040863] Switched to NOHz mode on CPU #0
<6>[    5.040863] Switched to NOHz mode on CPU #1
<6>[    5.052856] musb-hdrc: version 6.0, musb-dma, otg (peripheral+host)
<7>[    5.053375] musb-hdrc: ConfigData=0xde (UTMI-8, dyn FIFOs, bulk combine, bulk split, HB-ISO Rx, HB-ISO Tx, SoftConn)
<7>[    5.053405] musb-hdrc: MHDRC RTL version 2.0 
<7>[    5.053771] musb-hdrc: setup fifo_mode 4
<7>[    5.053771] musb-hdrc: 28/31 max ep, 16384/16384 memory
<6>[    5.054168] musb-hdrc musb-hdrc: USB OTG mode controller at fc0ab000 using DMA, IRQ 124
<6>[    5.054748] NET: Registered protocol family 2
<6>[    5.054840] IP route cache hash table entries: 4096 (order: 2, 16384 bytes)
<6>[    5.054840] TCP established hash table entries: 16384 (order: 5, 131072 bytes)
<6>[    5.054840] TCP bind hash table entries: 16384 (order: 5, 196608 bytes)
<6>[    5.057434] TCP: Hash tables configured (established 16384 bind 16384)
<6>[    5.057434] TCP reno registered
<6>[    5.057434] UDP hash table entries: 128 (order: 0, 4096 bytes)
<6>[    5.057739] UDP-Lite hash table entries: 128 (order: 0, 4096 bytes)
<6>[    5.057739] NET: Registered protocol family 1
<6>[    5.058532] Trying to unpack rootfs image as initramfs...
<6>[    5.088928] Freeing initrd memory: 316K
<6>[    6.194946] PHOENIX_LAST_TURNOFF_STS: 0x01
<6>[    6.199645] console [ttyFIQ0] enabled
<6>[    6.199645] Registered FIQ tty driver c78c6c00
<6>[    6.200927] omap-iommu omap-iommu.0: ducati registered
<6>[    6.201843] omap-iommu omap-iommu.1: tesla registered
<4>[    6.209899] highmem bounce pool size: 64 pages
<6>[    6.210144] ashmem: initialized
<6>[    6.220123] fuse init (API version 7.16)
<6>[    6.220672] msgmni has been set to 219
<6>[    6.220916] Registering char device tf_driver (122:0)
<6>[    6.223114] io scheduler noop registered
<6>[    6.223205] io scheduler deadline registered
<6>[    6.223388] io scheduler cfq registered (default)
<6>[    6.224426] OMAP DSS rev 4.0
<6>[    6.227661] dsscomp: initializing.
<7>[    6.227783] misc dsscomp: display0=s6e8aa0
<7>[    6.227966] misc dsscomp: display1=hdmi_panel
<6>[    6.228057] misc dsscomp: found 2 displays and 4 overlays
<3>[    6.228393] could not allocate slot
<6>[    6.229400] omap-rproc omap-rproc.1: powering up ipu
<6>[    6.229827] virtio_rpmsg_bus virtio0: rpmsg backend virtproc probed successfully
<6>[    6.230560] virtio_rpmsg_bus virtio1: rpmsg backend virtproc probed successfully
<6>[    6.231384] omap_uart.0: ttyO0 at MMIO 0x4806a000 (irq = 104) is a OMAP UART0
<6>[    6.264343] omap_uart.1: ttyO1 at MMIO 0x4806c000 (irq = 105) is a OMAP UART1
<6>[    6.280303] omap_uart.3: ttyO3 at MMIO 0x4806e000 (irq = 102) is a OMAP UART3
<6>[    6.373626] omap_ion_probe: adding heap secure_input of type 2 with ba300000@5a00000
<6>[    6.374267] omap_ion_probe: adding heap tiler of type 4 with ae900000@5100000
<6>[    6.374267] omap_ion_probe: adding heap nonsecure_tiler of type 4 with ada00000@f00000
<6>[    6.374725] PVR: PVRCore_Init
<6>[    6.375152] PVR: LinuxMMInit: Maximum page pool size: 10800
<6>[    6.375152] PVR: PVRSRVDriverProbe(pDevice=c78b3200)
<6>[    6.375152] PVR: SGX register base: 0x56000000
<6>[    6.375671] PVR: SGX register size: 65535
<6>[    6.375885] PVR: SGX IRQ: 53
<6>[    6.375885] PVR: EnableSystemClocks: Enabling System Clocks
<6>[    6.376403] PVR: PVRCore_Init: major device 250
<6>[    6.381439] brd: module loaded
<6>[    6.383666] loop: module loaded
<6>[    6.388275] input: barometer as /devices/virtual/input/input0
<6>[    6.388854] sec_jack_probe : Registering jack driver
<6>[    6.389587] i2c i2c-4: mpu_probe: 0
<6>[    6.406738] i2c i2c-4: Installing irq using 205
<6>[    6.406738] i2c i2c-4: Module Param interface = mpuirq
<4>[    6.406738] i2c-core: driver [mpu3050] using legacy suspend method
<4>[    6.406738] i2c-core: driver [mpu3050] using legacy resume method
<6>[    6.406738] mpu_init: Probe name mpu3050
<6>[    6.407989] i2c i2c-4: bma250_mod_probe: bma250
<6>[    6.408111] i2c i2c-4: Installing Accel irq using 282
<6>[    6.420684] i2c i2c-4: mpu3050: +bma250
<6>[    6.420684] bma250_mod_init: Probe name bma250_mod
<6>[    6.420684] i2c i2c-4: yas530_mod_probe: yas530
<6>[    6.420684] i2c i2c-4: Installing Compass irq using 336
<6>[    6.442687] i2c i2c-4: mpu3050: +yas530
<6>[    6.442871] yas530_mod_init: Probe name yas530_mod
<6>[    6.443511] lte_modem_bootloader successfully probed
<6>[    6.449707] omap_temp_sensor omap_temp_sensor.0: omap_temp_sensor probed
<6>[    6.454376] m25p80 spi3.0: w25q80 (1024 Kbytes)
<6>[    6.455596] PPP generic driver version 2.4.2
<6>[    6.455963] PPP Deflate Compression module registered
<6>[    6.455963] PPP BSD Compression module registered
<6>[    6.456939] PPP MPPE Compression module registered
<6>[    6.457153] NET: Registered protocol family 24
<6>[    6.457153] tun: Universal TUN/TAP device driver, 1.6
<6>[    6.457153] tun: (C) 1999-2004 Max Krasnyansky <maxk@qualcomm.com>
<6>[    6.457153] usbcore: registered new interface driver asix
<6>[    6.459991] usbcore: registered new interface driver cdc_ether
<6>[    6.459991] usbcore: registered new interface driver net1080
<6>[    6.460388] usbcore: registered new interface driver cdc_subset
<6>[    6.460540] usbcore: registered new interface driver zaurus
<6>[    6.460540] cdc_ncm: 04-Aug-2011
<6>[    6.460540] usbcore: registered new interface driver cdc_ncm
<6>[    6.461090] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
<6>[    7.459564] ehci-omap ehci-omap.0: OMAP-EHCI Host Controller
<6>[    7.459564] ehci-omap ehci-omap.0: new USB bus registered, assigned bus number 1
<6>[    7.460113] ehci-omap ehci-omap.0: irq 109, io mem 0x4a064c00
<6>[    7.475219] ehci-omap ehci-omap.0: USB 2.0 started, EHCI 1.00
<6>[    7.475219] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002
<6>[    7.475219] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
<6>[    7.475677] usb usb1: Product: OMAP-EHCI Host Controller
<6>[    7.475860] usb usb1: Manufacturer: Linux 3.0.31-g396c4df ehci_hcd
<6>[    7.475860] usb usb1: SerialNumber: ehci-omap.0
<6>[    7.475860] hub 1-0:1.0: USB hub found
<6>[    7.475860] hub 1-0:1.0: 3 ports detected
<6>[    7.506469] usbcore: registered new interface driver cdc_acm
<6>[    7.506469] cdc_acm: USB Abstract Control Model driver for USB modems and ISDN adapters
<6>[    7.506469] Initializing USB Mass Storage driver...
<6>[    7.507171] usbcore: registered new interface driver usb-storage
<6>[    7.507171] USB Mass Storage support registered.
<6>[    7.507171] usbcore: registered new interface driver usbserial
<6>[    7.507171] usbserial: USB Serial Driver core
<6>[    7.709197] android_usb gadget: Mass Storage Function, version: 2009/09/11
<6>[    7.709197] android_usb gadget: Number of LUNs=1
<6>[    7.709503]  lun0: LUN: removable file: (no medium)
<6>[    7.709747] android_usb gadget: android_usb ready
<6>[    7.710205] musb-hdrc musb-hdrc: MUSB HDRC host driver
<6>[    7.710205] musb-hdrc musb-hdrc: new USB bus registered, assigned bus number 2
<6>[    7.710205] usb usb2: New USB device found, idVendor=1d6b, idProduct=0002
<6>[    7.710205] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1
<6>[    7.710815] usb usb2: Product: MUSB HDRC host driver
<6>[    7.711090] usb usb2: Manufacturer: Linux 3.0.31-g396c4df musb-hcd
<6>[    7.711212] usb usb2: SerialNumber: musb-hdrc
<6>[    7.711212] hub 2-0:1.0: USB hub found
<6>[    7.711212] hub 2-0:1.0: 1 port detected
<6>[    7.712768] usbcore: registered new interface driver xpad
<6>[    7.712768] usbcore: registered new interface driver usb_acecad
<6>[    7.713134] acecad: v3.2:USB Acecad Flair tablet driver
<6>[    7.713134] usbcore: registered new interface driver aiptek
<6>[    7.713134] aiptek: v2.3 (May 2, 2007):Aiptek HyperPen USB Tablet Driver (Linux 2.6.x)
<6>[    7.713134] aiptek: Bryan W. Headley/Chris Atenasio/Cedric Brun/Rene van Paassen
<6>[    7.713897] usbcore: registered new interface driver gtco
<4>[    7.713897] GTCO usb driver version: 2.00.0006
<6>[    7.714019] usbcore: registered new interface driver hanwang
<6>[    7.714172] usbcore: registered new interface driver kbtab
<6>[    7.714172] kbtab: v0.0.2:USB KB Gear JamStudio Tablet driver
<6>[    7.714172] usbcore: registered new interface driver wacom
<6>[    7.714782] wacom: v1.52:USB Wacom tablet driver
<6>[    7.714782] input: Melfas MMSxxx Touchscreen as /devices/platform/omap/omap_i2c.3/i2c-3/3-0048/input/input1
<6>[    8.092681] GPIO Input Driver: Start gpio inputs for tuna-gpio-keypad in interrupt mode
<6>[    8.093109] GPIO Input Driver: Start gpio inputs for tuna-gpio-keypad in interrupt mode
<6>[    8.093566] input: tuna-gpio-keypad as /devices/virtual/input/input2
<6>[    8.094329] input: proximity as /devices/virtual/input/input3
<6>[    8.095001] input: lightsensor-level as /devices/virtual/input/input4
<6>[    8.098602] using rtc device, twl_rtc, for alarms
<6>[    8.099090] twl_rtc twl_rtc: rtc core: registered twl_rtc as rtc0
<6>[    8.099609] i2c /dev entries driver
<6>[    8.099609] dmm: probe base: fd300000, irq 145
<6>[    8.103820] max17040 4-0036: MAX17040 Fuel-Gauge Ver 3
<6>[    8.106384] OMAP Watchdog Timer Rev 0x01: initial timeout 30 sec
<6>[    8.109130] device-mapper: uevent: version 1.0.3
<6>[    8.109710] device-mapper: ioctl: 4.20.0-ioctl (2011-02-02) initialised: dm-devel@redhat.com
<6>[    8.109924] Bluetooth: HCI UART driver ver 2.2
<6>[    8.110046] Bluetooth: HCI H4 protocol initialized
<6>[    8.110290] cpuidle: using governor ladder
<6>[    8.110473] cpuidle: using governor menu
<6>[    8.118316] usbcore: registered new interface driver usbhid
<6>[    8.118530] usbhid: USB HID core driver
<6>[    8.119079] logger: created 256K log 'log_main'
<6>[    8.119262] logger: created 256K log 'log_events'
<6>[    8.119567] logger: created 256K log 'log_radio'
<6>[    8.119934] logger: created 256K log 'log_system'
<6>[    8.121734] OMAP HSI: HSI driver version 0.4.2
<6>[    8.122009] omap_hsi omap_hsi.0: Registering IRQ mpu_p1 (99)
<6>[    8.122131] omap_hsi omap_hsi.0: HSI port 1 initialized
<6>[    8.122314] omap_hsi omap_hsi.0: Registering IRQ mpu_dma (103)
<6>[    8.122558] omap_hsi omap_hsi.0: HSI Hardware REVISION 0x50043101
<4>[    8.125091] omap_hsi omap_hsi.0: Cannot set HSI FClk to default value: 96000000. Will retry on next open
<6>[    8.129333] SDP4430 SoC init
<6>[    8.140808] max17040 4-0036: online = 1 vcell = 4106250 soc = 91 status = 3 health = 1 temp = 350 charger status = 0
<6>[    8.162475] asoc: null-codec-dai <-> MultiMedia1 mapping ok
<6>[    8.164062] asoc: null-codec-dai <-> MultiMedia2 mapping ok
<6>[    8.165985] asoc: null-codec-dai <-> Voice mapping ok
<6>[    8.167419] asoc: null-codec-dai <-> Tones mapping ok
<6>[    8.169036] asoc: null-codec-dai <-> Vibra mapping ok
<6>[    8.170135] asoc: null-codec-dai <-> MODEM mapping ok
<6>[    8.171142] asoc: null-codec-dai <-> MultiMedia1 LP mapping ok
<6>[    8.172973] asoc: FM Digital <-> omap-mcbsp-dai.1 mapping ok
<6>[    8.174438] asoc: twl6040-dl1 <-> mcpdm-dl mapping ok
<6>[    8.175811] asoc: dit-hifi <-> omap-mcasp-dai mapping ok
<4>[    8.178100] twl6040-codec twl6040-codec: ASoC: Failed to create Aux/FM Stereo In debugfs file
<6>[    8.179199] asoc: twl6040-dl1 <-> mcpdm-dl1 mapping ok
<6>[    8.180267] asoc: twl6040-ul <-> mcpdm-ul1 mapping ok
<6>[    8.181182] asoc: twl6040-dl2 <-> mcpdm-dl2 mapping ok
<6>[    8.182220] asoc: twl6040-vib <-> mcpdm-vib mapping ok
<6>[    8.183197] asoc: Bluetooth <-> omap-mcbsp-dai.0 mapping ok
<6>[    8.184265] asoc: Bluetooth <-> omap-mcbsp-dai.0 mapping ok
<6>[    8.185241] asoc: FM Digital <-> omap-mcbsp-dai.1 mapping ok
<6>[    8.186370] asoc: MODEM <-> omap-mcbsp-dai.1 mapping ok
<6>[    8.188171] input: Tuna Headset Jack as /devices/platform/soc-audio/sound/card0/input5
<6>[    8.191375] asoc: hdmi-audio-codec <-> hdmi-audio-dai mapping ok
<6>[    8.191986] ALSA device list:
<6>[    8.192169]   #0: TI OMAP4 Board
<6>[    8.192260]   #1: OMAP4HDMI
<6>[    8.192504] GACT probability NOT on
<6>[    8.192687] Mirror/redirect action on
<6>[    8.192779] u32 classifier
<6>[    8.192871]     Actions configured
<6>[    8.192993] Netfilter messages via NETLINK v0.30.
<6>[    8.193237] nf_conntrack version 0.5.0 (11101 buckets, 44404 max)
<6>[    8.194610] ctnetlink v0.93: registering with nfnetlink.
<6>[    8.194854] NF_TPROXY: Transparent proxy support initialized, version 4.1.0
<6>[    8.194976] NF_TPROXY: Copyright (c) 2006-2007 BalaBit IT Ltd.
<6>[    8.195648] xt_time: kernel timezone is -0000
<6>[    8.198303] ip_tables: (C) 2000-2006 Netfilter Core Team
<6>[    8.198760] arp_tables: (C) 2002 David S. Miller
<6>[    8.198944] TCP cubic registered
<6>[    8.199035] Initializing XFRM netlink socket
<6>[    8.200286] NET: Registered protocol family 10
<6>[    8.207031] Mobile IPv6
<6>[    8.207214] ip6_tables: (C) 2000-2006 Netfilter Core Team
<6>[    8.207794] IPv6 over IPv4 tunneling driver
<6>[    8.214752] NET: Registered protocol family 17
<6>[    8.214965] NET: Registered protocol family 15
<6>[    8.215332] Bluetooth: RFCOMM TTY layer initialized
<6>[    8.215545] Bluetooth: RFCOMM socket layer initialized
<6>[    8.215637] Bluetooth: RFCOMM ver 1.11
<6>[    8.215759] Bluetooth: BNEP (Ethernet Emulation) ver 1.3
<6>[    8.215942] Bluetooth: HIDP (Human Interface Emulation) ver 1.2
<6>[    8.216186] NET: Registered protocol family 35
<6>[    8.216766] VFP support v0.3: implementor 41 architecture 3 part 30 variant 9 rev 4
<6>[    8.216979] ThumbEE CPU extension supported.
<5>[    8.217193] Registering SWP/SWPB emulation handler
<6>[    8.219116] mmc0: new high speed MMC card at address 0001
<6>[    8.219818] mmcblk0: mmc0:0001 SEM32G 29.7 GiB 
<6>[    8.220153] mmcblk0boot0: mmc0:0001 SEM32G partition 1 2.00 MiB
<6>[    8.220520] mmcblk0boot1: mmc0:0001 SEM32G partition 2 2.00 MiB
<6>[    8.224822] Reprogramming LPDDR2 timings to 400000000 Hz
<6>[    8.225524] cpufreq_interactive_input_connect: connect to Melfas MMSxxx Touchscreen
<3>[    8.226135] Power Management for TI OMAP4.
<6>[    8.226440] OMAP4 PM: Static dependency added between MPUSS and DUCATI <-> L4_PER/CFG and L3_1.
<4>[    8.227447] sr_init: No PMIC hook to init smartreflex
<6>[    8.227600]  mmcblk0: p1 p2 p3 p4 p5 p6 p7 p8 p9 p10 p11 p12 p13
<6>[    8.227783] smartreflex smartreflex.0: omap_sr_probe: SmartReflex driver initialized
<6>[    8.228088] smartreflex smartreflex.1: omap_sr_probe: SmartReflex driver initialized
<6>[    8.228363] smartreflex smartreflex.2: omap_sr_probe: SmartReflex driver initialized
<6>[    8.228729] SmartReflex class 1.5 driver: initialized (86400000ms)
<6>[    8.229736] dmtimer id 10 at 48086000...
<6>[    8.229827] OMAP_TIMER_OCP_CFG_REG: 0x000015
<6>[    8.229980] OMAP_TIMER_SYS_STAT_REG: 0x000001
<6>[    8.230072] OMAP_TIMER_STAT_REG: 0x000000
<6>[    8.230163] OMAP_TIMER_INT_EN_REG: 0x000000
<6>[    8.230316] OMAP_TIMER_WAKEUP_EN_REG: 0x000000
<6>[    8.230407] OMAP_TIMER_CTRL_REG: 0x000000
<6>[    8.230560] OMAP_TIMER_COUNTER_REG: 0x000000
<6>[    8.230651] OMAP_TIMER_LOAD_REG: 0x000000
<6>[    8.230743] OMAP_TIMER_MATCH_REG: 0x000000
<6>[    8.230926] OMAP_TIMER_IF_CTRL_REG: 0x000004
<6>[    8.231201]  mmcblk0boot1: unknown partition table
<6>[    8.231628] dmtimer id 10 at 48086000...
<6>[    8.231811] OMAP_TIMER_OCP_CFG_REG: 0x000015
<6>[    8.231903] OMAP_TIMER_SYS_STAT_REG: 0x000001
<6>[    8.231994] OMAP_TIMER_STAT_REG: 0x000000
<6>[    8.232086] OMAP_TIMER_INT_EN_REG: 0x000000
<6>[    8.232177] OMAP_TIMER_WAKEUP_EN_REG: 0x000000
<6>[    8.232330] OMAP_TIMER_CTRL_REG: 0x001842
<6>[    8.232452] OMAP_TIMER_COUNTER_REG: 0xfffffffe
<6>[    8.232543] OMAP_TIMER_LOAD_REG: 0xfffffa56
<6>[    8.232604]  mmcblk0boot0: unknown partition table
<6>[    8.232788] OMAP_TIMER_MATCH_REG: 0xfffffa60
<6>[    8.232940] OMAP_TIMER_IF_CTRL_REG: 0x000004
<6>[    8.264282] sr_class1p5_calib_work: mpu: Calibration complete: Voltage:Nominal=1203000,Calib=1010000,margin=0
<6>[    8.264465] sr_class1p5_calib_work: iva: Calibration complete: Voltage:Nominal=1140000,Calib=987520,margin=0
<6>[    8.264739] sr_class1p5_calib_work: core: Calibration complete: Voltage:Nominal=1127000,Calib=1038160,margin=13000
<6>[    8.327087] phone_active_irq_handler : phone_state=0
<6>[    8.346527] usbcore: registered new interface driver if_usb_driver
<6>[    8.362609] [MODEM_IF] cdma_ipc0 state changed: OFFLINE
<6>[    8.362670] phone_active_irq_handler : phone_state=0
<6>[    8.362762] [MODEM_IF] cdma_ipc0 state changed: OFFLINE
<6>[    8.362854] phone_active_irq_handler : phone_state=0
<6>[    8.363067] [MODEM_IF] cdma_ipc0 state changed: OFFLINE
<6>[    8.363189] phone_active_irq_handler : phone_state=0
<6>[    8.363861] clock: disabling unused clocks to save power
<6>[    8.392272] [MODEM_IF] cdma_ipc0 state changed: OFFLINE
<6>[    8.392333] phone_active_irq_handler : phone_state=0
<6>[    8.538085] cannot apply mgr(lcd) on inactive device
<4>[    8.538208] omapfb omapfb: failed to apply dispc config
<6>[    8.538330] cannot apply mgr(tv) on inactive device
<4>[    8.538421] omapfb omapfb: failed to apply dispc config
<6>[    8.538513] cannot apply mgr(lcd2) on inactive device
<4>[    8.538665] omapfb omapfb: failed to apply dispc config
<6>[    8.540893] regulator_init_complete: VAUX2_6030: disabling
<6>[    8.541595] regulator_init_complete: VDAC: disabling
<6>[    8.542388] regulator_init_complete: VPP: disabling
<6>[    8.543090] regulator_init_complete: VUSB: disabling
<7>[    8.543518] omaplfb OMAPLFBInitFBDev: Device 0: Requesting 4 TILER 2D framebuffers
<4>[    8.544067] ## wifi_probe
<4>[    8.544128] wifi_set_power = 1
<4>[    9.061157] wifi_set_carddetect = 1
<4>[    9.061431] 
<4>[    9.061462] Dongle Host Driver, version 5.90.195.75
<4>[    9.061462] Compiled in drivers/net/wireless/bcmdhd on Jul 16 2012 at 18:07:53
<6>[    9.084564] sr_class1p5_calib_work: mpu: Calibration complete: Voltage:Nominal=1380000,Calib=1240000,margin=0
<6>[    9.187469] mmc1: new high speed SDIO card at address 0001
<4>[    9.188110] F1 signature read @0x18000000=0x16444330
<4>[    9.191436] DHD: dongle ram size is set to 294912(orig 294912)
<4>[    9.191986] wl_create_event_handler thr:5c started
<4>[    9.195404] p2p0: P2P Interface Registered
<4>[    9.195526] dhd_attach thr:5d started
<4>[    9.195648] dhd_attach thr:5e started
<4>[    9.195800] dhd_attach thr:5f started
<4>[    9.195953] wifi_get_mac_addr
<4>[    9.199157] Broadcom Dongle Host Driver: register interface [wlan0] MAC: 00:90:4c:11:22:33
<4>[    9.199279] wifi_set_power = 0
<4>[    9.717376] =========== WLAN placed in RESET ========
<6>[    9.718566] fsa9480 4-0025: cable detect change, from 'unknown/none' to 'usb-peripheral'
<6>[    9.720245] twl_rtc twl_rtc: setting system clock to 2012-10-10 14:11:25 UTC (1349878285)
<6>[    9.720733] Freeing init memory: 288K
<4>[    9.723999] init (1): /proc/1/oom_adj is deprecated, please use /proc/1/oom_score_adj instead.
<6>[    9.752532] max17040 4-0036: online = 1 vcell = 4093750 soc = 91 status = 3 health = 1 temp = 350 charger status = 0
<6>[    9.805236] keychord: using input dev tuna-gpio-keypad for fevent
<6>[    9.876068] EXT4-fs (mmcblk0p10): mounted filesystem with ordered data mode. Opts: (null)
<6>[    9.884460] EXT4-fs (mmcblk0p3): mounted filesystem with ordered data mode. Opts: (null)
<6>[    9.884613] fs_mgr: Running /system/bin/e2fsck on /dev/block/platform/omap/omap_hsmmc.0/by-name/cache
<6>[    9.916687] HDCP: loaded keys
<6>[    9.943359] mms_ts 3-0048: fw version 0x65 already present
<6>[    9.943664] mms_ts 3-0048: Melfas MMS-series touch controller initialized
<6>[    9.973724] EXT4-fs (mmcblk0p11): mounted filesystem with ordered data mode. Opts: nomblk_io_submit,errors=panic
<6>[    9.974304] fs_mgr: Running /system/bin/e2fsck on /dev/block/platform/omap/omap_hsmmc.0/by-name/userdata
<6>[    9.975463] sr_class1p5_calib_work: core: Calibration complete: Voltage:Nominal=962000,Calib=886240,margin=38000
<3>[    9.993438] Invalid Device Structure
<4>[   10.022216] sr_class1p5_calib_work: mpu Stop sampling: Voltage Nominal=1025000 samples=11
<6>[   10.022705] sr_class1p5_calib_work: mpu: Calibration complete: Voltage:Nominal=1025000,Calib=840000,margin=10000
<6>[   10.171997] omap-rproc omap-rproc.1: Loaded BIOS image ducati-m3.bin, size 4515836
<6>[   10.172088] omap-rproc omap-rproc.1: BIOS image version is 2
<6>[   10.185882] omap-iommu omap-iommu.0: iommu_get: ducati qos_request
<4>[   10.199829] omap_hwmod: ipu: failed to hardreset
<6>[   10.201110] omap-iommu omap-iommu.0: ducati: version 2.1
<6>[   10.208831] omap-rproc omap-rproc.1: remote processor ipu is now up
<6>[   10.221862] omap_rpmsg_mbox_callback: received echo reply from ipu !
<6>[   10.221954] omap_rpmsg_mbox_callback: received echo reply from ipu !
<6>[   10.222076] omap_rpmsg_mbox_callback: received echo reply from ipu !
<6>[   10.222198] omap_rpmsg_mbox_callback: received echo reply from ipu !
<6>[   10.222320] virtio_rpmsg_bus virtio0: creating channel rpmsg-client-sample addr 0x32
<6>[   10.222564] virtio_rpmsg_bus virtio0: creating channel rpmsg-client-sample addr 0x33
<6>[   10.222869] virtio_rpmsg_bus virtio0: creating channel rpmsg-omx addr 0x3c
<6>[   10.223144] rpmsg_omx rpmsg-omx0: new OMX connection srv channel: 1024 -> 60!
<6>[   10.233032] sr_class1p5_calib_work: mpu: Calibration complete: Voltage:Nominal=1317000,Calib=1110000,margin=0
<6>[   10.755218] max17040 4-0036: online = 1 vcell = 4107500 soc = 91 status = 1 health = 1 temp = 350 charger status = 0
<6>[   11.322021] virtio_rpmsg_bus virtio1: creating channel rpmsg-omx addr 0x3c
<6>[   11.322845] rpmsg_omx rpmsg-omx1: new OMX connection srv channel: 1024 -> 60!
<6>[   63.615570] max17040 4-0036: online = 1 vcell = 4140000 soc = 91 status = 1 health = 1 temp = 350 charger status = 0
<6>[  116.475769] max17040 4-0036: online = 1 vcell = 4142500 soc = 91 status = 1 health = 1 temp = 350 charger status = 0
<6>[  162.302337] EXT4-fs (mmcblk0p12): mounted filesystem with ordered data mode. Opts: nomblk_io_submit,errors=panic
<3>[  162.448394] init: cannot execve('/system/etc/install-recovery.sh'): Permission denied
<3>[  162.499816] android_usb: already disabled
<6>[  162.500305] mtp_bind_config
<6>[  162.674560] SMC: SecureCRC=0xBC7458DC
<6>[  162.787780] PVR: Installing MISR with cookie c08e7208
<6>[  162.788055] PVR: Installing device LISR SGX ISR on IRQ 53 with cookie c79b1a00
<6>[  162.788146] PVR: OSUnMapPhysToLin: unmapping 65535 bytes from c8be0000
<6>[  162.788269] PVR: SysFinalise: Version string: SGX revision = 1.2.0
<6>[  162.797912] android_work: sent uevent USB_STATE=CONNECTED
<6>[  162.802795] android_work: sent uevent USB_STATE=DISCONNECTED
<6>[  162.821136] warning: `rild' uses 32-bit capabilities (legacy support in use)
<6>[  162.863891] android_work: sent uevent USB_STATE=CONNECTED
<6>[  162.870574] SMC PA: INFO 00000001 00:00:00.000 ------ ------  --------------- Booting... (t=14:13:58.643) SMCXG01.04p11.32196     /Widevine Integration
<6>[  162.871887] SMC PA: INFO 00000002 00:00:00.000 ------ ------  --------------- Booted
<6>[  163.031829] android_usb gadget: high speed config #1: android
<6>[  163.032043] android_work: sent uevent USB_STATE=CONFIGURED
<6>[  164.090942] [MODEM_IF] misc_open : lte_boot0
<6>[  164.091003] [MODEM_IF] cmc221_on()
<6>[  164.186737] s6e8aa: d/b 1, p 1, b-01390ffb
<6>[  164.186767] s6e8aa: c0, 1, b-003cff6c, got v 3439157, factory wants 3433298
<6>[  164.186798] s6e8aa: c1, 1, b-00854d87, got v 3686904, factory wants 3678369
<6>[  164.186828] s6e8aa: c2, 1, b-003798e7, got v 3400258, factory wants 3394333
 
Yeah, that is very interesting!

It looks like there's a definite pause between seconds 11, 63, 116, and 162 and looks like the whole boot time took 163 seconds (2.7 minutes) if we're interpreting the timestamps correctly.

I don't think we can assume that the last message reported between the delays is the item/component in question, but the first message reported between delays might be. I'm looking specifically at this one:

<6>[ 162.302337] EXT4-fs (mmcblk0p12): mounted filesystem with ordered data mode. Opts: nomblk_io_submit,errors=panic

which does indeed jibe with the /userdata partition (userdata -> /dev/block/mmcblk0p12), so this does indeed appear (to me, at least) indicate that its the e2fsck running and taking his sweet time ;) :).

Do you have a lot of files on your "SD card" and how much free space are you using?

~ ~ ~

One other thing I would like to mention that I haven't really posted anywhere about before:

I am personally worried about the "dirty shutdowns" that are used by apps (including my own Android Root Toolkit) that utility the "reboot" utility to reboot one's particular device into recovery / bootloader mode, etc.

If you compare and contrast how fast this actually occurs vs. doing a "standard" power-down of your device via long-pressing the power button and selecting "Power Off", you'll see at least a 15 - 30 second delay in the standard shutdown while the radios are turned off, processes are (cleanly) shutdown, and most importantly, filesystems are un-mounted.

I've actually spent quit a bit of time trying to figure-out how to implement this same "clean shutdown" process in my Android Root Toolkit app, but the system calls which allow you to do this are not easy to import/reference in my Eclipse project (and I haven't figured-out how to do it--yet).

So, I wonder if you are doing any of the "dirty" reboots? and if you were to simply start doing clean (normal) shutdowns, would your long reboot issues disappear (the thinking being that the filesystems (including /data) are being properly un-mounted and not flagged as "dirty" which probably necessitates e2fsck being invoked at the next bootup?

(full disclosure: I only do clean shutdowns nowadays ;) :)).
 
Good point about the shutdowns. I do indeed use widget pro to reboot my phone once in a while. I will use the regular phone-off mechanism for a while and see if that helps. I was using this before I went to JB as well and it never was an issue.....
 
Good point about the shutdowns. I do indeed use widget pro to reboot my phone once in a while. I will use the regular phone-off mechanism for a while and see if that helps. I was using this before I went to JB as well and it never was an issue.....

Yeah, I've always used Quickboot (love that app!) and I replicated its functionality in my app, too.

But, I had a hard-bricking several months back and I've been very careful with my replacement device (my bricking occurred after a power-down under ICS--it simply would not power back on afterwards).

I'm just wondering if there is more caching that's going-on in JB (that certainly would help with it's smoothness (i.e., Project Butter :)) and that doing anything other than a normal shut down is likely to lead to the need for filesystem checking on the subsequent reboot.

Would be easy for you to try, eh? :)
 
I just did a second clean shut down, then booted it up. Still took a long time to boot up fully. That shouldn't happen if it is indeed e2fsck checking/fixing the filesystem, would it ?
 
I just tried it again; a clean shutdown, boot up.
What happened was this.
I very briefly see the google name w unlocked lock.
then the screen actually goes completely dark... for about 20 seconds. then I get my login screen !
Not sure what is going on , but it is weird.
Before this I would see the 4 colored google X symbol.. Usually about 30 seconds. then the login screen.....
weird weird weird.
 
I just did a second clean shut down, then booted it up. Still took a long time to boot up fully. That shouldn't happen if it is indeed e2fsck checking/fixing the filesystem, would it ?

I wouldn't think so...

I just tried it again; a clean shutdown, boot up.
What happened was this.
I very briefly see the google name w unlocked lock.
then the screen actually goes completely dark... for about 20 seconds. then I get my login screen !
Not sure what is going on , but it is weird.
Before this I would see the 4 colored google X symbol.. Usually about 30 seconds. then the login screen.....
weird weird weird.

Yeah, weird indeed!

If you read through gapi's http://androidforums.com/verizon-galaxy-nexus-all-things-root/612349-still-stuicking-boot-animation-logcat.html thread, you'll see that there's definitely something "different" going on with JB vs. prior versions of Android.
 
I've had this happen. I timed it and when turning off the phone and waiting a sec then powering it up, the boot time was as expected, but when using my widgetsoid reboot widget it hung on the google screen for about 4 minutes.

Then something happened a few days later and my widgetsoid widget had to ask for root permissions again, and this time it rebooted as expected with only a short time on the google screen.
 
Back
Top Bottom