Search

Technical Discussion Group Forum

This forum is provided for user discussion. While Beacon EmbeddedWorks support staff and engineers participate, Beacon EmbeddedWorks does not guarantee the accuracy of all information within in the Technical Discussion Group (TDG).

The "Articles" forums provide brief Articles written by Beacon EmbeddedWorks engineers that address the most frequently asked technical questions.

To receive email notifications when updates are posted for a Beacon EmbeddedWorks product download, please subscribe to the TDG Forum of interest.

TDG Forum

PrevPrev Go to previous topic
NextNext Go to next topic
Last Post 18 May 2020 05:57 PM by  James McColl
MMC Timeout SD Card Boot
 10 Replies
Sort:
You are not authorized to post a reply.
Author Messages
James McColl
New Member
New Member
Posts:7


--
15 May 2020 05:48 PM

    When I build the kernel and follow the instructions for an SD Card boot using the development board the boot errors out with:

    [    3.797424] mmc1: error -110 whilst initialising SD card

    .... snip ....

    [    3.905212] Waiting for root device /dev/mmcbulk1p2...

    And never loads. I have tried multiple SD Cards and built the kernel both using the VM and natively following the Linux Mainstream BSP instructions. Is this a known issue?

    I have tried experimenting with different device names (mmcbulk0p2, mmc0p2, mmc1p2) and different format options (ext3, etc) in the boot args and I'm kind of at a loss. The ramfs version build and boot fine, but I need need to be able to store things between boots. Any clues, did I miss something in the BSP Guide?

     

    Completely debug below:

    U-Boot 2018.03 (May 04 2018 - 17:59:03 -0500)

    OMAP3630/3730-GP ES1.2, CPU-OPP2, L3-200MHz, Max CPU Clock 1 GHz
    Model: LogicPD Zoom OMAP3 Development Kit
    Logic DM37x/OMAP35x reference board + LPDDR/NAND
    DRAM:  256 MiB
    NAND:  512 MiB
    MMC:   OMAP SD/MMC: 0
    Loading Environment from NAND... OK
    OMAP die ID: 0ba200029fd840000138abb71500a011
    Board: DM37xx Torpedo
    Net:   smc911x-0
    Hit any key to stop autoboot:  0 
    switch to partitions #0, OK
    mmc0 is current device
    ** Unable to read file boot.scr **
    Booting with DT from mmc0 ...
    Bootargs: 
    root=/dev/mmcbulk1p2 rw rootfstype=ext4 rootwait console=ttyO0,115200n8 mtdparts=omap2-nand.0:512k(MLO),1792k(u-boot),128k(spl-os),128k(u-boot-env),6m(kernel),-(fs) ignore_loglevel early_printk no_console_suspend
    4896352 bytes read in 376 ms (12.4 MiB/s)
    69785 bytes read in 9 ms (7.4 MiB/s)
    ## Flattened Device Tree blob at 88000000
       Booting using the fdt blob at 0x88000000
       Loading Device Tree to 8dede000, end 8def2098 ... OK

    Starting kernel ...

    [    0.000000] Booting Linux on physical CPU 0x0
    [    0.000000] Linux version 4.9.89 (logic@logic) (gcc version 7.3.0 (Buildroot 2018.02) ) #1 Fri May 4 18:02:33 CDT 2018
    [    0.000000] CPU: ARMv7 Processor [413fc082] revision 2 (ARMv7), cr=10c5387d
    [    0.000000] CPU: PIPT / VIPT nonaliasing data cache, VIPT aliasing instruction cache
    [    0.000000] OF: fdt:Machine model: LogicPD Zoom DM3730 Torpedo + Wireless Development Kit
    [    0.000000] debug: ignoring loglevel setting.
    [    0.000000] cma: Reserved 16 MiB at 0x8e800000
    [    0.000000] Memory policy: Data cache writeback
    [    0.000000] On node 0 totalpages: 65280
    [    0.000000] free_area_init_node: node 0, pgdat c0b89290, node_mem_map cfcb1000
    [    0.000000]   Normal zone: 574 pages used for memmap
    [    0.000000]   Normal zone: 0 pages reserved
    [    0.000000]   Normal zone: 65280 pages, LIFO batch:15
    [    0.000000] CPU: All CPU(s) started in SVC mode.
    [    0.000000] OMAP3630 ES1.2 (l2cache iva sgx neon isp 192mhz_clk)
    [    0.000000] pcpu-alloc: s0 r0 d32768 u32768 alloc=1*32768
    [    0.000000] pcpu-alloc: [0] 0 
    [    0.000000] Built 1 zonelists in Zone order, mobility grouping on.  Total pages: 64706
    [    0.000000] Kernel command line: root=/dev/mmcbulk1p2 rw rootfstype=ext4 rootwait console=ttyO0,115200n8 mtdparts=omap2-nand.0:512k(MLO),1792k(u-boot),128k(spl-os),128k(u-boot-env),6m(kernel),-(fs) ignore_logleveld
    [    0.000000] PID hash table entries: 1024 (order: 0, 4096 bytes)
    [    0.000000] Dentry cache hash table entries: 32768 (order: 5, 131072 bytes)
    [    0.000000] Inode-cache hash table entries: 16384 (order: 4, 65536 bytes)
    [    0.000000] Memory: 230736K/261120K available (7168K kernel code, 572K rwdata, 1672K rodata, 1024K init, 256K bss, 14000K reserved, 16384K cma-reserved, 0K highmem)
    [    0.000000] Virtual kernel memory layout:
    [    0.000000]     vector  : 0xffff0000 - 0xffff1000   (   4 kB)
    [    0.000000]     fixmap  : 0xffc00000 - 0xfff00000   (3072 kB)
    [    0.000000]     vmalloc : 0xd0000000 - 0xff800000   ( 760 MB)
    [    0.000000]     lowmem  : 0xc0000000 - 0xcff00000   ( 255 MB)
    [    0.000000]     pkmap   : 0xbfe00000 - 0xc0000000   (   2 MB)
    [    0.000000]     modules : 0xbf000000 - 0xbfe00000   (  14 MB)
    [    0.000000]       .text : 0xc0008000 - 0xc0800000   (8160 kB)
    [    0.000000]       .init : 0xc0a00000 - 0xc0b00000   (1024 kB)
    [    0.000000]       .data : 0xc0b00000 - 0xc0b8f110   ( 573 kB)
    [    0.000000]        .bss : 0xc0b91000 - 0xc0bd10c0   ( 257 kB)
    [    0.000000] NR_IRQS:16 nr_irqs:16 16
    [    0.000000] IRQ: Found an INTC at 0xfa200000 (revision 4.0) with 96 interrupts
    [    0.000000] Clocking rate (Crystal/Core/MPU): 26.0/400/600 MHz
    [    0.000000] OMAP clockevent source: timer1 at 32768 Hz
    [    0.000000] clocksource: 32k_counter: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 58327039986419 ns
    [    0.000000] sched_clock: 32 bits at 32kHz, resolution 30517ns, wraps every 65535999984741ns
    [    0.000000] OMAP clocksource: 32k_counter at 32768 Hz
    [    0.000640] Console: colour dummy device 80x30
    [    0.000671] Calibrating delay loop... 597.60 BogoMIPS (lpj=2988032)
    [    0.058319] pid_max: default: 32768 minimum: 301
    [    0.058502] Security Framework initialized
    [    0.058532] Mount-cache hash table entries: 1024 (order: 0, 4096 bytes)
    [    0.058563] Mountpoint-cache hash table entries: 1024 (order: 0, 4096 bytes)
    [    0.059417] CPU: Testing write buffer coherency: ok
    [    0.059753] Setting up static identity map for 0x80100000 - 0x80100070
    [    0.062591] devtmpfs: initialized
    [    0.091491] VFP support v0.3: implementor 41 architecture 3 part 30 variant c rev 3
    [    0.091827] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
    [    0.091857] futex hash table entries: 256 (order: -1, 3072 bytes)
    [    0.093109] pinctrl core: initialized pinctrl subsystem
    [    0.094421] NET: Registered protocol family 16
    [    0.096649] DMA: preallocated 256 KiB pool for atomic coherent allocations
    [    0.110137] omap_hwmod: mcbsp2_sidetone using broken dt data from mcbsp
    [    0.110565] omap_hwmod: mcbsp3_sidetone using broken dt data from mcbsp
    [    0.165863] cpuidle: using governor ladder
    [    0.165893] cpuidle: using governor menu
    [    0.166442] Reprogramming SDRC clock to 400000000 Hz
    [    0.173461] OMAP GPIO hardware version 2.5
    [    0.187377] irq: no irq domain found for /ocp@68000000/l4@48000000/scm@2000/pinmux@30 !
    [    0.187805] irq: no irq domain found for /ocp@68000000/l4@48000000/scm@2000/pinmux@30 !
    [    0.191314] irq: no irq domain found for /ocp@68000000/l4@48000000/scm@2000/pinmux@30 !
    [    0.192108] irq: no irq domain found for /ocp@68000000/pinmux@480025a0 !
    [    0.198730] omap-gpmc 6e000000.gpmc: GPMC revision 5.0
    [    0.199340] gpmc cs0 before gpmc_cs_program_settings:
    [    0.199340] cs0 GPMC_CS_CONFIG1: 0x00001800
    [    0.199371] cs0 GPMC_CS_CONFIG2: 0x00141400
    [    0.199371] cs0 GPMC_CS_CONFIG3: 0x00141400
    [    0.199371] cs0 GPMC_CS_CONFIG4: 0x0f010f01
    [    0.199401] cs0 GPMC_CS_CONFIG5: 0x010c1414
    [    0.199401] cs0 GPMC_CS_CONFIG6: 0x1f0f0a80
    [    0.199401] gpmc cs0 access configuration:
    [    0.199401] gpmc,mux-add-data = <0>
    [    0.199432] gpmc,device-width = <1>
    [    0.199432] gpmc,wait-pin = <0>
    [    0.199432] gpmc,burst-length = <4>
    [    0.199462] gpmc,cycle2cycle-samecsen = <1>
    [    0.199462] gpmc cs0 timings configuration:
    [    0.199462] gpmc,cs-on-ns = <0> /* 0 ns - 0 ns; 0 ticks */
    [    0.199493] gpmc,cs-rd-off-ns = <100> /* 96 ns - 100 ns; 20 ticks */
    [    0.199493] gpmc,cs-wr-off-ns = <100> /* 96 ns - 100 ns; 20 ticks */
    [    0.199493] gpmc,adv-on-ns = <0> /* 0 ns - 0 ns; 0 ticks */
    [    0.199523] gpmc,adv-rd-off-ns = <100> /* 96 ns - 100 ns; 20 ticks */
    [    0.199523] gpmc,adv-wr-off-ns = <100> /* 96 ns - 100 ns; 20 ticks */
    [    0.199554] gpmc,oe-on-ns = <5> /* 1 ns - 5 ns; 1 ticks */
    [    0.199554] gpmc,oe-off-ns = <75> /* 71 ns - 75 ns; 15 ticks */
    [    0.199554] gpmc,we-on-ns = <5> /* 1 ns - 5 ns; 1 ticks */
    [    0.199584] gpmc,we-off-ns = <75> /* 71 ns - 75 ns; 15 ticks */
    [    0.199584] gpmc,rd-cycle-ns = <100> /* 96 ns - 100 ns; 20 ticks */
    [    0.199615] gpmc,wr-cycle-ns = <100> /* 96 ns - 100 ns; 20 ticks */
    [    0.199615] gpmc,access-ns = <60> /* 56 ns - 60 ns; 12 ticks */
    [    0.199615] gpmc,page-burst-access-ns = <5> /* 1 ns - 5 ns; 1 ticks */
    [    0.199645] gpmc,bus-turnaround-ns = <0> /* 0 ns - 0 ns; 0 ticks */
    [    0.199645] gpmc,cycle2cycle-delay-ns = <50> /* 46 ns - 50 ns; 10 ticks */
    [    0.199645] gpmc,wait-monitoring-ns = <0> /* 0 ns - 0 ns; 0 ticks */
    [    0.199676] gpmc,clk-activation-ns = <0> /* 0 ns - 0 ns; 0 ticks */
    [    0.199676] gpmc,wr-data-mux-bus-ns = <75> /* 71 ns - 75 ns; 15 ticks */
    [    0.199707] gpmc,wr-access-ns = <155> /* 151 ns - 155 ns; 31 ticks */
    [    0.199707] GPMC CS0: cs_on            :   0 ticks,   0 ns (was   0 ticks)   0 ns
    [    0.199737] GPMC CS0: cs_rd_off        :   9 ticks,  45 ns (was  20 ticks)  44 ns
    [    0.199737] GPMC CS0: cs_wr_off        :   9 ticks,  45 ns (was  20 ticks)  44 ns
    [    0.199737] GPMC CS0: adv_on           :   2 ticks,  10 ns (was   0 ticks)   6 ns
    [    0.199768] GPMC CS0: adv_rd_off       :   7 ticks,  35 ns (was  20 ticks)  34 ns
    [    0.199768] GPMC CS0: adv_wr_off       :   9 ticks,  45 ns (was  20 ticks)  44 ns
    [    0.199798] GPMC CS0: oe_on            :   0 ticks,   0 ns (was   1 ticks)   0 ns
    [    0.199798] GPMC CS0: oe_off           :  11 ticks,  55 ns (was  15 ticks)  54 ns
    [    0.199798] GPMC CS0: we_on            :   0 ticks,   0 ns (was   1 ticks)   0 ns
    [    0.199829] GPMC CS0: we_off           :   8 ticks,  40 ns (was  15 ticks)  40 ns
    [    0.199829] GPMC CS0: rd_cycle         :  17 ticks,  85 ns (was  20 ticks)  82 ns
    [    0.199859] GPMC CS0: wr_cycle         :  17 ticks,  85 ns (was  20 ticks)  82 ns
    [    0.199859] GPMC CS0: access           :  13 ticks,  65 ns (was  12 ticks)  64 ns
    [    0.199859] GPMC CS0: page_burst_access:   0 ticks,   0 ns (was   1 ticks)   0 ns
    [    0.199890] GPMC CS0: bus_turnaround   :   0 ticks,   0 ns (was   0 ticks)   0 ns
    [    0.199890] GPMC CS0: cycle2cycle_delay:   0 ticks,   0 ns (was  10 ticks)   0 ns
    [    0.199920] GPMC CS0: wr_data_mux_bus  :   0 ticks,   0 ns (was  15 ticks)   0 ns
    [    0.199920] GPMC CS0: wr_access        :   8 ticks,  40 ns (was  31 ticks)  40 ns
    [    0.199920] GPMC CS0: wait_monitoring  :   0 ticks,   0 ns (was   0 ticks)   0 ns
    [    0.199951] GPMC CS0: clk_activation   :   0 ticks,   0 ns (was   0 ticks)   0 ns
    [    0.199951] GPMC CS0 CLK period is 5 ns (div 1)
    [    0.199951] gpmc cs0 after gpmc_cs_set_timings:
    [    0.199981] cs0 GPMC_CS_CONFIG1: 0x00001800
    [    0.199981] cs0 GPMC_CS_CONFIG2: 0x00090900
    [    0.199981] cs0 GPMC_CS_CONFIG3: 0x00090702
    [    0.199981] cs0 GPMC_CS_CONFIG4: 0x08000b00
    [    0.200012] cs0 GPMC_CS_CONFIG5: 0x000d1111
    [    0.200012] cs0 GPMC_CS_CONFIG6: 0x08000000
    [    0.200012] gpmc cs0 access configuration:
    [    0.200042] gpmc,mux-add-data = <0>
    [    0.200042] gpmc,device-width = <1>
    [    0.200042] gpmc,wait-pin = <0>
    [    0.200042] gpmc,burst-length = <4>
    [    0.200073] gpmc cs0 timings configuration:
    [    0.200073] gpmc,cs-on-ns = <0> /* 0 ns - 0 ns; 0 ticks */
    [    0.200073] gpmc,cs-rd-off-ns = <45> /* 41 ns - 45 ns; 9 ticks */
    [    0.200103] gpmc,cs-wr-off-ns = <45> /* 41 ns - 45 ns; 9 ticks */
    [    0.200103] gpmc,adv-on-ns = <10> /* 6 ns - 10 ns; 2 ticks */
    [    0.200103] gpmc,adv-rd-off-ns = <35> /* 31 ns - 35 ns; 7 ticks */
    [    0.200134] gpmc,adv-wr-off-ns = <45> /* 41 ns - 45 ns; 9 ticks */
    [    0.200134] gpmc,oe-on-ns = <0> /* 0 ns - 0 ns; 0 ticks */
    [    0.200164] gpmc,oe-off-ns = <55> /* 51 ns - 55 ns; 11 ticks */
    [    0.200164] gpmc,we-on-ns = <0> /* 0 ns - 0 ns; 0 ticks */
    [    0.200164] gpmc,we-off-ns = <40> /* 36 ns - 40 ns; 8 ticks */
    [    0.200195] gpmc,rd-cycle-ns = <85> /* 81 ns - 85 ns; 17 ticks */
    [    0.200195] gpmc,wr-cycle-ns = <85> /* 81 ns - 85 ns; 17 ticks */
    [    0.200195] gpmc,access-ns = <65> /* 61 ns - 65 ns; 13 ticks */
    [    0.200225] gpmc,page-burst-access-ns = <0> /* 0 ns - 0 ns; 0 ticks */
    [    0.200225] gpmc,bus-turnaround-ns = <0> /* 0 ns - 0 ns; 0 ticks */
    [    0.200225] gpmc,cycle2cycle-delay-ns = <0> /* 0 ns - 0 ns; 0 ticks */
    [    0.200256] gpmc,wait-monitoring-ns = <0> /* 0 ns - 0 ns; 0 ticks */
    [    0.200256] gpmc,clk-activation-ns = <0> /* 0 ns - 0 ns; 0 ticks */
    [    0.200256] gpmc,wr-data-mux-bus-ns = <0> /* 0 ns - 0 ns; 0 ticks */
    [    0.200286] gpmc,wr-access-ns = <40> /* 36 ns - 40 ns; 8 ticks */
    [    0.200958] gpmc cs1 before gpmc_cs_program_settings:
    [    0.200988] cs1 GPMC_CS_CONFIG1: 0x00001000
    [    0.200988] cs1 GPMC_CS_CONFIG2: 0x00080801
    [    0.201019] cs1 GPMC_CS_CONFIG3: 0x00000000
    [    0.201019] cs1 GPMC_CS_CONFIG4: 0x08010801
    [    0.201019] cs1 GPMC_CS_CONFIG5: 0x00080a0a
    [    0.201019] cs1 GPMC_CS_CONFIG6: 0x03000280
    [    0.201049] gpmc cs1 access configuration:
    [    0.201049] gpmc,mux-add-data = <0>
    [    0.201049] gpmc,device-width = <1>
    [    0.201049] gpmc,wait-pin = <0>
    [    0.201080] gpmc,burst-length = <4>
    [    0.201080] gpmc,cycle2cycle-samecsen = <1>
    [    0.201080] gpmc cs1 timings configuration:
    [    0.201110] gpmc,cs-on-ns = <5> /* 1 ns - 5 ns; 1 ticks */
    [    0.201110] gpmc,cs-rd-off-ns = <40> /* 36 ns - 40 ns; 8 ticks */
    [    0.201110] gpmc,cs-wr-off-ns = <40> /* 36 ns - 40 ns; 8 ticks */
    [    0.201141] gpmc,adv-on-ns = <0> /* 0 ns - 0 ns; 0 ticks */
    [    0.201141] gpmc,adv-rd-off-ns = <0> /* 0 ns - 0 ns; 0 ticks */
    [    0.201141] gpmc,adv-wr-off-ns = <0> /* 0 ns - 0 ns; 0 ticks */
    [    0.201171] gpmc,oe-on-ns = <5> /* 1 ns - 5 ns; 1 ticks */
    [    0.201171] gpmc,oe-off-ns = <40> /* 36 ns - 40 ns; 8 ticks */
    [    0.201202] gpmc,we-on-ns = <5> /* 1 ns - 5 ns; 1 ticks */
    [    0.201202] gpmc,we-off-ns = <40> /* 36 ns - 40 ns; 8 ticks */
    [    0.201202] gpmc,rd-cycle-ns = <50> /* 46 ns - 50 ns; 10 ticks */
    [    0.201232] gpmc,wr-cycle-ns = <50> /* 46 ns - 50 ns; 10 ticks */
    [    0.201232] gpmc,access-ns = <40> /* 36 ns - 40 ns; 8 ticks */
    [    0.201232] gpmc,page-burst-access-ns = <0> /* 0 ns - 0 ns; 0 ticks */
    [    0.201263] gpmc,bus-turnaround-ns = <0> /* 0 ns - 0 ns; 0 ticks */
    [    0.201263] gpmc,cycle2cycle-delay-ns = <10> /* 6 ns - 10 ns; 2 ticks */
    [    0.201263] gpmc,wait-monitoring-ns = <0> /* 0 ns - 0 ns; 0 ticks */
    [    0.201293] gpmc,clk-activation-ns = <0> /* 0 ns - 0 ns; 0 ticks */
    [    0.201293] gpmc,wr-data-mux-bus-ns = <0> /* 0 ns - 0 ns; 0 ticks */
    [    0.201324] gpmc,wr-access-ns = <15> /* 11 ns - 15 ns; 3 ticks */
    [    0.201324] GPMC CS1: cs_on            :   0 ticks,   0 ns (was   1 ticks)   0 ns
    [    0.201324] GPMC CS1: cs_rd_off        :   9 ticks,  45 ns (was   8 ticks)  42 ns
    [    0.201354] GPMC CS1: cs_wr_off        :   8 ticks,  40 ns (was   8 ticks)  36 ns
    [    0.201354] GPMC CS1: adv_on           :   2 ticks,  10 ns (was   0 ticks)   6 ns
    [    0.201385] GPMC CS1: adv_rd_off       :   3 ticks,  15 ns (was   0 ticks)  12 ns
    [    0.201385] GPMC CS1: adv_wr_off       :   3 ticks,  15 ns (was   0 ticks)  12 ns
    [    0.201385] GPMC CS1: oe_on            :   0 ticks,   0 ns (was   1 ticks)   0 ns
    [    0.201416] GPMC CS1: oe_off           :   9 ticks,  45 ns (was   8 ticks)  42 ns
    [    0.201416] GPMC CS1: we_on            :   0 ticks,   0 ns (was   1 ticks)   0 ns
    [    0.201446] GPMC CS1: we_off           :   8 ticks,  40 ns (was   8 ticks)  36 ns
    [    0.201446] GPMC CS1: rd_cycle         :  12 ticks,  60 ns (was  10 ticks)  60 ns
    [    0.201446] GPMC CS1: wr_cycle         :  11 ticks,  55 ns (was  10 ticks)  54 ns
    [    0.201477] GPMC CS1: access           :   8 ticks,  40 ns (was   8 ticks)  36 ns
    [    0.201477] GPMC CS1: page_burst_access:   0 ticks,   0 ns (was   0 ticks)   0 ns
    [    0.201477] GPMC CS1: bus_turnaround   :   0 ticks,   0 ns (was   0 ticks)   0 ns
    [    0.201507] GPMC CS1: cycle2cycle_delay:   0 ticks,   0 ns (was   2 ticks)   0 ns
    [    0.201507] GPMC CS1: wr_data_mux_bus  :   4 ticks,  20 ns (was   0 ticks)  18 ns
    [    0.201538] GPMC CS1: wr_access        :   9 ticks,  45 ns (was   3 ticks)  42 ns
    [    0.201538] GPMC CS1: wait_monitoring  :   0 ticks,   0 ns (was   0 ticks)   0 ns
    [    0.201538] GPMC CS1: clk_activation   :   0 ticks,   0 ns (was   0 ticks)   0 ns
    [    0.201568] GPMC CS1 CLK period is 5 ns (div 1)
    [    0.201568] gpmc cs1 after gpmc_cs_set_timings:
    [    0.201568] cs1 GPMC_CS_CONFIG1: 0x00001000
    [    0.201599] cs1 GPMC_CS_CONFIG2: 0x00080900
    [    0.201599] cs1 GPMC_CS_CONFIG3: 0x00030302
    [    0.201599] cs1 GPMC_CS_CONFIG4: 0x08000900
    [    0.201599] cs1 GPMC_CS_CONFIG5: 0x00080b0c
    [    0.201629] cs1 GPMC_CS_CONFIG6: 0x090400c0
    [    0.201629] gpmc cs1 access configuration:
    [    0.201629] gpmc,mux-add-data = <0>
    [    0.201629] gpmc,device-width = <1>
    [    0.201660] gpmc,wait-pin = <0>
    [    0.201660] gpmc,burst-length = <4>
    [    0.201660] gpmc,cycle2cycle-samecsen = <1>
    [    0.201660] gpmc,cycle2cycle-diffcsen = <1>
    [    0.201690] gpmc cs1 timings configuration:
    [    0.201690] gpmc,cs-on-ns = <0> /* 0 ns - 0 ns; 0 ticks */
    [    0.201690] gpmc,cs-rd-off-ns = <45> /* 41 ns - 45 ns; 9 ticks */
    [    0.201721] gpmc,cs-wr-off-ns = <40> /* 36 ns - 40 ns; 8 ticks */
    [    0.201721] gpmc,adv-on-ns = <10> /* 6 ns - 10 ns; 2 ticks */
    [    0.201751] gpmc,adv-rd-off-ns = <15> /* 11 ns - 15 ns; 3 ticks */
    [    0.201751] gpmc,adv-wr-off-ns = <15> /* 11 ns - 15 ns; 3 ticks */
    [    0.201751] gpmc,oe-on-ns = <0> /* 0 ns - 0 ns; 0 ticks */
    [    0.201782] gpmc,oe-off-ns = <45> /* 41 ns - 45 ns; 9 ticks */
    [    0.201782] gpmc,we-on-ns = <0> /* 0 ns - 0 ns; 0 ticks */
    [    0.201782] gpmc,we-off-ns = <40> /* 36 ns - 40 ns; 8 ticks */
    [    0.201812] gpmc,rd-cycle-ns = <60> /* 56 ns - 60 ns; 12 ticks */
    [    0.201812] gpmc,wr-cycle-ns = <55> /* 51 ns - 55 ns; 11 ticks */
    [    0.201812] gpmc,access-ns = <40> /* 36 ns - 40 ns; 8 ticks */
    [    0.201843] gpmc,page-burst-access-ns = <0> /* 0 ns - 0 ns; 0 ticks */
    [    0.201843] gpmc,bus-turnaround-ns = <0> /* 0 ns - 0 ns; 0 ticks */
    [    0.201843] gpmc,cycle2cycle-delay-ns = <0> /* 0 ns - 0 ns; 0 ticks */
    [    0.201873] gpmc,wait-monitoring-ns = <0> /* 0 ns - 0 ns; 0 ticks */
    [    0.201873] gpmc,clk-activation-ns = <0> /* 0 ns - 0 ns; 0 ticks */
    [    0.201904] gpmc,wr-data-mux-bus-ns = <20> /* 16 ns - 20 ns; 4 ticks */
    [    0.201904] gpmc,wr-access-ns = <45> /* 41 ns - 45 ns; 9 ticks */
    [    0.207611] No ATAGs?
    [    0.207611] hw-breakpoint: debug architecture 0x4 unsupported.
    [    0.208709] OMAP DMA hardware revision 5.0
    [    0.232177] omap-dma-engine 48056000.dma-controller: OMAP DMA engine driver (LinkedList1/2/3 supported)
    [    0.234191] reg-fixed-voltage video_reg: could not find pctldev for node /ocp@68000000/l4@48000000/scm@2000/pinmux@30/pinmux_panel_pwr_pins, deferring probe
    [    0.238769] omap-iommu 480bd400.mmu: 480bd400.mmu registered
    [    0.239471] SCSI subsystem initialized
    [    0.239746] libata version 3.00 loaded.
    [    0.240142] omap_i2c 48070000.i2c: could not find pctldev for node /ocp@68000000/l4@48000000/scm@2000/pinmux@30/pinmux_i2c1_pins, deferring probe
    [    0.240997] omap_i2c 48072000.i2c: bus 1 rev4.4 at 400 kHz
    [    0.241882] omap_i2c 48060000.i2c: bus 2 rev4.4 at 400 kHz
    [    0.242156] media: Linux media interface: v0.10
    [    0.242218] Linux video capture interface: v2.00
    [    0.242279] pps_core: LinuxPPS API ver. 1 registered
    [    0.242279] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti
    [    0.242309] PTP clock support registered
    [    0.243621] clocksource: Switched to clocksource 32k_counter
    [    0.276672] VFS: Disk quotas dquot_6.6.0
    [    0.276794] VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
    [    0.289001] NET: Registered protocol family 2
    [    0.289794] TCP established hash table entries: 2048 (order: 1, 8192 bytes)
    [    0.289855] TCP bind hash table entries: 2048 (order: 1, 8192 bytes)
    [    0.289886] TCP: Hash tables configured (established 2048 bind 2048)
    [    0.289978] UDP hash table entries: 256 (order: 0, 4096 bytes)
    [    0.289978] UDP-Lite hash table entries: 256 (order: 0, 4096 bytes)
    [    0.290130] NET: Registered protocol family 1
    [    0.290588] RPC: Registered named UNIX socket transport module.
    [    0.290618] RPC: Registered udp transport module.
    [    0.290618] RPC: Registered tcp transport module.
    [    0.290618] RPC: Registered tcp NFSv4.1 backchannel transport module.
    [    0.293182] hw perfevents: enabled with armv7_cortex_a8 PMU driver, 5 counters available
    [    0.296020] audit: initializing netlink subsys (disabled)
    [    0.296081] audit: type=2000 audit(0.290:1): initialized
    [    0.297119] workingset: timestamp_bits=14 max_order=16 bucket_order=2
    [    0.297851] NFS: Registering the id_resolver key type
    [    0.297912] Key type id_resolver registered
    [    0.297912] Key type id_legacy registered
    [    0.297973] jffs2: version 2.2. (NAND) (SUMMARY)  �© 2001-2006 Red Hat, Inc.
    [    0.299407] io scheduler noop registered
    [    0.299407] io scheduler deadline registered
    [    0.299468] io scheduler cfq registered (default)
    [    0.301116] pinctrl-single 48002030.pinmux: 284 pins at pa fa002030 size 568
    [    0.301391] pinctrl-single 48002a00.pinmux: 46 pins at pa fa002a00 size 92
    [    0.301910] pinctrl-single 480025a0.pinmux: 46 pins at pa fa0025a0 size 92
    [    0.303588] omapdss_dss 48050000.dss: master bind failed: -517
    [    0.304351] panel-dpi display: failed to find video source
    [    0.307220] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled
    [    0.309814] 4806a000.serial: ttyO0 at MMIO 0x4806a000 (irq = 88, base_baud = 3000000) is a OMAP UART0
    [    2.110382] console [ttyO0] enabled
    [    2.114868] 4806c000.serial: ttyO1 at MMIO 0x4806c000 (irq = 89, base_baud = 3000000) is a OMAP UART1
    [    2.125091] omap_uart 49020000.serial: no wakeirq for uart2
    [    2.131011] 49020000.serial: ttyO2 at MMIO 0x49020000 (irq = 90, base_baud = 3000000) is a OMAP UART2
    [    2.141326] omap_uart 49042000.serial: no wakeirq for uart3
    [    2.147308] 49042000.serial: ttyO3 at MMIO 0x49042000 (irq = 96, base_baud = 3000000) is a OMAP UART3
    [    2.171020] brd: module loaded
    [    2.181549] loop: module loaded
    [    2.217193] mtdoops: mtd device (mtddev=name/number) must be supplied
    [    2.224914] nand: device found, Manufacturer ID: 0x2c, Chip ID: 0xbc
    [    2.231567] nand: Micron MT29F4G16ABBDA3W
    [    2.235809] nand: 512 MiB, SLC, erase size: 128 KiB, page size: 2048, OOB size: 64
    [    2.243713] nand: using OMAP_ECC_BCH8_CODE_HW_DETECTION_SW
    [    2.251892] 6 cmdlinepart partitions found on MTD device omap2-nand.0
    [    2.258666] Creating 6 MTD partitions on "omap2-nand.0":
    [    2.264251] 0x000000000000-0x000000080000 : "MLO"
    [    2.270843] 0x000000080000-0x000000240000 : "u-boot"
    [    2.278015] 0x000000240000-0x000000260000 : "spl-os"
    [    2.284545] 0x000000260000-0x000000280000 : "u-boot-env"
    [    2.291473] 0x000000280000-0x000000880000 : "kernel"
    [    2.300170] 0x000000880000-0x000020000000 : "fs"
    [    2.488159] libphy: Fixed MDIO Bus: probed
    [    2.554687] libphy: smsc911x-mdio: probed
    [    2.559600] smsc911x 2c000000.ethernet eth0: MAC Address: 00:08:ee:10:3c:eb
    [    2.568298] mousedev: PS/2 mouse device common for all mice
    [    2.574188] i2c /dev entries driver
    [    2.579620] omap_hsmmc 4809c000.mmc: Got CD GPIO
    [    2.645080] ledtrig-cpu: registered to indicate activity on CPUs
    [    2.651794] oprofile: using arm/armv7
    [    2.656005] Initializing XFRM netlink socket
    [    2.660583] NET: Registered protocol family 10
    [    2.666503] sit: IPv6, IPv4 and MPLS over IPv4 tunneling driver
    [    2.673553] NET: Registered protocol family 17
    [    2.678283] NET: Registered protocol family 15
    [    2.682952] bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this.
    [    2.696594] Key type dns_resolver registered
    [    2.701324] omap2_set_init_voltage: unable to find boot up OPP for vdd_mpu_iva
    [    2.708923] omap2_set_init_voltage: unable to set vdd_mpu_iva
    [    2.714935] random: fast init done
    [    2.718505] omap2_set_init_voltage: unable to find boot up OPP for vdd_core
    [    2.725799] omap2_set_init_voltage: unable to set vdd_core
    [    2.732940] ThumbEE CPU extension supported.
    [    2.737457] SmartReflex Class3 initialized
    [    2.794219] twl 0-0048: PIH (irq 23) chaining IRQs 342..350
    [    2.800109] twl 0-0048: power (irq 347) chaining IRQs 350..357
    [    2.817504] VAUX4: Bringing 2800000uV into 1800000-1800000uV
    [    2.846282] vwl1271: supplied by VMMC2
    [    2.859832] twl4030_gpio twl4030-gpio: gpio (irq 342) chaining IRQs 358..375
    [    2.961303] omap_i2c 48070000.i2c: bus 0 rev4.4 at 2600 kHz
    [    2.967742] OMAP DSS rev 2.0
    [    2.970947] omapdss_dss 48050000.dss: bound 48050400.dispc (ops 0xc083bb98)
    [    2.983001] Console: switching to colour frame buffer device 60x34
    [    3.624053] omapfb omapfb: using display '28' mode 480x272
    [    3.630493] omap_hsmmc 4809c000.mmc: Got CD GPIO
    [    3.797424] mmc1: error -110 whilst initialising SD card
    [    3.844787] hctosys: unable to open rtc device (rtc0)
    [    3.850067] sr_init: No PMIC hook to init smartreflex
    [    3.855590] smartreflex smartreflex.0: omap_sr_probe: SmartReflex driver initialized
    [    3.863952] smartreflex smartreflex.1: omap_sr_probe: SmartReflex driver initialized
    [    3.873077] fixed-supply: disabling
    [    3.880645] VAUX3: disabling
    [    3.885437] VDAC: disabling
    [    3.890075] VUSB1V5: disabling
    [    3.894531] VUSB1V8: disabling
    [    3.898925] VUSB3V1: disabling
    [    3.905212] Waiting for root device /dev/mmc0p2...
    [  201.004058] random: crng init done
     

    Adam Ford
    Advanced Member
    Advanced Member
    Posts:794


    --
    17 May 2020 01:24 PM
    It appears there is a spelling error on the device name.

    It should /dev/mmcblk1p2

    (blk not bulk)

    adam
    James McColl
    New Member
    New Member
    Posts:7


    --
    18 May 2020 08:58 AM

    Adam,

    Sorry that's my fault, I had 'mmc0p2' in there from one of my prior runs and I "found and placed" it with that spelling error of 'mmcbulk1p2' instead of 'mmcblk1p2'. Just to be doubly positive I re-ran it and it had the same result:

    .... snip ....

    [    3.797515] mmc1: error -110 whilst initialising SD card

    .... snip ....

    [    3.905303] Waiting for root device /dev/mmcblk1p2...

    James McColl
    New Member
    New Member
    Posts:7


    --
    18 May 2020 08:58 AM

    Adam,

    Sorry that's my fault, I had 'mmc0p2' in there from one of my prior runs and I "found and placed" it with that spelling error of 'mmcbulk1p2' instead of 'mmcblk1p2'. Just to be doubly positive I re-ran it and it had the same result:

    .... snip ....

    [    3.797515] mmc1: error -110 whilst initialising SD card

    .... snip ....

    [    3.905303] Waiting for root device /dev/mmcblk1p2...

    James McColl
    New Member
    New Member
    Posts:7


    --
    18 May 2020 09:18 AM
    Also u-boot's ext4ls so no problems listing the partition with the root file system on it and error: -110 is ETIMOUT, so I'm wondering if there is something that needs to be changed in the device driver?
    Adam Ford
    Advanced Member
    Advanced Member
    Posts:794


    --
    18 May 2020 11:18 AM
    James,

    I cannot replicate your issue, but there was some discussion a while ago in the Linux community about the gpio muxing. In the kernel, there is a file called:
    arch/arm/mach-omap2/pdata-quirks.c

    Look for a function that reads static void __init omap3_logicpd_torpedo_init(void)

    Could you could try commenting out the following line to see if it makes any difference after rebuilding the kernel?
    /* omap3_gpio126_127_129(); */

    I'll post my boot log in the next message

    Adam Ford
    Advanced Member
    Advanced Member
    Posts:794


    --
    18 May 2020 11:19 AM
    \00
    U-Boot SPL 2018.03 (May 18 2020 - 09:57:03 -0500)
    Trying to boot from MMC1
    spl_load_image_fat_os: error reading image args, err - -2


    U-Boot 2018.03 (May 18 2020 - 09:57:03 -0500)

    OMAP3630/3730-GP ES1.2, CPU-OPP2, L3-200MHz, Max CPU Clock 1 GHz
    Model: LogicPD Zoom OMAP3 Development Kit
    Logic DM37x/OMAP35x reference board + LPDDR/NAND
    DRAM: 256 MiB
    NAND: 512 MiB
    MMC: OMAP SD/MMC: 0
    Loading Environment from NAND... OK
    OMAP die ID: 0efc00029ff800000146a60618027029
    Board: DM37xx Torpedo
    Net: smc911x-0
    Hit any key to stop autoboot: 2  1  0
    Booting with DT from mmc0 ...
    Bootargs:
    root=/dev/mmcblk1p2 rw rootfstype=ext4 rootwait console=ttyO0,115200n8 mtdparts=omap2-nand.0:512k(MLO),1792k(u-boot),128k(spl-os),128k(u-boot-env),6m(kernel),-(fs) ignore_loglevel early_printk no_console_suspend
    4896360 bytes read in 377 ms (12.4 MiB/s)
    69785 bytes read in 9 ms (7.4 MiB/s)
    ## Flattened Device Tree blob at 88000000
    Booting using the fdt blob at 0x88000000
    Loading Device Tree to 8dede000, end 8def2098 ... OK

    Starting kernel ...

    [ 0.000000] Booting Linux on physical CPU 0x0
    [ 0.000000] Linux version 4.9.89 (logic@logic) (gcc version 7.3.0 (Buildroot 2018.02) ) #1 Mon May 18 09:59:32 CDT 2020
    [ 0.000000] CPU: ARMv7 Processor [413fc082] revision 2 (ARMv7), cr=10c5387d
    [ 0.000000] CPU: PIPT / VIPT nonaliasing data cache, VIPT aliasing instruction cache
    [ 0.000000] OF: fdt:Machine model: LogicPD Zoom DM3730 Torpedo + Wireless Development Kit
    [ 0.000000] debug: ignoring loglevel setting.
    [ 0.000000] cma: Reserved 16 MiB at 0x8e800000
    [ 0.000000] Memory policy: Data cache writeback
    [ 0.000000] On node 0 totalpages: 65280
    [ 0.000000] free_area_init_node: node 0, pgdat c0b89290, node_mem_map cfcb1000
    [ 0.000000] Normal zone: 574 pages used for memmap
    [ 0.000000] Normal zone: 0 pages reserved
    [ 0.000000] Normal zone: 65280 pages, LIFO batch:15
    [ 0.000000] CPU: All CPU(s) started in SVC mode.
    [ 0.000000] OMAP3630 ES1.2 (l2cache iva sgx neon isp 192mhz_clk)
    [ 0.000000] pcpu-alloc: s0 r0 d32768 u32768 alloc=1*32768
    [ 0.000000] pcpu-alloc: [0] 0
    [ 0.000000] Built 1 zonelists in Zone order, mobility grouping on. Total pages: 64706
    [ 0.000000] Kernel command line: root=/dev/mmcblk1p2 rw rootfstype=ext4 rootwait console=ttyO0,115200n8 mtdparts=omap2-nand.0:512k(MLO),1792k(u-boot),128k(spl-os),128k(u-boot-env),6m(kernel),-(fs) ignore_loglevel early_printk no_console_suspend
    [ 0.000000] PID hash table entries: 1024 (order: 0, 4096 bytes)
    [ 0.000000] Dentry cache hash table entries: 32768 (order: 5, 131072 bytes)
    [ 0.000000] Inode-cache hash table entries: 16384 (order: 4, 65536 bytes)
    [ 0.000000] Memory: 230736K/261120K available (7168K kernel code, 572K rwdata, 1672K rodata, 1024K init, 256K bss, 14000K reserved, 16384K cma-reserved, 0K highmem)
    [ 0.000000] Virtual kernel memory layout:
    [ 0.000000] vector : 0xffff0000 - 0xffff1000 ( 4 kB)
    [ 0.000000] fixmap : 0xffc00000 - 0xfff00000 (3072 kB)
    [ 0.000000] vmalloc : 0xd0000000 - 0xff800000 ( 760 MB)
    [ 0.000000] lowmem : 0xc0000000 - 0xcff00000 ( 255 MB)
    [ 0.000000] pkmap : 0xbfe00000 - 0xc0000000 ( 2 MB)
    [ 0.000000] modules : 0xbf000000 - 0xbfe00000 ( 14 MB)
    [ 0.000000] .text : 0xc0008000 - 0xc0800000 (8160 kB)
    [ 0.000000] .init : 0xc0a00000 - 0xc0b00000 (1024 kB)
    [ 0.000000] .data : 0xc0b00000 - 0xc0b8f110 ( 573 kB)
    [ 0.000000] .bss : 0xc0b91000 - 0xc0bd10c0 ( 257 kB)
    [ 0.000000] NR_IRQS:16 nr_irqs:16 16
    [ 0.000000] IRQ: Found an INTC at 0xfa200000 (revision 4.0) with 96 interrupts
    [ 0.000000] Clocking rate (Crystal/Core/MPU): 26.0/400/600 MHz
    [ 0.000000] OMAP clockevent source: timer1 at 32768 Hz
    [ 0.000000] clocksource: 32k_counter: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 58327039986419 ns
    [ 0.000000] sched_clock: 32 bits at 32kHz, resolution 30517ns, wraps every 65535999984741ns
    [ 0.000030] OMAP clocksource: 32k_counter at 32768 Hz
    [ 0.000671] Console: colour dummy device 80x30
    [ 0.000701] Calibrating delay loop... 597.60 BogoMIPS (lpj=2988032)
    [ 0.058349] pid_max: default: 32768 minimum: 301
    [ 0.058532] Security Framework initialized
    [ 0.058563] Mount-cache hash table entries: 1024 (order: 0, 4096 bytes)
    [ 0.058593] Mountpoint-cache hash table entries: 1024 (order: 0, 4096 bytes)
    [ 0.059448] CPU: Testing write buffer coherency: ok
    [ 0.059783] Setting up static identity map for 0x80100000 - 0x80100070
    [ 0.062622] devtmpfs: initialized
    [ 0.091522] VFP support v0.3: implementor 41 architecture 3 part 30 variant c rev 3
    [ 0.091857] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
    [ 0.091888] futex hash table entries: 256 (order: -1, 3072 bytes)
    [ 0.093139] pinctrl core: initialized pinctrl subsystem
    [ 0.094451] NET: Registered protocol family 16
    [ 0.096710] DMA: preallocated 256 KiB pool for atomic coherent allocations
    [ 0.110137] omap_hwmod: mcbsp2_sidetone using broken dt data from mcbsp
    [ 0.110534] omap_hwmod: mcbsp3_sidetone using broken dt data from mcbsp
    [ 0.165893] cpuidle: using governor ladder
    [ 0.165924] cpuidle: using governor menu
    [ 0.166473] Reprogramming SDRC clock to 400000000 Hz
    [ 0.173522] OMAP GPIO hardware version 2.5
    [ 0.187408] irq: no irq domain found for /ocp@68000000/l4@48000000/scm@2000/pinmux@30 !
    [ 0.187866] irq: no irq domain found for /ocp@68000000/l4@48000000/scm@2000/pinmux@30 !
    [ 0.191345] irq: no irq domain found for /ocp@68000000/l4@48000000/scm@2000/pinmux@30 !
    [ 0.192138] irq: no irq domain found for /ocp@68000000/pinmux@480025a0 !
    [ 0.198760] omap-gpmc 6e000000.gpmc: GPMC revision 5.0
    [ 0.199371] gpmc cs0 before gpmc_cs_program_settings:
    [ 0.199371] cs0 GPMC_CS_CONFIG1: 0x00001800
    [ 0.199401] cs0 GPMC_CS_CONFIG2: 0x00141400
    [ 0.199401] cs0 GPMC_CS_CONFIG3: 0x00141400
    [ 0.199401] cs0 GPMC_CS_CONFIG4: 0x0f010f01
    [ 0.199401] cs0 GPMC_CS_CONFIG5: 0x010c1414
    [ 0.199432] cs0 GPMC_CS_CONFIG6: 0x1f0f0a80
    [ 0.199432] gpmc cs0 access configuration:
    [ 0.199432] gpmc,mux-add-data = <0>
    [ 0.199462] gpmc,device-width = <1>
    [ 0.199462] gpmc,wait-pin = <0>
    [ 0.199462] gpmc,burst-length = <4>
    [ 0.199493] gpmc,cycle2cycle-samecsen = <1>
    [ 0.199493] gpmc cs0 timings configuration:
    [ 0.199493] gpmc,cs-on-ns = <0> /* 0 ns - 0 ns; 0 ticks */
    [ 0.199523] gpmc,cs-rd-off-ns = <100> /* 96 ns - 100 ns; 20 ticks */
    [ 0.199523] gpmc,cs-wr-off-ns = <100> /* 96 ns - 100 ns; 20 ticks */
    [ 0.199523] gpmc,adv-on-ns = <0> /* 0 ns - 0 ns; 0 ticks */
    [ 0.199554] gpmc,adv-rd-off-ns = <100> /* 96 ns - 100 ns; 20 ticks */
    [ 0.199554] gpmc,adv-wr-off-ns = <100> /* 96 ns - 100 ns; 20 ticks */
    [ 0.199584] gpmc,oe-on-ns = <5> /* 1 ns - 5 ns; 1 ticks */
    [ 0.199584] gpmc,oe-off-ns = <75> /* 71 ns - 75 ns; 15 ticks */
    [ 0.199584] gpmc,we-on-ns = <5> /* 1 ns - 5 ns; 1 ticks */
    [ 0.199615] gpmc,we-off-ns = <75> /* 71 ns - 75 ns; 15 ticks */
    [ 0.199615] gpmc,rd-cycle-ns = <100> /* 96 ns - 100 ns; 20 ticks */
    [ 0.199615] gpmc,wr-cycle-ns = <100> /* 96 ns - 100 ns; 20 ticks */
    [ 0.199645] gpmc,access-ns = <60> /* 56 ns - 60 ns; 12 ticks */
    [ 0.199645] gpmc,page-burst-access-ns = <5> /* 1 ns - 5 ns; 1 ticks */
    [ 0.199676] gpmc,bus-turnaround-ns = <0> /* 0 ns - 0 ns; 0 ticks */
    [ 0.199676] gpmc,cycle2cycle-delay-ns = <50> /* 46 ns - 50 ns; 10 ticks */
    [ 0.199676] gpmc,wait-monitoring-ns = <0> /* 0 ns - 0 ns; 0 ticks */
    [ 0.199707] gpmc,clk-activation-ns = <0> /* 0 ns - 0 ns; 0 ticks */
    [ 0.199707] gpmc,wr-data-mux-bus-ns = <75> /* 71 ns - 75 ns; 15 ticks */
    [ 0.199707] gpmc,wr-access-ns = <155> /* 151 ns - 155 ns; 31 ticks */
    [ 0.199737] GPMC CS0: cs_on : 0 ticks, 0 ns (was 0 ticks) 0 ns
    [ 0.199737] GPMC CS0: cs_rd_off : 9 ticks, 45 ns (was 20 ticks) 44 ns
    [ 0.199768] GPMC CS0: cs_wr_off : 9 ticks, 45 ns (was 20 ticks) 44 ns
    [ 0.199768] GPMC CS0: adv_on : 2 ticks, 10 ns (was 0 ticks) 6 ns
    [ 0.199798] GPMC CS0: adv_rd_off : 7 ticks, 35 ns (was 20 ticks) 34 ns
    [ 0.199798] GPMC CS0: adv_wr_off : 9 ticks, 45 ns (was 20 ticks) 44 ns
    [ 0.199829] GPMC CS0: oe_on : 0 ticks, 0 ns (was 1 ticks) 0 ns
    [ 0.199829] GPMC CS0: oe_off : 11 ticks, 55 ns (was 15 ticks) 54 ns
    [ 0.199829] GPMC CS0: we_on : 0 ticks, 0 ns (was 1 ticks) 0 ns
    [ 0.199859] GPMC CS0: we_off : 8 ticks, 40 ns (was 15 ticks) 40 ns
    [ 0.199859] GPMC CS0: rd_cycle : 17 ticks, 85 ns (was 20 ticks) 82 ns
    [ 0.199890] GPMC CS0: wr_cycle : 17 ticks, 85 ns (was 20 ticks) 82 ns
    [ 0.199890] GPMC CS0: access : 13 ticks, 65 ns (was 12 ticks) 64 ns
    [ 0.199890] GPMC CS0: page_burst_access: 0 ticks, 0 ns (was 1 ticks) 0 ns
    [ 0.199920] GPMC CS0: bus_turnaround : 0 ticks, 0 ns (was 0 ticks) 0 ns
    [ 0.199920] GPMC CS0: cycle2cycle_delay: 0 ticks, 0 ns (was 10 ticks) 0 ns
    [ 0.199920] GPMC CS0: wr_data_mux_bus : 0 ticks, 0 ns (was 15 ticks) 0 ns
    [ 0.199951] GPMC CS0: wr_access : 8 ticks, 40 ns (was 31 ticks) 40 ns
    [ 0.199951] GPMC CS0: wait_monitoring : 0 ticks, 0 ns (was 0 ticks) 0 ns
    [ 0.199981] GPMC CS0: clk_activation : 0 ticks, 0 ns (was 0 ticks) 0 ns
    [ 0.199981] GPMC CS0 CLK period is 5 ns (div 1)
    [ 0.199981] gpmc cs0 after gpmc_cs_set_timings:
    [ 0.200012] cs0 GPMC_CS_CONFIG1: 0x00001800
    [ 0.200012] cs0 GPMC_CS_CONFIG2: 0x00090900
    [ 0.200012] cs0 GPMC_CS_CONFIG3: 0x00090702
    [ 0.200012] cs0 GPMC_CS_CONFIG4: 0x08000b00
    [ 0.200042] cs0 GPMC_CS_CONFIG5: 0x000d1111
    [ 0.200042] cs0 GPMC_CS_CONFIG6: 0x08000000
    [ 0.200042] gpmc cs0 access configuration:
    [ 0.200042] gpmc,mux-add-data = <0>
    [ 0.200073] gpmc,device-width = <1>
    [ 0.200073] gpmc,wait-pin = <0>
    [ 0.200073] gpmc,burst-length = <4>
    [ 0.200103] gpmc cs0 timings configuration:
    [ 0.200103] gpmc,cs-on-ns = <0> /* 0 ns - 0 ns; 0 ticks */
    [ 0.200103] gpmc,cs-rd-off-ns = <45> /* 41 ns - 45 ns; 9 ticks */
    [ 0.200134] gpmc,cs-wr-off-ns = <45> /* 41 ns - 45 ns; 9 ticks */
    [ 0.200134] gpmc,adv-on-ns = <10> /* 6 ns - 10 ns; 2 ticks */
    [ 0.200134] gpmc,adv-rd-off-ns = <35> /* 31 ns - 35 ns; 7 ticks */
    [ 0.200164] gpmc,adv-wr-off-ns = <45> /* 41 ns - 45 ns; 9 ticks */
    [ 0.200164] gpmc,oe-on-ns = <0> /* 0 ns - 0 ns; 0 ticks */
    [ 0.200164] gpmc,oe-off-ns = <55> /* 51 ns - 55 ns; 11 ticks */
    [ 0.200195] gpmc,we-on-ns = <0> /* 0 ns - 0 ns; 0 ticks */
    [ 0.200195] gpmc,we-off-ns = <40> /* 36 ns - 40 ns; 8 ticks */
    [ 0.200225] gpmc,rd-cycle-ns = <85> /* 81 ns - 85 ns; 17 ticks */
    [ 0.200225] gpmc,wr-cycle-ns = <85> /* 81 ns - 85 ns; 17 ticks */
    [ 0.200225] gpmc,access-ns = <65> /* 61 ns - 65 ns; 13 ticks */
    [ 0.200256] gpmc,page-burst-access-ns = <0> /* 0 ns - 0 ns; 0 ticks */
    [ 0.200256] gpmc,bus-turnaround-ns = <0> /* 0 ns - 0 ns; 0 ticks */
    [ 0.200256] gpmc,cycle2cycle-delay-ns = <0> /* 0 ns - 0 ns; 0 ticks */
    [ 0.200286] gpmc,wait-monitoring-ns = <0> /* 0 ns - 0 ns; 0 ticks */
    [ 0.200286] gpmc,clk-activation-ns = <0> /* 0 ns - 0 ns; 0 ticks */
    [ 0.200286] gpmc,wr-data-mux-bus-ns = <0> /* 0 ns - 0 ns; 0 ticks */
    [ 0.200317] gpmc,wr-access-ns = <40> /* 36 ns - 40 ns; 8 ticks */
    [ 0.200988] gpmc cs1 before gpmc_cs_program_settings:
    [ 0.201019] cs1 GPMC_CS_CONFIG1: 0x00001000
    [ 0.201019] cs1 GPMC_CS_CONFIG2: 0x00080801
    [ 0.201019] cs1 GPMC_CS_CONFIG3: 0x00000000
    [ 0.201049] cs1 GPMC_CS_CONFIG4: 0x08010801
    [ 0.201049] cs1 GPMC_CS_CONFIG5: 0x00080a0a
    [ 0.201049] cs1 GPMC_CS_CONFIG6: 0x03000280
    [ 0.201049] gpmc cs1 access configuration:
    [ 0.201080] gpmc,mux-add-data = <0>
    [ 0.201080] gpmc,device-width = <1>
    [ 0.201080] gpmc,wait-pin = <0>
    [ 0.201080] gpmc,burst-length = <4>
    [ 0.201110] gpmc,cycle2cycle-samecsen = <1>
    [ 0.201110] gpmc cs1 timings configuration:
    [ 0.201110] gpmc,cs-on-ns = <5> /* 1 ns - 5 ns; 1 ticks */
    [ 0.201141] gpmc,cs-rd-off-ns = <40> /* 36 ns - 40 ns; 8 ticks */
    [ 0.201141] gpmc,cs-wr-off-ns = <40> /* 36 ns - 40 ns; 8 ticks */
    [ 0.201171] gpmc,adv-on-ns = <0> /* 0 ns - 0 ns; 0 ticks */
    [ 0.201171] gpmc,adv-rd-off-ns = <0> /* 0 ns - 0 ns; 0 ticks */
    [ 0.201171] gpmc,adv-wr-off-ns = <0> /* 0 ns - 0 ns; 0 ticks */
    [ 0.201202] gpmc,oe-on-ns = <5> /* 1 ns - 5 ns; 1 ticks */
    [ 0.201202] gpmc,oe-off-ns = <40> /* 36 ns - 40 ns; 8 ticks */
    [ 0.201202] gpmc,we-on-ns = <5> /* 1 ns - 5 ns; 1 ticks */
    [ 0.201232] gpmc,we-off-ns = <40> /* 36 ns - 40 ns; 8 ticks */
    [ 0.201232] gpmc,rd-cycle-ns = <50> /* 46 ns - 50 ns; 10 ticks */
    [ 0.201232] gpmc,wr-cycle-ns = <50> /* 46 ns - 50 ns; 10 ticks */
    [ 0.201263] gpmc,access-ns = <40> /* 36 ns - 40 ns; 8 ticks */
    [ 0.201263] gpmc,page-burst-access-ns = <0> /* 0 ns - 0 ns; 0 ticks */
    [ 0.201293] gpmc,bus-turnaround-ns = <0> /* 0 ns - 0 ns; 0 ticks */
    [ 0.201293] gpmc,cycle2cycle-delay-ns = <10> /* 6 ns - 10 ns; 2 ticks */
    [ 0.201293] gpmc,wait-monitoring-ns = <0> /* 0 ns - 0 ns; 0 ticks */
    [ 0.201324] gpmc,clk-activation-ns = <0> /* 0 ns - 0 ns; 0 ticks */
    [ 0.201324] gpmc,wr-data-mux-bus-ns = <0> /* 0 ns - 0 ns; 0 ticks */
    [ 0.201324] gpmc,wr-access-ns = <15> /* 11 ns - 15 ns; 3 ticks */
    [ 0.201354] GPMC CS1: cs_on : 0 ticks, 0 ns (was 1 ticks) 0 ns
    [ 0.201354] GPMC CS1: cs_rd_off : 9 ticks, 45 ns (was 8 ticks) 42 ns
    [ 0.201385] GPMC CS1: cs_wr_off : 8 ticks, 40 ns (was 8 ticks) 36 ns
    [ 0.201385] GPMC CS1: adv_on : 2 ticks, 10 ns (was 0 ticks) 6 ns
    [ 0.201385] GPMC CS1: adv_rd_off : 3 ticks, 15 ns (was 0 ticks) 12 ns
    [ 0.201416] GPMC CS1: adv_wr_off : 3 ticks, 15 ns (was 0 ticks) 12 ns
    [ 0.201416] GPMC CS1: oe_on : 0 ticks, 0 ns (was 1 ticks) 0 ns
    [ 0.201446] GPMC CS1: oe_off : 9 ticks, 45 ns (was 8 ticks) 42 ns
    [ 0.201446] GPMC CS1: we_on : 0 ticks, 0 ns (was 1 ticks) 0 ns
    [ 0.201446] GPMC CS1: we_off : 8 ticks, 40 ns (was 8 ticks) 36 ns
    [ 0.201477] GPMC CS1: rd_cycle : 12 ticks, 60 ns (was 10 ticks) 60 ns
    [ 0.201477] GPMC CS1: wr_cycle : 11 ticks, 55 ns (was 10 ticks) 54 ns
    [ 0.201477] GPMC CS1: access : 8 ticks, 40 ns (was 8 ticks) 36 ns
    [ 0.201507] GPMC CS1: page_burst_access: 0 ticks, 0 ns (was 0 ticks) 0 ns
    [ 0.201507] GPMC CS1: bus_turnaround : 0 ticks, 0 ns (was 0 ticks) 0 ns
    [ 0.201538] GPMC CS1: cycle2cycle_delay: 0 ticks, 0 ns (was 2 ticks) 0 ns
    [ 0.201538] GPMC CS1: wr_data_mux_bus : 4 ticks, 20 ns (was 0 ticks) 18 ns
    [ 0.201538] GPMC CS1: wr_access : 9 ticks, 45 ns (was 3 ticks) 42 ns
    [ 0.201568] GPMC CS1: wait_monitoring : 0 ticks, 0 ns (was 0 ticks) 0 ns
    [ 0.201568] GPMC CS1: clk_activation : 0 ticks, 0 ns (was 0 ticks) 0 ns
    [ 0.201568] GPMC CS1 CLK period is 5 ns (div 1)
    [ 0.201599] gpmc cs1 after gpmc_cs_set_timings:
    [ 0.201599] cs1 GPMC_CS_CONFIG1: 0x00001000
    [ 0.201599] cs1 GPMC_CS_CONFIG2: 0x00080900
    [ 0.201629] cs1 GPMC_CS_CONFIG3: 0x00030302
    [ 0.201629] cs1 GPMC_CS_CONFIG4: 0x08000900
    [ 0.201629] cs1 GPMC_CS_CONFIG5: 0x00080b0c
    [ 0.201629] cs1 GPMC_CS_CONFIG6: 0x090400c0
    [ 0.201660] gpmc cs1 access configuration:
    [ 0.201660] gpmc,mux-add-data = <0>
    [ 0.201660] gpmc,device-width = <1>
    [ 0.201660] gpmc,wait-pin = <0>
    [ 0.201690] gpmc,burst-length = <4>
    [ 0.201690] gpmc,cycle2cycle-samecsen = <1>
    [ 0.201690] gpmc,cycle2cycle-diffcsen = <1>
    [ 0.201690] gpmc cs1 timings configuration:
    [ 0.201721] gpmc,cs-on-ns = <0> /* 0 ns - 0 ns; 0 ticks */
    [ 0.201721] gpmc,cs-rd-off-ns = <45> /* 41 ns - 45 ns; 9 ticks */
    [ 0.201751] gpmc,cs-wr-off-ns = <40> /* 36 ns - 40 ns; 8 ticks */
    [ 0.201751] gpmc,adv-on-ns = <10> /* 6 ns - 10 ns; 2 ticks */
    [ 0.201751] gpmc,adv-rd-off-ns = <15> /* 11 ns - 15 ns; 3 ticks */
    [ 0.201782] gpmc,adv-wr-off-ns = <15> /* 11 ns - 15 ns; 3 ticks */
    [ 0.201782] gpmc,oe-on-ns = <0> /* 0 ns - 0 ns; 0 ticks */
    [ 0.201782] gpmc,oe-off-ns = <45> /* 41 ns - 45 ns; 9 ticks */
    [ 0.201812] gpmc,we-on-ns = <0> /* 0 ns - 0 ns; 0 ticks */
    [ 0.201812] gpmc,we-off-ns = <40> /* 36 ns - 40 ns; 8 ticks */
    [ 0.201812] gpmc,rd-cycle-ns = <60> /* 56 ns - 60 ns; 12 ticks */
    [ 0.201843] gpmc,wr-cycle-ns = <55> /* 51 ns - 55 ns; 11 ticks */
    [ 0.201843] gpmc,access-ns = <40> /* 36 ns - 40 ns; 8 ticks */
    [ 0.201873] gpmc,page-burst-access-ns = <0> /* 0 ns - 0 ns; 0 ticks */
    [ 0.201873] gpmc,bus-turnaround-ns = <0> /* 0 ns - 0 ns; 0 ticks */
    [ 0.201873] gpmc,cycle2cycle-delay-ns = <0> /* 0 ns - 0 ns; 0 ticks */
    [ 0.201904] gpmc,wait-monitoring-ns = <0> /* 0 ns - 0 ns; 0 ticks */
    [ 0.201904] gpmc,clk-activation-ns = <0> /* 0 ns - 0 ns; 0 ticks */
    [ 0.201904] gpmc,wr-data-mux-bus-ns = <20> /* 16 ns - 20 ns; 4 ticks */
    [ 0.201934] gpmc,wr-access-ns = <45> /* 41 ns - 45 ns; 9 ticks */
    [ 0.207611] No ATAGs?
    [ 0.207641] hw-breakpoint: debug architecture 0x4 unsupported.
    [ 0.208740] OMAP DMA hardware revision 5.0
    [ 0.232147] omap-dma-engine 48056000.dma-controller: OMAP DMA engine driver (LinkedList1/2/3 supported)
    [ 0.234191] reg-fixed-voltage video_reg: could not find pctldev for node /ocp@68000000/l4@48000000/scm@2000/pinmux@30/pinmux_panel_pwr_pins, deferring probe
    [ 0.238739] omap-iommu 480bd400.mmu: 480bd400.mmu registered
    [ 0.239440] SCSI subsystem initialized
    [ 0.239807] libata version 3.00 loaded.
    [ 0.240173] omap_i2c 48070000.i2c: could not find pctldev for node /ocp@68000000/l4@48000000/scm@2000/pinmux@30/pinmux_i2c1_pins, deferring probe
    [ 0.241027] omap_i2c 48072000.i2c: bus 1 rev4.4 at 400 kHz
    [ 0.241882] omap_i2c 48060000.i2c: bus 2 rev4.4 at 400 kHz
    [ 0.242187] media: Linux media interface: v0.10
    [ 0.242248] Linux video capture interface: v2.00
    [ 0.242309] pps_core: LinuxPPS API ver. 1 registered
    [ 0.242309] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti
    [ 0.242340] PTP clock support registered
    [ 0.243652] clocksource: Switched to clocksource 32k_counter
    [ 0.276672] VFS: Disk quotas dquot_6.6.0
    [ 0.276794] VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
    [ 0.289031] NET: Registered protocol family 2
    [ 0.289825] TCP established hash table entries: 2048 (order: 1, 8192 bytes)
    [ 0.289855] TCP bind hash table entries: 2048 (order: 1, 8192 bytes)
    [ 0.289886] TCP: Hash tables configured (established 2048 bind 2048)
    [ 0.289978] UDP hash table entries: 256 (order: 0, 4096 bytes)
    [ 0.290008] UDP-Lite hash table entries: 256 (order: 0, 4096 bytes)
    [ 0.290161] NET: Registered protocol family 1
    [ 0.290618] RPC: Registered named UNIX socket transport module.
    [ 0.290618] RPC: Registered udp transport module.
    [ 0.290649] RPC: Registered tcp transport module.
    [ 0.290649] RPC: Registered tcp NFSv4.1 backchannel transport module.
    [ 0.293212] hw perfevents: enabled with armv7_cortex_a8 PMU driver, 5 counters available
    [ 0.296051] audit: initializing netlink subsys (disabled)
    [ 0.296112] audit: type=2000 audit(0.290:1): initialized
    [ 0.297149] workingset: timestamp_bits=14 max_order=16 bucket_order=2
    [ 0.297882] NFS: Registering the id_resolver key type
    [ 0.297943] Key type id_resolver registered
    [ 0.297943] Key type id_legacy registered
    [ 0.298004] jffs2: version 2.2. (NAND) (SUMMARY) © 2001-2006 Red Hat, Inc.
    [ 0.299438] io scheduler noop registered
    [ 0.299468] io scheduler deadline registered
    [ 0.299499] io scheduler cfq registered (default)
    [ 0.301147] pinctrl-single 48002030.pinmux: 284 pins at pa fa002030 size 568
    [ 0.301422] pinctrl-single 48002a00.pinmux: 46 pins at pa fa002a00 size 92
    [ 0.301940] pinctrl-single 480025a0.pinmux: 46 pins at pa fa0025a0 size 92
    [ 0.303619] omapdss_dss 48050000.dss: master bind failed: -517
    [ 0.304382] panel-dpi display: failed to find video source
    [ 0.307250] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled
    [ 0.309875] 4806a000.serial: ttyO0 at MMIO 0x4806a000 (irq = 88, base_baud = 3000000) is a OMAP UART0
    [ 2.110839] console [ttyO0] enabled
    [ 2.115325] 4806c000.serial: ttyO1 at MMIO 0x4806c000 (irq = 89, base_baud = 3000000) is a OMAP UART1
    [ 2.125518] omap_uart 49020000.serial: no wakeirq for uart2
    [ 2.131469] 49020000.serial: ttyO2 at MMIO 0x49020000 (irq = 90, base_baud = 3000000) is a OMAP UART2
    [ 2.141754] omap_uart 49042000.serial: no wakeirq for uart3
    [ 2.147766] 49042000.serial: ttyO3 at MMIO 0x49042000 (irq = 96, base_baud = 3000000) is a OMAP UART3
    [ 2.171478] brd: module loaded
    [ 2.182006] loop: module loaded
    [ 2.214263] at24 2-0050: 8192 byte 24c64 EEPROM, writable, 1 bytes/write
    [ 2.222808] mtdoops: mtd device (mtddev=name/number) must be supplied
    [ 2.230560] nand: device found, Manufacturer ID: 0x2c, Chip ID: 0xbc
    [ 2.237243] nand: Micron MT29F4G16ABBDA3W
    [ 2.241424] nand: 512 MiB, SLC, erase size: 128 KiB, page size: 2048, OOB size: 64
    [ 2.249359] nand: using OMAP_ECC_BCH8_CODE_HW_DETECTION_SW
    [ 2.257507] 6 cmdlinepart partitions found on MTD device omap2-nand.0
    [ 2.264282] Creating 6 MTD partitions on "omap2-nand.0":
    [ 2.269866] 0x000000000000-0x000000080000 : "MLO"
    [ 2.276489] 0x000000080000-0x000000240000 : "u-boot"
    [ 2.283569] 0x000000240000-0x000000260000 : "spl-os"
    [ 2.290161] 0x000000260000-0x000000280000 : "u-boot-env"
    [ 2.297119] 0x000000280000-0x000000880000 : "kernel"
    [ 2.305725] 0x000000880000-0x000020000000 : "fs"
    [ 2.488952] libphy: Fixed MDIO Bus: probed
    [ 2.554718] libphy: smsc911x-mdio: probed
    [ 2.559631] smsc911x 2c000000.ethernet eth0: MAC Address: 00:08:ee:07:5b:09
    [ 2.568359] mousedev: PS/2 mouse device common for all mice
    [ 2.574218] i2c /dev entries driver
    [ 2.579681] omap_hsmmc 4809c000.mmc: Got CD GPIO
    [ 2.645111] ledtrig-cpu: registered to indicate activity on CPUs
    [ 2.651824] oprofile: using arm/armv7
    [ 2.656036] Initializing XFRM netlink socket
    [ 2.660583] NET: Registered protocol family 10
    [ 2.666503] sit: IPv6, IPv4 and MPLS over IPv4 tunneling driver
    [ 2.673583] NET: Registered protocol family 17
    [ 2.678283] random: fast init done
    [ 2.681854] NET: Registered protocol family 15
    [ 2.686553] bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this.
    [ 2.700195] Key type dns_resolver registered
    [ 2.704956] omap2_set_init_voltage: unable to find boot up OPP for vdd_mpu_iva
    [ 2.712493] omap2_set_init_voltage: unable to set vdd_mpu_iva
    [ 2.718566] omap2_set_init_voltage: unable to find boot up OPP for vdd_core
    [ 2.725830] omap2_set_init_voltage: unable to set vdd_core
    [ 2.733001] ThumbEE CPU extension supported.
    [ 2.737518] SmartReflex Class3 initialized
    [ 2.794250] twl 0-0048: PIH (irq 23) chaining IRQs 342..350
    [ 2.800140] twl 0-0048: power (irq 347) chaining IRQs 350..357
    [ 2.838592] vwl1271: supplied by VMMC2
    [ 2.854980] twl4030_gpio twl4030-gpio: gpio (irq 342) chaining IRQs 358..375
    [ 2.956329] omap_i2c 48070000.i2c: bus 0 rev4.4 at 2600 kHz
    [ 2.962738] OMAP DSS rev 2.0
    [ 2.966003] omapdss_dss 48050000.dss: bound 48050400.dispc (ops 0xc083bb98)
    [ 2.978057] Console: switching to colour frame buffer device 60x34
    [ 3.624084] omapfb omapfb: using display '28' mode 480x272
    [ 3.630523] omap_hsmmc 4809c000.mmc: Got CD GPIO
    [ 3.742462] mmc1: host does not support reading read-only switch, assuming write-enable
    [ 3.752838] mmc1: new high speed SDHC card at address b368
    [ 3.759277] mmcblk1: mmc1:b368 00000 3.75 GiB
    [ 3.765686] mmcblk1: p1 p2
    [ 3.844818] hctosys: unable to open rtc device (rtc0)
    [ 3.850097] sr_init: No PMIC hook to init smartreflex
    [ 3.855682] smartreflex smartreflex.0: omap_sr_probe: SmartReflex driver initialized
    [ 3.863983] smartreflex smartreflex.1: omap_sr_probe: SmartReflex driver initialized
    [ 3.873107] fixed-supply: disabling
    [ 3.880584] VAUX3: disabling
    [ 3.884094] omap_hsmmc 480ad000.mmc: card claims to support voltages below defined range
    [ 3.893585] VDAC: disabling
    [ 3.897766] VUSB1V5: disabling
    [ 3.902191] VUSB1V8: disabling
    [ 3.906646] VUSB3V1: disabling
    [ 3.921874] mmc2: new high speed SDIO card at address 0001
    [ 3.934143] EXT4-fs (mmcblk1p2): mounted filesystem with ordered data mode. Opts: (null)
    [ 3.942657] VFS: Mounted root (ext4 filesystem) on device 179:2.
    [ 3.958007] devtmpfs: mounted
    [ 3.962982] Freeing unused kernel memory: 1024K
    [ 4.272827] EXT4-fs (mmcblk1p2): re-mounted. Opts: (null)
    Starting logging: OK
    Populating /dev using udev: [ 4.444732] udevd[100]: starting version 3.2.5
    [ 4.559173] udevd[101]: starting eudev-3.2.5
    [ 5.032531] input: TSC200X touchscreen as /devices/platform/68000000.ocp/48060000.i2c/i2c-2/2-0048/input/input0
    [ 5.064575] usbcore: registered new interface driver usbfs
    [ 5.070404] usbcore: registered new interface driver hub
    [ 5.076141] usbcore: registered new device driver usb
    [ 5.107360] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver
    [ 5.138946] input: twl4030_pwrbutton as /devices/platform/68000000.ocp/48070000.i2c/i2c-0/0-0048/48070000.i2c:twl@48:pwrbutton/input/input1
    [ 5.186157] ohci-omap3: OHCI OMAP3 driver
    [ 5.191040] ohci-omap3 48064400.ohci: OHCI Host Controller
    [ 5.196899] ohci-omap3 48064400.ohci: new USB bus registered, assigned bus number 1
    [ 5.272308] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
    [ 5.279235] Warning! ehci_hcd should always be loaded before uhci_hcd and ohci_hcd, not after
    [ 5.309051] twl_rtc 48070000.i2c:twl@48:rtc: Enabling TWL-RTC
    [ 5.334838] ohci-omap3 48064400.ohci: irq 92, io mem 0x48064400
    [ 5.356506] twl_rtc 48070000.i2c:twl@48:rtc: rtc core: registered 48070000.i2c:twl@48 as rtc0
    [ 5.427612] ehci-omap: OMAP-EHCI Host Controller driver
    [ 5.462585] HS USB OTG: no transceiver configured
    [ 5.467590] musb-hdrc musb-hdrc.0.auto: musb_init_controller failed with status -517
    [ 5.477813] HS USB OTG: no transceiver configured
    [ 5.482757] musb-hdrc musb-hdrc.0.auto: musb_init_controller failed with status -517
    [ 5.519592] Driver for 1-wire Dallas network protocol.
    [ 5.562042] usb usb1: New USB device found, idVendor=1d6b, idProduct=0001
    [ 5.569274] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
    [ 5.576843] usb usb1: Product: OHCI Host Controller
    [ 5.581939] usb usb1: Manufacturer: Linux 4.9.89 ohci_hcd
    [ 5.587615] usb usb1: SerialNumber: 48064400.ohci
    [ 5.597595] twl4030_usb 48070000.i2c:twl@48:twl4030-usb: Initialized TWL4030 USB module
    [ 5.607543] musb-hdrc musb-hdrc.0.auto: MUSB HDRC host driver
    [ 5.646301] omap_hdq 480b2000.1w: OMAP HDQ Hardware Rev 0.5. Driver in Interrupt mode
    [ 5.696990] omap-sham 480c3000.sham: hw accel on OMAP rev 0.9
    [ 5.753997] omap-aes 480c5000.aes: OMAP AES hw accel rev: 2.6
    [ 5.779785] omap-aes 480c5000.aes: will run requests pump with realtime priority
    [ 5.795501] hub 1-0:1.0: USB hub found
    [ 5.827728] hub 1-0:1.0: 3 ports detected
    [ 5.855224] musb-hdrc musb-hdrc.0.auto: new USB bus registered, assigned bus number 2
    [ 5.863739] ehci-omap 48064800.ehci: EHCI Host Controller
    [ 5.882049] 480bc000.isp supply vdd-csiphy1 not found, using dummy regulator
    [ 5.889648] 480bc000.isp supply vdd-csiphy2 not found, using dummy regulator
    [ 5.897308] omap3isp 480bc000.isp: Revision 15.0 found
    [ 5.903167] iommu: Adding device 480bc000.isp to group 0
    [ 5.908843] omap-iommu 480bd400.mmu: 480bd400.mmu: version 1.1
    [ 5.915283] omap3isp 480bc000.isp: Entity type for entity OMAP3 ISP CCP2 was not initialized!
    [ 5.954406] usb usb2: New USB device found, idVendor=1d6b, idProduct=0002
    [ 5.961517] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1
    [ 5.969116] usb usb2: Product: MUSB HDRC host driver
    [ 5.974334] usb usb2: Manufacturer: Linux 4.9.89 musb-hcd
    [ 5.979980] usb usb2: SerialNumber: musb-hdrc.0.auto
    [ 6.101593] backlight supply power not found, using dummy regulator
    [ 6.162811] input: gpio_keys as /devices/platform/gpio_keys/input/input2
    [ 6.206817] hub 2-0:1.0: USB hub found
    [ 6.226470] omap3isp 480bc000.isp: Entity type for entity OMAP3 ISP CSI2a was not initialized!
    [ 6.235839] hub 2-0:1.0: 1 port detected
    [ 6.243164] omap-twl4030 sound: ASoC: CPU DAI (null) not registered
    [ 6.249847] omap-twl4030 sound: devm_snd_soc_register_card() failed: -517
    [ 6.269226] backlight supply power not found, using dummy regulator
    [ 6.281188] omap-twl4030 sound: ASoC: CPU DAI (null) not registered
    [ 6.287841] omap-twl4030 sound: devm_snd_soc_register_card() failed: -517
    [ 6.295013] ehci-omap 48064800.ehci: new USB bus registered, assigned bus number 3
    [ 6.314575] omap3isp 480bc000.isp: Entity type for entity OMAP3 ISP CCDC was not initialized!
    [ 6.359344] omap3isp 480bc000.isp: Entity type for entity OMAP3 ISP preview was not initialized!
    [ 6.402954] omap3isp 480bc000.isp: Entity type for entity OMAP3 ISP resizer was not initialized!
    [ 6.425018] ehci-omap 48064800.ehci: irq 93, io mem 0x48064800
    [ 6.449249] omap3isp 480bc000.isp: Entity type for entity OMAP3 ISP AEWB was not initialized!
    [ 6.458312] omap3isp 480bc000.isp: Entity type for entity OMAP3 ISP AF was not initialized!
    [ 6.467102] omap3isp 480bc000.isp: Entity type for entity OMAP3 ISP histogram was not initialized!
    [ 6.476531] omap3isp 480bc000.isp: Entity type for entity mt9p031 1-0048 was not initialized!
    [ 6.486206] ehci-omap 48064800.ehci: USB 2.0 started, EHCI 1.00
    [ 6.507324] usb usb3: New USB device found, idVendor=1d6b, idProduct=0002
    [ 6.514495] usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=1
    [ 6.522064] usb usb3: Product: EHCI Host Controller
    [ 6.527191] usb usb3: Manufacturer: Linux 4.9.89 ehci_hcd
    [ 6.532836] usb usb3: SerialNumber: 48064800.ehci
    [ 6.636444] hub 3-0:1.0: USB hub found
    [ 6.640472] hub 3-0:1.0: 3 ports detected
    [ 6.657409] omap-twl4030 sound: ASoC: CPU DAI (null) not registered
    [ 6.664062] omap-twl4030 sound: devm_snd_soc_register_card() failed: -517
    [ 6.672302] mt9p031 1-0048: MT9P031 detected at address 0x48
    [ 6.697937] omap_wdt: OMAP Watchdog Timer Rev 0x31: initial timeout 60 sec
    [ 6.778564] bq27000-battery bq27000-battery: support ver. 1.2.0 enabled
    [ 6.801727] omap-twl4030 sound: twl4030-hifi <-> 49022000.mcbsp mapping ok
    [ 6.869079] bq27000-battery bq27000-battery: battery is not calibrated! ignoring capacity values
    done
    Initializing random number generator... done.
    Starting system message bus: done
    Starting network: OK


    Welcome to Buildroot

    buildroot login: [ 7.717224] wlcore: loaded
    James McColl
    New Member
    New Member
    Posts:7


    --
    18 May 2020 12:06 PM

    Adam,

    No joy unforetunately on commenting out omap3_gpio126_127_129. I also so the compiler warning for omap3_gpio126_127_129 being declared but not used so I know it actually made it into the compilation. Boot still hangs waiting for /dev/mmcblk1p2.

     

    I recompiled the kernel with MMC device driver debug turned on and was able to get the torpedo to boot into Linux. Of course then I had to also see all the MMC operations while I'm issuing commands over serial. :-) The debug output looks like it is trying to enurate what kind of SD Card I have plugged in and then after roughly 20 seconds figures out what kind of SD Card it is and boots from it. This is just a guess based upon the other debug around what was going on, I didn't actually look up the message IDs.

     

    I also removed rootwait from the boot line to force it to tell me what partitions are available and I saw /dev/mmcblk1p2, so I know that it is in /dev. I read somewhere on a xilinx forum that there is a timeout somewhere in the MMC device driver that cause simliar behavior to this. So I'm going to see if I can whatever timeout is in there and extended.

     

    If you have any other guess, I'm all ears because this is really strange....I'm also starting to wonder if the development board is somehow damaged....but that wouldn't explain how the ramfs works just fine so its probably not that.

    Adam Ford
    Advanced Member
    Advanced Member
    Posts:794


    --
    18 May 2020 12:10 PM
    Have you tried different SD cards to see if you can narrow it down to a brand and/or speed rating? U-Boot may not be as aggressive to setting speeds, whereas Linux will try to push the speeds depending on the SD card info returned.

    If you eject the card and re-insert the card once it starts to wait, can you see if it works? It could be transitioning from one state to another and getting hung up in the transition state from whatever U-Boot configured to whatever Linux configured.

    Also, if you boot from a RAMdisk, are you able to mount the ext partition on the SD card and read/write to it?

    adam

    James McColl
    New Member
    New Member
    Posts:7


    --
    18 May 2020 01:56 PM

    Adam,

    Results as follows:

    1. Types of SD Cards tried:

    a. San Disk Micro SD Ultra 64 GB XC 1 (Class 10): Resulted in kernel panic and it looks like it appears in device as mmcblk1

    [    3.964630] VFS: Cannot open root device "mmcblk1p2" or unknown-block(179,2): error -6
    [    3.972900] Please append a correct "root=" boot option; here are the available partitions:
    [    3.981689] 0100           16384 ram0 [    3.985443]  (driver?)
    [    3.987915] 0101           16384 ram1 [    3.991638]  (driver?)
    [    3.994140] 0102           16384 ram2 [    3.997863]  (driver?)
    [    4.000335] 0103           16384 ram3 [    4.004089]  (driver?)
    [    4.006561] 0104           16384 ram4 [    4.010284]  (driver?)
    [    4.012756] 0105           16384 ram5 [    4.016510]  (driver?)
    [    4.018951] 0106           16384 ram6 [    4.022705]  (driver?)
    [    4.025177] 0107           16384 ram7 [    4.028900]  (driver?)
    [    4.031372] 0108           16384 ram8 [    4.035125]  (driver?)
    [    4.037597] 0109           16384 ram9 [    4.041320]  (driver?)
    [    4.043823] 010a           16384 ram10 [    4.047637]  (driver?)
    [    4.050109] 010b           16384 ram11 [    4.053924]  (driver?)
    [    4.056396] 010c           16384 ram12 [    4.060211]  (driver?)
    [    4.062683] 010d           16384 ram13 [    4.066528]  (driver?)
    [    4.069000] 010e           16384 ram14 [    4.072814]  (driver?)
    [    4.075317] 010f           16384 ram15 [    4.079132]  (driver?)
    [    4.081604] 1f00             512 mtdblock0 [    4.085815]  (driver?)
    [    4.088287] 1f01            1792 mtdblock1 [    4.092468]  (driver?)
    [    4.094940] 1f02             128 mtdblock2 [    4.099121]  (driver?)
    [    4.101593] 1f03             128 mtdblock3 [    4.105804]  (driver?)
    [    4.108276] 1f04            6144 mtdblock4 [    4.112457]  (driver?)
    [    4.114929] 1f05          515584 mtdblock5 [    4.119110]  (driver?)
    [    4.121582] b300           31344 mmcblk1 [    4.125610]  driver: mmcblk
    [    4.128540] Kernel panic - not syncing: VFS: Unable to mount root fs on unknown-block(179,2)
    [    4.137359] CPU: 0 PID: 1 Comm: swapper Not tainted 4.9.89 #6
    [    4.143341] Hardware name: Generic OMAP36xx (Flattened Device Tree)
    [    4.149932] [] (unwind_backtrace) from [] (show_stack+0x10/0x14)
    [    4.158050] [] (show_stack) from [] (panic+0xb8/0x244)
    [    4.165252] [] (panic) from [] (mount_block_root+0x17c/0x260)
    [    4.173065] [] (mount_block_root) from [] (mount_root+0xf8/0x124)
    [    4.181243] [] (mount_root) from [] (prepare_namespace+0x160/0x1c4)
    [    4.189636] [] (prepare_namespace) from [] (kernel_init_freeable+0x1b0/0x200)
    [    4.198913] [] (kernel_init_freeable) from [] (kernel_init+0x8/0x10c)
    [    4.207458] [] (kernel_init) from [] (ret_from_fork+0x14/0x24)
    [    4.215362] ---[ end Kernel panic - not syncing: VFS: Unable to mount root fs on unknown-block(179,2)

    b. LogicPD WinCE Demo 6.0 SD Card (I peeled the sticker off and the card has no markings and googling the numbers on the back don't tell me anything): Results in the waiting for /dev/mmcblk1p2 error.

    c. LogicPD Linux SD Card (same as above): Results in the waiting for /dev/mmcblk1p2 error.

    d. San Disk Micro SD HC 32 GB Class 2: Works just fine, booted on the first try. I pulled this card out of an old Gumstix Overo Fire which is also an OMAP3 architecture so I figured it might work.

    2. Ejecting and re-inserting the cards from 1.b. and 1.c. does not appear to change anything it is still waiting at the /dev/mmcblk1p2 line.

    3. Testing the ramdisk from 1.b. and 1.c.:

    1.b. With the card split 50/50 FAT32 boot partition and EXT4 partition, the SD Card does not appear in /dev.

    1.c.

       1. Using the card in its factory provided state and a cleared nand chip the SD Card's boot partition is mounted at /mnt/mmcblk1p1

       2. With the card reformatted to a 50/50 split FAT32 and EXT4, nand cleared and boot env set as per the RAMDisk section of the SDK user guide, it boots but the again the device is not visible from /dev.

    [Conclusion]

    It seems that the card speeds is the culprit and that I should stick to class 2 SD Cards or modify some stuff in Linux to make it work :-)

    James McColl
    New Member
    New Member
    Posts:7


    --
    18 May 2020 05:57 PM

    Ok so I found the answer for the Kernel panic with the SDXC card as per: AM/DM37x Multimedia Device TRM:

     

    24.1.1 MMC/SD/SDIO Features The main features of the MMC/SD/SDIO host controller are:

    • Full compliance with MMC command/response sets as defined in the Multimedia Card System Specification, v4.2 including high-capacity (size > 2GB) cards HC MMC.

    • Full compliance with SD command/response sets as defined in the SD Memory Card Specifications, v2.0 including high-capacity SDHC cards up to 32GB.

    • Full compliance with SDIO command/response sets and interrupt/read-wait mode as defined in the SDIO Card Specification, Part E1, v2.00 • Full compliance with sets as defined in the SD Card Specification, Part A2, SD Host Controller Standard Specification, v2.00

     

    And according to Wiki: The Secure Digital eXtended Capacity (SDXC) format, announced in January 2009 and defined in version 3.01 of the SD specification. So that explains that. So my bad I should have RTFM on this one.

    You are not authorized to post a reply.