U-Boot 1.1.4 (Mar 18 2007 - 12:22:00) OMAP2430C-GP revision 3, PRCM #5A TI 2430SDP 1.1 Version + mDDR (Boot NOR) DRAM: 128 MB Flash: 192 MB NAND:64 MB In: serial Out: serial Err: serial Hit any key to stop autoboot: 3  2  1  0 Using MAC Address 08:00:28:01:16:8F BOOTP broadcast 1 DHCP client bound to address 192.168.57.132 TFTP from server 192.168.57.1; our IP address is 192.168.57.132 Filename 'uImage.2430sdp'. Load address: 0x80000000 Loading: *################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# #### done Bytes transferred = 4341824 (424040 hex) ## Booting image at 80000000 ... Image Name: Linux-3.9.0-rc3-00344-ga937536 Image Type: ARM Linux Kernel Image (uncompressed) Data Size: 4341760 Bytes = 4.1 MB Load Address: 80008000 Entry Point: 80008000 Verifying Checksum ... OK OK Starting kernel ... [ 0.000000] Booting Linux on physical CPU 0x0 [ 0.000000] Linux version 3.9.0-rc3-00344-ga937536 (paul@nozomi) (gcc version 4.5.1 (Sourcery G++ Lite 2010.09-50) ) #1 SMP Sun Mar 17 19:44:31 MDT 2013 [ 0.000000] CPU: ARMv6-compatible processor [4107b366] revision 6 (ARMv6TEJ), cr=00c5387d [ 0.000000] CPU: VIPT aliasing data cache, VIPT aliasing instruction cache [ 0.000000] Machine: OMAP2430 sdp2430 board [ 0.000000] cma: CMA: reserved 16 MiB at 86800000 [ 0.000000] Memory policy: ECC disabled, Data cache writeback [ 0.000000] CPU: All CPU(s) started in SVC mode. [ 0.000000] OMAP2430 [ 0.000000] [ 0.000000] Clocking rate (Crystal/DPLL/MPU): 13.0/532/266 MHz [ 0.000000] PERCPU: Embedded 9 pages/cpu @c0eaf000 s13632 r8192 d15040 u36864 [ 0.000000] Built 1 zonelists in Zone order, mobility grouping on. Total pages: 32256 [ 0.000000] Kernel command line: console=ttyO0,115200n8 earlyprintk root=/dev/nfs nfsroot=192.168.57.1:/srv/nfs4/rootfs2 nfsrootdebug ip=dhcp init=/bin/sh [ 0.000000] PID hash table entries: 512 (order: -1, 2048 bytes) [ 0.000000] Dentry cache hash table entries: 16384 (order: 4, 65536 bytes) [ 0.000000] Inode-cache hash table entries: 8192 (order: 3, 32768 bytes) [ 0.000000] __ex_table already sorted, skipping sort [ 0.000000] Memory: 127MB = 127MB total [ 0.000000] Memory: 98456k/98456k available, 32616k reserved, 0K highmem [ 0.000000] Virtual kernel memory layout: [ 0.000000] vector : 0xffff0000 - 0xffff1000 ( 4 kB) [ 0.000000] fixmap : 0xfff00000 - 0xfffe0000 ( 896 kB) [ 0.000000] vmalloc : 0xc8800000 - 0xff000000 ( 872 MB) [ 0.000000] lowmem : 0xc0000000 - 0xc8000000 ( 128 MB) [ 0.000000] pkmap : 0xbfe00000 - 0xc0000000 ( 2 MB) [ 0.000000] modules : 0xbf000000 - 0xbfe00000 ( 14 MB) [ 0.000000] .text : 0xc0008000 - 0xc075e5d4 (7514 kB) [ 0.000000] .init : 0xc075f000 - 0xc07b4540 ( 342 kB) [ 0.000000] .data : 0xc07b6000 - 0xc084fa60 ( 615 kB) [ 0.000000] .bss : 0xc084fa60 - 0xc0daacc8 (5485 kB) [ 0.000000] hw-breakpoint: CPUID feature registers not supported. Assuming v6 debug is present. [ 0.000000] Hierarchical RCU implementation. [ 0.000000] RCU restricting CPUs from NR_CPUS=2 to nr_cpu_ids=1. [ 0.000000] NR_IRQS:16 nr_irqs:16 16 [ 0.000000] IRQ: Found an INTC at 0xfa0fe000 (revision 3.0) with 96 interrupts [ 0.000000] Total of 96 interrupts on 1 active controller [ 0.000000] OMAP clockevent source: GPTIMER1 at 32768 Hz [ 0.000000] sched_clock: 32 bits at 32kHz, resolution 30517ns, wraps every 131071999ms [ 0.000000] OMAP clocksource: 32k_counter at 32768 Hz [ 0.000000] Console: colour dummy device 80x30 [ 0.000000] Lock dependency validator: Copyright (c) 2006 Red Hat, Inc., Ingo Molnar [ 0.000000] ... MAX_LOCKDEP_SUBCLASSES: 8 [ 0.000000] ... MAX_LOCK_DEPTH: 48 [ 0.000000] ... MAX_LOCKDEP_KEYS: 8191 [ 0.000000] ... CLASSHASH_SIZE: 4096 [ 0.000000] ... MAX_LOCKDEP_ENTRIES: 16384 [ 0.000000] ... MAX_LOCKDEP_CHAINS: 32768 [ 0.000000] ... CHAINHASH_SIZE: 16384 [ 0.000000] memory used by lock dependency info: 3695 kB [ 0.000000] per task-struct memory footprint: 1152 bytes [ 0.002716] Calibrating delay loop... 175.51 BogoMIPS (lpj=877568) [ 0.129638] pid_max: default: 32768 minimum: 301 [ 0.131042] Security Framework initialized [ 0.131439] Mount-cache hash table entries: 512 [ 0.142852] CPU: Testing write buffer coherency: ok [ 0.147033] CPU0: thread -1, cpu 0, socket -1, mpidr 4107b366 [ 0.147186] Setting up static identity map for 0xc055a490 - 0xc055a500 [ 0.154998] Brought up 1 CPUs [ 0.155059] SMP: Total of 1 processors activated (175.51 BogoMIPS). [ 0.155090] CPU: All CPU(s) started in SVC mode. [ 0.164855] devtmpfs: initialized [ 0.197326] omap_hwmod: usb_otg_hs: cannot be enabled for reset (3) [ 0.223175] pinctrl core: initialized pinctrl subsystem [ 0.241088] regulator-dummy: no parameters [ 0.251708] NET: Registered protocol family 16 [ 0.275421] DMA: preallocated 256 KiB pool for atomic coherent allocations [ 0.278594] omap-gpmc omap-gpmc: GPMC revision 3.0 [ 0.302215] omap_gpio omap_gpio.0: Could not get gpio dbck [ 0.308563] OMAP GPIO hardware version 2.4 [ 0.312042] omap_gpio omap_gpio.1: Could not get gpio dbck [ 0.318878] omap_gpio omap_gpio.2: Could not get gpio dbck [ 0.325927] omap_gpio omap_gpio.3: Could not get gpio dbck [ 0.332336] omap_gpio omap_gpio.4: Could not get gpio dbck [ 0.357055] omap_mux_init: Add partition: #1: core, flags: b [ 0.366058] omap_mux_get_by_name: Could not find signal sys_nirq [ 0.402435] hw-breakpoint: found 6 breakpoint and 1 watchpoint registers. [ 0.402496] hw-breakpoint: maximum watchpoint size is 4 bytes. [ 0.460998] OMAP DMA hardware revision 4.0 [ 0.464935] Serial: AMBA PL011 UART driver [ 0.684722] bio: create slab at 0 [ 0.961914] omap-dma-engine omap-dma-engine: OMAP DMA engine driver [ 0.986022] SCSI subsystem initialized [ 0.994293] usbcore: registered new interface driver usbfs [ 0.996002] usbcore: registered new interface driver hub [ 0.998718] usbcore: registered new device driver usb [ 1.013854] omap_i2c omap_i2c.1: bus 1 rev3.3 at 100 kHz [ 1.060607] twl 2-0048: PIH (irq 23) chaining IRQs 274..282 [ 1.062377] twl 2-0048: power (irq 279) chaining IRQs 282..289 [ 1.071258] twl4030_gpio twl4030_gpio: gpio (irq 274) chaining IRQs 290..307 [ 1.090911] VMMC1: 1850 <--> 3150 mV at 1850 mV normal standby [ 1.094055] omap_i2c omap_i2c.2: bus 2 rev3.3 at 100 kHz [ 1.119598] Switching to clocksource 32k_counter [ 1.522827] NET: Registered protocol family 2 [ 1.528472] TCP established hash table entries: 1024 (order: 1, 8192 bytes) [ 1.529022] TCP bind hash table entries: 1024 (order: 3, 36864 bytes) [ 1.530456] TCP: Hash tables configured (established 1024 bind 1024) [ 1.531249] TCP: reno registered [ 1.531372] UDP hash table entries: 256 (order: 2, 20480 bytes) [ 1.532135] UDP-Lite hash table entries: 256 (order: 2, 20480 bytes) [ 1.535064] NET: Registered protocol family 1 [ 1.539031] RPC: Registered named UNIX socket transport module. [ 1.539093] RPC: Registered udp transport module. [ 1.539154] RPC: Registered tcp transport module. [ 1.539184] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 1.542633] NetWinder Floating Point Emulator V0.97 (double precision) [ 1.543548] CPU PMU: probing PMU on CPU 0 [ 1.543609] hw perfevents: enabled with v6 PMU driver, 3 counters available [ 2.130004] VFS: Disk quotas dquot_6.5.2 [ 2.130950] Dquot-cache hash table entries: 1024 (order 0, 4096 bytes) [ 2.139007] NFS: Registering the id_resolver key type [ 2.140533] Key type id_resolver registered [ 2.140624] Key type id_legacy registered [ 2.141265] jffs2: version 2.2. (NAND) (SUMMARY) © 2001-2006 Red Hat, Inc. [ 2.143371] msgmni has been set to 224 [ 2.154327] io scheduler noop registered [ 2.154388] io scheduler deadline registered [ 2.154632] io scheduler cfq registered (default) [ 2.166839] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 2.189697] omap_uart.0: ttyO0 at MMIO 0x4806a000 (irq = 88) is a OMAP UART0 [ 2.891510] console [ttyO0] enabled [ 2.902404] omap_uart.1: ttyO1 at MMIO 0x4806c000 (irq = 89) is a OMAP UART1 [ 2.915039] omap_uart.2: ttyO2 at MMIO 0x4806e000 (irq = 90) is a OMAP UART2 [ 2.935638] omap_rng omap_rng: OMAP Random Number Generator ver. 50 [ 3.043548] brd: module loaded [ 3.105773] loop: module loaded [ 3.125640] mtdoops: mtd device (mtddev=name/number) must be supplied [ 3.135437] OneNAND driver initializing [ 3.165924] smc91x.c: v1.1, sep 22 2004 by Nicolas Pitre [ 3.183288] eth0: SMC91C94 (rev 9) at c887a300 IRQ 263 [nowait] [ 3.189605] eth0: Ethernet addr: 08:00:28:01:16:8f [ 3.201995] usbcore: registered new interface driver asix [ 3.209289] usbcore: registered new interface driver ax88179_178a [ 3.217468] usbcore: registered new interface driver cdc_ether [ 3.225463] usbcore: registered new interface driver smsc95xx [ 3.233276] usbcore: registered new interface driver net1080 [ 3.241027] usbcore: registered new interface driver cdc_subset [ 3.248901] usbcore: registered new interface driver zaurus [ 3.256683] usbcore: registered new interface driver cdc_ncm [ 3.269165] usbcore: registered new interface driver cdc_wdm [ 3.275360] Initializing USB Mass Storage driver... [ 3.282409] usbcore: registered new interface driver usb-storage [ 3.288787] USB Mass Storage support registered. [ 3.295501] usbcore: registered new interface driver usbtest [ 3.307830] mousedev: PS/2 mouse device common for all mice [ 3.331359] input: twl4030_pwrbutton as /devices/platform/omap_i2c.2/i2c-2/2-004b/twl4030_pwrbutton/input/input0 [ 3.355102] twl_rtc twl_rtc: Power up reset detected. [ 3.367736] twl_rtc twl_rtc: Enabling TWL-RTC [ 3.387359] twl_rtc twl_rtc: rtc core: registered twl_rtc as rtc0 [ 3.399291] i2c /dev entries driver [ 3.413085] Driver for 1-wire Dallas network protocol. [ 3.432006] omap_wdt: OMAP Watchdog Timer Rev 0x22: initial timeout 60 sec [ 3.454772] omap-dma-engine omap-dma-engine: allocating channel for 62 [ 3.462249] omap-dma-engine omap-dma-engine: allocating channel for 61 [ 3.642883] ledtrig-cpu: registered to indicate activity on CPUs [ 3.658599] usbcore: registered new interface driver usbhid [ 3.664794] usbhid: USB HID core driver [ 3.674163] oprofile: using arm/armv6 [ 3.680236] TCP: cubic registered [ 3.684143] Initializing XFRM netlink socket [ 3.689178] NET: Registered protocol family 17 [ 3.694335] NET: Registered protocol family 15 [ 3.700134] Key type dns_resolver registered [ 3.705322] VFP support v0.3: implementor 41 architecture 1 part 20 variant b rev 2 [ 3.713745] mux: Failed to setup hwmod io irq -22 [ 3.736755] Power Management for OMAP2 initializing [ 3.742126] PRCM revision 1.0 [ 3.773376] twl_rtc twl_rtc: setting system clock to 2000-01-01 00:00:00 UTC (946684800) [ 3.789367] eth0: link up [ 3.804962] mmc0: host does not support reading read-only switch. assuming write-enable. [ 3.814392] Sending DHCP requests .[ 3.819885] mmc0: new SDHC card at address e624 ,[ 3.835388] mmcblk0: mmc0:e624 SD04G 3.69 GiB OK [ 3.842926] IP-Config: Got DHCP answer from 192.168.57.1, my address is 192.168.57.132 [ 3.852661] IP-Config: Complete: [ 3.856140] device=eth0, hwaddr=08:00:28:01:16:8f, ipaddr=192.168.57.132, mask=255.255.255.0, gw=255.255.255.255 [ 3.867553] host=192.168.57.132, domain=example.org, nis-domain=(none) [ 3.875061] bootserver=0.0.0.0, rootserver=192.168.57.1, rootpath= [ 3.881988] nameserver0=192.168.57.1[ 3.919708] mmcblk0: p1 [ 3.976074] [ 3.977691] ===================================== [ 3.982849] [ BUG: swapper/0/1 still has locks held! ] [ 3.988311] 3.9.0-rc3-00344-ga937536 #1 Not tainted [ 3.993560] ------------------------------------- [ 3.998535] 1 lock held by swapper/0/1: [ 4.002655] #0: (&type->s_umount_key#13/1){+.+.+.}, at: [] sget+0x248/0x574 [ 4.011352] [ 4.011352] stack backtrace: [ 4.016052] [] (unwind_backtrace+0x0/0xf0) from [] (rpc_wait_bit_killable+0x98/0xcc) [ 4.026184] [] (rpc_wait_bit_killable+0x98/0xcc) from [] (__wait_on_bit+0x74/0xb8) [ 4.036163] [] (__wait_on_bit+0x74/0xb8) from [] (out_of_line_wait_on_bit+0x6c/0x78) [ 4.046295] [] (out_of_line_wait_on_bit+0x6c/0x78) from [] (__rpc_execute+0xf0/0x360) [ 4.056488] [] (__rpc_execute+0xf0/0x360) from [] (rpc_run_task+0x98/0xa4) [ 4.065704] [] (rpc_run_task+0x98/0xa4) from [] (rpc_call_sync+0x48/0xb4) [ 4.074798] [] (rpc_call_sync+0x48/0xb4) from [] (nfs_proc_get_root+0x70/0x124) [ 4.084472] [] (nfs_proc_get_root+0x70/0x124) from [] (nfs_get_root+0x58/0x190) [ 4.094116] [] (nfs_get_root+0x58/0x190) from [] (nfs_fs_mount_common+0x98/0x158) [ 4.103942] [] (nfs_fs_mount_common+0x98/0x158) from [] (nfs_try_mount+0x144/0x214) [ 4.113952] [] (nfs_try_mount+0x144/0x214) from [] (nfs_fs_mount+0x178/0x850) [ 4.123413] [] (nfs_fs_mount+0x178/0x850) from [] (mount_fs+0x44/0x184) [ 4.132324] [] (mount_fs+0x44/0x184) from [] (vfs_kern_mount+0x4c/0xc0) [ 4.141235] [] (vfs_kern_mount+0x4c/0xc0) from [] (do_mount+0x6d0/0x858) [ 4.150177] [] (do_mount+0x6d0/0x858) from [] (sys_mount+0x84/0xb8) [ 4.158721] [] (sys_mount+0x84/0xb8) from [] (do_mount_root+0x24/0xb0) [ 4.167541] [] (do_mount_root+0x24/0xb0) from [] (mount_root+0x50/0xf8) [ 4.176483] [] (mount_root+0x50/0xf8) from [] (prepare_namespace+0x160/0x1c4) [ 4.185943] [] (prepare_namespace+0x160/0x1c4) from [] (kernel_init_freeable+0x17c/0x1c4) [ 4.196502] [] (kernel_init_freeable+0x17c/0x1c4) from [] (kernel_init+0x8/0xe4) [ 4.206268] [] (kernel_init+0x8/0xe4) from [] (ret_from_fork+0x14/0x24) [ 4.215515] VFS: Mounted root (nfs filesystem) on device 0:12. [ 4.223327] devtmpfs: mounted [ 4.228179] Freeing init memory: 340K /bin/sh: 0: can't access tty; job control turned off # # # %% Start PM dynamic idle test (retention only) %% Preparation # # mount -t sysfs none /sys mount -t debugfs none /debug # # # # # # echo -1 > /sys/devices/platform/omap_uart.0/power/autosuspend_delay_ms # # echo -1 > /sys/devices/platform/omap_uart.1/power/autosuspend_delay_ms # # echo -1 > /sys/devices/platform/omap_uart.2/power/autosuspend_delay_ms # # echo -1 > /sys/devices/platform/omap_uart.3/power/autosuspend_delay_ms /bin/sh: 17: cannot create /sys/devices/platform/omap_uart.3/power/autosuspend_delay_ms: Directory nonexistent # # echo 0 > /debug/pm_debug/enable_off_mode # # %% Baseline pm_debug count dump cat /debug/pm_debug/count mdm_pwrdm (ON),OFF:0,RET:0,INA:0,ON:1,RET-LOGIC-OFF:0,RET-MEMBANK1-OFF:0 core_pwrdm (ON),OFF:0,RET:0,INA:0,ON:1,RET-LOGIC-OFF:0,RET-MEMBANK1-OFF:0,RET-MEMBANK2-OFF:0,RET-MEMBANK3-OFF:0 mpu_pwrdm (ON),OFF:0,RET:0,INA:0,ON:1,RET-LOGIC-OFF:0,RET-MEMBANK1-OFF:0 dsp_pwrdm (ON),OFF:0,RET:0,INA:0,ON:1,RET-LOGIC-OFF:0,RET-MEMBANK1-OFF:0 gfx_pwrdm (ON),OFF:0,RET:0,INA:0,ON:1,RET-LOGIC-OFF:0,RET-MEMBANK1-OFF:0 dss_clkdm->core_pwrdm (1) core_l4_clkdm->core_pwrdm (35) core_l3_clkdm->core_pwrdm (0) gfx_clkdm->gfx_pwrdm (0) dsp_clkdm->dsp_pwrdm (0) mdm_clkdm->mdm_pwrdm (0) mpu_clkdm->mpu_pwrdm (0) # # # # ./test_rootfs_access_nonexistent_file__ret_susp /bin/sh: 25: ./test_rootfs_access_nonexistent_file__ret_susp: not found # # %% End retention double suspend/resume UART wakeup test %% Start retention dynamic idle UART wakeup test echo 3000 > /sys/devices/platform/omap_uart.0/power/autosuspend_delay_ms # # echo 3000 > /sys/devices/platform/omap_uart.1/power/autosuspend_delay_ms # # echo 3000 > /sys/devices/platform/omap_uart.2/power/autosuspend_delay_ms # # echo 3000 > /sys/devices/platform/omap_uart.3/power/autosuspend_delay_ms /bin/sh: 33: cannot create /sys/devices/platform/omap_uart.3/power/autosuspend_delay_ms: Directory nonexistent # # # # # # cat /debug/pm_debug/count mdm_pwrdm (ON),OFF:0,RET:0,INA:0,ON:1,RET-LOGIC-OFF:0,RET-MEMBANK1-OFF:0 core_pwrdm (ON),OFF:0,RET:0,INA:0,ON:1,RET-LOGIC-OFF:0,RET-MEMBANK1-OFF:0,RET-MEMBANK2-OFF:0,RET-MEMBANK3-OFF:0 mpu_pwrdm (ON),OFF:0,RET:0,INA:0,ON:1,RET-LOGIC-OFF:0,RET-MEMBANK1-OFF:0 dsp_pwrdm (ON),OFF:0,RET:0,INA:0,ON:1,RET-LOGIC-OFF:0,RET-MEMBANK1-OFF:0 gfx_pwrdm (ON),OFF:0,RET:0,INA:0,ON:1,RET-LOGIC-OFF:0,RET-MEMBANK1-OFF:0 dss_clkdm->core_pwrdm (1) core_l4_clkdm->core_pwrdm (33) core_l3_clkdm->core_pwrdm (0) gfx_clkdm->gfx_pwrdm (0) dsp_clkdm->dsp_pwrdm (0) mdm_clkdm->mdm_pwrdm (0) mpu_clkdm->mpu_pwrdm (0) # # # # ./test_rootfs_access_nonexistent_file__ret_dyn /bin/sh: 43: ./test_rootfs_access_nonexistent_file__ret_dyn: not found # # echo -1 > /sys/devices/platform/omap_uart.0/power/autosuspend_delay_ms # # echo -1 > /sys/devices/platform/omap_uart.1/power/autosuspend_delay_ms # # echo -1 > /sys/devices/platform/omap_uart.2/power/autosuspend_delay_ms # # echo -1 > /sys/devices/platform/omap_uart.3/power/autosuspend_delay_ms /bin/sh: 51: cannot create /sys/devices/platform/omap_uart.3/power/autosuspend_delay_ms: Directory nonexistent # # %% End retention dynamic idle UART wakeup test %% End PM dynamic idle test (retention only)