Kernel crash on boot with USB -> SATA adapter

[ashgill3]

Greetings.

I have an interesting problem with a USB to SATA adapter that I am connecting to USB 3 port on my Pogo v4.

When I connect the adapter directly to the USB 3 port, I get a kernel crash.

When I connect the adapter to the same USB 3 port through a USB 2 hub, the kernel does not crash, and I can mount the attached SATA hard drive.

I'm booting off a USB flash drive connected to the top USB connector.

Here are the boot logs for when the USB to SATA adapter is connected directly:

$this->bbcode_second_pass_code('', '

U-Boot 1.1.4 (Oct 1 2011 - 12:06:06) Cloud Engines 1.1.2 (3.4.27) PHYADDR=0

U-Boot code: 00600000 -> 0067FFF0 BSS: -> 006918B4

Soc: 88F6192 A1 (DDR2)
CPU running @ 800Mhz L2 running @ 400Mhz
SysClock = 200Mhz , TClock = 166Mhz

DRAM CAS Latency = 3 tRP = 3 tRAS = 8 tRCD=3
DRAM CS[0] base 0x00000000 size 128MB
DRAM Total size 128MB 16bit width
Addresses 8M - 0M are saved for the U-Boot usage.
Mem malloc Initialization (8M - 7M): Done
NAND:128 MB
Flash: 0 kB

CPU : Marvell Feroceon (Rev 1)
CLOUD ENGINES BOARD: PPV4A3

Streaming disabled
Write allocate disabled


USB 0: host mode
PEX 0: PCI Express Root Complex Interface
PEX interface detected Link X1
Net: egiga0 [PRIME]
Hit any key to stop autoboot: 1  0
Unknown command 'usb' - try 'help'

NAND read: device 0 offset 0x100000, size 0x73d0c
474380 bytes read: OK
## Starting application at 0x00800000 ...


U-Boot 1.1.4 (Jan 13 2012 - 22:33:21) Arch Linux ARM (PPV4 r1) PHYADDR=0

U-Boot code: 00600000 -> 0067FFF0 BSS: -> 006CFD60

Soc: 88F6192 A1 (DDR2)
CPU running @ 800Mhz L2 running @ 400Mhz
SysClock = 200Mhz , TClock = 166Mhz

DRAM CAS Latency = 3 tRP = 3 tRAS = 8 tRCD=3
DRAM CS[0] base 0x00000000 size 128MB
DRAM Total size 128MB 16bit width
Addresses 8M - 0M are saved for the U-Boot usage.
Mem malloc Initialization (8M - 7M): Done
NAND:128 MB
Flash: 0 kB

CPU : Marvell Feroceon (Rev 1)
CLOUD ENGINES BOARD: PPV4A3

Streaming disabled
Write allocate disabled


USB 0: host mode
PEX 0: PCI Express Root Complex Interface
PEX interface detected Link X1
Net: egiga0 [PRIME]
Hit any key to stop autoboot: 1  0
(Re)start USB...
USB: scanning bus for devices... 2 USB Device(s) found
Waiting for storage device(s) to settle before scanning...
1 Storage Device(s) found

Reset IDE:
Marvell Serial ATA Adapter
Integrated Sata device found

No Fat FS detected

IDE device 0 not available
....
.
......
............
.........................
...................................................
......................................................................................................
...........................................................................

2869736 bytes read
## Booting image at 00800000 ...
Image Name: Linux-3.1.10-16-ARCH
Created: 2013-02-12 1:17:35 UTC
Image Type: ARM Linux Kernel Image (uncompressed)
Data Size: 2869672 Bytes = 2.7 MB
Load Address: 00008000
Entry Point: 00008000
Verifying Checksum ... OK
OK

Starting kernel ...

Uncompressing Linux... done, booting the kernel.
[ 10.022194] xhci_hcd 0000:00:01.0: Failed to enable MSI-X
[ 10.027577] xhci_hcd 0000:00:01.0: failed to allocate MSI entry
[ 10.850236] usb 1-1.1: device descriptor read/64, error -32
[ 11.039807] rtc-mv rtc-mv: internal RTC not ticking
[ 11.050756] drivers/rtc/hctosys.c: unable to open rtc device (rtc0)
[ 11.420237] usb 1-1.3: device descriptor read/64, error -32
[ 11.610111] usb 1-1.3: device descriptor read/64, error -32
[ 11.711532] Kernel panic - not syncing: VFS: Unable to mount root fs on unknown-block(8,1)
[ 11.719854] [<c000d290>] (unwind_backtrace+0x0/0xe0) from [<c03cc914>] (panic+0x58/0x198)
[ 11.728016] [<c03cc914>] (panic+0x58/0x198) from [<c052fc2c>] (mount_block_root+0x230/0x280)
[ 11.736472] [<c052fc2c>] (mount_block_root+0x230/0x280) from [<c052ff3c>] (prepare_namespace+0x160/0x1c0)
[ 11.746038] [<c052ff3c>] (prepare_namespace+0x160/0x1c0) from [<c052f8c8>] (kernel_init+0x11c/0x14c)
[ 11.755169] [<c052f8c8>] (kernel_init+0x11c/0x14c) from [<c000945c>] (kernel_thread_exit+0x0/0x8)
')

Here are the boot logs for when USB to SATA adapter is connected via the USB 2 hub:

$this->bbcode_second_pass_code('', '
U-Boot 1.1.4 (Oct 1 2011 - 12:06:06) Cloud Engines 1.1.2 (3.4.27) PHYADDR=0

U-Boot code: 00600000 -> 0067FFF0 BSS: -> 006918B4

Soc: 88F6192 A1 (DDR2)
CPU running @ 800Mhz L2 running @ 400Mhz
SysClock = 200Mhz , TClock = 166Mhz

DRAM CAS Latency = 3 tRP = 3 tRAS = 8 tRCD=3
DRAM CS[0] base 0x00000000 size 128MB
DRAM Total size 128MB 16bit width
Addresses 8M - 0M are saved for the U-Boot usage.
Mem malloc Initialization (8M - 7M): Done
NAND:128 MB
Flash: 0 kB

CPU : Marvell Feroceon (Rev 1)
CLOUD ENGINES BOARD: PPV4A3

Streaming disabled
Write allocate disabled


USB 0: host mode
PEX 0: PCI Express Root Complex Interface
PEX interface detected Link X1
Net: egiga0 [PRIME]
Hit any key to stop autoboot: 1  0
Unknown command 'usb' - try 'help'

NAND read: device 0 offset 0x100000, size 0x73d0c
474380 bytes read: OK
## Starting application at 0x00800000 ...

U-Boot 1.1.4 (Jan 13 2012 - 22:33:21) Arch Linux ARM (PPV4 r1) PHYADDR=0

U-Boot code: 00600000 -> 0067FFF0 BSS: -> 006CFD60

Soc: 88F6192 A1 (DDR2)
CPU running @ 800Mhz L2 running @ 400Mhz
SysClock = 200Mhz , TClock = 166Mhz

DRAM CAS Latency = 3 tRP = 3 tRAS = 8 tRCD=3
DRAM CS[0] base 0x00000000 size 128MB
DRAM Total size 128MB 16bit width
Addresses 8M - 0M are saved for the U-Boot usage.
Mem malloc Initialization (8M - 7M): Done
NAND:128 MB
Flash: 0 kB

CPU : Marvell Feroceon (Rev 1)
CLOUD ENGINES BOARD: PPV4A3

Streaming disabled
Write allocate disabled


USB 0: host mode
PEX 0: PCI Express Root Complex Interface
PEX interface detected Link X1
Net: egiga0 [PRIME]
Hit any key to stop autoboot: 1  0
(Re)start USB...
USB: scanning bus for devices... 2 USB Device(s) found
Waiting for storage device(s) to settle before scanning...
1 Storage Device(s) found

Reset IDE:
Marvell Serial ATA Adapter
Integrated Sata device found

No Fat FS detected

IDE device 0 not available
....
.
......
............
.........................
...................................................
......................................................................................................
...........................................................................

2869736 bytes read
## Booting image at 00800000 ...
Image Name: Linux-3.1.10-16-ARCH
Created: 2013-02-12 1:17:35 UTC
Image Type: ARM Linux Kernel Image (uncompressed)
Data Size: 2869672 Bytes = 2.7 MB
Load Address: 00008000
Entry Point: 00008000
Verifying Checksum ... OK
OK

Starting kernel ...

Uncompressing Linux... done, booting the kernel.
[ 10.021907] xhci_hcd 0000:00:01.0: Failed to enable MSI-X
[ 10.027290] xhci_hcd 0000:00:01.0: failed to allocate MSI entry
[ 10.849854] usb 1-1.1: device descriptor read/64, error -32
[ 11.039443] rtc-mv rtc-mv: internal RTC not ticking
[ 11.050386] drivers/rtc/hctosys.c: unable to open rtc device (rtc0)
[ 11.419855] usb 1-1.3: device descriptor read/64, error -32
[ 11.609854] usb 1-1.3: device descriptor read/64, error -32
[ 11.879853] usb 1-1.3: device descriptor read/64, error -32
[ 12.069836] usb 1-1.3: device descriptor read/64, error -32
[ 12.453601] sd 1:0:0:0: [sda] No Caching mode page present
[ 12.459071] sd 1:0:0:0: [sda] Assuming drive cache: write through
[ 12.468349] sd 1:0:0:0: [sda] No Caching mode page present
[ 12.474091] sd 1:0:0:0: [sda] Assuming drive cache: write through
[ 12.484476] sd 1:0:0:0: [sda] No Caching mode page present
[ 12.490184] sd 1:0:0:0: [sda] Assuming drive cache: write through
[ 12.689467] usb 1-1.3: device not accepting address 7, error -32

Welcome to Arch Linux ARM!

<snip>
')

Any suggestions?

[ashgill3]

I was wondering if it was a problem with a USB 2.0 device being connected to a USB 3.0 port, so I went and purchased a USB 3.0 to ESATA adapter, and tested that.

Same results:

$this->bbcode_second_pass_code('', 'Starting kernel ...

Uncompressing Linux... done, booting the kernel.
[ 10.022189] xhci_hcd 0000:00:01.0: Failed to enable MSI-X
[ 10.027572] xhci_hcd 0000:00:01.0: failed to allocate MSI entry
[ 10.850235] usb 1-1.1: device descriptor read/64, error -32
[ 11.039805] rtc-mv rtc-mv: internal RTC not ticking
[ 11.050719] drivers/rtc/hctosys.c: unable to open rtc device (rtc0)
[ 11.420235] usb 1-1.3: device descriptor read/64, error -32
[ 11.610235] usb 1-1.3: device descriptor read/64, error -32
[ 11.710973] Kernel panic - not syncing: VFS: Unable to mount root fs on unknown-block(8,1)
[ 11.719249] [<c000d290>] (unwind_backtrace+0x0/0xe0) from [<c03cc914>] (panic+0x58/0x198)
[ 11.727460] [<c03cc914>] (panic+0x58/0x198) from [<c052fc2c>] (mount_block_root+0x230/0x280)
[ 11.735906] [<c052fc2c>] (mount_block_root+0x230/0x280) from [<c052ff3c>] (prepare_namespace+0x160/0x1c0)
[ 11.745470] [<c052ff3c>] (prepare_namespace+0x160/0x1c0) from [<c052f8c8>] (kernel_init+0x11c/0x14c)
[ 11.754602] [<c052f8c8>] (kernel_init+0x11c/0x14c) from [<c000945c>] (kernel_thread_exit+0x0/0x8)
')

Both of these adapters work when plugged in post-boot.

The first adapter tested (USB 2.0) is a Sunplus SATA bridge:

$this->bbcode_second_pass_code('', 'Dev #2: Sunplus Technology Co.,Ltd. - USB to Serial-ATA bridge
- Serial Number: FDC0FD30EF00000FD0FCAFF0760143
wTotalLength: 32
bNumInterfaces: 1
bConfigurationValue: 1
iConfiguration: 4
bmAttributes: c0h
MaxPower: 1
bInterfaceNumber: 0
bAlternateSetting: 0
bNumEndpoints: 2
bInterfaceClass: 8
bInterfaceSubClass: 6
bInterfaceProtocol: 80
iInterface: 5
bEndpointAddress: 81h
bmAttributes: 02h
wMaxPacketSize: 512
bInterval: 0
bRefresh: 0
bSynchAddress: 0
bEndpointAddress: 02h
bmAttributes: 02h
wMaxPacketSize: 512
bInterval: 0
bRefresh: 0
bSynchAddress: 0')

The second adapter tested (USB 3.0) is a Transcend StoreJet 25M3:

$this->bbcode_second_pass_code('', 'Dev #2: ASMedia - AS2105
- Serial Number: WD-WCAWZ0760143
wTotalLength: 44
bNumInterfaces: 1
bConfigurationValue: 1
iConfiguration: 0
bmAttributes: c0h
MaxPower: 0
bInterfaceNumber: 0
bAlternateSetting: 0
bNumEndpoints: 2
bInterfaceClass: 8
bInterfaceSubClass: 6
bInterfaceProtocol: 80
iInterface: 0
bEndpointAddress: 81h
bmAttributes: 02h
wMaxPacketSize: 1024
bInterval: 0
bRefresh: 0
bSynchAddress: 0
bEndpointAddress: 02h
bmAttributes: 02h
wMaxPacketSize: 1024
bInterval: 0
bRefresh: 0
bSynchAddress: 0')

[moonman]

Update the kernel and see if that helps
$this->bbcode_second_pass_code('', 'pacman -Sy linux-kirkwood linux-headers-kirkwood')

[ashgill3]

Good suggestion. When I try this, I get the following prompt:

$this->bbcode_second_pass_code('', ':: Synchronizing package databases...
core 42.4 KiB 161K/s 00:00 [##############################################] 100%
extra 475.1 KiB 339K/s 00:01 [##############################################] 100%
community 499.5 KiB 328K/s 00:02 [##############################################] 100%
alarm 5.1 KiB 477K/s 00:00 [##############################################] 100%
aur 17.4 KiB 379K/s 00:00 [##############################################] 100%
resolving dependencies...
looking for inter-conflicts...
:: linux-kirkwood and linux are in conflict. Remove linux? [y/N]
')

Given that it's pretty easy to re-install everything from scratch, I'm going to give "removing linux" a try, despite how ominous that sounds...

[ashgill3]

I got the below warning:

$this->bbcode_second_pass_code('', '**********************************************************************
WARNING! This kernel requires a newer U-Boot than many devices such as
the Dockstar and Pogoplug v2 originally came with. If you do
not have an updated version, your system WILL NOT BOOT.
**********************************************************************')
Everything installed without any errors, so here goes with a reboot...

[ashgill3]

And the update worked. I now am running the Kirkwood-linux kernel.

$this->bbcode_second_pass_code('', 'Arch Linux 3.7.10-0-ARCH (ttyS0)')

Sadly, it didn't resolve the problem. When I boot the system with the USB to SATA adapter plugged in, I still get the same kernel panic:

$this->bbcode_second_pass_code('', '## Booting image at 00800000 ...
Image Name: Linux-3.7.10-0-ARCH
Created: 2013-02-28 1:55:57 UTC
Image Type: ARM Linux Kernel Image (uncompressed)
Data Size: 3195816 Bytes = 3 MB
Load Address: 00008000
Entry Point: 00008000
Verifying Checksum ... OK
OK

Starting kernel ...

Uncompressing Linux... done, booting the kernel.
[ 10.799419] rtc-mv rtc-mv: internal RTC not ticking
[ 10.819769] usb 1-1.1: device descriptor read/64, error -32
[ 10.847148] drivers/rtc/hctosys.c: unable to open rtc device (rtc0)
[ 11.198063] sd 1:0:0:0: [sda] No Caching mode page present
[ 11.203608] sd 1:0:0:0: [sda] Assuming drive cache: write through
[ 11.211363] sd 1:0:0:0: [sda] No Caching mode page present
[ 11.216860] sd 1:0:0:0: [sda] Assuming drive cache: write through
[ 11.399759] usb 1-1.3: device descriptor read/64, error -32
[ 11.589759] usb 1-1.3: device descriptor read/64, error -32
[ 11.656796] sd 1:0:0:0: [sda] No Caching mode page present
[ 11.662305] sd 1:0:0:0: [sda] Assuming drive cache: write through
[ 11.668652] Kernel panic - not syncing: VFS: Unable to mount root fs on unknown-block(8,1)
[ 11.676922] [<c000d700>] (unwind_backtrace+0x0/0xe0) from [<c044349c>] (panic+0x80/0x1dc)
[ 11.685083] [<c044349c>] (panic+0x80/0x1dc) from [<c05d2d20>] (mount_block_root+0x260/0x2b4)
[ 11.693501] [<c05d2d20>] (mount_block_root+0x260/0x2b4) from [<c05d3030>] (prepare_namespace+0x15c/0x1bc)
[ 11.703036] [<c05d3030>] (prepare_namespace+0x15c/0x1bc) from [<c043bf80>] (kernel_init+0x8/0x104)
[ 11.711968] [<c043bf80>] (kernel_init+0x8/0x104) from [<c0008db0>] (ret_from_fork+')