This thread has been locked.

If you have a related question, please click the "Ask a related question" button in the top right corner. The newly created question will be automatically linked to this question.

AM3352 USB SETUPEND issue when plug USB cable in/out sometimes

Other Parts Discussed in Thread: AM3352

The issue is as following description:

Our device is using AM3352 which connect to a PAD(such as Lenovo PAD or Nexus PAD) through USB, when plugging in/out USB cable between our device and PAD many times, the PAD can’t identify our device by USB, only reboot our device can fix it;

 

We have made some debugging on it, but can’t get solutions, Please get detailed information from attachment;

 

It’s an urgent issue about the USB on AM3352 now, please help  us to fix this issue, thanks in advance!

some debug message for your reference:

[ 16.939262] musb-hdrc musb-hdrc.0: usbintr (4) epintr(0)
[ 16.944951] musb-hdrc musb-hdrc.0: ** IRQ peripheral usb0004 tx0000 rx0000
[ 16.952225] musb-hdrc musb-hdrc.0: Rain::<== Power=f0, DevCtl=99, int_usb=0x4
[ 16.959777] musb-hdrc musb-hdrc.0: BUS RESET as b_peripheral
[ 17.056981] musb-hdrc musb-hdrc.0: usbintr (0) epintr(1)
[ 17.062654] musb-hdrc musb-hdrc.0: ** IRQ peripheral usb0000 tx0001 rx0000
[ 17.069942] musb-hdrc musb-hdrc.0: csr 0001, count 8, myaddr 0, ep0stage setup
[ 17.077589] musb-hdrc musb-hdrc.0: SETUP req00.05 v001d i0000 l0
[ 17.083949] musb-hdrc musb-hdrc.0: handled 1, csr 0001, ep0stage in/status
[ 17.291577] musb-hdrc musb-hdrc.0: usbintr (0) epintr(1)
[ 17.297229] musb-hdrc musb-hdrc.0: ** IRQ peripheral usb0000 tx0001 rx0000
[ 17.304508] musb-hdrc musb-hdrc.0: csr 0011, count 8, myaddr 0, ep0stage in/status
[ 17.312511] musb_g_ep0_irq 722: SetupEnd came in a wrong ep0stage idle
[ 17.319415] musb_g_ep0_irq 726: Rain::SetupEnd came in a wrong ep0stage: 0000 csr: 0001.
[ 17.327976] musb-hdrc musb-hdrc.0: SETUP req00.05 v001d i0000 l0
[ 17.334332] musb-hdrc musb-hdrc.0: handled 1, csr 0001, ep0stage in/status
[ 17.521153] musb-hdrc musb-hdrc.0: usbintr (1) epintr(0)
[ 17.526799] musb-hdrc musb-hdrc.0: ** IRQ peripheral usb0001 tx0000 rx0000
[ 17.534071] musb-hdrc musb-hdrc.0: Rain::<== Power=f0, DevCtl=99, int_usb=0x1
[ 17.541620] musb-hdrc musb-hdrc.0: SUSPEND (b_peripheral) devctl 99 power f0
[ 17.549079] gadgetfs: suspended from state 2
[ 17.758409] musb-hdrc musb-hdrc.0: usbintr (4) epintr(0)
[ 17.764072] musb-hdrc musb-hdrc.0: ** IRQ peripheral usb0004 tx0000 rx0000
[ 17.771346] musb-hdrc musb-hdrc.0: Rain::<== Power=f0, DevCtl=99, int_usb=0x4
[ 17.778895] musb-hdrc musb-hdrc.0: BUS RESET as b_peripheral
[ 17.889167] musb-hdrc musb-hdrc.0: usbintr (0) epintr(1)
[ 17.894805] musb-hdrc musb-hdrc.0: ** IRQ peripheral usb0000 tx0001 rx0000
[ 17.902091] musb-hdrc musb-hdrc.0: csr 0001, count 8, myaddr 0, ep0stage setup
[ 17.909736] musb-hdrc musb-hdrc.0: SETUP req00.05 v001f i0000 l0
[ 17.916095] musb-hdrc musb-hdrc.0: handled 1, csr 0001, ep0stage in/status
[ 18.126799] musb-hdrc musb-hdrc.0: usbintr (0) epintr(1)
[ 18.132461] musb-hdrc musb-hdrc.0: ** IRQ peripheral usb0000 tx0001 rx0000
[ 18.139744] musb-hdrc musb-hdrc.0: csr 0011, count 8, myaddr 0, ep0stage in/status
[ 18.147745] musb_g_ep0_irq 722: SetupEnd came in a wrong ep0stage idle
[ 18.154647] musb_g_ep0_irq 726: Rain::SetupEnd came in a wrong ep0stage: 0000 csr: 0001.
[ 18.163207] musb-hdrc musb-hdrc.0: SETUP req00.05 v001f i0000 l0
[ 18.169566] musb-hdrc musb-hdrc.0: handled 1, csr 0001, ep0stage in/status
[ 18.384751] musb-hdrc musb-hdrc.0: usbintr (1) epintr(0)
[ 18.390397] musb-hdrc musb-hdrc.0: ** IRQ peripheral usb0001 tx0000 rx0000
[ 18.397670] musb-hdrc musb-hdrc.0: Rain::<== Power=f0, DevCtl=99, int_usb=0x1
[ 18.405221] musb-hdrc musb-hdrc.0: SUSPEND (b_peripheral) devctl 99 power f0
[ 18.412681] gadgetfs: suspended from state 2

I have attached more messages and information in attachment, please help to check them;

  

Log of the USB plug in when the CAM is in the bad state*

[80473.421795] usb bus reset!!!
[80473.770441] musb_g_ep0_irq 726: SetupEnd came in a wrong ep0stage setup
[80473.777497] usb bus reset!!!
[80474.004875] usb bus reset!!!
[80474.120324] musb_g_ep0_irq 726: SetupEnd came in a wrong ep0stage setup
[80474.127368] usb bus reset!!!
[80474.350263] musb_g_ep0_irq 726: SetupEnd came in a wrong ep0stage setup
[80474.357304] usb bus reset!!!
[80474.584973] usb bus reset!!!
[80474.910330] musb_g_ep0_irq 726: SetupEnd came in a wrong ep0stage in/status
[80475.124881] usb bus reset!!!
[80475.390321] musb_g_ep0_irq 726: SetupEnd came in a wrong ep0stage out/status

*I've removed my other debug messages from the original log file
    musb_bad_state.log
=~=~=~=~=~=~=~=~=~=~=~= PuTTY log 2015.06.16 14:36:58 =~=~=~=~=~=~=~=~=~=~=~=

U-Boot SPL 2013.01.01 (Jun 04 2015 - 11:52:35)
Done quick memory test
OMAP SD/MMC: 0
reading u-boot.img
reading u-boot.img


U-Boot 2013.01.01 (Jun 04 2015 - 11:52:35)

The battery is at 82 percent.
Verified the power/battery status.
I2C:   ready
DRAM:  128 MiB
NAND:  128 MiB
MMC:   OMAP SD/MMC: 0, OMAP SD/MMC: 1
musb-hdrc: ConfigData=0xde (UTMI-8, dyn FIFOs, bulk combine, bulk split, HB-ISO Rx, HB-ISO Tx, SoftConn)
musb-hdrc: MHDRC RTL version 2.0 
musb-hdrc: setup fifo_mode 4
musb-hdrc: 28/31 max ep, 16384/16384 memory
USB Peripheral mode controller at 47401000 using PIO, IRQ 0
Hit any key to stop autoboot:  0 
mmc0 is current device
SD/MMC found on device 0
reading CAMProgram.txt
** Unable to read file CAMProgram.txt **
reading uImage
3119264 bytes read in 304 ms (9.8 MiB/s)
Booting from mmc ...
## Booting kernel from Legacy Image at 80007fc0 ...
   Image Name:   Linux-3.2.0-ts-armv7l
   Image Type:   ARM Linux Kernel Image (uncompressed)
   Data Size:    3119200 Bytes = 3 MiB
   Load Address: 80008000
   Entry Point:  80008000
   Verifying Checksum ... OK
   XIP Kernel Image ... OK
OK

Starting kernel ...

[    0.000000] Initializing cgroup subsys cpu
[    0.000000] Linux version 3.2.0-ts-armv7l (rain@raining) (gcc version 4.7.3 (Timesys 20131104) ) #4 PREEMPT Tue Jun 16 13:35:31 CST 2015
[    0.000000] CPU: ARMv7 Processor [413fc082] revision 2 (ARMv7), cr=10c53c7d
[    0.000000] CPU: PIPT / VIPT nonaliasing data cache, VIPT aliasing instruction cache
[    0.000000] Machine: ge_cc10
[    0.000000] Memory policy: ECC disabled, Data cache writeback
[    0.000000] On node 0 totalpages: 32768
[    0.000000] free_area_init_node: node 0, pgdat c06233e4, node_mem_map c066e000
[    0.000000]   Normal zone: 256 pages used for memmap
[    0.000000]   Normal zone: 0 pages reserved
[    0.000000]   Normal zone: 32512 pages, LIFO batch:7
[    0.000000] AM335X ES2.0 (neon )
[    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: 32512
[    0.000000] Kernel command line: console=ttyO0,115200n8 root=/dev/mmcblk0p2 ro rootfstype=ext3 rootwait ip=none
[    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] Memory: 128MB = 128MB total
[    0.000000] Memory: 123324k/123324k available, 7748k 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]     modules : 0xbf000000 - 0xc0000000   (  16 MB)
[    0.000000]       .text : 0xc0008000 - 0xc058e000   (5656 kB)
[    0.000000]       .init : 0xc058e000 - 0xc05d3000   ( 276 kB)
[    0.000000]       .data : 0xc05d4000 - 0xc0624b68   ( 323 kB)
[    0.000000]        .bss : 0xc0624b8c - 0xc066d730   ( 291 kB)
[    0.000000] NR_IRQS:396
[    0.000000] IRQ: Found an INTC at 0xfa200000 (revision 5.0) with 128 interrupts
[    0.000000] Total of 128 interrupts on 1 active controller
[    0.000000] OMAP clockevent source: GPTIMER2 at 24000000 Hz
[    0.000000] omap_dm_timer_switch_src: Switching to HW default clocksource(sys_clkin_ck) for timer1, this may impact timekeeping in low power state
[    0.000000] OMAP clocksource: GPTIMER1 at 24000000 Hz
[    0.000000] sched_clock: 32 bits at 24MHz, resolution 41ns, wraps every 178956ms
[    0.000000] Console: colour dummy device 80x30
[    0.000623] Calibrating delay loop... 298.59 BogoMIPS (lpj=1492992)
[    0.064122] pid_max: default: 32768 minimum: 301
[    0.064635] Security Framework initialized
[    0.064877] Mount-cache hash table entries: 512
[    0.066208] CPU: Testing write buffer coherency: ok
[    0.066393] ftrace: allocating 16921 entries in 50 pages
[    0.176402] devtmpfs: initialized
[    0.204811] omap_hwmod: gfx: failed to hardreset
[    0.223576] omap_hwmod: pruss: failed to hardreset
[    0.227064] print_constraints: dummy: 
[    0.228454] NET: Registered protocol family 16
[    0.236144] OMAP GPIO hardware version 0.1
[    0.245587] omap_mux_init: Add partition: #1: core, flags: 0
[    0.251290]  omap_i2c.1: alias fck already exists
[    0.255648]  omap_hsmmc.0: alias fck already exists
[    0.256477]  omap_hsmmc.1: alias fck already exists
[    0.265726]  omap2_mcspi.1: alias fck already exists
[    0.266460]  omap2_mcspi.2: alias fck already exists
[    0.267338]  edma.0: alias fck already exists
[    0.267396]  edma.0: alias fck already exists
[    0.267445]  edma.0: alias fck already exists
[    0.333096] bio: create slab <bio-0> at 0
[    0.341809] SCSI subsystem initialized
[    0.347506] usbcore: registered new interface driver usbfs
[    0.348635] usbcore: registered new interface driver hub
[    0.349360] usbcore: registered new device driver usb
[    0.350285] registerd cppi-dma Intr @ IRQ 17
[    0.350324] Cppi41 Init Done Qmgr-base(c887a000) dma-base(c8878000)
[    0.350351] Cppi41 Init Done
[    0.350429] musb-ti81xx musb-ti81xx: musb0, board_mode=0x2, plat_mode=0x2
[    0.351335] musb-ti81xx musb-ti81xx: musb1, board_mode=0x2, plat_mode=0x0
[    0.374291] omap_i2c omap_i2c.1: bus 1 rev2.4.0 at 100 kHz
[    0.376460] tps65910 1-002d: JTAGREVNUM 0x0
[    0.381526] print_constraints: VRTC: 
[    0.383678] print_constraints: VIO: at 1800 mV 
[    0.386938] print_constraints: VDD1: 600 <--> 1500 mV at 950 mV normal 
[    0.389959] print_constraints: VDD2: at 950 mV 
[    0.391593] print_constraints: VDD3: 5000 mV 
[    0.393644] print_constraints: VDIG1: at 1800 mV 
[    0.395910] print_constraints: VDIG2: at 1800 mV 
[    0.397957] print_constraints: VPLL: at 1800 mV 
[    0.400052] print_constraints: VDAC: at 1800 mV 
[    0.402093] print_constraints: VAUX1: at 1800 mV 
[    0.404299] print_constraints: VAUX2: at 3300 mV 
[    0.406393] print_constraints: VAUX33: at 3300 mV 
[    0.408450] print_constraints: VMMC: at 3300 mV 
[    0.410024] tps65910 1-002d: No interrupt support, no core IRQ
[    0.419086] cfg80211: Calling CRDA to update world regulatory domain
[    0.422853] Switching to clocksource gp timer
[    0.526772] musb-hdrc: version 6.0, ?dma?, otg (peripheral+host)
[    0.527343] musb-hdrc musb-hdrc.0: dma type: dma-cppi41
[    0.528c musb-hdrc.0: musb-hdrc: hw_ep 5tx, max 512
[    0.529825] musb-hdrc musb-hdrc.0: musb-hdrc: hw_ep 5rx, max 512
[    0.529860] musb-hdrc musb-hdrc.0: musb-hdrc: hw_ep 6tx, max 512
[    0.529895] musb-hdrc musb-hdrc.0: musb-hdrc: hw_ep 6rx, max 512
[    0.529930] musb-hdrc musb-hdrc.0: musb-hdrc: hw_ep 7tx, max 512
[    0.529964] musb-hdrc musb-hdrc.0: musb-hdrc: hw_ep 7rx, max 512
[    0.529999] musb-hdrc musb-hdrc.0: musb-hdrc: hw_ep 8tx, max 512
[    0.530034] musb-hdrc musb-hdrc.0: musb-hdrc: hw_ep 8rx, max 512
[    0.530069] musb-hdrc musb-hdrc.0: musb-hdrc: hw_ep 9tx, max 512
[    0.530104] musb-hdrc musb-hdrc.0: musb-hdrc: hw_ep 9rx, max 512
[    0.530138] musb-hdrc musb-hdrc.0: musb-hdrc: hw_ep 10tx, max 256
[    0.530173] musb-hdrc musb-hdrc.0: musb-hdrc: hw_ep 10rx, max 64
[    0.530208] musb-hdrc musb-hdrc.0: musb-hdrc: hw_ep 11tx, max 256
[    0.530243] musb-hdrc musb-hdrc.0: musb-hdrc: hw_ep 11rx, max 64
[    0.530278] musb-hdrc musb-hdrc.0: musb-hdrc: hw_ep 12tx, max 256
[    0.530312] musb-hdrc musb-hdrc.0: musb-hdrc: hw_ep 12rx, max 64
[    0.530348] musb-hdrc musb-hdrc.0: musb-hdrc: hw_ep 13shared, max 4096
[    0.530384] musb-hdrc musb-hdrc.0: musb-hdrc: hw_ep 14shared, max 1024
[    0.530421] musb-hdrc musb-hdrc.0: musb-hdrc: hw_ep 15shared, max 1024
[    0.530451] musb-hdrc.0: bulk split disabled
[    0.530476] musb-hdrc.0: bulk combine disabled
[    0.531888] musb-hdrc musb-hdrc.0: PERIPHERAL mode, status 0, dev98
[    0.532134] musb-hdrc musb-hdrc.0: USB Peripheral mode controller at c883c000 using DMA, IRQ 18
[   musb-hdrc: hw_ep 6tx, max 512
[    0.535262] musb-hdrc musb-hdrc.1: musb-hdrc: hw_ep 6rx, max 512
[    0.535297] musb-hdrc musb-hdrc.1: musb-hdrc: hw_ep 7tx, max 512
[    0.535331] musb-hdrc musb-hdrc.1: musb-hdrc: hw_ep 7rx, max 512
[    0.535366] musb-hdrc musb-hdrc.1: musb-hdrc: hw_ep 8tx, max 512
[    0.535401] musb-hdrc musb-hdrc.1: musb-hdrc: hw_ep 8rx, max 512
[    0.535436] musb-hdrc musb-hdrc.1: musb-hdrc: hw_ep 9tx, max 512
[    0.535470] musb-hdrc musb-hdrc.1: musb-hdrc: hw_ep 9rx, max 512
[    0.535505] musb-hdrc musb-hdrc.1: musb-hdrc: hw_ep 10tx, max 256
[    0.535540] musb-hdrc musb-hdrc.1: musb-hdrc: hw_ep 10rx, max 64
[    0.535575] musb-hdrc musb-hdrc.1: musb-hdrc: hw_ep 11tx, max 256
[    0.535610] musb-hdrc musb-hdrc.1: musb-hdrc: hw_ep 11rx, max 64
[    0.535644] musb-hdrc musb-hdrc.1: musb-hdrc: hw_ep 12tx, max 256
[    0.535679] musb-hdrc musb-hdrc.1: musb-hdrc: hw_ep 12rx, max 64
[    0.535714] musb-hdrc musb-hdrc.1: musb-hdrc: hw_ep 13shared, max 4096
[    0.535751] musb-hdrc musb-hdrc.1: musb-hdrc: hw_ep 14shared, max 1024
[    0.535788] musb-hdrc musb-hdrc.1: musb-hdrc: hw_ep 15shared, max 1024
[    0.535818] musb-hdrc.1: bulk split disabled
[    0.535842] musb-hdrc.1: bulk combine disabled
[    0.536084] musb-hdrc musb-hdrc.1: MUSB HDRC host driver
[    0.536389] musb-hdrc musb-hdrc.1: new USB bus registered, assigned bus number 1
[    0.536798] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002
[    0.536843] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    0.536877] usb usb1: Product: MUSB HDRC host driver
[    0.536907] usb usb1: Manufacturer: Linux 3.2.0-ts-armv7l musb-hcd
[    0.536938] usb usb1: SerialNumber: musb-hdrc.1
[    0.539811] on CS0
[    0.577042] msgmni has been set to 240
[    0.580084] io scheduler noop registered
[    0.580126] io scheduler deadline registered
[    0.580583] io scheduler cfq registered (default)
[    0.583229] omap_uart.0: ttyO0 at MMIO 0x44e09000 (irq = 72) is a OMAP UART0
[    1.753850] console [ttyO0] enabled
[    1.759468] omap_uart.1: ttyO1 at MMIO 0x48022000 (irq = 73) is a OMAP UART1
[    1.768797] omap_uart.2: ttyO2 at MMIO 0x48024000 (irq = 74) is a OMAP UART2
[    1.777895] omap_uart.3: ttyO3 at MMIO 0x481a6000 (irq = 44) is a OMAP UART3
[    1.786991] omap_uart.4: ttyO4 at MMIO 0x481a8000 (irq = 45) is a OMAP UART4
[    1.796062] omap_uart.5: ttyO5 at MMIO 0x481aa000 (irq = 46) is a OMAP UART5
[    1.838539] brd: module loaded
[    1.859570] loop: module loaded
[    1.872326] omap2-nand driver initializing
[    1.878940] NAND device: Manufacturer ID: 0xc2, Chip ID: 0xf1 (Macronix NAND 128MiB 3,3V 8-bit)
[    1.889049] Creating 8 MTD partitions on "omap2-nand.0":
[    1.894875] 0x000000000000-0x000000060000 : "SPL"
[    1.905527] 0x000000060000-0x000000100000 : "U-Boot"
[    1.916723] 0x000000100000-0x0000001a0000 : "U-Boot.recovery"
[    1.928690] 0x0000001a0000-0x000000200000 : "U-Boot Env"
[    1.939716] 0x000000200000-0x000000700000 : "Kernel"
[    1.959957] 0x000000700000-0x000002f00000 : "RO File System"
[    2.051965] 0x000002f00000-0x000005700000 : "File System Update"
[    2.144957] 0x000005700000-0x000008000000 : "RW File System"
[    2.243564] usbcore: registered new interface driver cdc_ether
[    2.250420] usbcore: registered new interface driver cdc_subset
[    2.256977] Initializing USB Mass Storage driver...
[    2.262839] usbcore: registered new interface driver usb-storage
[    2.269393] USB Mass Storage support registered.
[    2.274884] usbcore: registered new interface driver usbtest
[    2.281929] mousedev: PS/2 mouse device common for all mice
[    2.289258] input: extint as /devices/platform/extint/input/input0
[    2.299270] omap_rtc am33xx-rtc: rtc core: registered am33xx-rtc as rtc0
[    2.307447] i2c /dev entries driver
[    2.318414] bq27x00-battery 1-0055: support ver. 1.2.0 enabled
[    2.340468] OMAP Watchdog Timer Rev 0x01: initial timeout 30 sec
[    2.353954] cpuidle: using governor ladder
[    2.360216] cpuidle: using governor menu
[    2.373569] Registered led device: tlc5947-0
[    2.378869] Registered led device: tlc5947-1
[    2.384488] Registered led device: tlc5947-2
[    2.389700] Registered led device: tlc5947-3
[    2.394935] Registered led device: tlc5947-4
[    2.400130] Registered led device: tlc5947-5
[    2.405489] Registered led device: tlc5947-6
[    2.413975] Registered led device: tlc5947-7
[    2.419340] Registered led device: tlc5947-8
[    2.424758] Registered led device: tlc5947-9
[    2.433237] Registered led device: tlc5947-10
[    2.438645] Registered led device: tlc5947-11
[    2.444039] Registered led device: tlc5947-12
[    2.449342] Registered led device: tlc5947-13
[    2.454816] Registered led device: tlc5947-14
[    2.460116] Registered led device: tlc5947-15
[    2.465410] Registered led device: tlc5947-16
[    2.470704] Registered led device: tlc5947-17
[    2.476170] Registered led device: tlc5947-18
[    2.481480] Registered led device: tlc5947-19
[    2.486773] Registered led device: tlc5947-20
[    2.492082] Registered led device: tlc5947-21
[    2.497522] Registered led device: tlc5947-22
[    2.502816] Registered led device: tlc5947-23
[    2.516956] usbcore: registered new interface driver usbhid
[    2.522837] usbhid: USB HID core driver
[    2.529793] tiadc tiadc: attached adc driver
[    2.535228] oprofile: hardware counters not available
[    2.540715] oprofile: using timer interrupt.
[    2.545414] nf_conntrack version 0.5.0 (1926 buckets, 7704 max)
[    2.553402] ip_tables: (C) 2000-2006 Netfilter Core Team
[    2.559407] TCP cubic registered
[    2.562857] NET: Registered protocol family 17
[    2.567812] Registering the dns_resolver key type
[    2.573157] VFP support v0.3: implementor 41 architecture 3 part 30 variant c rev 3
[    2.581399] ThumbEE CPU extension supported.
[    2.586081] mux: Failed to setup hwmod io irq -22
[    2.595519] Power Management for AM33XX family
[    2.600745] Trying to load am335x-pm-firmware.bin (60 secs timeout)
[    2.607590] Copied the M3 firmware to UMEM
[    2.612139] Cortex M3 Firmware Version = 0x186
[    2.616981] clock: disabling unused clocks to save power
[    2.630863] input: gpio-keys as /devices/platform/gpio-keys/input/input1
[    2.640655] omap_rtc am33xx-rtc: setting system clock to 2000-01-01 00:00:00 UTC (946684800)
[    2.652043] Waiting for root device /dev/mmcblk0p2...
[    2.780375] mmc0: new high speed SDHC card at address 0003
[    2.788139] mmcblk0: mmc0:0003 SB08G 7.21 GiB 
[    2.796859]  mmcblk0: p1 p2
[    2.893214] EXT3-fs (mmcblk0p2): mounted filesystem with ordered data mode
[    2.900671] VFS: Mounted root (ext3 filesystem) readonly on device 179:2.
[    2.907951] kjournald starting.  Commit interval 5 seconds
[    2.926168] devtmpfs: mounted
[    2.930440] Freeing init memory: 276K
[    3.038655] mmc1: card claims to support voltages below the defined range. These will be ignored.
[    3.065047] mmc1: queuing unknown CIS tuple 0x91 (3 bytes)
[    3.074991] mmc1: new SDIO card at address 0001

init started: BusyBox v1.20.2 (2015-02-19 16:27:57 CST)
Setting hotplug handler: [ OK ]
Creating device files: Auto-mount of [/media/mmcblk0p1] successful
[ OK ]
Starting watchdog daemon: Setting timezone and system clock: [OK]
Starting system logging.
Mounting read/write partition...
[    7.962100] UBI: attaching mtd7 to ubi0
[    7.966436] UBI: physical eraseblock size:   131072 bytes (128 KiB)
[    7.973108] UBI: logical eraseblock size:    126976 bytes
[    7.978818] UBI: smallest flash I/O unit:    2048
[    7.983823] UBI: VID header offset:          2048 (aligned 2048)
[    7.990173] UBI: data offset:                4096
[    9.537847] UBI: max. sequence number:       53
[    9.613280] UBI: attached mtd7 to ubi0
[    9.617309] UBI: MTD device name:            "RW File System"
[    9.643198] UBI: MTD device size:            41 MiB
[    9.648417] UBI: number of good PEBs:        328
[    9.667246] UBI: number of bad PEBs:         0
[    9.671995] UBI: number of corrupted PEBs:   0
[    9.676867] UBI: max. allowed volumes:       128
[    9.681754] UBI: wear-leveling threshold:    4096
[    9.686779] UBI: number of internal volumes: 1
[    9.691480] UBI: number of user volumes:     1
[    9.696212] UBI: available PEBs:             23
[    9.701003] UBI: total number of reserved PEBs: 305
[    9.706189] UBI: number of PEBs reserved for bad PEB handling: 3
[    9.712543] UBI: max/mean erase counter: 1/0
[    9.717085] UBI: image sequence number:  776135292
[    9.722217] UBI: background thread "ubi_bgt0d" started, PID 865
UBI device number 0, total 328 LEBs (41648128 bytes, 39.7 MiB), available 23 LEBs (2920448 bytes, 2.8 MiB), LEB size 126976 bytes (124.0 KiB)
[   10.058733] UBIFS: recovery needed
[   10.574961] UBIFS: recovery completed
[   10.578920] UBIFS: mounted UBI device 0, volume 0, name "data"
[   10.585149] UBIFS: file system size:   36696064 bytes (35836 KiB, 34 MiB, 289 LEBs)
[   10.593281] UBIFS: journal size:       1777664 bytes (1736 KiB, 1 MiB, 14 LEBs)
[   10.601011] UBIFS: media format:       w4/r0 (latest is w4/r0)
[   10.607205] UBIFS: default compressor: lzo
[   10.611541] UBIFS: reserved for root:  1733245 bytes (1692 KiB)
Starting dbus: [ OK ]
Changed CPU Frequency from 300000 to: 300000
Starting Bluetooth...
Starting USB...
[   11.264691] gadgetfs: USB Gadget filesystem, version 24 Aug 2004
[   11.338781] nop musb-hdrc.0: failed to start (null): -120
Found a Texas Instruments' chip!
Firmware file : /lib/firmware/TIInit_7.6.15.bts
Loaded BTS script version 1
texas: changing baud rate to 3000000, flow control to 1
Internet Systems Consortium DHCP Client V3.1-ESV-R3
Copyright 2004-2011 Internet Systems Consortium.
All rights reserved.
For info, please visit https://www.isc.org/software/dhcp/

can't create /var/state/dhcp/dhclient.leases: No such file or directory
ifconfig: SIOCSIFADDR: No such device
Bind socket to interface: No such device

If you did not get this software from ftp.isc.org, please
get the latest from ftp.isc.org and install that before
requesting help.

If you did get this software from ftp.isc.org and have not
yet read the README, please read it before requesting help.
If you intend to request help from the dhcp-server@isc.org
mailing list, please read the section on the README about
submitting bug reports and requests for help.

Please do not under any circumstances send requests for
help directly to the authors of this software - please
send them to the appropriate mailing list as described in
the README file.

exiting.
Starting CC10 application.


BusyBox v1.20.2 (2015-02-19 16:27:57 CST) built-in shell (ash)
Enter 'help' for a list of built-in commands.

# AcquisitionMain:  main> Logging started, build timestamp: Fri May  1 10:37:12 2015
AcquisitionMain:  main> Build CC10/MCU/CAM-relCAMConnect10v1.0-Linux-ARM-32-1077808
AcquisitionMain:  main> Build Version 1.0.0R3
EventLog:  CreateLoggers> Poco Logger opened at level 8
AcquisitionMain:  main> == Starting CAM Application ==
AcquisitionMain:  main> Application Build CC10/MCU/CAM-relCAMConnect10v1.0-Linux-ARM-32-1077808 Fri May  1 10:37:12 2015
BluetoothManager:  BluetoothManager> Delaying Bluetooth Manager start, Bluetooth not ready.
[   14.043664] Bluetooth: Core ver 2.16
[   14.064130] NET: Registered protocol family 31
[   14.068905] Bluetooth: HCI device and connection manager initialized
[   14.098408] Bluetooth: HCI socket layer initialized
[   14.108951] Bluetooth: L2CAP socket layer initialized
[   14.130551] Bluetooth: HCI UART driver ver 2.2
[   14.143275] Bluetooth: HCILL protocol initialized
Device setup complete
BluetoothManager:  SetDeviceClass> Set device class to 0x900
BluetoothManager:  SetName> Set Bluetooth name to: CAM-84:DD:20:A4:CF:39
CAMControl:  InitVersionInfo> Software Version 1.0.0R3
CAMControl:  InitVersionInfo> File System Version CAM-mainCAMConnect10-TimesysFactory-40-996027
CAMControl:  RunApplication> Configuring Connection Manager...
DSPManager:  Start> Starting DSP download thread
CAMControl:  RunApplication> Creating LED Manager...
ERROR: /jenkins/workspace/CC10/MCU/CAM-relCAMConnect10v1.0-Linux-ARM/CAM/MCU/Watchdog/Source/Watchdog.cpp line 79
Watchdog:  WatchDogMainTask> ERROR: Could not open watchdog device /dev/watchdog: 
CAMControl:  RunApplication> Configuring Power Manager...
CAMControl:  RunApplication> Configuring Mode Manager...
CAMControl:  RunApplication> Creating Battery Manager...
BatteryManager:  BatteryManager> Initial Battery state is 2, remaining percentage is 82, AC power detect is 0
CAMControl:  RunApplication> Creating Button Manager...
CAMControl:  RunApplication> Configuring Bluetooth Manager...
CAMControl:  RunApplication> Creating ECGHookupAdvisor Manager...
CAMControl:  RunApplication> Configuring Acquisition Manager...
CAMControl:  RunApplication> Configuring Configuration Manager...
CAMControl:  RunApplication> Configuring File Transfer Manager...
CAMControl:  RunApplication> Creating Firmware Update Manager...
CAMControl:  RunApplication> Creating Diagnostic Manager...
CAMControl:  RunApplication> Initializing Configuration Manager...
CAMControl:  RunApplication> Initializing LED Manager...
CAMControl:  RunApplication> Initializing Battery Manager...
BatteryManager:  Initialize> Starting Battery Status thread
CAMControl:  RunApplication> Initializin[   16.717092] gadgetfs: bound to musb-hdrc driver
g Button Manager[   16.722552] musb-hdrc musb-hdrc.0: <== devctl 98
...
ButtonManager:  Init> Initializing
ButtonManager:  Init> Starting CheckButton and PowerButtonTimer threads
ButtonManager:  Init> Initialized
CAMControl:  RunApplication> Initializing Power Manager...
CAMControl:  RunApplication> Initializing Bluetooth Manager...
CAMControl:  RunApplication> Initializing ECG Hookup Advisor...
CAMControl:  RunApplication> Initializing Acquisition Manager...
AcquisitionManager:  AcquisitionPacketConsumer> Registering host packet consumer
AcquisitionManager:  AcquisitionPacketConsumer> Registering DSP packet consumer
PowerManager:  PowerManagerTask> PowerManagerTask started
AcquisitionManager:  Acquisito[   16.786429] musb-hdrc musb-hdrc.0: ** IRQ peripheral usb0001 tx0000 rx0000
[   16.794237] musb-hdrc musb-hdrc.0: Rain::<== Power=e0, DevCtl=99, int_usb=0x1
[   16.801793] musb-hdrc musb-hdrc.0: SUSPEND (b_idle) devctl 99 power e0
nDSPTask> AcquisitionManagerDSPTask started
AcquisitionManager:  AcquisitonHostTask> AcquisitionManagerTask started
CAMControl:  RunApplication> Initializing File Download Ma[   16.823547] Bluetooth: RFCOMM TTY layer initialized
nager...
CAMCon[   16.829690] Bluetooth: RFCOMM socket layer initialized
trol:  RunApplication> Initializing Firmware Update Manager...
CAMControl:  Run[   16.843182] Bluetooth: RFCOMM ver 1.11
Application> Initializing Diagnostic Manager...
DiagnosticManager:  DiagnosticManagerTask> DiagnosticManagerTask started
Watchdog:  SetTimeout> Setting watchdog timeout to 1 seconds from -1
CAMControl:  update> Idle mode update, Disconnecting...
CAMControl:  DisconnectAll> Disconnecting, but host was NULL
ERROR: /jenkins/workspace/CC10/MCU/CAM-relCAMConnect10v1.0-Linux-ARM/CAM/MCU/CAMControl/Source/CAMControl.cpp line 889
CAMControl:  RunApplication> ERROR: Could not remove the restart reason file /data/restartReason.txt
BluetoothManager:  SeverMainline> Bluetooth server started
UsbManager:  Init> Initializing...
UsbManager:  Init> Initialized
UsbConnection:  Open> USB Connection opened
CAMControl:  RunApplication> Waiting for shutdown...
BluetoothManager:  SeverMainline> Server is bound to address: 84:DD:20:A4:CF:39 port/channel: 16
[   16.942114] musb-hdrc musb-hdrc.0: ** IRQ peripheral usb0004 tx0000 rx0000
[   16.949443] musb-hdrc musb-hdrc.0: Rain::<== Power=f0, DevCtl=99, int_usb=0x4
[   16.956996] musb-hdrc musb-hdrc.0: BUS RESET as b_peripheral
[   17.061536] musb-hdrc musb-hdrc.0: ** IRQ peripheral usb0000 tx0001 rx0000
[   17.068857] musb-hdrc musb-hdrc.0: csr 0001, count 8, myaddr 0, ep0stage setup
[   17.076508] musb-hdrc musb-hdrc.0: SETUP req00.05 v000b i0000 l0
[   17.082870] musb-hdrc musb-hdrc.0: handled 1, csr 0001, ep0stage in/status
[   17.090237] musb-hdrc musb-hdrc.0: ** IRQ peripheral usb0000 tx0001 rx0000
[   17.097521] musb-hdrc musb-hdrc.0: csr 0000, count 0, myaddr 0, ep0stage in/status
[   17.111130] musb-hdrc musb-hdrc.0: ** IRQ peripheral usb0000 tx0001 rx0000
[   17.118427] musb-hdrc musb-hdrc.0: csr 0001, count 8, myaddr 11, ep0stage idle
[   17.126075] musb-hdrc musb-hdrc.0: SETUP req80.06 v0100 i0000 l8
[   17.132434] musb-hdrc musb-hdrc.0: handled 0, csr 0001, ep0stage in
[   17.139068] gadgetfs: connected
[   17.142422] musb-hdrc musb-hdrc.0: queue to ep0 (OUT/RX), length=8
[   17.148978] musb-hdrc musb-hdrc.0: TX ep0 fifo c883c420 count 8 buf c7521d00
[   17.156573] musb-hdrc musb-hdrc.0: ** IRQ peripheral usb0000 tx0001 rx0000
[   17.163858] musb-hdrc musb-hdrc.0: csr 0001, count 8, myaddr 11, ep0stage out/status
[   17.172056] musb-hdrc musb-hdrc.0: SETUP req80.06 v0100 i0000 l18
[   17.178508] musb-hdrc musb-hdrc.0: handled 0, csr 0001, ep0stage in
[   17.185142] musb-hdrc musb-hdrc.0: queue to ep0 (OUT/RX), length=18
[   17.191780] musb-hdrc musb-hdrc.0: TX ep0 fifo c883c420 count 18 buf c7521d00
[   17.199397] musb-hdrc musb-hdrc.0: ** IRQ peripheral usb0000 tx0001 rx0000
[   17.206678] musb-hdrc musb-hdrc.0: csr 0001, count 8, myaddr 11, ep0stage out/status
[   17.214875] musb-hdrc musb-hdrc.0: SETUP req80.06 v0200 i0000 l9
[   17.221233] musb-hdrc musb-hdrc.0: handled 0, csr 0001, ep0stage in
[   17.227869] musb-hdrc musb-hdrc.0: queue to ep0 (OUT/RX), length=9
[   17.234413] musb-hdrc musb-hdrc.0: TX ep0 fifo c883c420 count 9 buf c7521ce0
[   17.241941] musb-hdrc musb-hdrc.0: ** IRQ peripheral usb0000 tx0001 rx0000
[   17.249222] musb-hdrc musb-hdrc.0: csr 0000, count 0, myaddr 11, ep0stage out/status
[   17.257451] musb-hdrc musb-hdrc.0: ** IRQ peripheral usb0000 tx0001 rx0000
[   17.264731] musb-hdrc musb-hdrc.0: csr 0001, count 8, myaddr 11, ep0stage idle
[   17.272378] musb-hdrc musb-hdrc.0: SETUP req80.06 v0200 i0000 l32
[   17.278827] musb-hdrc musb-hdrc.0: handled 0, csr 0001, ep0stage in
[   17.285461] musb-hdrc musb-hdrc.0: queue to ep0 (OUT/RX), length=32
[   17.292096] musb-hdrc musb-hdrc.0: TX ep0 fifo c883c420 count 32 buf c7521ce0
[   17.299705] musb-hdrc musb-hdrc.0: ** IRQ peripheral usb0000 tx0001 rx0000
[   17.306984] musb-hdrc musb-hdrc.0: csr 0001, count 8, myaddr 11, ep0stage out/status
[   17.315180] musb-hdrc musb-hdrc.0: SETUP req80.06 v0300 i0000 l255
[   17.321721] musb-hdrc musb-hdrc.0: handled 0, csr 0001, ep0stage in
[   17.328418] musb-hdrc musb-hdrc.0: ** IRQ peripheral usb0000 tx0001 rx0000
[   17.335697] musb-hdrc musb-hdrc.0: csr 0000, count 0, myaddr 11, ep0stage in
[   17.3431 req00.09 v0003 i0000 l0
[   17.464087] musb-hdrc musb-hdrc.0: handled 0, csr 0001, ep0stage wait
[   17.470905] gadgetfs: configuration #3
UsbManager:  StartIO> USB Low Le[   17.481919] musb-hdrc musb-hdrc.0: queue to ep0 (OUT/RX), length=0
vel Connection: [   17.489725] musb-hdrc musb-hdrc.0: ** IRQ peripheral usb0000 tx0001 rx0000
[   17.498331] musb-hdrc musb-hdrc.0: csr 0000, count 0, myaddr 11, ep0stage in/status
Connected
DSPManager:  DspDownloadThread> DSP Firmware Downloaded
CAMControl:  update> DspManager: Activating DSP connection...
ConnectionManager:  ActivateDSPConnection> ConnectionManager: Opening DSP Connection...
DSPManager:  Receive> DSP System Start Broadcast message (Normal Power up) received
DSPManager:  DspLoadWaitThread> DSP Running Successfully
CAMControl:  update> DspManager: Unknown DSP Manager Status!
DSPManager:  Receive> DSP Version: 1.0.0 Proto Letter: 0x0 Proto Number: 0x1421 Build Month: 5 Build Day: 1 Build Hour: 1

# ButtonManager:  OnPowerButtonPress> Power button long pressed
PowerManager:  update> Observed a power button long press, powering off...
PowerManager:  OffMode> Changing to off state
ConnectionManager:  AssemblerTask>  attempted to transmit while not connected
CAMControl:  TurnOffDSP> Deactivating DSP connection...
UARTConnection:  Close> UARTConnection closing
DSPManager:  ShutOff> Powering off DSP!!!
BluetoothManager:  SeverMainline> Bluetooth server stopped
UsbManager:  UnInit> Uninitializing...
UsbManager:  StopIO> USB Low Level Connection: Disconnected
[   29.303419] gadgetfs: disconnected
[   29.307962] musb-hdrc musb-hdrc.0: ** IRQ peripheral usb0020 tx0000 rx0000
[   29.315242] musb-hdrc musb-hdrc.0: Rain::<== Power=f0, DevCtl=98, int_usb=0x20
[   29.322893] musb-hdrc musb-hdrc.0: DISCONNECT (b_peripheral) as Peripheral, devctl 98
[   29.331169] musb gadgetfs gadget disconnected.
[   29.346907] musb-hdrc musb-hdrc.0: HDRC disabled
[   29.352164] musb-hdrc musb-hdrc.0: HDRC disabled
UsbManager:  UnInit> Uninitialized
UsbManager:  UnInit> Uninitializing...
UsbManager:  UnInit> Uninitialized
UsbConnection:  Close> USB Connection closed
UARTConnection:  Close> UARTConnection closing
CAMControl:  RunApplication> Performing Uninits...
ButtonManager:  UnInit> Uninitializing
Log of the USB plug in when the CAM is functioning normally* 

[  132.637492] usb bus reset!!!
[  132.772198] usb bus reset!!!

*I've removed other debug messages from the original log
U-Boot SPL 2013.01.01 (Jun 04 2015 - 11:52:35)
Done quick memory test
OMAP SD/MMC: 0
reading u-boot.img
reading u-boot.img


U-Boot 2013.01.01 (Jun 04 2015 - 11:52:35)

AC Power applied.
Verified the power/battery status.
I2C:   ready
DRAM:  128 MiB
NAND:  128 MiB
MMC:   OMAP SD/MMC: 0, OMAP SD/MMC: 1
musb-hdrc: ConfigData=0xde (UTMI-8, dyn FIFOs, bulk combine, bulk split, HB-ISO Rx, HB-ISO Tx, SoftConn)
musb-hdrc: MHDRC RTL version 2.0 
musb-hdrc: setup fifo_mode 4
musb-hdrc: 28/31 max ep, 16384/16384 memory
USB Peripheral mode controller at 47401000 using PIO, IRQ 0
Hit any key to stop autoboot:  0 
mmc0 is current device
SD/MMC found on device 0
reading CAMProgram.txt
** Unable to read file CAMProgram.txt **
reading uImage
3119520 bytes read in 304 ms (9.8 MiB/s)
Booting from mmc ...
## Booting kernel from Legacy Image at 80007fc0 ...
   Image Name:   Linux-3.2.0-ts-armv7l
   Image Type:   ARM Linux Kernel Image (uncompressed)
   Data Size:    3119456 Bytes = 3 MiB
   Load Address: 80008000
   Entry Point:  80008000
   Verifying Checksum ... OK
   XIP Kernel Image ... OK
OK

Starting kernel ...

[    0.000000] Initializing cgroup subsys cpu
[    0.000000] Linux version 3.2.0-ts-armv7l (rain@raining) (gcc version 4.7.3 (Timesys 20131104) ) #6 PREEMPT Wed Jun 17 11:34:35 CST 2015
[    0.000000] CPU: ARMv7 Processor [413fc082] revision 2 (ARMv7), cr=10c53c7d
[    0.000000] CPU: PIPT / VIPT nonaliasing data cache, VIPT aliasing instruction cache
[    0.000000] Machine: ge_cc10
[    0.000000] Memory policy: ECC disabled, Data cache writeback
[    0.000000] On node 0 totalpages: 32768
[    0.000000] free_area_init_node: node 0, pgdat c06233e4, node_mem_map c066e000
[    0.000000]   Normal zone: 256 pages used for memmap
[    0.000000]   Normal zone: 0 pages reserved
[    0.000000]   Normal zone: 32512 pages, LIFO batch:7
[    0.000000] AM335X ES2.0 (neon )
[    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: 32512
[    0.000000] Kernel command line: console=ttyO0,115200n8 root=/dev/mmcblk0p2 ro rootfstype=ext3 rootwait ip=none
[    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] Memory: 128MB = 128MB total
[    0.000000] Memory: 123324k/123324k available, 7748k 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]     modules : 0xbf000000 - 0xc0000000   (  16 MB)
[    0.000000]       .text : 0xc0008000 - 0xc058e000   (5656 kB)
[    0.000000]       .init : 0xc058e000 - 0xc05d3000   ( 276 kB)
[    0.000000]       .data : 0xc05d4000 - 0xc0624b68   ( 323 kB)
[    0.000000]        .bss : 0xc0624b8c - 0xc066d730   ( 291 kB)
[    0.000000] NR_IRQS:396
[    0.000000] IRQ: Found an INTC at 0xfa200000 (revision 5.0) with 128 interrupts
[    0.000000] Total of 128 interrupts on 1 active controller
[    0.000000] OMAP clockevent source: GPTIMER2 at 24000000 Hz
[    0.000000] omap_dm_timer_switch_src: Switching to HW default clocksource(sys_clkin_ck) for timer1, this may impact timekeeping in low power state
[    0.000000] OMAP clocksource: GPTIMER1 at 24000000 Hz
[    0.000000] sched_clock: 32 bits at 24MHz, resolution 41ns, wraps every 178956ms
[    0.000000] Console: colour dummy device 80x30
[    0.000620] Calibrating delay loop... 298.59 BogoMIPS (lpj=1492992)
[    0.064120] pid_max: default: 32768 minimum: 301
[    0.064625] Security Framework initialized
[    0.064868] Mount-cache hash table entries: 512
[    0.066202] CPU: Testing write buffer coherency: ok
[    0.066392] ftrace: allocating 16921 entries in 50 pages
[    0.176364] devtmpfs: initialized
[    0.204778] omap_hwmod: gfx: failed to hardreset
[    0.223535] omap_hwmod: pruss: failed to hardreset
[    0.227038] print_constraints: dummy: 
[    0.228440] NET: Registered protocol family 16
[    0.236176] OMAP GPIO hardware version 0.1
[    0.245583] omap_mux_init: Add partition: #1: core, flags: 0
[    0.251335]  omap_i2c.1: alias fck already exists
[    0.255690]  omap_hsmmc.0: alias fck already exists
[    0.256503]  omap_hsmmc.1: alias fck already exists
[    0.265831]  omap2_mcspi.1: alias fck already exists
[    0.266572]  omap2_mcspi.2: alias fck already exists
[    0.267459]  edma.0: alias fck already exists
[    0.267515]  edma.0: alias fck already exists
[    0.267564]  edma.0: alias fck already exists
[    0.333471] bio: create slab <bio-0> at 0
[    0.342249] SCSI subsystem initialized
[    0.347927] usbcore: registered new interface driver usbfs
[    0.349062] usbcore: registered new interface driver hub
[    0.349799] usbcore: registered new device driver usb
[    0.350711] registerd cppi-dma Intr @ IRQ 17
[    0.350748] Cppi41 Init Done Qmgr-base(c887a000) dma-base(c8878000)
[    0.350776] Cppi41 Init Done
[    0.350851] musb-ti81xx musb-ti81xx: musb0, board_mode=0x2, plat_mode=0x2
[    0.351764] musb-ti81xx musb-ti81xx: musb1, board_mode=0x2, plat_mode=0x0
[    0.356476] omap_i2c omap_i2c.1: bus 1 rev2.4.0 at 100 kHz
[    0.358689] tps65910 1-002d: JTAGREVNUM 0x0
[    0.363821] print_constraints: VRTC: 
[    0.366352] print_constraints: VIO: at 1800 mV 
[    0.369445] print_constraints: VDD1: 600 <--> 1500 mV at 950 mV normal 
[    0.372484] print_constraints: VDD2: at 950 mV 
[    0.374318] print_constraints: VDD3: 5000 mV 
[    0.376361] print_constraints: VDIG1: at 1800 mV 
[    0.378444] print_constraints: VDIG2: at 1800 mV 
[    0.380476] print_constraints: VPLL: at 1800 mV 
[    0.382571] print_constraints: VDAC: at 1800 mV 
[    0.384780] print_constraints: VAUX1: at 1800 mV 
[    0.386837] print_constraints: VAUX2: at 3300 mV 
[    0.388892] print_constraints: VAUX33: at 3300 mV 
[    0.390947] print_const  512
[    0.512625] musb-hdrc musb-hdrc.0: musb-hdrc: hw_ep 1rx, max 512
[    0.512659] musb-hdrc musb-hdrc.0: musb-hdrc: hw_ep 2tx, max 512
[    0.512694] musb-hdrc musb-hdrc.0: musb-hdrc: hw_ep 2rx, max 512
[    0.512729] musb-hdrc musb-hdrc.0: musb-hdrc: hw_ep 3tx, max 512
[    0.512764] musb-hdrc musb-hdrc.0: musb-hdrc: hw_ep 3rx, max 512
[    0.512798] musb-hdrc musb-hdrc.0: musb-hdrc: hw_ep 4tx, max 512
[    0.512833] musb-hdrc musb-hdrc.0: musb-hdrc: hw_ep 4rx, max 512
[    0.512867] musb-hdrc musb-hdrc.0: musb-hdrc: hw_ep 5tx, max 512
[    0.512902] musb-hdrc musb-hdrc.0: musb-hdrc: hw_ep 5rx, max 512
[    0.512936] musb-hdrc musb-hdrc.0: musb-hdrc: hw_ep 6tx, max 512
[    0.512971] musb-hdrc musb-hdrc.0: musb-hdrc: hw_ep 6rx, max 512
[    0.513007] musb-hdrc musb-hdrc.0: musb-hdrc: hw_ep 7tx, max 512
[    0.513042] musb-hdrc musb-hdrc.0: musb-hdrc: hw_ep 7rx, max 512
[    0.513076] musb-hdrc musb-hdrc.0: musb-hdrc: hw_ep 8tx, max 512
[    0.513111] musb-hdrc musb-hdrc.0: musb-hdrc: hw_ep 8rx, max 512
[    0.513146] musb-hdrc musb-hdrc.0: musb-hdrc: hw_ep 9tx, max 512
[    0.513180] musb-hdrc musb-hdrc.0: musb-hdrc: hw_ep 9rx, max 512
[    0.513215] musb-hdrc musb-hdrc.0: musb-hdrc: hw_ep 10tx, max 256
[    0.513250] musb-hdrc musb-hdrc.0: musb-hdrc: hw_ep 10rx, max 64
[    0.513284] musb-hdrc musb-hdrc.0: musb-hdrc: hw_ep 11tx, max 256
[    0.513319] musb-hdrc musb-hdrc.0: musb-hdrc: hw_ep 11rx, max 64
[    0.513354] musb-hdrc musb-hdrc.0: musb-hdrc: hw_ep 12tx, max 256
[    0.513389] musb-hdrc musb-hdrc.0: musb-hdrc: hw_ep 12rx 18] musb-hdrc musb-hdrc.1: musb-hdrc: hw_ep 1tx, max 512
[    0.518153] musb-hdrc musb-hdrc.1: musb-hdrc: hw_ep 1rx, max 512
[    0.518188] musb-hdrc musb-hdrc.1: musb-hdrc: hw_ep 2tx, max 512
[    0.518223] musb-hdrc musb-hdrc.1: musb-hdrc: hw_ep 2rx, max 512
[    0.518258] musb-hdrc musb-hdrc.1: musb-hdrc: hw_ep 3tx, max 512
[    0.518293] musb-hdrc musb-hdrc.1: musb-hdrc: hw_ep 3rx, max 512
[    0.518327] musb-hdrc musb-hdrc.1: musb-hdrc: hw_ep 4tx, max 512
[    0.518362] musb-hdrc musb-hdrc.1: musb-hdrc: hw_ep 4rx, max 512
[    0.518396] musb-hdrc musb-hdrc.1: musb-hdrc: hw_ep 5tx, max 512
[    0.518431] musb-hdrc musb-hdrc.1: musb-hdrc: hw_ep 5rx, max 512
[    0.518466] musb-hdrc musb-hdrc.1: musb-hdrc: hw_ep 6tx, max 512
[    0.518500] musb-hdrc musb-hdrc.1: musb-hdrc: hw_ep 6rx, max 512
[    0.518536] musb-hdrc musb-hdrc.1: musb-hdrc: hw_ep 7tx, max 512
[    0.518570] musb-hdrc musb-hdrc.1: musb-hdrc: hw_ep 7rx, max 512
[    0.518605] musb-hdrc musb-hdrc.1: musb-hdrc: hw_ep 8tx, max 512
[    0.518639] musb-hdrc musb-hdrc.1: musb-hdrc: hw_ep 8rx, max 512
[    0.518674] musb-hdrc musb-hdrc.1: musb-hdrc: hw_ep 9tx, max 512
[    0.518708] musb-hdrc musb-hdrc.1: musb-hdrc: hw_ep 9rx, max 512
[    0.518743] musb-hdrc musb-hdrc.1: musb-hdrc: hw_ep 10tx, max 256
[    0.518778] musb-hdrc musb-hdrc.1: musb-hdrc: hw_ep 10rx, max 64
[    0.518813] musb-hdrc musb-hdrc.1: musb-hdrc: hw_ep 11tx, max 256
[    0.518849] musb-hdrc musb-hdrc.1: musb-hdrc: hw_ep 11rx, max 64
[    0.518884] musb-hdrc musb-hdrc.1: musb-hdrc: hw_ep 12tx, max 256
[    0.518918] musb-hdrc musb-hdrc.1: musb-hdrc: hw_ep 12rx, max 64
[    0.518953] musb-hdrc musb-hdrc.1: musb-hdrc: hw_ep 13shared, max 4096
[    0.518990] musb-hdrc musb-hdrc.1: musb-hdrc: hw_ep 14shared, max 1 tries: 1024 (order: 0, 4096 bytes)
[    0.529448] TCP established hash table entries: 4096 (order: 3, 32768 bytes)
[    0.529669] TCP bind hash table entries: 4096 (order: 2, 16384 bytes)
[    0.529804] TCP: Hash tables configured (established 4096 bind 4096)
[    0.529839] TCP reno registered
[    0.529875] UDP hash table entries: 256 (order: 0, 4096 bytes)
[    0.529935] UDP-Lite hash table entries: 256 (order: 0, 4096 bytes)
[    0.530715] NET: Registered protocol family 1
[    0.532183] RPC: Registered named UNIX socket transport module.
[    0.532222] RPC: Registered udp transport module.
[    0.532248] RPC: Registered tcp transport module.
[    0.532273] RPC: Registered tcp NFSv4.1 backchannel transport module.
[    0.533065] NetWinder Floating Point Emulator V0.97 (double precision)
[    0.533673] omap-gpmc omap-gpmc: GPMC revision 6.0
[    0.533722] Registering NAND on CS0
[    0.560199] msgmni has been set to 240
[    0.563235] io scheduler noop registered
[    0.563273] io scheduler deadline registered
[    0.563731] io scheduler cfq registered (default)
[    0.566449] omap_uart.0: ttyO0 at MMIO 0x44e09000 (irq = 72) is a OMAP UART0
[    1.774866] console [ttyO0] enabled
[    1.780552] omap_uart.1: ttyO1 at MMIO 0x48022000 (irq = 73) is a OMAP UART1
[    1.789855] omap_uart.2: ttyO2 at MMIO 0x48024000 (irq = 74) is a OMAP UART2
[    1.798983] omap_uart.3: ttyO3 at MMIO 0x481a6000 (irq = 44) is a OMAP UART3
[    1.808083] omap_uart.4: ttyO4 at MMIO 0x481a8000 (irq = 45) is a OMAP UART4
[    1.817155] omap_ua iB 3,3V 8-bit)
[    1.910518] Creating 8 MTD partitions on "omap2-nand.0":
[    1.916329] 0x000000000000-0x000000060000 : "SPL"
[    1.927074] 0x000000060000-0x000000100000 : "U-Boot"
[    1.938294] 0x000000100000-0x0000001a0000 : "U-Boot.recovery"
[    1.950271] 0x0000001a0000-0x000000200000 : "U-Boot Env"
[    1.961340] 0x000000200000-0x000000700000 : "Kernel"
[    1.981575] 0x000000700000-0x000002f00000 : "RO File System"
[    2.073460] 0x000002f00000-0x000005700000 : "File System Update"
[    2.166430] 0x000005700000-0x000008000000 : "RW File System"
[    2.264520] usbcore: registered new interface driver cdc_ether
[    2.271719] usbcore: registered new interface driver cdc_subset
[    2.278349] Initializing USB Mass Storage driver...
[    2.284225] usbcore: registered new interface driver usb-storage
[    2.290799] USB Mass Storage support registered.
[    2.296299] usbcore: registered new interface driver usbtest
[    2.303362] mousedev: PS/2 mouse device common for all mice
[    2.310711] input: extint as /devices/platform/extint/input/input0
[    2.320723] omap_rtc am33xx-rtc: rtc core: registered am33xx-rtc as rtc0
[    2.328208] am33xx-rtc: already running
[    2.332968] i2c /dev entries driver
[    2.343901] bq27x00-battery 1-0055: support ver. 1.2.0 enabled
[    2.366220] OMAP Watchdog Timer Rev 0x01: initial timeout 30 sec
[    2.378362] cpuidle: using governor ladder
[    2.384648] cpuidle: using governor menu
[    2.398053] Registered led device: tlc5947-0
[    2.403335] Registered led device: tlc5947-1
[    2.409019] Registered led device: tlc5947-2
[    2.414245] Registered led device: tlc5947-3
[    2.419508] Registered led device: tlc5947-4
[    2.424702] Registered led device: tlc5947-5
[    2.430084] Registered led device: tlc5947-6
[    2.438564] Registered led device: tlc5947-7
[    2.443916] Registered led device: tlc5947-8
[    2.449345] Registered led device: tlc5947-9
[    2.457820] Registered led device: tlc5947-10
[    2.463215] Registered led device: tlc5947-11
[    2.468599] Registered led device: tlc5947-12
[    2.473896] Registered led device: tlc5947-13
[    2.479369] Registered led device: tlc5947-14
[    2.484664] Registered led device: tlc5947-15
[    2.489953] Registered led device: tlc5947-16
[    2.495252] Registered led device: tlc5947-17
[    2.500746] Registered led device: tlc5947-18
[    2.506135] Registered led device: tlc5947-19
[    2.511318] Registered led device: tlc5947-20
[    2.516874] Registered led device: tlc5947-21
[    2.522201] Registered led device: tlc5947-22
[    2.527512] Registered led device: tlc5947-23
[    2.541529] usbcore: registered new interface driver usbhid
[    2.547885] usbhid: USB HID core driver
[    2.554515] tiadc tiadc: attached adc driver
[    2.560036] oprofile: hardware counters not available
[    2.565389] oprofile: using timer interrupt.
[    2.570180] nf_conntrack version 0.5.0 (1926 buckets, 7704 max)
[    2.578189] ip_tables: (C) 2000-2006 Netfilter Core Team
[    2.584125] TCP cubic registered
[    2.587671] NET: Registered protocol family 17
[    2.592555] Registering the dns_resolver key type
[    2.597854] VFP support v0.3: implementor 41 architecture 3 part 30 variant c rev 3
[    2.606137] ThumbEE CPU extension supported.
[    2.610783] mux: Failed to setup hwmod io irq -22
[    2.619966] Power Management for AM33XX family
[    2.625197] Trying to load am335x-pm-firmware.bin (60 secs timeout)
[    2.632144] Copied the M3 firmware to UMEM
[    2.636827] Cortex M3 Firmware Version = 0x186
[    2.641596] clock: disabling unused clocks to save power
[    2.655375] input: gpio-keys as /devices/platform/gpio-keys/input/input1
[    2.665375] omap_rtc am33xx-rtc: setting system clock to 2000-01-01 00:19:27 UTC (946685967)
[    2.676931] Waiting for root device /dev/mmcblk0p2...
[    2.813274] mmc0: new high speed SDHC card at address 0003
[    2.821050] mmcblk0: mmc0:0003 SB08G 7.21 GiB 
[    2.829844]  mmcblk0: p1 p2
[    2.916114] EXT3-fs (mmcblk0p2): mounted filesystem with ordered data mode
[    2.923578] VFS: Mounted root (ext3 filesystem) readonly on device 179:2.
[    2.930836] kjournald starting.  Commit interval 5 seconds
[    2.949117] devtmpfs: mounted
[    2.953388] Freeing init memory: 276K
[    3.081568] mmc1: card claims to support voltages below the defined range. These will be ignored.
[    3.107150] mmc1: queuing unknown CIS tuple 0x91 (3 bytes)
[    3.116179] mmc1: new SDIO card at address 0001

init started: BusyBox v1.20.2 (2015-02-19 16:27:57 CST)
Setting hotplug handler: [ OK ]
Creating device files: Auto-mount of [/media/mmcblk0p1] successful
[ OK ]
Starting watchdog daemon: Setting timezone and system clock: [OK]
Starting system logging.
Mounting read/write partition...
[    7.975224] UBI: attaching mtd7 to ubi0
[    7.979565] UBI: physical eraseblock size:   131072 bytes (128 KiB)
[    7.986232] UBI: logical eraseblock size:    126976 bytes
[    7.991941] UBI: smallest flash I/O unit:    2048
[    7.996945] UBI: VID header offset:          2048 (aligned 2048)
[    8.003294] UBI: data offset:                4096
[    9.548158] UBI: max. sequence number:       398
[    9.626178] UBI: attached mtd7 to ubi0
[    9.630207] UBI: MTD device name:            "RW File System"
[    9.656089] UBI: MTD device size:            41 MiB
[    9.661306] UBI: number of good PEBs:        328
[    9.676089] UBI: number of bad PEBs:         0
[    9.680834] UBI: number of corrupted PEBs:   0
[    9.685532] UBI: max. allowed volumes:       128
[    9.691875] UBI: wear-leveling threshold:    4096
[    9.696993] UBI: number of internal volumes: 1
[    9.701697] UBI: number of user volumes:     1
[    9.706441] UBI: available PEBs:             23
[    9.711233] UBI: total number of reserved PEBs: 305
[    9.716424] UBI: number of PEBs reserved for bad PEB handling: 3
[    9.722774] UBI: max/mean erase counter: 2/1
[    9.727314] UBI: image sequence number:  776135292
[    9.732448] UBI: background thread "ubi_bgt0d" started, PID 865
UBI device number 0, total 328 LEBs (41648128 bytes, 39.7 MiB), available 23 LEBs (2920448 bytes, 2.8 MiB), LEB size 126976 bytes (124.0 KiB)
[   10.068416] UBIFS: recovery needed
[   10.592289] UBIFS: recovery completed
[   10.596320] UBIFS: mounted UBI device 0, volume 0, name "data"
[   10.602502] UBIFS: file system size:   36696064 bytes (35836 KiB, 34 MiB, 289 LEBs)
[   10.610631] UBIFS: journal size:       1777664 bytes (1736 KiB, 1 MiB, 14 LEBs)
[   10.618391] UBIFS: media format:       w4/r0 (latest is w4/r0)
[   10.624557] UBIFS: default compressor: lzo
[   10.628920] UBIFS: reserved for root:  1733245 bytes (1692 KiB)
Starting dbus: [ OK ]
Changed CPU Frequency from 300000 to: 300000
Starting Bluetooth...
Starting USB...
[   11.289716] gadgetfs: USB Gadget filesystem, version 24 Aug 2004
[   11.370581] nop musb-hdrc.0: failed to start (null): -120
Found a Texas Instruments' chip!
Firmware file : /lib/firmware/TIInit_7.6.15.bts
Loaded BTS script version 1
texas: changing baud rate to 3000000, flow control to 1
Internet Systems Consortium DHCP Client V3.1-ESV-R3
Copyright 2004-2011 Internet Systems Consortium.
All rights reserved.
For info, please visit https://www.isc.org/software/dhcp/

can't create /var/state/dhcp/dhclient.leases: No such file or directory
ifconfig: SIOCSIFADDR: No such device
Bind socket to interface: No such device

If you did not get this software from ftp.isc.org, please
get the latest from ftp.isc.org and install that before
requesting help.

If you did get this software from ftp.isc.org and have not
yet read the README, please read it before requesting help.
If you intend to request help from the dhcp-server@isc.org
mailing list, please read the section on the README about
submitting bug reports and requests for help.

Please do not under any circumstances send requests for
help directly to the authors of this software - please
send them to the appropriate mailing list as described in
the README file.

exiting.
Starting CC10 application.


BusyBox v1.20.2 (2015-02-19 16:27:57 CST) built-in shell (ash)
Enter 'help' for a list of built-in commands.

# AcquisitionMain:  main> Logging started, build timestamp: Fri May  1 10:37:12 2015
AcquisitionMain:  main> Build CC10/MCU/CAM-relCAMConnect10v1.0-Linux-ARM-32-1077808
AcquisitionMain:  main> Build Version 1.0.0R3
EventLog:  CreateLoggers> Poco Logger opened at level 8
AcquisitionMain:  main> == Starting CAM Application ==
AcquisitionMain:  main> Application Build CC10/MCU/CAM-relCAMConnect10v1.0-Linux-ARM-32-1077808 Fri May  1 10:37:12 2015
BluetoothManager:  BluetoothManager> Delaying Bluetooth Manager start, Bluetooth not ready.
[   14.065786] Bluetooth: Core ver 2.16
[   14.077672] NET: Registered protocol family 31
[   14.082429] Bluetooth: HCI device and connection manager initialized
[   14.116170] Bluetooth: HCI socket layer initialized
[   14.121408] Bluetooth: L2CAP socket layer initialized
[   14.153659] Bluetooth: HCI UART driver ver 2.2
[   14.166161] Bluetooth: HCILL protocol initialized
Device setup complete
BluetoothManager:  SetDeviceClass> Set device class to 0x900
BluetoothManager:  SetName> Set Bluetooth name to: CAM-84:DD:20:A4:CF:39
CAMControl:  InitVersionInfo> Software Version 1.0.0R3
CAMControl:  InitVersionInfo> File System Version CAM-mainCAMConnect10-TimesysFactory-40-996027
CAMControl:  RunApplication> Configuring Connection Manager...
DSPManager:  Start> Starting DSP download thread
CAMControl:  RunApplication> Creating LED Manager...
ERROR: /jenkins/workspace/CC10/MCU/CAM-relCAMConnect10v1.0-Linux-ARM/CAM/MCU/Watchdog/Source/Watchdog.cpp line 79
Watchdog:  WatchDogMainTask> ERROR: Could not open watchdog device /dev/watchdog: 
CAMControl:  RunApplication> Configuring Power Manager...
CAMControl:  RunApplication> Configuring Mode Manager...
CAMControl:  RunApplication> Creating Battery Manager...
BatteryManager:  BatteryManager> Initial Battery state is 0, remaining percentage is 56, AC power detect is 1
CAMControl:  RunApplication> Creating Button Manager...
CAMControl:  RunApplication> Configuring Bluetooth Manager...
CAMControl:  RunApplication> Creating ECGHookupAdvisor Manager...
CAMControl:  RunApplication> Configuring Acquisition Manager...
CAMControl:  RunApplication> Configuring Configuration Manager...
CAMControl:  RunApplication> Configuring File Transfer Manager...
CAMControl:  RunApplication> Creating Firmware Update Manager...
CAMControl:  RunApplication> Creating Diagnostic Manager...
CAMControl:  RunApplication> Initializing Configuration Manager...
CAMControl:  RunApplication> Initializing LED Manager...
CAMControl:  RunApplication> Initializing Battery Manager...
Batte[   16.711993] gadgetfs: bound to musb-hdrc driver
ryManager:  Init[   16.717730] musb-hdrc musb-hdrc.0: <== devctl 98
ialize> Starting Battery Status thread
CAMControl:  RunApplication> Initializing Button Manager...
ButtonManager:  Init> Initializing
ButtonManager:  Init> Starting CheckButton and PowerButtonTimer threads
ButtonManager:  Init> Initialized
CAMControl:  RunApplication> Initializing Power Manager...
CAMControl:  RunApplication> Initializing Bluetooth Manager...
CAMControl:  RunApplication> Initializing ECG Hookup Advisor...
CAMControl:  RunApplication> Initializing Acquisition Manager...
AcquisitionManager:  AcquisitionPacketConsumer> Registering host packet consumer
PowerManager:  PowerManagerTask> PowerMana[   16.779061] musb-hdrc musb-hdrc.0: usbintr (1) epintr(0)
[   16.784764] musb-hdrc musb-hdrc.0: ** IRQ peripheral usb0001 tx0000 rx0000
[   16.792039] musb-hdrc musb-hdrc.0: Rain::<== Power=e0, DevCtl=99, int_usb=0x1
[   16.799590] musb-hdrc musb-hdrc.0: SUSPEND (b_idle) devctl 99 power e0
gerTask started
AcquisitionManager:  AcquisitionPacketConsumer> Registering DSP packet consumer
AcquisitionManager:  AcquisitonDSPTask> AcquisitionManagerDSPTask started
AcquisitionManager:  AcquisitonHostTask> AcquisitionManagerTask started
CAMControl:  RunApplication> Initializing File Download Ma[   16.832722] Bluetooth: RFCOMM TTY layer initialized
nager...
CAMCon[   16.839374] Bluetooth: RFCOMM socket layer initialized
trol:  RunApplic[   16.845776] Bluetooth: RFCOMM ver 1.11
ation> Initializing Firmware Update Manager...
CAMControl:  RunApplication> Initializing Diagnostic Manager...
DiagnosticManager:  DiagnosticManagerTask> DiagnosticManagerTask started
Watchdog:  SetTimeout> Setting watchdog timeout to 1 seconds from -1
CAMControl:  update> Idle mode update, Disconnecting...
CAMControl:  DisconnectAll> Disconnecting, but host was NULL
ERROR: /jenkins/workspace/CC10/MCU/CAM-relCAMConnect10v1.0-Linux-ARM/CAM/MCU/CAMControl/Source/CAMControl.cpp line 889
CAMControl:  RunApplication> ERROR: Could not remove the restart reason file /data/restartReason.txt
BluetoothManager:  SeverMainline> Bluetooth server started
UsbManager:  Init> Initializing...
UsbManager:  Init> Initialized
UsbConnection:  Open> USB Connection opened
CAMControl:  RunApplication> Waiting for shutdown...
BluetoothManager:  SeverMainline> Server is bound to address: 84:DD:20:A4:CF:39 port/channel: 16
[   16.939262] musb-hdrc musb-hdrc.0: usbintr (4) epintr(0)
[   16.944951] musb-hdrc musb-hdrc.0: ** IRQ peripheral usb0004 tx0000 rx0000
[   16.952225] musb-hdrc musb-hdrc.0: Rain::<== Power=f0, DevCtl=99, int_usb=0x4
[   16.959777] musb-hdrc musb-hdrc.0: BUS RESET as b_peripheral
[   17.056981] musb-hdrc musb-hdrc.0: usbintr (0) epintr(1)
[   17.062654] musb-hdrc musb-hdrc.0: ** IRQ peripheral usb0000 tx0001 rx0000
[   17.069942] musb-hdrc musb-hdrc.0: csr 0001, count 8, myaddr 0, ep0stage setup
[   17.077589] musb-hdrc musb-hdrc.0: SETUP req00.05 v001d i0000 l0
[   17.083949] musb-hdrc musb-hdrc.0: handled 1, csr 0001, ep0stage in/status
[   17.291577] musb-hdrc musb-hdrc.0: usbintr (0) epintr(1)
[   17.297229] musb-hdrc musb-hdrc.0: ** IRQ peripheral usb0000 tx0001 rx0000
[   17.304508] musb-hdrc musb-hdrc.0: csr 0011, count 8, myaddr 0, ep0stage in/status
[   17.312511] musb_g_ep0_irq 722: SetupEnd came in a wrong ep0stage idle
[   17.319415] musb_g_ep0_irq 726: Rain::SetupEnd came in a wrong ep0stage: 0000 csr: 0001.
[   17.327976] musb-hdrc musb-hdrc.0: SETUP req00.05 v001d i0000 l0
[   17.334332] musb-hdrc musb-hdrc.0: handled 1, csr 0001, ep0stage in/status
[   17.521153] musb-hdrc musb-hdrc.0: usbintr (1) epintr(0)
[   17.526799] musb-hdrc musb-hdrc.0: ** IRQ peripheral usb0001 tx0000 rx0000
[   17.534071] musb-hdrc musb-hdrc.0: Rain::<== Power=f0, DevCtl=99, int_usb=0x1
[   17.541620] musb-hdrc musb-hdrc.0: SUSPEND (b_peripheral) devctl 99 power f0
[   17.549079] gadgetfs: suspended from state 2
[   17.758409] musb-hdrc musb-hdrc.0: usbintr (4) epintr(0)
[   17.764072] musb-hdrc musb-hdrc.0: ** IRQ peripheral usb0004 tx0000 rx0000
[   17.771346] musb-hdrc musb-hdrc.0: Rain::<== Power=f0, DevCtl=99, int_usb=0x4
[   17.778895] musb-hdrc musb-hdrc.0: BUS RESET as b_peripheral
[   17.889167] musb-hdrc musb-hdrc.0: usbintr (0) epintr(1)
[   17.894805] musb-hdrc musb-hdrc.0: ** IRQ peripheral usb0000 tx0001 rx0000
[   17.902091] musb-hdrc musb-hdrc.0: csr 0001, count 8, myaddr 0, ep0stage setup
[   17.909736] musb-hdrc musb-hdrc.0: SETUP req00.05 v001f i0000 l0
[   17.916095] musb-hdrc musb-hdrc.0: handled 1, csr 0001, ep0stage in/status
[   18.126799] musb-hdrc musb-hdrc.0: usbintr (0) epintr(1)
[   18.132461] musb-hdrc musb-hdrc.0: ** IRQ peripheral usb0000 tx0001 rx0000
[   18.139744] musb-hdrc musb-hdrc.0: csr 0011, count 8, myaddr 0, ep0stage in/status
[   18.147745] musb_g_ep0_irq 722: SetupEnd came in a wrong ep0stage idle
[   18.154647] musb_g_ep0_irq 726: Rain::SetupEnd came in a wrong ep0stage: 0000 csr: 0001.
[   18.163207] musb-hdrc musb-hdrc.0: SETUP req00.05 v001f i0000 l0
[   18.169566] musb-hdrc musb-hdrc.0: handled 1, csr 0001, ep0stage in/status
[   18.384751] musb-hdrc musb-hdrc.0: usbintr (1) epintr(0)
[   18.390397] musb-hdrc musb-hdrc.0: ** IRQ peripheral usb0001 tx0000 rx0000
[   18.397670] musb-hdrc musb-hdrc.0: Rain::<== Power=f0, DevCtl=99, int_usb=0x1
[   18.405221] musb-hdrc musb-hdrc.0: SUSPEND (b_peripheral) devctl 99 power f0
[   18.412681] gadgetfs: suspended from state 2
[   18.578359] musb-hdrc musb-hdrc.0: usbintr (4) epintr(0)
[   18.584015] musb-hdrc musb-hdrc.0: ** IRQ peripheral usb0004 tx0000 rx0000
[   18.591288] musb-hdrc musb-hdrc.0: Rain::<== Power=f0, DevCtl=99, int_usb=0x4
[   18.598835] musb-hdrc musb-hdrc.0: BUS RESET as b_peripheral
DSPManager:  DspDownloadThread> DSP Firmware Downloaded
CAMControl:  update> DspManager: Activating DSP connection...
ConnectionManager:  ActivateDSPConnection> ConnectionManager: Opening DSP Connection...
[   18.697663] musb-hdrc musb-hdrc.0: usbintr (0) epintr(1)
[   18.703337] musb-hdrc musb-hdrc.0: ** IRQ peripheral usb0000 tx0001 rx0000
[   18.710622] musb-hdrc musb-hdrc.0: csr 0001, count 8, myaddr 0, ep0stage setup
[   18.718269] musb-hdrc musb-hdrc.0: SETUP req00.05 v0021 i0000 l0
[   18.724629] musb-hdrc musb-hdrc.0: handled 1, csr 0001, ep0stage in/status
[   18.918620] musb-hdrc musb-hdrc.0: usbintr (0) epintr(1)
[   18.924258] musb-hdrc musb-hdrc.0: ** IRQ peripheral usb0000 tx0001 rx0000
[   18.931535] musb-hdrc musb-hdrc.0: csr 0011, count 8, myaddr 0, ep0stage in/status
[   18.939541] musb_g_ep0_irq 722: SetupEnd came in a wrong ep0stage idle
[   18.946444] musb_g_ep0_irq 726: Rain::SetupEnd came in a wrong ep0stage: 0000 csr: 0001.
[   18.955002] musb-hdrc musb-hdrc.0: SETUP req00.05 v0021 i0000 l0
[   18.961359] musb-hdrc musb-hdrc.0: handled 1, csr 0001, ep0stage in/status
[   19.150474] musb-hdrc musb-hdrc.0: usbintr (1) epintr(0)
[   19.156123] musb-hdrc musb-hdrc.0: ** IRQ peripheral usb0001 tx0000 rx0000
[   19.163396] musb-hdrc musb-hdrc.0: Rain::<== Power=f0, DevCtl=99, int_usb=0x1
[   19.170947] musb-hdrc musb-hdrc.0: SUSPEND (b_peripheral) devctl 99 power f0
[   19.178407] gadgetfs: suspended from state 2
[   19.398212] musb-hdrc musb-hdrc.0: usbintr (4) epintr(0)
[   19.403855] musb-hdrc musb-hdrc.0: ** IRQ peripheral usb0004 tx0000 rx0000
[   19.411128] musb-hdrc musb-hdrc.0: Rain::<== Power=f0, DevCtl=99, int_usb=0x4
[   19.418674] musb-hdrc musb-hdrc.0: BUS RESET as b_peripheral
[   19.527971] musb-hdrc musb-hdrc.0: usbintr (0) epintr(1)
[   19.533606] musb-hdrc musb-hdrc.0: ** IRQ peripheral usb0000 tx0001 rx0000
[   19.540885] musb-hdrc musb-hdrc.0: csr 0011, count 8, myaddr 0, ep0stage setup
[   19.548520] musb_g_ep0_irq 722: SetupEnd came in a wrong ep0stage idle
[   19.555424] musb_g_ep0_irq 726: Rain::SetupEnd came in a wrong ep0stage: 0000 csr: 0001.
[   19.563984] musb-hdrc musb-hdrc.0: SETUP req00.05 v0023 i0000 l0
[   19.570341] musb-hdrc musb-hdrc.0: handled 1, csr 0001, ep0stage in/status
[   19.777464] musb-hdrc musb-hdrc.0: usbintr (0) epintr(1)
[   19.783105] musb-hdrc musb-hdrc.0: ** IRQ peripheral usb0000 tx0001 rx0000
[   19.790385] musb-hdrc musb-hdrc.0: csr 0011, count 8, myaddr 0, ep0stage in/status
[   19.798385] musb_g_ep0_irq 722: SetupEnd came in a wrong ep0stage idle
[   19.805286] musb_g_ep0_irq 726: Rain::SetupEnd came in a wrong ep0stage: 0000 csr: 0001.
[   19.813844] musb-hdrc musb-hdrc.0: SETUP req00.05 v0023 i0000 l0
[   19.820199] musb-hdrc musb-hdrc.0: handled 1, csr 0001, ep0stage in/status
DSPManager:  Receive> DSP System Start Broadcast message (Normal Power up) received
DSPManager:  DspLoadWaitThread> DSP Running Successfully
CAMControl:  update> DspManager: Unknown DSP Manager Status!
[   20.000961] musb-hdrc musb-hdrc.0: usbintr (1) epintr(0)
[   20.006627] musb-hdrc musb-hdrc.0: ** IRQ peripheral usb0001 tx0000 rx0000
[   20.013900] musb-hdrc musb-hdrc.0: Rain::<== Power=f0, DevCtl=99, int_usb=0x1
[   20.021449] musb-hdrc musb-hdrc.0: SUSPEND (b_peripheral) devctl 99 power f0
[   20.028911] gadgetfs: suspended from state 2
DSPManager:  Receive> DSP Version: 1.0.0 Proto Letter: 0x0 Proto Number: 0x1421 Build Month: 5 Build Day: 1 Build Hour: 1
[   20.229047] musb-hdrc musb-hdrc.0: usbintr (4) epintr(0)
[   20.234721] musb-hdrc musb-hdrc.0: ** IRQ peripheral usb0004 tx0000 rx0000
[   20.241994] musb-hdrc musb-hdrc.0: Rain::<== Power=f0, DevCtl=99, int_usb=0x4
[   20.249545] musb-hdrc musb-hdrc.0: BUS RESET as b_peripheral
[   20.347566] musb-hdrc musb-hdrc.0: usbintr (0) epintr(1)
[   20.353201] musb-hdrc musb-hdrc.0: ** IRQ peripheral usb0000 tx0001 rx0000
[   20.360487] musb-hdrc musb-hdrc.0: csr 0001, count 8, myaddr 0, ep0stage setup
[   20.368134] musb-hdrc musb-hdrc.0: SETUP req00.05 v0025 i0000 l0
[   20.374492] musb-hdrc musb-hdrc.0: handled 1, csr 0001, ep0stage in/status
[   20.571684] musb-hdrc musb-hdrc.0: usbintr (0) epintr(1)
[   20.577326] musb-hdrc musb-hdrc.0: ** IRQ peripheral usb0000 tx0001 rx0000
[   20.584605] musb-hdrc musb-hdrc.0: csr 0011, count 8, myaddr 0, ep0stage in/status
[   20.592609] musb_g_ep0_irq 722: SetupEnd came in a wrong ep0stage idle
[   20.599514] musb_g_ep0_irq 726: Rain::SetupEnd came in a wrong ep0stage: 0000 csr: 0001.
[   20.608072] musb-hdrc musb-hdrc.0: SETUP req00.05 v0025 i0000 l0
[   20.614429] musb-hdrc musb-hdrc.0: handled 1, csr 0001, ep0stage in/status
[   20.621783] musb-hdrc musb-hdrc.0: usbintr (0) epintr(1)
[   20.627414] musb-hdrc musb-hdrc.0: ** IRQ peripheral usb0000 tx0001 rx0000
[   20.634692] musb-hdrc musb-hdrc.0: csr 0000, count 0, myaddr 0, ep0stage in/status
[   20.653471] musb-hdrc musb-hdrc.0: usbintr (0) epintr(1)
[   20.659106] musb-hdrc musb-hdrc.0: ** IRQ peripheral usb0000 tx0001 rx0000
[   20.666383] musb-hdrc musb-hdrc.0: csr 0001, count 8, myaddr 37, ep0stage idle
[   20.674027] musb-hdrc musb-hdrc.0: SETUP req80.06 v0100 i0000 l8
[   20.680384] musb-hdrc musb-hdrc.0: handled 0, csr 0001, ep0stage in
[   20.687015] gadgetfs: connected
[   20.690370] musb-hdrc musb-hdrc.0: queue to ep0 (OUT/RX), length=8
[   20.696924] musb-hdrc musb-hdrc.0: TX ep0 fifo c883c420 count 8 buf c74afb00
[   20.704494] musb-hdrc musb-hdrc.0: usbintr (0) epintr(1)
[   20.710123] musb-hdrc musb-hdrc.0: ** IRQ peripheral usb0000 tx0001 rx0000
[   20.717400] musb-hdrc musb-hdrc.0: csr 0001, count 8, myaddr 37, ep0stage out/status
[   20.725595] musb-hdrc musb-hdrc.0: SETUP req80.06 v0100 i0000 l18
[   20.732044] musb-hdrc musb-hdrc.0: handled 0, csr 0001, ep0stage in
[   20.738679] musb-hdrc musb-hdrc.0: queue to ep0 (OUT/RX), length=18
[   20.745315] musb-hdrc musb-hdrc.0: TX ep0 fifo c883c420 count 18 buf c74afb00
[   20.752917] musb-hdrc musb-hdrc.0: usbintr (0) epintr(1)
[   20.758546] musb-hdrc musb-hdrc.0: ** IRQ peripheral usb0000 tx0001 rx0000
[   20.765824] musb-hdrc musb-hdrc.0: csr 0001, count 8, myaddr 37, ep0stage out/status
[   20.774019] musb-hdrc musb-hdrc.0: SETUP req80.06 v0200 i0000 l9
[   20.780376] musb-hdrc musb-hdrc.0: handled 0, csr 0001, ep0stage in
[   20.787012] musb-hdrc musb-hdrc.0: queue to ep0 (OUT/RX), length=9
[   20.793556] musb-hdrc musb-hdrc.0: TX ep0 fifo c883c420 count 9 buf c74afae0
[   20.801056] musb-hdrc musb-hdrc.0: usbintr (0) epintr(1)
[   20.806683] musb-hdrc musb-hdrc.0: ** IRQ peripheral usb0000 tx0001 rx0000
[   20.813960] musb-hdrc musb-hdrc.0: csr 0001, count 8, myaddr 37, ep0stage out/status
[   20.822155] musb-hdrc musb-hdrc.0: SETUP req80.06 v0200 i0000 l32
[   20.828605] musb-hdrc musb-hdrc.0: handled 0, csr 0001, ep0stage in
[   20.835237] musb-hdrc musb-hdrc.0: queue to ep0 (OUT/RX), length=32
[   20.841872] musb-hdrc musb-hdrc.0: TX ep0 fifo c883c420 count 32 buf c74afae0
[   20.849459] musb-hdrc musb-hdrc.0: usbintr (0) epintr(1)
[   20.855086] musb-hdrc musb-hdrc.0: ** IRQ peripheral usb0000 tx0001 rx0000
[   20.862363] musb-hdrc musb-hdrc.0: csr 0001, count 8, myaddr 37, ep0stage out/status
[   20.870559] musb-hdrc musb-hdrc.0: SETUP req80.06 v0300 i0000 l255
[   20.877100] musb-hdrc musb-hdrc.0: handled 0, csr 0001, ep0stage in
[   20.883772] musb-hdrc musb-hdrc.0: usbintr (0) epintr(1)
[   20.889399] musb-hdrc musb-hdrc.0: ** IRQ peripheral usb0000 tx0001 rx0000
[   20.896676] musb-hdrc musb-hdrc.0: csr 0000, count 0, myaddr 37, ep0stage in
[   20.904131] musb-hdrc musb-hdrc.0: odd; csr0 0000
[   20.909660] musb-hdrc musb-hdrc.0: queue to ep0 (OUT/RX), length=1
[   20.916217] musb-hdrc musb-hdrc.0: TX ep0 fifo c883c420 count 1 buf c73a0484
[   20.923752] musb-hdrc musb-hdrc.0: usbintr (0) epintr(1)
[   20.929382] musb-hdrc musb-hdrc.0: ** IRQ peripheral usb0000 tx0001 rx0000
[   20.936662] musb-hdrc musb-hdrc.0: csr 0001, count 8, myaddr 37, ep0stage out/status
[   20.944861] musb-hdrc musb-hdrc.0: SETUP req80.06 v0300 i0000 l2
[   20.951219] musb-hdrc musb-hdrc.0: handled 0, csr 0001, ep0stage in
[   20.957910] musb-hdrc musb-hdrc.0: usbintr (0) epintr(1)
[   20.963539] musb-hdrc musb-hdrc.0: ** IRQ peripheral usb0000 tx0001 rx0000
[   20.970815] musb-hdrc musb-hdrc.0: csr 0000, count 0, myaddr 37, ep0stage in
[   20.978269] musb-hdrc musb-hdrc.0: odd; csr0 0000
[   20.987126] musb-hdrc musb-hdrc.0: queue to ep0 (OUT/RX), length=1
[   20.993700] musb-hdrc musb-hdrc.0: TX ep0 fifo c883c420 count 1 buf c73a0484
[   21.001246] musb-hdrc musb-hdrc.0: usbintr (0) epintr(1)
[   21.006879] musb-hdrc musb-hdrc.0: ** IRQ peripheral usb0000 tx0001 rx0000
[   21.014160] musb-hdrc musb-hdrc.0: csr 0001, count 8, myaddr 37, ep0stage out/status
[   21.022358] musb-hdrc musb-hdrc.0: SETUP req00.09 v0003 i0000 l0
[   21.028714] musb-hdrc musb-hdrc.0: handled 0, csr 0001, ep0stage wait
[   21.035531] gadgetfs: configuration #3
[   21.039584] musb-hdrc musb-hdrc.0: usbintr (0) epintr(1)
[   21.045213] musb-hdrc musb-hdrc.0: ** IRQ peripheral usb0000 tx0001 rx0000
[   21.052491] musb-hdrc musb-hdrc.0: csr 0001, count 0, myaddr 37, ep0stage wait
UsbManager:  StartIO> USB Low Le[   21.066936] mu Connected

# [  226.758494] musb-hdrc musb-hdrc.0: CPPI 4.1 IRQ: Tx 0, Rx 2
ERROR: /jenkins/workspace/CC10/MCU/CAM-relCAMConnect10v1.0-Linux-ARM/CAM/MCU/ConnectionManager/Source/Connection.cpp line 303
ConnectionManager:  Receive> ERROR: Sequence error: device tag=0x4001, sequenceNumber=0x0069, expected=0x0001
ConnectionManager:  ListenForConnectionActivty> Connection: Secondary command, parent tag=0x4040
ConnectionManager:  SendSecondaryResponse> Secondary response sent, parent tag=0x4040 outcome=0x0000
ConnectionManager:  ActivateHostConnection> ConnectionMana[  226.807977] musb-hdrc musb-hdrc.0: CPPI 4.1 IRQ: Tx 1, Rx 0
ger: Opening Host Connection...
PowerManager:  ChangePowerState> Power state already assigned when setting state
Watchdog:  SetTimeout> Setting watchdog timeout to 1 seconds from -1

  • I have zip all the messages and pictures, and upload them again, please check them;AM335x USB SETUPEND Issue.zip

  • Please post the Linux version you are using. You can also check if one of the patches listed here applies to your case: http://processors.wiki.ti.com/index.php/Sitara_Linux_MUSB_Issues

  • Linux version 3.2.0-ts-armv7l (rain@raining) (gcc version 4.7.3 (Timesys 20131104) ) #4 PREEMPT Tue Jun 16 13:35:31 CST 2015

    I have looked at the website you posted, I don't find useful information.
    you can download the attachments which have very detailed messages;

    Thanks
  • It seems the information in log USB_bad_state_more_msg.txt does not match with that in USB Capture.bmp. But do you have USB autosuspend enabled? If so, please try the following patch to see if it fixes the issue. If not, please contact Timesys for support on their Kernel package. We only support TI AMSDK kernels.


    The patch below is a backport of patch #8.4 in processors.wiki.ti.com/.../Sitara_Linux_MUSB_Issues.

    diff --git a/drivers/usb/core/hub.c b/drivers/usb/core/hub.c
    index 5b4da30..c47ef39 100644
    --- a/drivers/usb/core/hub.c
    +++ b/drivers/usb/core/hub.c
    @@ -2578,7 +2578,7 @@ int usb_port_resume(struct usb_device *udev, pm_message_t msg)
                    /* drive resume for at least 20 msec */
                    dev_dbg(&udev->dev, "usb %sresume\n",
                                    (PMSG_IS_AUTO(msg) ? "auto-" : ""));
    -               msleep(25);
    +               msleep(40);
     
                    /* Virtual root hubs can trigger on GET_PORT_STATUS to
                     * stop resume signaling.  Then finish the resume
    diff --git a/drivers/usb/musb/musb_core.c b/drivers/usb/musb/musb_core.c
    index 075aa5f..82641b1 100644
    --- a/drivers/usb/musb/musb_core.c
    +++ b/drivers/usb/musb/musb_core.c
    @@ -493,7 +493,7 @@ static irqreturn_t musb_stage0_irq(struct musb *musb, u8 int_usb,
                                                    (USB_PORT_STAT_C_SUSPEND << 16)
                                                    | MUSB_PORT_STAT_RESUME;
                                    musb->rh_timer = jiffies
    -                                               + msecs_to_jiffies(20);
    +                                               + msecs_to_jiffies(40);
     
                                    musb->xceiv->state = OTG_STATE_A_HOST;
                                    musb->is_active = 1;
    
  • Hello liubin,

    thanks for your support;

    I have tried the solution you posted, the issue still exists, no any improvement;

    we are using the BSP from timesys, it's just a choice; and we are also using the ti-sdk-am335x-evm-06.00.00.00, and we do the same testing on it, it has the same issue.

    The fail rate is about 10%, there may be 10 times in 100 plugin/out; in fact, not in the USB plugin/out, the issue maybe occur during Linux is booting; 

    We connect our device(using AM335X) to a PAD(such as nexus PAD or leveno PAD) by USB;

    From the messages, it seems that the high level driver prompt it connected, but in fact, we can not communicate by USB, in PAD, it prompts the USB don’t connect; plug in/out the USB cable can't recover this issue, only reboot our device can fix it, I think we can’t accept it.

    Have you any suggestions about this issue? need more or other information about it?

    U-Boot SPL 2013.01.01 (Jun 04 2015 - 11:52:35)
    Done quick memory test
    OMAP SD/MMC: 0
    reading u-boot.img
    reading u-boot.img
    
    
    U-Boot 2013.01.01 (Jun 04 2015 - 11:52:35)
    
    The battery is at 41 percent.
    Verified the power/battery status.
    I2C:   ready
    DRAM:  128 MiB
    NAND:  128 MiB
    MMC:   OMAP SD/MMC: 0, OMAP SD/MMC: 1
    musb-hdrc: ConfigData=0xde (UTMI-8, dyn FIFOs, bulk combine, bulk split, HB-ISO Rx, HB-ISO Tx, SoftConn)
    musb-hdrc: MHDRC RTL version 2.0
    musb-hdrc: setup fifo_mode 4
    musb-hdrc: 28/31 max ep, 16384/16384 memory
    USB Peripheral mode controller at 47401000 using PIO, IRQ 0
    Hit any key to stop autoboot:  0
    mmc0 is current device
    SD/MMC found on device 0
    reading CAMProgram.txt
    ** Unable to read file CAMProgram.txt **
    reading uImage
    3116608 bytes read in 305 ms (9.7 MiB/s)
    Booting from mmc ...
    ## Booting kernel from Legacy Image at 80007fc0 ...
       Image Name:   Linux-3.2.0-ts-armv7l
       Image Type:   ARM Linux Kernel Image (uncompressed)
       Data Size:    3116544 Bytes = 3 MiB
       Load Address: 80008000
       Entry Point:  80008000
       Verifying Checksum ... OK
       XIP Kernel Image ... OK
    OK
    
    Starting kernel ...
    
    [    0.000000] Initializing cgroup subsys cpu
    [    0.000000] Linux version 3.2.0-ts-armv7l (rain@raining) (gcc version 4.7.3 (Timesys 20131104) ) #11 PREEMPT Thu Jun 18 16:22:25 CST 2015
    [    0.000000] CPU: ARMv7 Processor [413fc082] revision 2 (ARMv7), cr=10c53c7d
    [    0.000000] CPU: PIPT / VIPT nonaliasing data cache, VIPT aliasing instruction cache
    [    0.000000] Machine: ge_cc10
    [    0.000000] Memory policy: ECC disabled, Data cache writeback
    [    0.000000] On node 0 totalpages: 32768
    [    0.000000] free_area_init_node: node 0, pgdat c06233e4, node_mem_map c066e000
    [    0.000000]   Normal zone: 256 pages used for memmap
    [    0.000000]   Normal zone: 0 pages reserved
    [    0.000000]   Normal zone: 32512 pages, LIFO batch:7
    [    0.000000] AM335X ES2.0 (neon )
    [    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: 32512
    [    0.000000] Kernel command line: console=ttyO0,115200n8 root=/dev/mmcblk0p2 ro rootfstype=ext3 rootwait ip=none
    [    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] Memory: 128MB = 128MB total
    [    0.000000] Memory: 123324k/123324k available, 7748k 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]     modules : 0xbf000000 - 0xc0000000   (  16 MB)
    [    0.000000]       .text : 0xc0008000 - 0xc058e000   (5656 kB)
    [    0.000000]       .init : 0xc058e000 - 0xc05d3000   ( 276 kB)
    [    0.000000]       .data : 0xc05d4000 - 0xc0624b68   ( 323 kB)
    [    0.000000]        .bss : 0xc0624b8c - 0xc066d730   ( 291 kB)
    [    0.000000] NR_IRQS:396
    [    0.000000] IRQ: Found an INTC at 0xfa200000 (revision 5.0) with 128 interrupts
    [    0.000000] Total of 128 interrupts on 1 active controller
    [    0.000000] OMAP clockevent source: GPTIMER2 at 24000000 Hz
    [    0.000000] omap_dm_timer_switch_src: Switching to HW default clocksource(sys_clkin_ck) for timer1, this may impact timekeeping in low power state
    [    0.000000] OMAP clocksource: GPTIMER1 at 24000000 Hz
    [    0.000000] sched_clock: 32 bits at 24MHz, resolution 41ns, wraps every 178956ms
    [    0.000000] Console: colour dummy device 80x30
    [    0.000617] Calibrating delay loop... 298.59 BogoMIPS (lpj=1492992)
    [    0.064115] pid_max: default: 32768 minimum: 301
    [    0.064620] Security Framework initialized
    [    0.064860] Mount-cache hash table entries: 512
    [    0.066194] CPU: Testing write buffer coherency: ok
    [    0.066376] ftrace: allocating 16921 entries in 50 pages
    [    0.176442] devtmpfs: initialized
    [    0.204889] omap_hwmod: gfx: failed to hardreset
    [    0.223640] omap_hwmod: pruss: failed to hardreset
    [    0.227155] print_constraints: dummy:
    [    0.228553] NET: Registered protocol family 16
    [    0.236256] OMAP GPIO hardware version 0.1
    [    0.245592] omap_mux_init: Add partition: #1: core, flags: 0
    [    0.251355]  omap_i2c.1: alias fck already exists
    [    0.255660]  omap_hsmmc.0: alias fck already exists
    [    0.256487]  omap_hsmmc.1: alias fck already exists
    [    0.265734]  omap2_mcspi.1: alias fck already exists
    [    0.266483]  omap2_mcspi.2: alias fck already exists
    [    0.267360]  edma.0: alias fck already exists
    [    0.267414]  edma.0: alias fck already exists
    [    0.267463]  edma.0: alias fck already exists
    [    0.333310] bio: create slab <bio-0> at 0
    [    0.342003] SCSI subsystem initialized
    [    0.347662] usbcore: registered new interface driver usbfs
    [    0.348776] usbcore: registered new interface driver hub
    [    0.349491] usbcore: registered new device driver usb
    [    0.350415] registerd cppi-dma Intr @ IRQ 17
    [    0.350454] Cppi41 Init Done Qmgr-base(c887a000) dma-base(c8878000)
    [    0.350481] Cppi41 Init Done
    [    0.350558] musb-ti81xx musb-ti81xx: musb0, board_mode=0x2, plat_mode=0x2
    [    0.351459] musb-ti81xx musb-ti81xx: musb1, board_mode=0x2, plat_mode=0x0
    [    0.356190] omap_i2c omap_i2c.1: bus 1 rev2.4.0 at 100 kHz
    [    0.358460] tps65910 1-002d: JTAGREVNUM 0x0
    [    0.363584] print_constraints: VRTC:
    [    0.366093] print_constraints: VIO: at 1800 mV
    [    0.369187] print_constraints: VDD1: 600 <--> 1500 mV at 950 mV normal
    [    0.372226] print_constraints: VDD2: at 950 mV
    [    0.373859] print_constraints: VDD3: 5000 mV
    [    0.376095] print_constraints: VDIG1: at 1800 mV
    [    0.378180] print_constraints: VDIG2: at 1800 mV
    [    0.380229] print_constraints: VPLL: at 1800 mV
    [    0.382324] print_constraints: VDAC: at 1800 mV
    [    0.384550] print_constraints: VAUX1: at 1800 mV
    [    0.386630] print_constraints: VAUX2: at 3300 mV
    [    0.388698] print_constraints: VAUX33: at 3300 mV
    [    0.390768] print_constraints: VMMC: at 3300 mV
    [    0.392384] tps65910 1-002d: No interrupt support, no core IRQ
    [    0.401264] cfg80211: Calling CRDA to update world regulatory domain
    [    0.405495] Switching to clocksource gp timer
    [    0.509539] musb-hdrc: version 6.0, ?dma?, otg (peripheral+host)
    [    0.510107] musbusb-hdrc.1: MUSB HDRC host driver
    [    0.517075] musb-hdrc musb-hdrc.1: new USB bus registered, assigned bus number 1
    [    0.517482] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002
    [    0.517523] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
    [    0.517558] usb usb1: Product: MUSB HDRC host driver
    [    0.517587] usb usb1: Manufacturer: Linux 3.2.0-ts-armv7l musb-hcd
    [    0.517618] usb usb1: SerialNumber: musb-hdrc.1
    [    0.520464] hub 1-0:1.0: USB hub found
    [    0.520556] hub 1-0:1.0: 1 port detected
    [    0.522478] musb-hdrc musb-hdrc.1: USB OTG mode controller at c883e800 using DMA, IRQ 19
    [    0.524441] NET: Registered protocol family 2
    [    0.525189] IP route cache hash table entries: 1024 (order: 0, 4096 bytes)
    [    0.526848] TCP established hash table entries: 4096 (order: 3, 32768 bytes)
    [    0.527069] TCP bind hash table entries: 4096 (order: 2, 16384 bytes)
    [    0.527205] TCP: Hash tables configured (established 4096 bind 4096)
    [    0.527241] TCP reno registered
    [    0.527276] UDP hash table entries: 256 (order: 0, 4096 bytes)
    [    0.527334] UDP-Lite hash table entries: 256 (order: 0, 4096 bytes)
    [    0.528119] NET: Registered protocol family 1
    [    0.529615] RPC: Registered named UNIX socket transport module.
    [    0.529653] RPC: Registered udp transport module.
    [    0.529679] RPC: Registered tcp transport module.
    [    0.529704] RPC: Registered tcp NFSv4.1 backchannel transport module.
    [    0.530514] NetWinder Floating Point Emulator V0.97 (double precision)
    [    0.531132] omap-gpmc omap-gpmc: GPMC revision 6.0
    [    0.531182] Registering NAND on CS0
    [    0.557462] msgmni has been set to 240
    [    0.560521] io scheduler noop registered
    [    0.560562] io scheduler deadline [    1.463047] loop: module loaded
    [    1.476288] omap2-nand driver initializing
    [    1.482352] NAND device: Manufacturer ID: 0xc2, Chip ID: 0xf1 (Macronix NAND 128MiB 3,3V 8-bit)
    [    1.492548] Creating 8 MTD partitions on "omap2-nand.0":
    [    1.498370] 0x000000000000-0x000000060000 : "SPL"
    [    1.509002] 0x000000060000-0x000000100000 : "U-Boot"
    [    1.520253] 0x000000100000-0x0000001a0000 : "U-Boot.recovery"
    [    1.532213] 0x0000001a0000-0x000000200000 : "U-Boot Env"
    [    1.543232] 0x000000200000-0x000000700000 : "Kernel"
    [    1.563499] 0x000000700000-0x000002f00000 : "RO File System"
    [    1.655376] 0x000002f00000-0x000005700000 : "File System Update"
    [    1.747705] 0x000005700000-0x000008000000 : "RW File System"
    [    1.846231] usbcore: registered new interface driver cdc_ether
    [    1.853071] usbcore: registered new interface driver cdc_subset
    [    1.859626] Initializing USB Mass Storage driver...
    [    1.865493] usbcore: registered new interface driver usb-storage
    [    1.872047] USB Mass Storage support registered.
    [    1.877531] usbcore: registered new interface driver usbtest
    [    1.884604] mousedev: PS/2 mouse device common for all mice
    [    1.891950] input: extint as /devices/platform/extint/input/input0
    [    1.901979] omap_rtc am33xx-rtc: rtc core: registered am33xx-rtc as rtc0
    [    1.909464] am33xx-rtc: already running
    [    1.914211] i2c /dev entries driver
    [    1.925096] bq27x00-battery 1-0055: support ver. 1.2.0 enabled
    [    1.947332] OMAP Watchdog Timer Rev 0x01: initial timeout 30 sec
    [    1.960795] cpuidle: using governor ladder
    [    1.967248] cpuidle: using governor menu
    [    1.980328] Registered led device: tlc5947-0
    [    1.986182] Registered led device: tlc5947-1
    [    1.991341] Registered led device: tlc5947-2
    [    1.996671] Registered led device: tlc5947-3
    [    2.001900] Registered led device: tlc5947-4
    [    2.007277] Registered led device: tlc5947-5
    [    2.015745] Registered led device: tlc5947-6
    [    2.021142] Registered led device: tlc5947-7
    [    2.027612] Registered led device: tlc5947-8
    [    2.034925] Registered led device: tlc5947-9
    [    2.040239] Registered led device: tlc5947-10
    [    2.045672] Registered led device: tlc5947-11
    [    2.050865] Registered led device: tlc5947-12
    [    2.056319] Registered led device: tlc5947-13
    [    2.061650] Registered led device: tlc5947-14
    [    2.066935] Registered led device: tlc5947-15
    [    2.072202] Registered led device: tlc5947-16
    [    2.077594] Registered led device: tlc5947-17
    [    2.082920] Registered led device: tlc5947-18
    [    2.088200] Registered led device: tlc5947-19
    [    2.093478] Registered led device: tlc5947-20
    [    2.098931] Registered led device: tlc5947-21
    [    2.104255] Registered led device: tlc5947-22
    [    2.109538] Registered led device: tlc5947-23
    [    2.123601] usbcore: registered new interface driver usbhid
    [    2.130314] usbhid: USB HID core driver
    [    2.137456] tiadc tiadc: attached adc driver
    [    2.142606] oprofile: hardware counters not available
    [    2.148123] oprofile: using timer interrupt.
    [    2.152710] nf_conntrack version 0.5.0 (1926 buckets, 7704 max)
    [    2.160677] ip_tables: (C) 2000-2006 Netfilter Core Team
    [    2.166708] TCP cubic registered
    [    2.170149] NET: Registered protocol family 17
    [    2.175033] Registering the dns_resolver key type
    [    2.180350] VFP support v0.3: implementor 41 architecture 3 part 30 variant c rev 3
    [    2.188654] ThumbEE CPU extension supported.
    [    2.193301] mux: Failed to setup hwmod io irq -22
    [    2.202465] Power Management for AM33XX family
    [    2.207937] Trying to load am335x-pm-firmware.bin (60 secs timeout)
    [    2.214768] Copied the M3 firmware to UMEM
    [    2.219378] Cortex M3 Firmware Version = 0x186
    [    2.224091] clock: disabling unused clocks to save power
    [    2.238263] input: gpio-keys as /devices/platform/gpio-keys/input/input1
    [    2.248174] omap_rtc am33xx-rtc: setting system clock to 2000-01-01 00:06:11 UTC (946685171)
    [    2.259325] Waiting for root device /dev/mmcblk0p2...
    [    2.343036] mmc0: new high speed SDHC card at address 0003
    [    2.350768] mmcblk0: mmc0:0003 SB08G 7.21 GiB
    [    2.359535]  mmcblk0: p1 p2
    [    2.395869] EXT3-fs (mmcblk0p2): mounted filesystem with ordered data mode
    [    2.403323] VFS: Mounted root (ext3 filesystem) readonly on device 179:2.
    [    2.410580] kjournald starting.  Commit interval 5 seconds
    [    2.428815] devtmpfs: mounted
    [    2.433094] Freeing init memory: 276K
    [    2.632053] mmc1: card claims to support voltages below the defined range. These will be ignored.
    init started: BusyBox v1.20.2 (2015-02-19 16:27:57 CST)
    [    2.659788] mmc1: queuing unknown CIS tuple 0x91 (3 bytes)
    [    2.677927] mmc1: new SDIO card at address 0001
    Setting hotplug handler: [ OK ]
    Creating device files: Auto-mount of [/media/mmcblk0p1] successful
    [ OK ]
    Starting watchdog daemon: Setting timezone and system clock: [OK]
    Starting system logging.
    Mounting read/write partition...
    [    7.469565] UBI: attaching mtd7 to ubi0
    [    7.473692] UBI: physical eraseblock size:   131072 bytes (128 KiB)
    [    7.480445] UBI: logical eraseblock size:    126976 bytes
    [    7.486191] UBI: smallest flash I/O unit:    2048
    [    7.491169] UBI: VID header offset:          2048 (aligned 2048)
    [    7.497549] UBI: data offset:                4096
    [    9.042560] UBI: max. sequence number:       439
    [    9.125909] UBI: attached mtd7 to ubi0
    [    9.129938] UBI: MTD device name:            "RW File System"
    [    9.136102] UBI: MTD device size:            41 MiB
    [    9.141264] UBI: number of good PEBs:        328
    [    9.170827] UBI: number of bad PEBs:         0
    [    9.175902] UBI: number of corrupted PEBs:   0
    [    9.180609] UBI: max. allowed volumes:       128
    [    9.185489] UBI: wear-leveling threshold:    4096
    [    9.190540] UBI: number of internal volumes: 1
    [    9.195238] UBI: number of user volumes:     1
    [    9.199976] UBI: available PEBs:             23
    [    9.204767] UBI: total number of reserved PEBs: 305
    [    9.209959] UBI: number of PEBs reserved for bad PEB handling: 3
    [    9.216338] UBI: max/mean erase counter: 2/1
    [    9.220853] UBI: image sequence number:  1108233127
    [    9.226100] UBI: background thread "ubi_bgt0d" started, PID 865
    UBI device number 0, total 328 LEBs (41648128 bytes, 39.7 MiB), available 23 LEBs (2920448 bytes, 2.8 MiB), LEB size 126976 bytes (124.0 KiB)
    [    9.562494] UBIFS: recovery needed
    [   10.046549] UBIFS: recovery completed
    [   10.050489] UBIFS: mounted UBI device 0, volume 0, name "data"
    [   10.056710] UBIFS: file system size:   36696064 bytes (35836 KiB, 34 MiB, 289 LEBs)
    [   10.064809] UBIFS: journal size:       1777664 bytes (1736 KiB, 1 MiB, 14 LEBs)
    [   10.072573] UBIFS: media format:       w4/r0 (latest is w4/r0)
    [   10.078769] UBIFS: default compressor: lzo
    [   10.083104] UBIFS: reserved for root:  1733245 bytes (1692 KiB)
    Starting dbus: [ OK ]
    Changed CPU Frequency from 300000 to: 300000
    Starting Bluetooth...
    Starting USB...
    [   10.755449] gadgetfs: USB Gadget filesystem, version 24 Aug 2004
    [   10.821448] nop musb-hdrc.0: failed to start (null): -120
    Found a Texas Instruments' chip!
    Firmware file : /lib/firmware/TIInit_7.6.15.bts
    Loaded BTS script version 1
    texas: changing baud rate to 3000000, flow control to 1
    Internet Systems Consortium DHCP Client V3.1-ESV-R3
    Copyright 2004-2011 Internet Systems Consortium.
    All rights reserved.
    For info, please visit https://www.isc.org/software/dhcp/
    
    can't create /var/state/dhcp/dhclient.leases: No such file or directory
    ifconfig: SIOCSIFADDR: No such device
    Bind socket to interface: No such device
    
    If you did not get this software from ftp.isc.org, please
    get the latest from ftp.isc.org and install that before
    requesting help.
    
    If you did get this software from ftp.isc.org and have not
    yet read the README, please read it before requesting help.
    If you intend to request help from the dhcp-server@isc.org
    mailing list, please read the section on the README about
    submitting bug reports and requests for help.
    
    Please do not under any circumstances send requests for
    help directly to the authors of this software - please
    send them to the appropriate mailing list as described in
    the README file.
    
    exiting.
    Starting CC10 application.
    
    
    BusyBox v1.20.2 (2015-02-19 16:27:57 CST) built-in shell (ash)
    Enter 'help' for a list of built-in commands.
    
    # AcquisitionMain:  main> Logging started, build timestamp: Fri May  1 10:37:12 2015
    AcquisitionMain:  main> Build CC10/MCU/CAM-relCAMConnect10v1.0-Linux-ARM-32-1077808
    AcquisitionMain:  main> Build Version 1.0.0R3
    EventLog:  CreateLoggers> Poco Logger opened at level 8
    AcquisitionMain:  main> == Starting CAM Application ==
    AcquisitionMain:  main> Application Build CC10/MCU/CAM-relCAMConnect10v1.0-Linux-ARM-32-1077808 Fri May  1 10:37:12 2015
    BluetoothManager:  BluetoothManager> Delaying Bluetooth Manager start, Bluetooth not ready.
    [   13.495277] Bluetooth: Core ver 2.16
    [   13.507372] NET: Registered protocol family 31
    [   13.512133] Bluetooth: HCI device and connection manager initialized
    [   13.545926] Bluetooth: HCI socket layer initialized
    [   13.551165] Bluetooth: L2CAP socket layer initialized
    [   13.582066] Bluetooth: HCI UART driver ver 2.2
    [   13.595908] Bluetooth: HCILL protocol initialized
    Device setup complete
    BluetoothManager:  SetDeviceClass> Set device class to 0x900
    BluetoothManager:  SetName> Set Bluetooth name to: CAM-84:DD:20:A4:CF:39
    CAMControl:  InitVersionInfo> Software Version 1.0.0R3
    CAMControl:  InitVersionInfo> File System Version CAM-mainCAMConnect10-TimesysFactory-40-996027
    CAMControl:  RunApplication> Configuring Connection Manager...
    DSPManager:  Start> Starting DSP download thread
    CAMControl:  RunApplication> Creating LED Manager...
    ERROR: /jenkins/workspace/CC10/MCU/CAM-relCAMConnect10v1.0-Linux-ARM/CAM/MCU/Watchdog/Source/Watchdog.cpp line 79
    Watchdog:  WatchDogMainTask> ERROR: Could not open watchdog device /dev/watchdog:
    CAMControl:  RunApplication> Configuring Power Manager...
    CAMControl:  RunApplication> Configuring Mode Manager...
    CAMControl:  RunApplication> Creating Battery Manager...
    BatteryManager:  BatteryManager> Initial Battery state is 2, remaining percentage is 41, AC power detect is 0
    CAMControl:  RunApplication> Creating Button Manager...
    CAMControl:  RunApplication> Configuring Bluetooth Manager...
    CAMControl:  RunApplication> Creating ECGHookupAdvisor Manager...
    CAMControl:  RunApplication> Configuring Acquisition Manager...
    CAMControl:  RunApplication> Configuring Configuration Manager...
    CAMControl:  RunApplication> Configuring File Transfer Manager...
    CAMControl:  RunApplication> Creating Firmware Update Manager...
    CAMControl:  RunApplication> Creating Diagnostic Manager...
    CAMControl:  RunApplication> Initializing Configuration Manager...
    CAMControl:  RunApplication> Initializing LED Manager...
    CAMControl:  RunApplication> Initializing Battery Manager...
    BatteryManager:  Initialize> Starting Battery Status thread
    CAMControl:  RunApplication> Initializin[   16.141559] gadgetfs: bound to musb-hdrc driver
    g Button Manager...
    ButtonManager:  Init> Initializing
    ButtonManager:  Init> Starting CheckButton and PowerButtonTimer threads
    ButtonManager:  Init> Initialized
    CAMControl:  RunApplication> Initializing Power Manager...
    CAMControl:  RunApplication> Initializing Bluetooth Manager...
    CAMControl:  RunApplication> Initializing ECG Hookup Advisor...
    CAMControl:  RunApplication> Initializing Acquisition Manager...
    AcquisitionManager:  AcquisitionPacketConsumer> Registering host packet consumer
    PowerManager:  PowerManagerTask> PowerManagerTask started
    AcquisitionManager:  AcquisitionPacketConsumer> Registering DSP packet consumer
    AcquisitionManager:  AcquisitonDSPTask> AcquisitionManagerDSPTask started
    AcquisitionManager:  AcquisitonHostTask> AcquisitionManagerTask sta[   16.216187] Bluetooth: RFCOMM TTY layer initialized
    rted
    CAMControl[   16.221942] Bluetooth: RFCOMM socket layer initialized
    :  RunApplicatio[   16.229445] Bluetooth: RFCOMM ver 1.11
    n> Initializing File Download Manager...
    CAMControl:  RunApplication> Initializing Firmware Update Manager...
    CAMControl:  RunApplication> Initializing Diagnostic Manager...
    DiagnosticManager:  DiagnosticManagerTask> DiagnosticManagerTask started
    Watchdog:  SetTimeout> Setting watchdog timeout to 1 seconds from -1
    CAMControl:  update> Idle mode update, Disconnecting...
    CAMControl:  DisconnectAll> Disconnecting, but host was NULL
    ERROR: /jenkins/workspace/CC10/MCU/CAM-relCAMConnect10v1.0-Linux-ARM/CAM/MCU/CAMControl/Source/CAMControl.cpp line 889
    CAMControl:  RunApplication> ERROR: Could not remove the restart reason file /data/restartReason.txt
    BluetoothManager:  SeverMainline> Bluetooth server started
    UsbManager:  Init> Initializing...
    UsbManager:  Init> Initialized
    UsbConnection:  Open> USB Connection opened
    CAMControl:  RunApplication> Waiting for shutdown...
    BluetoothManager:  SeverMainline> Server is bound to address: 84:DD:20:A4:CF:39 port/channel: 16
    [   16.538500] gadgetfs: connected
    [   16.542227] musb_g_ep0_irq 722: SetupEnd came in a wrong ep0stage idle
    [   16.549147] musb_g_ep0_irq 726: Rain201506180420::SetupEnd came in a wrong ep0stage: 0000 csr: 0011.
    [   16.563203] gadgetfs: suspended from state 3
    [   16.793467] gadgetfs: disconnected
    UsbManager:  StopIO> USB Low Level Connection: Disconnected
    [   16.954273] gadgetfs: connected
    [   16.962517] musb_g_ep0_irq 722: SetupEnd came in a wrong ep0stage setup
    [   16.969523] musb_g_ep0_irq 726: Rain201506180420::SetupEnd came in a wrong ep0stage: 0001 csr: 0011.
    [   16.979309] musb_g_ep0_irq 722: SetupEnd came in a wrong ep0stage out/status
    [   16.986769] musb_g_ep0_irq 726: Rain201506180420::SetupEnd came in a wrong ep0stage: 0005 csr: 0011.
    [   16.996424] musb_g_ep0_irq 819: SETUP packet len 0 != 8 ?
    [   17.002205] gadgetfs: suspended from state 3
    [   17.006762] musb_g_ep0_irq 722: SetupEnd came in a wrong ep0stage out/status
    [   17.014216] musb_g_ep0_irq 726: Rain201506180420::SetupEnd came in a wrong ep0stage: 0005 csr: 0001.
    [   17.215484] gadgetfs: disconnected
    UsbManager:  StopIO> USB Low Level Connection: Disconnected
    [   17.334976] musb_g_ep0_irq 722: SetupEnd came in a wrong ep0stage setup
    [   17.342002] musb_g_ep0_irq 726: Rain201506180420::SetupEnd came in a wrong ep0stage: 0001 csr: 0011.
    [   17.351654] musb_g_ep0_irq 819: SETUP packet len 10 != 8 ?
    [   17.357538] musb_g_ep0_irq 722: SetupEnd came in a wrong ep0stage setup
    [   17.364534] musb_g_ep0_irq 726: Rain201506180420::SetupEnd came in a wrong ep0stage: 0001 csr: 0001.
    [   17.543064] musb_g_ep0_irq 722: SetupEnd came in a wrong ep0stage in/status
    [   17.550443] musb_g_ep0_irq 726: Rain201506180420::SetupEnd came in a wrong ep0stage: 0004 csr: 0001.
    [   17.755038] gadgetfs: suspended from state 2
    DSPManager:  DspDownloadThread> DSP Firmware Downloaded
    CAMControl:  update> DspManager: Activating DSP connection...
    ConnectionManager:  ActivateDSPConnection> ConnectionManager: Opening DSP Connection...
    [   18.132162] gadgetfs: connected
    [   18.151564] musb_g_ep0_irq 722: SetupEnd came in a wrong ep0stage idle
    [   18.158494] musb_g_ep0_irq 726: Rain201506180420::SetupEnd came in a wrong ep0stage: 0000 csr: 0011.
    [   18.168233] musb_g_ep0_irq 726: Rain201506180420::SetupEnd came in a wrong ep0stage: 0005 csr: 0000.
    [   18.177958] musb_g_ep0_irq 722: SetupEnd came in a wrong ep0stage idle
    [   18.184863] musb_g_ep0_irq 726: Rain201506180420::SetupEnd came in a wrong ep0stage: 0000 csr: 0001.
    [   18.194524] gadgetfs: configuration #3
    UsbManager:  StartIO> USB Low Level Connection: Connected
    DSPManager:  Receive> DSP System Start Broadcast message (Normal Power up) received
    DSPManager:  DspLoadWaitThread> DSP Running Successfully
    CAMControl:  update> DspManager: Unknown DSP Manager Status!
    DSPManager:  Receive> DSP Version: 1.0.0 Proto Letter: 0x0 Proto Number: 0x1421 Build Month: 5 Build Day: 1 Build Hour: 1
    
    

  • Your attached log has the following message, which is very likely caused by improper USB clock. Please first check the clock.

    [   16.996424] musb_g_ep0_irq 819: SETUP packet len 0 != 8 ?

  • This message "musb_g_ep0_irq 819: SETUP packet len 0 != 8 ?" don't occur every time, if you look at all the messages I have posted, you will find it;

    if  it's caused by improper USB clock, who will lead to improper USB clock?

    in fact, if no SETUPEND issue, the USB transmission will be no problem, i have done the long time transmission by USB over 48 hours, no any problems during transmission.

    The question is why the USB plug in/out many times will lead to  SETUPEND issue or improper USB clock you said? USB driver issue or AM3352 hardware issue?

  • yu zheng said:
    if  it's caused by improper USB clock, who will lead to improper USB clock?

    Do you use 24MHz crystal on the board? If not, have you changed uboot source code accordingly to support the crystal?

    yu zheng said:
    The question is why the USB plug in/out many times will lead to  SETUPEND issue or improper USB clock you said?

    You said the issue only happens after plug in/out multiple times, but the log you attached learlier (msg_usb_bad_state_plug_in_out.txt) shows the following error message right after the board is booted. Does it mean USB still works at the beginning even with the following errors?

    [   16.542227] musb_g_ep0_irq 722: SetupEnd came in a wrong ep0stage idle

    Can you please capture the following data for me?

    - Capture the kernel log similar to msg_usb_bad_state_plug_in_out.txt again, which shows the error message;

    - Also use a protocol analyzer to capture the bus trace at the same time.

    Please send me the console log and the trace .usb file.

  • I said "The fail rate is about 10%, there may be 10 times in 100 plugin/out; in fact, not in the USB plugin/out, the issue maybe occur during Linux is booting; ";

    I have a question, is there a way to let USB always work at full speed? 

    I have tried some ways, but it doesn't work.

    Thanks

  • such as

    I have tried this way, it doesn't work;

    musb_writeb(regs, MUSB_POWER, MUSB_POWER_ISOUPDATE
    | MUSB_POWER_SOFTCONN
    - | MUSB_POWER_HSENAB
    + /*| MUSB_POWER_HSENAB */
    /* ENSUSPEND wedges tusb */
    /* | MUSB_POWER_ENSUSPEND */
    );

    we want to let our USB device work as full speed always, and check whether this issue still exits?

  • yu zheng said:
    in fact, not in the USB plugin/out, the issue maybe occur during Linux is booting;

    Ok, if you still need my help, please provide the kernel log after booting up and its corresponding USB bus trace, which I asked in my previous response.

  • yu zheng said:
    I have tried this way, it doesn't work;

    musb_writeb(regs, MUSB_POWER, MUSB_POWER_ISOUPDATE
    | MUSB_POWER_SOFTCONN
    - | MUSB_POWER_HSENAB
    + /*| MUSB_POWER_HSENAB */

    What do you mean it does not work? Unable to work in full-speed mode? If so, please provide the kernel log and bus trace.

    The code change you did is correct to set the controller to work in full-speed mode.

  • Thanks, I have tried this way, let USB work at full speed, it seems that it works;

    musb_writeb(regs, MUSB_POWER, MUSB_POWER_ISOUPDATE
    - | MUSB_POWER_HSENAB
    + /*| MUSB_POWER_HSENAB */
    /* ENSUSPEND wedges tusb */
    /* | MUSB_POWER_ENSUSPEND */
    );

    At the full speed, i test it over 200 times, the issue don't occurs again, I don't know the reason.

  • yu zheng said:
    I have tried this way, let USB work at full speed, it seems that it works;

    This is also an evidence showing that this could be a clock issue. Have you checked the crystal configuration as I suggested before?

    To further investigate the issue in high-speed, can you please provide the following information I asked before?

    Bin Liu said:
    Can you please capture the following data for me?

    - Capture the kernel log similar to msg_usb_bad_state_plug_in_out.txt again, which shows the error message;

    - Also use a protocol analyzer to capture the bus trace at the same time.

    Please send me the console log and the trace .usb file.

  • Hello Bin Liu
    I confirm with our hardware engineer, we do use 24MHz crystal on the board;
    This message "musb_g_ep0_irq 819: SETUP packet len 0 != 8 ?" seems to occur only after I enable the USB debug messages, such as dev_dbg;
    if I don't enable it, I don't find the message printed out, I have done many tests;

    Now, I haven't a USB protocol analyzer, if I got it, I will capture the bus trace and send to you;
    and do you want me to enable the USB dev_dbg message?
  • yu zheng said:
    This message "musb_g_ep0_irq 819: SETUP packet len 0 != 8 ?" seems to occur only after I enable the USB debug messages, such as dev_dbg;
    if I don't enable it, I don't find the message printed out, I have done many tests;

    This message is printed in ERR() function call, which should not rely on dev_dbg. Do you see this message in dmesg log even if USB debug is not enabled?

    yu zheng said:
    and do you want me to enable the USB dev_dbg message?

    Yes, please use the same way as you captured 'msg_usb_bad_state_plug_in_out.txt' log before, and capture the corresponding USB bus trace as while. I'd like to compare the USB traffic between the kernel and the BUS.