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 = 4342016 (424100 hex) ## Booting image at 80000000 ... Image Name: Linux-3.9.0-rc2-00001-g5f7326f Image Type: ARM Linux Kernel Image (uncompressed) Data Size: 4341952 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-rc2-00001-g5f7326f (paul@nozomi) (gcc version 4.5.1 (Sourcery G++ Lite 2010.09-50) ) #1 SMP Sat Mar 16 13:30:32 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 - 0xc075e5d0 (7514 kB) [ 0.000000] .init : 0xc075f000 - 0xc07b4540 ( 342 kB) [ 0.000000] .data : 0xc07b6000 - 0xc084f060 ( 613 kB) [ 0.000000] .bss : 0xc084f060 - 0xc0daa2c8 (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.002746] Calibrating delay loop... 175.51 BogoMIPS (lpj=877568) [ 0.129669] pid_max: default: 32768 minimum: 301 [ 0.131042] Security Framework initialized [ 0.131469] Mount-cache hash table entries: 512 [ 0.142913] CPU: Testing write buffer coherency: ok [ 0.147064] CPU0: thread -1, cpu 0, socket -1, mpidr 4107b366 [ 0.147216] Setting up static identity map for 0xc055a1d0 - 0xc055a240 [ 0.155303] Brought up 1 CPUs [ 0.155364] SMP: Total of 1 processors activated (175.51 BogoMIPS). [ 0.155395] CPU: All CPU(s) started in SVC mode. [ 0.165252] devtmpfs: initialized [ 0.197540] omap_hwmod: usb_otg_hs: cannot be enabled for reset (3) [ 0.223449] pinctrl core: initialized pinctrl subsystem [ 0.241424] regulator-dummy: no parameters [ 0.252166] NET: Registered protocol family 16 [ 0.275787] DMA: preallocated 256 KiB pool for atomic coherent allocations [ 0.278961] omap-gpmc omap-gpmc: GPMC revision 3.0 [ 0.302581] omap_gpio omap_gpio.0: Could not get gpio dbck [ 0.308898] OMAP GPIO hardware version 2.4 [ 0.312377] omap_gpio omap_gpio.1: Could not get gpio dbck [ 0.319549] omap_gpio omap_gpio.2: Could not get gpio dbck [ 0.326263] omap_gpio omap_gpio.3: Could not get gpio dbck [ 0.332672] omap_gpio omap_gpio.4: Could not get gpio dbck [ 0.357238] omap_mux_init: Add partition: #1: core, flags: b [ 0.366210] _omap_mux_get_by_name: Could not find signal sys_nirq [ 0.402679] hw-breakpoint: found 6 breakpoint and 1 watchpoint registers. [ 0.402740] hw-breakpoint: maximum watchpoint size is 4 bytes. [ 0.461944] OMAP DMA hardware revision 4.0 [ 0.465240] Serial: AMBA PL011 UART driver [ 0.677398] bio: create slab at 0 [ 0.955322] omap-dma-engine omap-dma-engine: OMAP DMA engine driver [ 0.979736] SCSI subsystem initialized [ 0.987274] usbcore: registered new interface driver usbfs [ 0.989501] usbcore: registered new interface driver hub [ 0.991699] usbcore: registered new device driver usb [ 1.006683] omap_i2c omap_i2c.1: bus 1 rev3.3 at 100 kHz [ 1.054473] twl 2-0048: PIH (irq 23) chaining IRQs 274..282 [ 1.056213] twl 2-0048: power (irq 279) chaining IRQs 282..289 [ 1.065032] twl4030_gpio twl4030_gpio: gpio (irq 274) chaining IRQs 290..307 [ 1.084320] VMMC1: 1850 <--> 3150 mV at 1850 mV normal standby [ 1.088073] omap_i2c omap_i2c.2: bus 2 rev3.3 at 100 kHz [ 1.114044] Switching to clocksource 32k_counter [ 1.518432] NET: Registered protocol family 2 [ 1.524108] TCP established hash table entries: 1024 (order: 1, 8192 bytes) [ 1.524658] TCP bind hash table entries: 1024 (order: 3, 36864 bytes) [ 1.526275] TCP: Hash tables configured (established 1024 bind 1024) [ 1.526916] TCP: reno registered [ 1.527008] UDP hash table entries: 256 (order: 2, 20480 bytes) [ 1.527801] UDP-Lite hash table entries: 256 (order: 2, 20480 bytes) [ 1.530609] NET: Registered protocol family 1 [ 1.534576] RPC: Registered named UNIX socket transport module. [ 1.534637] RPC: Registered udp transport module. [ 1.534667] RPC: Registered tcp transport module. [ 1.534729] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 1.538177] NetWinder Floating Point Emulator V0.97 (double precision) [ 1.539093] CPU PMU: probing PMU on CPU 0 [ 1.539154] hw perfevents: enabled with v6 PMU driver, 3 counters available [ 2.120819] VFS: Disk quotas dquot_6.5.2 [ 2.121582] Dquot-cache hash table entries: 1024 (order 0, 4096 bytes) [ 2.129821] NFS: Registering the id_resolver key type [ 2.131439] Key type id_resolver registered [ 2.131500] Key type id_legacy registered [ 2.131958] jffs2: version 2.2. (NAND) (SUMMARY) © 2001-2006 Red Hat, Inc. [ 2.134033] msgmni has been set to 224 [ 2.145416] io scheduler noop registered [ 2.145507] io scheduler deadline registered [ 2.145751] io scheduler cfq registered (default) [ 2.158081] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 2.181213] omap_uart.0: ttyO0 at MMIO 0x4806a000 (irq = 88) is a OMAP UART0 [ 2.883270] console [ttyO0] enabled [ 2.894195] omap_uart.1: ttyO1 at MMIO 0x4806c000 (irq = 89) is a OMAP UART1 [ 2.907012] omap_uart.2: ttyO2 at MMIO 0x4806e000 (irq = 90) is a OMAP UART2 [ 2.927947] omap_rng omap_rng: OMAP Random Number Generator ver. 50 [ 3.036315] brd: module loaded [ 3.100311] loop: module loaded [ 3.120086] mtdoops: mtd device (mtddev=name/number) must be supplied [ 3.130004] OneNAND driver initializing [ 3.160308] smc91x.c: v1.1, sep 22 2004 by Nicolas Pitre [ 3.177734] eth0: SMC91C94 (rev 9) at c887a300 IRQ 263 [nowait] [ 3.184051] eth0: Ethernet addr: 08:00:28:01:16:8f [ 3.196411] usbcore: registered new interface driver asix [ 3.203704] usbcore: registered new interface driver ax88179_178a [ 3.211914] usbcore: registered new interface driver cdc_ether [ 3.219970] usbcore: registered new interface driver smsc95xx [ 3.227813] usbcore: registered new interface driver net1080 [ 3.235504] usbcore: registered new interface driver cdc_subset [ 3.243347] usbcore: registered new interface driver zaurus [ 3.251129] usbcore: registered new interface driver cdc_ncm [ 3.263610] usbcore: registered new interface driver cdc_wdm [ 3.269805] Initializing USB Mass Storage driver... [ 3.276885] usbcore: registered new interface driver usb-storage [ 3.283264] USB Mass Storage support registered. [ 3.290069] usbcore: registered new interface driver usbtest [ 3.302337] mousedev: PS/2 mouse device common for all mice [ 3.331481] input: twl4030_pwrbutton as /devices/platform/omap_i2c.2/i2c-2/2-004b/twl4030_pwrbutton/input/input0 [ 3.354949] twl_rtc twl_rtc: Power up reset detected. [ 3.365753] twl_rtc twl_rtc: Enabling TWL-RTC [ 3.391326] twl_rtc twl_rtc: rtc core: registered twl_rtc as rtc0 [ 3.402343] i2c /dev entries driver [ 3.416656] Driver for 1-wire Dallas network protocol. [ 3.434967] omap_wdt: OMAP Watchdog Timer Rev 0x22: initial timeout 60 sec [ 3.458312] omap-dma-engine omap-dma-engine: allocating channel for 62 [ 3.465820] omap-dma-engine omap-dma-engine: allocating channel for 61 [ 3.647430] ledtrig-cpu: registered to indicate activity on CPUs [ 3.663146] usbcore: registered new interface driver usbhid [ 3.669372] usbhid: USB HID core driver [ 3.678649] oprofile: using arm/armv6 [ 3.684692] TCP: cubic registered [ 3.688629] Initializing XFRM netlink socket [ 3.693695] NET: Registered protocol family 17 [ 3.698883] NET: Registered protocol family 15 [ 3.704681] Key type dns_resolver registered [ 3.709869] VFP support v0.3: implementor 41 architecture 1 part 20 variant b rev 2 [ 3.718292] mux: Failed to setup hwmod io irq -22 [ 3.741302] Power Management for OMAP2 initializing [ 3.746673] PRCM revision 1.0 [ 3.777893] twl_rtc twl_rtc: setting system clock to 2000-01-01 00:00:00 UTC (946684800) [ 3.793853] eth0: link up [ 3.809326] mmc0: host does not support reading read-only switch. assuming write-enable. [ 3.818756] Sending DHCP requests .[ 3.824279] mmc0: new SDHC card at address e624 ,[ 3.839874] mmcblk0: mmc0:e624 SD04G 3.69 GiB OK [ 3.859222] IP-Config: Got DHCP answer from 192.168.57.1, my address is 192.168.57.132 [ 3.869018] IP-Config: Complete: [ 3.872467] 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.883880] host=192.168.57.132, domain=example.org, nis-domain=(none) [ 3.891387] bootserver=0.0.0.0, rootserver=192.168.57.1, rootpath= [ 3.898315] nameserver0=192.168.57.1[ 3.923645] mmcblk0: p1 [ 3.980682] [ 3.982299] ===================================== [ 3.987487] [ BUG: swapper/0/1 still has locks held! ] [ 3.992919] 3.9.0-rc2-00001-g5f7326f #1 Not tainted [ 3.998168] ------------------------------------- [ 4.003173] 1 lock held by swapper/0/1: [ 4.007293] #0: (&type->s_umount_key#13/1){+.+.+.}, at: [] sget+0x248/0x574 [ 4.015991] [ 4.015991] stack backtrace: [ 4.020690] [] (unwind_backtrace+0x0/0xf0) from [] (rpc_wait_bit_killable+0x98/0xcc) [ 4.030822] [] (rpc_wait_bit_killable+0x98/0xcc) from [] (__wait_on_bit+0x74/0xb8) [ 4.040802] [] (__wait_on_bit+0x74/0xb8) from [] (out_of_line_wait_on_bit+0x6c/0x78) [ 4.050964] [] (out_of_line_wait_on_bit+0x6c/0x78) from [] (__rpc_execute+0xf0/0x360) [ 4.061157] [] (__rpc_execute+0xf0/0x360) from [] (rpc_run_task+0x98/0xa4) [ 4.070373] [] (rpc_run_task+0x98/0xa4) from [] (rpc_call_sync+0x48/0xb4) [ 4.079498] [] (rpc_call_sync+0x48/0xb4) from [] (nfs_proc_get_root+0x70/0x124) [ 4.089141] [] (nfs_proc_get_root+0x70/0x124) from [] (nfs_get_root+0x58/0x190) [ 4.098815] [] (nfs_get_root+0x58/0x190) from [] (nfs_fs_mount_common+0x98/0x158) [ 4.108642] [] (nfs_fs_mount_common+0x98/0x158) from [] (nfs_try_mount+0x144/0x214) [ 4.118682] [] (nfs_try_mount+0x144/0x214) from [] (nfs_fs_mount+0x178/0x850) [ 4.128143] [] (nfs_fs_mount+0x178/0x850) from [] (mount_fs+0x44/0x184) [ 4.137054] [] (mount_fs+0x44/0x184) from [] (vfs_kern_mount+0x4c/0xc0) [ 4.145965] [] (vfs_kern_mount+0x4c/0xc0) from [] (do_mount+0x6d0/0x858) [ 4.154907] [] (do_mount+0x6d0/0x858) from [] (sys_mount+0x84/0xb8) [ 4.163452] [] (sys_mount+0x84/0xb8) from [] (do_mount_root+0x24/0xb0) [ 4.172271] [] (do_mount_root+0x24/0xb0) from [] (mount_root+0x50/0xf8) [ 4.181213] [] (mount_root+0x50/0xf8) from [] (prepare_namespace+0x160/0x1c4) [ 4.190673] [] (prepare_namespace+0x160/0x1c4) from [] (kernel_init_freeable+0x17c/0x1c4) [ 4.201263] [] (kernel_init_freeable+0x17c/0x1c4) from [] (kernel_init+0x8/0xe4) [ 4.211029] [] (kernel_init+0x8/0xe4) from [] (ret_from_fork+0x14/0x24) [ 4.220245] VFS: Mounted root (nfs filesystem) on device 0:12. [ 4.228057] devtmpfs: mounted [ 4.232910] 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)