PogoPlug E02 won't boot after latest pacman update

This forum is for Marvell Kirkwood devices such as the GoFlex Home/Net, PogoPlug v1/v2, SheevaPlug, and ZyXEL devices.

PogoPlug E02 won't boot after latest pacman update

Postby rhester72 » Sun Aug 24, 2014 8:48 pm

I have a PogoPlug E02 that was previously running bodhi's SNTP uboot [http://forum.doozan.com/read.php?3,16003] and Doozan's rescue 2.8.2 [http://forum.doozan.com/read.php?4,7915]. It's served me without incident through many minor upgrades over the past two years. There are two USB drives attached, one 1TB drive in the top rear port and one 3TB in the lowest rear port, the former being the boot/ALARM drive.

This morning, I did a bog-standard "pacman -Syu", rebooted, and no joy - the uImage was found, but when it came time for the root filesystem to be mounted, it complained it couldn't find /dev/sda1 (even with the other drive unplugged). A rescue boot worked fine, I saw that the boot drive was indeed recognized as /dev/sda1, but no amount of toying with the system produced good results.

In desperation, I used pivot_root and a few mounting tricks to get ALARM on my drive up far enough that I could do a pacman installation of bodhi's mainline uboot (uboot-pogo_e02), which worked fine, and is confirmed as the current u-boot (though it made the situation slightly worse, as I also lost the rescue image in the process). Unfortunately, it's still blissfully unaware of the location of my root partition. Serial console boot:

$this->bbcode_second_pass_code('', '
U-Boot 2014.04.R4-1 (May 28 2014 - 22:14:04) Arch Linux ARM
Pogo E02

SoC: Kirkwood 88F6281_A0
DRAM: 256 MiB
WARNING: Caches not enabled
NAND: 128 MiB
In: serial
Out: serial
Err: serial
Net: egiga0
Hit any key to stop autoboot: 0
(Re)start USB...
USB0: USB EHCI 1.00
scanning bus 0 for devices... 3 USB Device(s) found
scanning usb for storage devices... 1 Storage Device(s) found

Partition Map for USB device 0 -- Partition Type: DOS

Part Start Sector Num Sectors UUID Type
1 2048 1953523119 0cfc01e2-01 83
Attempting to boot from usb 0:1...
** File not found /boot/uEnv.txt **
Checking if uenvcmd is set ...
Running default loadzimage ...
** File not found /boot/zImage **
Running default loaduimage ...
3924480 bytes read in 278 ms (13.5 MiB/s)
Booting from 0 ...
## Booting kernel from Legacy Image at 00810000 ...
Image Name: Linux-3.16.1-1-ARCH
Image Type: ARM Linux Kernel Image (uncompressed)
Data Size: 3924416 Bytes = 3.7 MiB
Load Address: 00008000
Entry Point: 00008000
Verifying Checksum ... OK
Loading Kernel Image ... OK

Starting kernel ...

Uncompressing Linux... done, booting the kernel.
[ 0.000000] Booting Linux on physical CPU 0x0
[ 0.000000] Initializing cgroup subsys cpuset
[ 0.000000] Initializing cgroup subsys cpu
[ 0.000000] Initializing cgroup subsys cpuacct
[ 0.000000] Linux version 3.16.1-1-ARCH (nobody@root-armv5-copy) (gcc version 4.8.2 20131219 (prerelease) (GCC) ) #1 PREEMPT Fri Aug 15 20:35:00 MDT 2014
[ 0.000000] CPU: Feroceon 88FR131 [56251311] revision 1 (ARMv5TE), cr=0005397f
[ 0.000000] CPU: VIVT data cache, VIVT instruction cache
[ 0.000000] Machine: Pogoplug E02
[ 0.000000] Memory policy: Data cache writeback
[ 0.000000] Built 1 zonelists in Zone order, mobility grouping on. Total pages: 65024
[ 0.000000] Kernel command line: console=ttyS0,115200 root=/dev/sda1 rw rootwait mtdparts=orion_nand:1M(u-boot),-(rootfs)
[ 0.000000] PID hash table entries: 1024 (order: 0, 4096 bytes)
[ 0.000000] Dentry cache hash table entries: 32768 (order: 5, 131072 bytes)
[ 0.000000] Inode-cache hash table entries: 16384 (order: 4, 65536 bytes)
[ 0.000000] allocated 524288 bytes of page_cgroup
[ 0.000000] please try 'cgroup_disable=memory' option if you don't want memory cgroups
[ 0.000000] Memory: 250884K/262144K available (5462K kernel code, 340K rwdata, 1668K rodata, 256K init, 697K bss, 11260K reserved)
[ 0.000000] Virtual kernel memory layout:
[ 0.000000] vector : 0xffff0000 - 0xffff1000 ( 4 kB)
[ 0.000000] fixmap : 0xffc00000 - 0xffe00000 (2048 kB)
[ 0.000000] vmalloc : 0xd0800000 - 0xff000000 ( 744 MB)
[ 0.000000] lowmem : 0xc0000000 - 0xd0000000 ( 256 MB)
[ 0.000000] modules : 0xbf000000 - 0xc0000000 ( 16 MB)
[ 0.000000] .text : 0xc0008000 - 0xc06feb1c (7131 kB)
[ 0.000000] .init : 0xc06ff000 - 0xc073f12c ( 257 kB)
[ 0.000000] .data : 0xc0740000 - 0xc07953bc ( 341 kB)
[ 0.000000] .bss : 0xc07953bc - 0xc08439a8 ( 698 kB)
[ 0.000000] SLUB: HWalign=32, Order=0-3, MinObjects=0, CPUs=1, Nodes=1
[ 0.000000] Preemptible hierarchical RCU implementation.
[ 0.000000] NR_IRQS:114
[ 0.000011] sched_clock: 32 bits at 200MHz, resolution 5ns, wraps every 21474836475ns
[ 13.685693] Console: colour dummy device 80x30
[ 13.685717] Calibrating delay loop... 1191.11 BogoMIPS (lpj=5955584)
[ 13.775594] pid_max: default: 32768 minimum: 301
[ 13.775686] Security Framework initialized
[ 13.775756] Mount-cache hash table entries: 1024 (order: 0, 4096 bytes)
[ 13.775777] Mountpoint-cache hash table entries: 1024 (order: 0, 4096 bytes)
[ 13.776561] Initializing cgroup subsys memory
[ 13.776711] Initializing cgroup subsys devices
[ 13.776764] Initializing cgroup subsys freezer
[ 13.776790] Initializing cgroup subsys net_cls
[ 13.776824] Initializing cgroup subsys blkio
[ 13.776856] Initializing cgroup subsys bfqio
[ 13.776906] Initializing cgroup subsys perf_event
[ 13.776928] Initializing cgroup subsys net_prio
[ 13.777043] CPU: Testing write buffer coherency: ok
[ 13.777481] Setting up static identity map for 0x5321e0 - 0x532238
[ 13.779575] devtmpfs: initialized
[ 13.782965] pinctrl core: initialized pinctrl subsystem
[ 13.783389] NET: Registered protocol family 16
[ 13.783844] DMA: preallocated 256 KiB pool for atomic coherent allocations
[ 13.784958] cpuidle: using governor ladder
[ 13.784986] cpuidle: using governor menu
[ 13.785120] Kirkwood: MV88F6281-A0, TCLK=200000000.
[ 13.785141] Feroceon L2: Enabling L2
[ 13.785180] Feroceon L2: Cache support initialised.
[ 13.817244] vgaarb: loaded
[ 13.817590] SCSI subsystem initialized
[ 13.818096] usbcore: registered new interface driver usbfs
[ 13.818172] usbcore: registered new interface driver hub
[ 13.818290] usbcore: registered new device driver usb
[ 13.818466] pps_core: LinuxPPS API ver. 1 registered
[ 13.818476] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
[ 13.818511] PTP clock support registered
[ 13.819542] Switched to clocksource orion_clocksource
[ 13.838149] FS-Cache: Loaded
[ 13.851351] NET: Registered protocol family 2
[ 13.852290] TCP established hash table entries: 2048 (order: 1, 8192 bytes)
[ 13.852326] TCP bind hash table entries: 2048 (order: 1, 8192 bytes)
[ 13.852357] TCP: Hash tables configured (established 2048 bind 2048)
[ 13.852421] TCP: reno registered
[ 13.852435] UDP hash table entries: 256 (order: 0, 4096 bytes)
[ 13.852456] UDP-Lite hash table entries: 256 (order: 0, 4096 bytes)
[ 13.852654] NET: Registered protocol family 1
[ 13.854379] futex hash table entries: 256 (order: -1, 3072 bytes)
[ 13.866466] VFS: Disk quotas dquot_6.5.2
[ 13.866738] Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
[ 13.868533] jffs2: version 2.2. (NAND) © 2001-2006 Red Hat, Inc.
[ 13.869898] fuse init (API version 7.23)
[ 13.871666] aufs 3.16-20140811
[ 13.871700] msgmni has been set to 490
[ 13.873556] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250)
[ 13.873577] io scheduler noop registered
[ 13.873592] io scheduler deadline registered
[ 13.874012] io scheduler cfq registered
[ 13.874065] io scheduler bfq registered (default)
[ 13.874073] BFQ I/O-scheduler version: v7r5
[ 13.874282] mv_xor mv_xor.0: Marvell shared XOR driver
[ 13.909633] mv_xor mv_xor.0: Marvell XOR: ( xor cpy )
[ 13.949623] mv_xor mv_xor.0: Marvell XOR: ( xor cpy )
[ 13.949819] mv_xor mv_xor.1: Marvell shared XOR driver
[ 13.989622] mv_xor mv_xor.1: Marvell XOR: ( xor cpy )
[ 14.029620] mv_xor mv_xor.1: Marvell XOR: ( xor cpy )
[ 14.035175] Serial: 8250/16550 driver, 2 ports, IRQ sharing disabled
[ 14.036244] console [ttyS0] disabled
[ 14.056348] serial8250.0: ttyS0 at MMIO 0xf1012000 (irq = 33, base_baud = 12500000) is a 16550A
[ 14.574195] console [ttyS0] enabled
[ 14.587119] brd: module loaded
[ 14.594896] loop: module loaded
[ 14.599205] nand: device found, Manufacturer ID: 0xec, Chip ID: 0xf1
[ 14.605629] nand: Samsung NAND 128MiB 3,3V 8-bit
[ 14.610279] nand: 128MiB, SLC, page size: 2048, OOB size: 64
[ 14.615976] Scanning device for bad blocks
[ 14.620682] Bad eraseblock 7 at 0x0000000e0000
[ 14.631939] Bad eraseblock 97 at 0x000000c20000
[ 14.645696] Bad eraseblock 219 at 0x000001b60000
[ 14.657197] Bad eraseblock 310 at 0x0000026c0000
[ 14.671133] Bad eraseblock 433 at 0x000003620000
[ 14.688084] Bad eraseblock 596 at 0x000004a80000
[ 14.693682] Bad eraseblock 609 at 0x000004c20000
[ 14.706016] Bad eraseblock 711 at 0x0000058e0000
[ 14.734324] 2 cmdlinepart partitions found on MTD device orion_nand
[ 14.740634] Creating 2 MTD partitions on "orion_nand":
[ 14.745795] 0x000000000000-0x000000100000 : "u-boot"
[ 14.751239] 0x000000100000-0x000008000000 : "rootfs"
[ 14.763611] libphy: orion_mdio_bus: probed
[ 14.767843] mv643xx_eth: MV-643xx 10/100/1000 ethernet driver version 1.4
[ 15.850469] mv643xx_eth_port mv643xx_eth_port.0 eth0: port 0 with MAC address 00:25:31:00:c9:e6
[ 15.859356] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
[ 15.865944] ehci-pci: EHCI PCI platform driver
[ 15.870494] ehci-orion: EHCI orion driver
[ 15.874607] orion-ehci orion-ehci.0: EHCI Host Controller
[ 15.880072] orion-ehci orion-ehci.0: new USB bus registered, assigned bus number 1
[ 15.887790] orion-ehci orion-ehci.0: irq 19, io mem 0xf1050000
[ 15.909577] orion-ehci orion-ehci.0: USB 2.0 started, EHCI 1.00
[ 15.916479] hub 1-0:1.0: USB hub found
[ 15.920326] hub 1-0:1.0: 1 port detected
[ 15.925038] usbcore: registered new interface driver usb-storage
[ 15.931554] mousedev: PS/2 mouse device common for all mice
[ 16.239597] usb 1-1: new high-speed USB device number 2 using orion-ehci
[ 16.392173] hub 1-1:1.0: USB hub found
[ 16.396304] hub 1-1:1.0: 4 ports detected
[ 16.679930] usb 1-1.3: new high-speed USB device number 3 using orion-ehci
[ 16.939571] rtc-mv rtc-mv: internal RTC not ticking
[ 16.944623] i2c /dev entries driver
[ 16.948732] sdhci: Secure Digital Host Controller Interface driver
[ 16.954967] sdhci: Copyright(c) Pierre Ossman
[ 16.959410] sdhci-pltfm: SDHCI platform and OF driver helper
[ 16.965616] ledtrig-cpu: registered to indicate activity on CPUs
[ 16.971825] usbcore: registered new interface driver r8712u
[ 16.977615] gre: GRE over IPv4 demultiplexor driver
[ 16.982562] TCP: cubic registered
[ 16.985956] NET: Registered protocol family 17
[ 16.990572] Key type dns_resolver registered
[ 16.995654] registered taskstats version 1
[ 17.001089] console [netcon0] enabled
[ 17.004766] netconsole: network logging started
[ 17.009397] drivers/rtc/hctosys.c: unable to open rtc device (rtc0)
[ 17.016953] Waiting for root device /dev/sda1...
')

and that's where it sits forever.

It seems likely that the initramfs is hosed in some way after the kernel update to 3.16.1-1 this morning, but I'm not quite certain how to get it un-hosed, or even confirm if that's the problem. I can easily mount the external USB drive on another Arch box, but I'm not quite sure where to go from there.

Suggestions?

Rodney
rhester72
 
Posts: 35
Joined: Tue Apr 30, 2013 4:24 pm

Re: PogoPlug E02 won't boot after latest pacman update

Postby rhester72 » Tue Aug 26, 2014 3:39 am

I've recovered the system - and boy, was that fun (rolled back uboot, rebuilt uboot environment from scratch against the SNTP uboot, re-established the Doozan rescue 2.8.2 and automatic SNTP...all from a USB key running an ancient Arch and learning a lot about off-device rollbacks with pacman; joy!).

So far, I've confirmed that kernel 3.16.1-1 kernel panics when scanning the USB bus and detecting the following device:

$this->bbcode_second_pass_code('', 'scsi 1:0:0:0: Direct-Access Seagate Backup+ BK A905 PQ: 0 ANSI: 6')

The system boots without incident on 3.15.6-1. I'm rolling forward to 3.15.7-1 and 3.15.8-1 to see which kernel in particular introduced the breakage, but for now, the message is that if you are using the above device as your root volume, do NOT upgrade to 3.16 unless you have a LOT of spare time on your hands and a desire for a great deal of pain and suffering.

More information as I have it (including a snapshot of the crash).

Rodney
rhester72
 
Posts: 35
Joined: Tue Apr 30, 2013 4:24 pm

Re: PogoPlug E02 won't boot after latest pacman update

Postby rhester72 » Wed Aug 27, 2014 3:12 am

Confirmed that 3.15.8 does not trigger the kernel panic, so the bug was introduced in 3.16. I don't know (yet, perhaps ever) if it affects x86 or only ARM. Full crashlog on 3.16 to follow tomorrow, should any Arch devs be interested. For now, I'm freezing on 3.15.8, as the last of the 3.15 tree.

The required downgrade packages are:

http://rollback.adminempire.com/packages/l/linux-kirkwood/linux-kirkwood-3.15.8-1-arm.pkg.tar.xz
http://rollback.adminempire.com/packages/l/linux-kirkwood-headers/linux-kirkwood-headers-3.15.8-1-arm.pkg.tar.xz

Depending on where you are in the world, the above rollback site may be _painfully_ slow. If so, I'm also mirroring them here:

http://multics.minidns.net/arch/

Rodney
rhester72
 
Posts: 35
Joined: Tue Apr 30, 2013 4:24 pm

Re: PogoPlug E02 won't boot after latest pacman update

Postby rhester72 » Thu Aug 28, 2014 2:18 am

I booted 3.16.1-1 from a USB flash drive (with no issues), then inserted my non-root drive:

$this->bbcode_second_pass_code('', '
[ 389.863269] usb 1-1.2: new high-speed USB device number 4 using orion-ehci
[ 390.083111] scsi1 : uas
[ 390.086109] ------------[ cut here ]------------
[ 390.090774] WARNING: CPU: 0 PID: 21 at drivers/usb/storage/uas.c:625 uas_submit_urbs.constprop.23+0x560/0x59c [uas]()
[ 390.101423] Modules linked in: uas mv_cesa ipv6
[ 390.106006] CPU: 0 PID: 21 Comm: khubd Not tainted 3.16.1-1-ARCH #1
[ 390.112320] [<c000f040>] (unwind_backtrace) from [<c000c588>] (show_stack+0x10/0x14)
[ 390.120103] [<c000c588>] (show_stack) from [<c001b7bc>] (warn_slowpath_common+0x70/0x90)
[ 390.128237] [<c001b7bc>] (warn_slowpath_common) from [<c001b878>] (warn_slowpath_null+0x1c/0x24)
[ 390.137066] [<c001b878>] (warn_slowpath_null) from [<bf06d1e8>] (uas_submit_urbs.constprop.23+0x560/0x59c [uas])
[ 390.147302] [<bf06d1e8>] (uas_submit_urbs.constprop.23 [uas]) from [<bf06d390>] (uas_queuecommand+0x16c/0x22c [uas])
[ 390.157886] [<bf06d390>] (uas_queuecommand [uas]) from [<c0365618>] (scsi_dispatch_cmd+0x98/0x230)
[ 390.166899] [<c0365618>] (scsi_dispatch_cmd) from [<c036c828>] (scsi_request_fn+0x2e4/0x514)
[ 390.175386] [<c036c828>] (scsi_request_fn) from [<c02a601c>] (__blk_run_queue+0x34/0x44)
[ 390.183521] [<c02a601c>] (__blk_run_queue) from [<c02ad940>] (blk_execute_rq_nowait+0xa0/0x148)
[ 390.192260] [<c02ad940>] (blk_execute_rq_nowait) from [<c02ada40>] (blk_execute_rq+0x58/0xe4)
[ 390.200833] [<c02ada40>] (blk_execute_rq) from [<c036ad94>] (scsi_execute+0xb8/0x138)
[ 390.208705] [<c036ad94>] (scsi_execute) from [<c036ae98>] (scsi_execute_req_flags+0x84/0xf8)
[ 390.217181] [<c036ae98>] (scsi_execute_req_flags) from [<c036e22c>] (scsi_probe_and_add_lun+0x178/0x9c8)
[ 390.226701] [<c036e22c>] (scsi_probe_and_add_lun) from [<c036ee60>] (__scsi_scan_target+0xdc/0x564)
[ 390.235788] [<c036ee60>] (__scsi_scan_target) from [<c036f564>] (scsi_scan_host_selected+0x108/0x1dc)
[ 390.245048] [<c036f564>] (scsi_scan_host_selected) from [<c036f6bc>] (do_scsi_scan_host+0x84/0x8c)
[ 390.254047] [<c036f6bc>] (do_scsi_scan_host) from [<c036f83c>] (scsi_scan_host+0x178/0x1e0)
[ 390.262442] [<c036f83c>] (scsi_scan_host) from [<bf06c4a8>] (uas_probe+0x278/0x2a8 [uas])
[ 390.270666] [<bf06c4a8>] (uas_probe [uas]) from [<c03eb754>] (usb_probe_interface+0x16c/0x21c)
[ 390.279325] [<c03eb754>] (usb_probe_interface) from [<c0347988>] (driver_probe_device+0x110/0x244)
[ 390.288328] [<c0347988>] (driver_probe_device) from [<c0346060>] (bus_for_each_drv+0x5c/0x8c)
[ 390.296899] [<c0346060>] (bus_for_each_drv) from [<c0347844>] (device_attach+0x70/0x88)
[ 390.304938] [<c0347844>] (device_attach) from [<c0346f2c>] (bus_probe_device+0x84/0xb0)
[ 390.312978] [<c0346f2c>] (bus_probe_device) from [<c03453d8>] (device_add+0x420/0x51c)
[ 390.320939] [<c03453d8>] (device_add) from [<c03e98b8>] (usb_set_configuration+0x540/0x774)
[ 390.329334] [<c03e98b8>] (usb_set_configuration) from [<c03f2d38>] (generic_probe+0x2c/0x78)
[ 390.337812] [<c03f2d38>] (generic_probe) from [<c03eb5d0>] (usb_probe_device+0x24/0x3c)
[ 390.345852] [<c03eb5d0>] (usb_probe_device) from [<c0347988>] (driver_probe_device+0x110/0x244)
[ 390.354590] [<c0347988>] (driver_probe_device) from [<c0346060>] (bus_for_each_drv+0x5c/0x8c)
[ 390.363153] [<c0346060>] (bus_for_each_drv) from [<c0347844>] (device_attach+0x70/0x88)
[ 390.371193] [<c0347844>] (device_attach) from [<c0346f2c>] (bus_probe_device+0x84/0xb0)
[ 390.379232] [<c0346f2c>] (bus_probe_device) from [<c03453d8>] (device_add+0x420/0x51c)
[ 390.387185] [<c03453d8>] (device_add) from [<c03e0b70>] (usb_new_device+0x1d0/0x354)
[ 390.394964] [<c03e0b70>] (usb_new_device) from [<c03e2360>] (hub_thread+0xd70/0x124c)
[ 390.402836] [<c03e2360>] (hub_thread) from [<c0039fd4>] (kthread+0xbc/0xd8)
[ 390.409833] [<c0039fd4>] (kthread) from [<c0009590>] (ret_from_fork+0x14/0x24)
[ 390.417080] ---[ end trace 3c38c708ad5120b5 ]---
[ 390.422120] ------------[ cut here ]------------
[ 390.426777] WARNING: CPU: 0 PID: 98 at drivers/usb/storage/uas.c:286 uas_try_complete.isra.13+0x10c/0x12c [uas]()
[ 390.437081] Modules linked in: uas mv_cesa ipv6
[ 390.441664] CPU: 0 PID: 98 Comm: systemd-timesyn Tainted: G W 3.16.1-1-ARCH #1
[ 390.449891] [<c000f040>] (unwind_backtrace) from [<c000c588>] (show_stack+0x10/0x14)
[ 390.457671] [<c000c588>] (show_stack) from [<c001b7bc>] (warn_slowpath_common+0x70/0x90)
[ 390.465805] [<c001b7bc>] (warn_slowpath_common) from [<c001b878>] (warn_slowpath_null+0x1c/0x24)
[ 390.474634] [<c001b878>] (warn_slowpath_null) from [<bf06bd34>] (uas_try_complete.isra.13+0x10c/0x12c [uas])
[ 390.484518] [<bf06bd34>] (uas_try_complete.isra.13 [uas]) from [<bf06c034>] (uas_data_cmplt+0x54/0x1b4 [uas])
[ 390.494483] [<bf06c034>] (uas_data_cmplt [uas]) from [<c03e4228>] (__usb_hcd_giveback_urb+0x60/0xd8)
[ 390.503660] [<c03e4228>] (__usb_hcd_giveback_urb) from [<c03e4ea0>] (usb_giveback_urb_bh+0xb4/0x140)
[ 390.512844] [<c03e4ea0>] (usb_giveback_urb_bh) from [<c00206e0>] (tasklet_action+0x8c/0x104)
[ 390.521325] [<c00206e0>] (tasklet_action) from [<c001fd88>] (__do_softirq+0xd4/0x2f8)
[ 390.529191] [<c001fd88>] (__do_softirq) from [<c002024c>] (irq_exit+0xb0/0xf8)
[ 390.536445] [<c002024c>] (irq_exit) from [<c0009db4>] (handle_IRQ+0x48/0xb0)
[ 390.543528] [<c0009db4>] (handle_IRQ) from [<c0530694>] (__irq_svc+0x34/0x98)
[ 390.550704] [<c0530694>] (__irq_svc) from [<c0118d90>] (__d_free+0x0/0x58)
[ 390.557608] [<c0118d90>] (__d_free) from [<cecdaf40>] (0xcecdaf40)
[ 390.563811] ---[ end trace 3c38c708ad5120b6 ]---
[ 390.576707] scsi 1:0:0:0: Direct-Access ASMT 2105 0 PQ: 0 ANSI: 6
[ 390.588587] sd 1:0:0:0: [sdb] 732566646 4096-byte logical blocks: (3.00 TB/2.72 TiB)
[ 390.596481] sd 1:0:0:0: Attached scsi generic sg1 type 0
[ 390.608428] sd 1:0:0:0: [sdb] Write Protect is off
[ 390.617528] sd 1:0:0:0: [sdb] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
[ 390.629556] sd 1:0:0:0: [sdb] 732566646 4096-byte logical blocks: (3.00 TB/2.72 TiB)
[ 390.807512] usb 1-1.2: stat urb: status -71
[ 421.012663] sd 1:0:0:0: [sdb] uas_eh_abort_handler cfbbe380 tag 0, inflight: CMD
[ 421.020097] ------------[ cut here ]------------
[ 421.024749] WARNING: CPU: 0 PID: 220 at drivers/usb/storage/uas.c:157 uas_mark_cmd_dead+0x88/0xc8 [uas]()
[ 421.034349] Modules linked in: uas mv_cesa ipv6
[ 421.038934] CPU: 0 PID: 220 Comm: kworker/u2:0 Tainted: G W 3.16.1-1-ARCH #1
[ 421.046984] Workqueue: scsi_tmf_1 scmd_eh_abort_handler
[ 421.052256] [<c000f040>] (unwind_backtrace) from [<c000c588>] (show_stack+0x10/0x14)
[ 421.060040] [<c000c588>] (show_stack) from [<c001b7bc>] (warn_slowpath_common+0x70/0x90)
[ 421.068174] [<c001b7bc>] (warn_slowpath_common) from [<c001b878>] (warn_slowpath_null+0x1c/0x24)
[ 421.077001] [<c001b878>] (warn_slowpath_null) from [<bf06b560>] (uas_mark_cmd_dead+0x88/0xc8 [uas])
[ 421.086104] [<bf06b560>] (uas_mark_cmd_dead [uas]) from [<bf06cbb0>] (uas_eh_abort_handler+0x88/0x160 [uas])
[ 421.095990] [<bf06cbb0>] (uas_eh_abort_handler [uas]) from [<c0369f04>] (scmd_eh_abort_handler+0x7c/0x140)
[ 421.105691] [<c0369f04>] (scmd_eh_abort_handler) from [<c0033a1c>] (process_one_work+0x140/0x464)
[ 421.114609] [<c0033a1c>] (process_one_work) from [<c00345c8>] (worker_thread+0x38/0x618)
[ 421.122740] [<c00345c8>] (worker_thread) from [<c0039fd4>] (kthread+0xbc/0xd8)
[ 421.130000] [<c0039fd4>] (kthread) from [<c0009590>] (ret_from_fork+0x14/0x24)
[ 421.137249] ---[ end trace 3c38c708ad5120b7 ]---
[ 421.153278] sd 1:0:0:0: [sdb] uas_cmd_cmplt cfbbe380 tag 0, inflight: CMD abort
[ 421.160631] sd 1:0:0:0: [sdb] cmd cmplt err -71
[ 421.165212] usb 1-1.2: stat urb: status -71
[ 421.169466] scsi host1: uas_eh_task_mgmt: ABORT TASK failed (wrong tag 0/256)
[ 421.180595] sd 1:0:0:0: uas_eh_device_reset_handler
[ 421.190652] sd 1:0:0:0: [sdb] uas_cmd_cmplt cfbbe380 tag 0, inflight: CMD abort
[ 421.197996] sd 1:0:0:0: [sdb] cmd cmplt err -71
[ 421.202580] usb 1-1.2: stat urb: status -71
[ 421.209650] scsi host1: uas_eh_task_mgmt: LOGICAL UNIT RESET failed (wrong tag 0/256)
[ 421.218551] scsi host1: uas_eh_bus_reset_handler start
[ 421.224683] sd 1:0:0:0: [sdb] uas_zap_dead cfbbe380 tag 0, inflight: CMD abort
[ 421.231944] sd 1:0:0:0: [sdb] abort completed
[ 421.312892] usb 1-1.2: reset high-speed USB device number 4 using orion-ehci
[ 421.524283] scsi host1: uas_eh_bus_reset_handler success
[ 428.793516] usb 1-1.2: stat urb: status -71
')

then my root drive:

$this->bbcode_second_pass_code('', '
[ 555.747144] usb 1-1.2: new high-speed USB device number 5 using orion-ehci
[ 555.904483] scsi2 : uas
[ 555.909687] scsi 2:0:0:0: Direct-Access Seagate Backup+ BK A905 PQ: 0 ANSI: 6
[ 555.921659] sd 2:0:0:0: [sdb] Spinning up disk...
[ 555.928179] sd 2:0:0:0: Attached scsi generic sg1 type 0
[ 556.936647] ..ready
[ 557.947919] sd 2:0:0:0: [sdb] 1953525167 512-byte logical blocks: (1.00 TB/931 GiB)
[ 558.108543] sd 2:0:0:0: [sdb] Write Protect is off
[ 558.114149] sd 2:0:0:0: [sdb] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
[ 558.142346] sdb: sdb1
[ 558.153051] sd 2:0:0:0: [sdb] Attached SCSI disk
')

This is exactly backwards of what I've encountered before, where it was the Seagate that caused problems, so it seems to potentially affect any external USB drive. If you trigger the above crashes a number of times, the kernel itself will sometimes give up and panic (though, as luck would have it, it didn't happen on this trial).

Anyway - there's definitely an issue with external USB handling in 3.16 on ARM that wasn't present in previous kernels. Hope that gives kernel hackers some sort of clue.

I'll do some trials tomorrow with 3.16 on x86 just to see if anything similar occurs.

Rodney
rhester72
 
Posts: 35
Joined: Tue Apr 30, 2013 4:24 pm

Re: PogoPlug E02 won't boot after latest pacman update

Postby Palmore » Tue Sep 16, 2014 1:09 am

Not to wake a sleeping giant, but I think I may be running into a similar issue, I have a thread going on over at

viewtopic.php?f=58&t=7796&p=42535#p42535

When I connect a 5TB Seagate Expansion disk, my device becomes unresponsive a short time later

$this->bbcode_second_pass_code('', 'Sep 15 20:30:47 plug01 systemd[1]: Started Session c4 of user root.
Sep 15 20:33:59 plug01 kernel: usb 3-2: new SuperSpeed USB device number 3 using xhci_hcd
Sep 15 20:34:00 plug01 kernel: scsi3 : uas
Sep 15 20:34:00 plug01 kernel: ------------[ cut here ]------------
Sep 15 20:34:00 plug01 kernel: WARNING: CPU: 0 PID: 21 at drivers/usb/storage/uas.c:625 uas_submit_urbs.constprop.23+0x560/0x59c [uas]()
Sep 15 20:34:00 plug01 kernel: Modules linked in: uas mv_cesa ipv6
Sep 15 20:34:00 plug01 kernel: CPU: 0 PID: 21 Comm: khubd Not tainted 3.16.2-1-ARCH #1
Sep 15 20:34:00 plug01 kernel: [<c000f040>] (unwind_backtrace) from [<c000c588>] (show_stack+0x10/0x14)
Sep 15 20:34:00 plug01 kernel: [<c000c588>] (show_stack) from [<c001b7bc>] (warn_slowpath_common+0x70/0x90)
Sep 15 20:34:00 plug01 kernel: [<c001b7bc>] (warn_slowpath_common) from [<c001b878>] (warn_slowpath_null+0x1c/0x24)
Sep 15 20:34:00 plug01 kernel: [<c001b878>] (warn_slowpath_null) from [<bf06d1e8>] (uas_submit_urbs.constprop.23+0x560/0x59c [uas])
Sep 15 20:34:00 plug01 kernel: [<bf06d1e8>] (uas_submit_urbs.constprop.23 [uas]) from [<bf06d390>] (uas_queuecommand+0x16c/0x22c [uas])
Sep 15 20:34:00 plug01 kernel: [<bf06d390>] (uas_queuecommand [uas]) from [<c0365eec>] (scsi_dispatch_cmd+0x98/0x230)
Sep 15 20:34:00 plug01 kernel: [<c0365eec>] (scsi_dispatch_cmd) from [<c036d0fc>] (scsi_request_fn+0x2e4/0x514)
Sep 15 20:34:00 plug01 kernel: [<c036d0fc>] (scsi_request_fn) from [<c02a6874>] (__blk_run_queue+0x34/0x44)
Sep 15 20:34:00 plug01 kernel: [<c02a6874>] (__blk_run_queue) from [<c02ae198>] (blk_execute_rq_nowait+0xa0/0x148)
Sep 15 20:34:00 plug01 kernel: [<c02ae198>] (blk_execute_rq_nowait) from [<c02ae298>] (blk_execute_rq+0x58/0xe4)
Sep 15 20:34:00 plug01 kernel: [<c02ae298>] (blk_execute_rq) from [<c036b668>] (scsi_execute+0xb8/0x138)
Sep 15 20:34:00 plug01 kernel: [<c036b668>] (scsi_execute) from [<c036b76c>] (scsi_execute_req_flags+0x84/0xf8)
Sep 15 20:34:00 plug01 kernel: [<c036b76c>] (scsi_execute_req_flags) from [<c036eb00>] (scsi_probe_and_add_lun+0x178/0x9c8)
Sep 15 20:34:00 plug01 kernel: [<c036eb00>] (scsi_probe_and_add_lun) from [<c036f734>] (__scsi_scan_target+0xdc/0x564)
Sep 15 20:34:00 plug01 kernel: [<c036f734>] (__scsi_scan_target) from [<c036fe38>] (scsi_scan_host_selected+0x108/0x1dc)
Sep 15 20:34:00 plug01 kernel: [<c036fe38>] (scsi_scan_host_selected) from [<c036ff90>] (do_scsi_scan_host+0x84/0x8c)
Sep 15 20:34:00 plug01 kernel: [<c036ff90>] (do_scsi_scan_host) from [<c0370110>] (scsi_scan_host+0x178/0x1e0)
Sep 15 20:34:00 plug01 kernel: [<c0370110>] (scsi_scan_host) from [<bf06c4a8>] (uas_probe+0x278/0x2a8 [uas])
Sep 15 20:34:00 plug01 kernel: [<bf06c4a8>] (uas_probe [uas]) from [<c03ec0d0>] (usb_probe_interface+0x16c/0x21c)
Sep 15 20:34:00 plug01 kernel: [<c03ec0d0>] (usb_probe_interface) from [<c034825c>] (driver_probe_device+0x110/0x244)
Sep 15 20:34:00 plug01 kernel: [<c034825c>] (driver_probe_device) from [<c0346934>] (bus_for_each_drv+0x5c/0x8c)
Sep 15 20:34:00 plug01 kernel: [<c0346934>] (bus_for_each_drv) from [<c0348118>] (device_attach+0x70/0x88)
Sep 15 20:34:00 plug01 kernel: [<c0348118>] (device_attach) from [<c0347800>] (bus_probe_device+0x84/0xb0)
Sep 15 20:34:00 plug01 kernel: [<c0347800>] (bus_probe_device) from [<c0345cac>] (device_add+0x420/0x51c)
Sep 15 20:34:00 plug01 kernel: [<c0345cac>] (device_add) from [<c03ea234>] (usb_set_configuration+0x540/0x774)
Sep 15 20:34:00 plug01 kernel: [<c03ea234>] (usb_set_configuration) from [<c03f36c0>] (generic_probe+0x2c/0x78)
Sep 15 20:34:00 plug01 kernel: [<c03f36c0>] (generic_probe) from [<c03ebf4c>] (usb_probe_device+0x24/0x3c)
Sep 15 20:34:00 plug01 kernel: [<c03ebf4c>] (usb_probe_device) from [<c034825c>] (driver_probe_device+0x110/0x244)
Sep 15 20:34:00 plug01 kernel: [<c034825c>] (driver_probe_device) from [<c0346934>] (bus_for_each_drv+0x5c/0x8c)
Sep 15 20:34:00 plug01 kernel: [<c0346934>] (bus_for_each_drv) from [<c0348118>] (device_attach+0x70/0x88)
Sep 15 20:34:00 plug01 kernel: [<c0348118>] (device_attach) from [<c0347800>] (bus_probe_device+0x84/0xb0)
Sep 15 20:34:00 plug01 kernel: [<c0347800>] (bus_probe_device) from [<c0345cac>] (device_add+0x420/0x51c)
Sep 15 20:34:00 plug01 kernel: [<c0345cac>] (device_add) from [<c03e143c>] (usb_new_device+0x1d0/0x354)
Sep 15 20:34:00 plug01 kernel: [<c03e143c>] (usb_new_device) from [<c03e2cd0>] (hub_thread+0xd88/0x1264)
Sep 15 20:34:00 plug01 kernel: [<c03e2cd0>] (hub_thread) from [<c0039fd4>] (kthread+0xbc/0xd8)
Sep 15 20:34:00 plug01 kernel: [<c0039fd4>] (kthread) from [<c0009590>] (ret_from_fork+0x14/0x24)
Sep 15 20:34:00 plug01 kernel: ---[ end trace 2f8ee2277200441c ]---
Sep 15 20:34:00 plug01 kernel: ------------[ cut here ]------------
Sep 15 20:34:00 plug01 kernel: WARNING: CPU: 0 PID: 86 at drivers/usb/storage/uas.c:286 uas_try_complete.isra.13+0x10c/0x12c [uas]()
Sep 15 20:34:00 plug01 kernel: Modules linked in: uas mv_cesa ipv6
Sep 15 20:34:00 plug01 kernel: CPU: 0 PID: 86 Comm: systemd-journal Tainted: G W 3.16.2-1-ARCH #1
Sep 15 20:34:00 plug01 kernel: [<c000f040>] (unwind_backtrace) from [<c000c588>] (show_stack+0x10/0x14)
Sep 15 20:34:00 plug01 kernel: [<c000c588>] (show_stack) from [<c001b7bc>] (warn_slowpath_common+0x70/0x90)
Sep 15 20:34:00 plug01 kernel: [<c001b7bc>] (warn_slowpath_common) from [<c001b878>] (warn_slowpath_null+0x1c/0x24)
Sep 15 20:34:00 plug01 kernel: [<c001b878>] (warn_slowpath_null) from [<bf06bd34>] (uas_try_complete.isra.13+0x10c/0x12c [uas])
Sep 15 20:34:00 plug01 kernel: [<bf06bd34>] (uas_try_complete.isra.13 [uas]) from [<bf06c034>] (uas_data_cmplt+0x54/0x1b4 [uas])
Sep 15 20:34:00 plug01 kernel: [<bf06c034>] (uas_data_cmplt [uas]) from [<c03e4ba4>] (__usb_hcd_giveback_urb+0x60/0xd8)
Sep 15 20:34:00 plug01 kernel: [<c03e4ba4>] (__usb_hcd_giveback_urb) from [<c040eb0c>] (xhci_irq+0x730/0x152c)
Sep 15 20:34:00 plug01 kernel: [<c040eb0c>] (xhci_irq) from [<c03e4138>] (usb_hcd_irq+0x24/0x38)
Sep 15 20:34:00 plug01 kernel: [<c03e4138>] (usb_hcd_irq) from [<c0059440>] (handle_irq_event_percpu+0x34/0x248)
Sep 15 20:34:00 plug01 kernel: [<c0059440>] (handle_irq_event_percpu) from [<c00596a4>] (handle_irq_event+0x50/0x84)
Sep 15 20:34:00 plug01 kernel: [<c00596a4>] (handle_irq_event) from [<c005bd18>] (handle_level_irq+0x98/0x16c)
Sep 15 20:34:00 plug01 kernel: [<c005bd18>] (handle_level_irq) from [<c0058d58>] (generic_handle_irq+0x20/0x34)
Sep 15 20:34:00 plug01 kernel: [<c0058d58>] (generic_handle_irq) from [<c0009db0>] (handle_IRQ+0x44/0xb0)
Sep 15 20:34:00 plug01 kernel: [<c0009db0>] (handle_IRQ) from [<c0531258>] (__irq_usr+0x38/0xa0)
Sep 15 20:34:00 plug01 kernel: ---[ end trace 2f8ee2277200441d ]---
Sep 15 20:34:00 plug01 kernel: scsi 3:0:0:0: Direct-Access Seagate Expansion Desk 0739 PQ: 0 ANSI: 6
Sep 15 20:34:00 plug01 kernel: sd 3:0:0:0: [sdc] Spinning up disk...
Sep 15 20:34:00 plug01 kernel: sd 3:0:0:0: Attached scsi generic sg2 type 0
Sep 15 20:34:13 plug01 kernel: .........ready
Sep 15 20:34:13 plug01 kernel: sd 3:0:0:0: [sdc] 1220942645 4096-byte logical blocks: (5.00 TB/4.54 TiB)
Sep 15 20:34:13 plug01 kernel: sd 3:0:0:0: [sdc] Write Protect is off
Sep 15 20:34:13 plug01 kernel: sd 3:0:0:0: [sdc] Mode Sense: 2b 00 10 08
Sep 15 20:34:13 plug01 kernel: sd 3:0:0:0: [sdc] Write cache: enabled, read cache: enabled, supports DPO and FUA
Sep 15 20:34:44 plug01 kernel: sd 3:0:0:0: uas_eh_abort_handler c7bf22a0 tag 0, inflight: IN')
Palmore
 
Posts: 10
Joined: Sun Sep 14, 2014 7:57 pm

Re: PogoPlug E02 won't boot after latest pacman update

Postby bodhi » Tue Sep 16, 2014 1:25 am

Does this Expansion Desk HDD have USB3.0 connection or USB2.0?
bodhi
 
Posts: 225
Joined: Sat Aug 13, 2011 10:06 am

Re: PogoPlug E02 won't boot after latest pacman update

Postby rhester72 » Tue Sep 16, 2014 4:54 am

Not sure who you were asking - mine is USB 3.0.

Rodney
rhester72
 
Posts: 35
Joined: Tue Apr 30, 2013 4:24 pm

Re: PogoPlug E02 won't boot after latest pacman update

Postby bodhi » Tue Sep 16, 2014 6:03 am

$this->bbcode_second_pass_quote('rhester72', 'N')ot sure who you were asking - mine is USB 3.0.

Rodney


Hi Rodney,

It was for both of you. I suspected that it has something to do with USB 3.0. To test my theory, use a USB 3.0 hub, plug that into the Pogo E02 and then plug in the Seagate HDD to the hub. It will downspeed the HDD to USB 2.0, of course. Or if you don't have USB 3.0 hub, but have a USB 2.0 base/enclosesure for the HDD, use that. Just to to see if you still have a kernel panic so we can narrow down the problem the possible cause by USB 3.0 interface.
bodhi
 
Posts: 225
Joined: Sat Aug 13, 2011 10:06 am

Re: PogoPlug E02 won't boot after latest pacman update

Postby Palmore » Tue Sep 16, 2014 2:52 pm

Mine is USB 3.0 as well.

I will try the USB 2.0 hub tonight when I get home and report back my findings.

Thank you.
Palmore
 
Posts: 10
Joined: Sun Sep 14, 2014 7:57 pm

Re: PogoPlug E02 won't boot after latest pacman update

Postby Palmore » Wed Sep 17, 2014 12:26 am

Ok, I just tested with a USB hub I had sitting around, it was a USB 3.0 HUB but had a USB 2.0 port on it.

I connected my Seagate Expansion 5TB USB 3.0 drive to one of the USB 2.0 ports, here is my log

You can also see my USB hub connect and initialize

$this->bbcode_second_pass_code('', 'Sep 16 20:05:37 plug01 kernel: usb 2-2: new high-speed USB device number 2 using xhci_hcd
Sep 16 20:05:38 plug01 kernel: hub 2-2:1.0: USB hub found
Sep 16 20:05:38 plug01 kernel: hub 2-2:1.0: 4 ports detected
Sep 16 20:05:38 plug01 kernel: usb 3-2: new SuperSpeed USB device number 3 using xhci_hcd
Sep 16 20:05:39 plug01 kernel: hub 3-2:1.0: USB hub found
Sep 16 20:05:39 plug01 kernel: hub 3-2:1.0: 4 ports detected
Sep 16 20:05:39 plug01 kernel: usb 2-2.4: new high-speed USB device number 3 using xhci_hcd
Sep 16 20:05:39 plug01 kernel: hub 2-2.4:1.0: USB hub found
Sep 16 20:05:39 plug01 kernel: hub 2-2.4:1.0: 4 ports detected
Sep 16 20:05:54 plug01 kernel: usb 2-2.2: new high-speed USB device number 4 using xhci_hcd
Sep 16 20:05:55 plug01 kernel: scsi3 : uas
Sep 16 20:05:55 plug01 kernel: ------------[ cut here ]------------
Sep 16 20:05:55 plug01 kernel: WARNING: CPU: 0 PID: 21 at drivers/usb/storage/uas.c:625 uas_submit_urbs.constprop.23+0x560/0x59c [uas]()
Sep 16 20:05:55 plug01 kernel: Modules linked in: uas mv_cesa ipv6
Sep 16 20:05:55 plug01 kernel: CPU: 0 PID: 21 Comm: khubd Not tainted 3.16.2-1-ARCH #1
Sep 16 20:05:55 plug01 kernel: [<c000f040>] (unwind_backtrace) from [<c000c588>] (show_stack+0x10/0x14)
Sep 16 20:05:55 plug01 kernel: [<c000c588>] (show_stack) from [<c001b7bc>] (warn_slowpath_common+0x70/0x90)
Sep 16 20:05:55 plug01 kernel: [<c001b7bc>] (warn_slowpath_common) from [<c001b878>] (warn_slowpath_null+0x1c/0x24)
Sep 16 20:05:55 plug01 kernel: [<c001b878>] (warn_slowpath_null) from [<bf06d1e8>] (uas_submit_urbs.constprop.23+0x560/0x59c [uas])
Sep 16 20:05:55 plug01 kernel: [<bf06d1e8>] (uas_submit_urbs.constprop.23 [uas]) from [<bf06d390>] (uas_queuecommand+0x16c/0x22c [uas])
Sep 16 20:05:55 plug01 kernel: [<bf06d390>] (uas_queuecommand [uas]) from [<c0365eec>] (scsi_dispatch_cmd+0x98/0x230)
Sep 16 20:05:55 plug01 kernel: [<c0365eec>] (scsi_dispatch_cmd) from [<c036d0fc>] (scsi_request_fn+0x2e4/0x514)
Sep 16 20:05:55 plug01 kernel: [<c036d0fc>] (scsi_request_fn) from [<c02a6874>] (__blk_run_queue+0x34/0x44)
Sep 16 20:05:55 plug01 kernel: [<c02a6874>] (__blk_run_queue) from [<c02ae198>] (blk_execute_rq_nowait+0xa0/0x148)
Sep 16 20:05:55 plug01 kernel: [<c02ae198>] (blk_execute_rq_nowait) from [<c02ae298>] (blk_execute_rq+0x58/0xe4)
Sep 16 20:05:55 plug01 kernel: [<c02ae298>] (blk_execute_rq) from [<c036b668>] (scsi_execute+0xb8/0x138)
Sep 16 20:05:55 plug01 kernel: [<c036b668>] (scsi_execute) from [<c036b76c>] (scsi_execute_req_flags+0x84/0xf8)
Sep 16 20:05:55 plug01 kernel: [<c036b76c>] (scsi_execute_req_flags) from [<c036eb00>] (scsi_probe_and_add_lun+0x178/0x9c8)
Sep 16 20:05:55 plug01 kernel: [<c036eb00>] (scsi_probe_and_add_lun) from [<c036f734>] (__scsi_scan_target+0xdc/0x564)
Sep 16 20:05:55 plug01 kernel: [<c036f734>] (__scsi_scan_target) from [<c036fe38>] (scsi_scan_host_selected+0x108/0x1dc)
Sep 16 20:05:55 plug01 kernel: [<c036fe38>] (scsi_scan_host_selected) from [<c036ff90>] (do_scsi_scan_host+0x84/0x8c)
Sep 16 20:05:55 plug01 kernel: [<c036ff90>] (do_scsi_scan_host) from [<c0370110>] (scsi_scan_host+0x178/0x1e0)
Sep 16 20:05:55 plug01 kernel: [<c0370110>] (scsi_scan_host) from [<bf06c4a8>] (uas_probe+0x278/0x2a8 [uas])
Sep 16 20:05:55 plug01 kernel: [<bf06c4a8>] (uas_probe [uas]) from [<c03ec0d0>] (usb_probe_interface+0x16c/0x21c)
Sep 16 20:05:55 plug01 kernel: [<c03ec0d0>] (usb_probe_interface) from [<c034825c>] (driver_probe_device+0x110/0x244)
Sep 16 20:05:55 plug01 kernel: [<c034825c>] (driver_probe_device) from [<c0346934>] (bus_for_each_drv+0x5c/0x8c)
Sep 16 20:05:55 plug01 kernel: [<c0346934>] (bus_for_each_drv) from [<c0348118>] (device_attach+0x70/0x88)
Sep 16 20:05:55 plug01 kernel: [<c0348118>] (device_attach) from [<c0347800>] (bus_probe_device+0x84/0xb0)
Sep 16 20:05:55 plug01 kernel: [<c0347800>] (bus_probe_device) from [<c0345cac>] (device_add+0x420/0x51c)
Sep 16 20:05:55 plug01 kernel: [<c0345cac>] (device_add) from [<c03ea234>] (usb_set_configuration+0x540/0x774)
Sep 16 20:05:55 plug01 kernel: [<c03ea234>] (usb_set_configuration) from [<c03f36c0>] (generic_probe+0x2c/0x78)
Sep 16 20:05:55 plug01 kernel: [<c03f36c0>] (generic_probe) from [<c03ebf4c>] (usb_probe_device+0x24/0x3c)
Sep 16 20:05:55 plug01 kernel: [<c03ebf4c>] (usb_probe_device) from [<c034825c>] (driver_probe_device+0x110/0x244)
Sep 16 20:05:55 plug01 kernel: [<c034825c>] (driver_probe_device) from [<c0346934>] (bus_for_each_drv+0x5c/0x8c)
Sep 16 20:05:55 plug01 kernel: [<c0346934>] (bus_for_each_drv) from [<c0348118>] (device_attach+0x70/0x88)
Sep 16 20:05:55 plug01 kernel: [<c0348118>] (device_attach) from [<c0347800>] (bus_probe_device+0x84/0xb0)
Sep 16 20:05:55 plug01 kernel: [<c0347800>] (bus_probe_device) from [<c0345cac>] (device_add+0x420/0x51c)
Sep 16 20:05:55 plug01 kernel: [<c0345cac>] (device_add) from [<c03e143c>] (usb_new_device+0x1d0/0x354)
Sep 16 20:05:55 plug01 kernel: [<c03e143c>] (usb_new_device) from [<c03e2cd0>] (hub_thread+0xd88/0x1264)
Sep 16 20:05:55 plug01 kernel: [<c03e2cd0>] (hub_thread) from [<c0039fd4>] (kthread+0xbc/0xd8)
Sep 16 20:05:55 plug01 kernel: [<c0039fd4>] (kthread) from [<c0009590>] (ret_from_fork+0x14/0x24)
Sep 16 20:05:55 plug01 kernel: ---[ end trace 50ee2f96a1337248 ]---
Sep 16 20:05:55 plug01 kernel: ------------[ cut here ]------------
Sep 16 20:05:55 plug01 kernel: WARNING: CPU: 0 PID: 86 at drivers/usb/storage/uas.c:286 uas_try_complete.isra.13+0x10c/0x12c [uas]()
Sep 16 20:05:55 plug01 kernel: Modules linked in: uas mv_cesa ipv6
Sep 16 20:05:55 plug01 kernel: CPU: 0 PID: 86 Comm: systemd-journal Tainted: G W 3.16.2-1-ARCH #1
Sep 16 20:05:55 plug01 kernel: [<c000f040>] (unwind_backtrace) from [<c000c588>] (show_stack+0x10/0x14)
Sep 16 20:05:55 plug01 kernel: [<c000c588>] (show_stack) from [<c001b7bc>] (warn_slowpath_common+0x70/0x90)
Sep 16 20:05:55 plug01 kernel: [<c001b7bc>] (warn_slowpath_common) from [<c001b878>] (warn_slowpath_null+0x1c/0x24)
Sep 16 20:05:55 plug01 kernel: [<c001b878>] (warn_slowpath_null) from [<bf06bd34>] (uas_try_complete.isra.13+0x10c/0x12c [uas])
Sep 16 20:05:55 plug01 kernel: [<bf06bd34>] (uas_try_complete.isra.13 [uas]) from [<bf06c034>] (uas_data_cmplt+0x54/0x1b4 [uas])
Sep 16 20:05:55 plug01 kernel: [<bf06c034>] (uas_data_cmplt [uas]) from [<c03e4ba4>] (__usb_hcd_giveback_urb+0x60/0xd8)
Sep 16 20:05:55 plug01 kernel: [<c03e4ba4>] (__usb_hcd_giveback_urb) from [<c040eb0c>] (xhci_irq+0x730/0x152c)
Sep 16 20:05:55 plug01 kernel: [<c040eb0c>] (xhci_irq) from [<c03e4138>] (usb_hcd_irq+0x24/0x38)
Sep 16 20:05:55 plug01 kernel: [<c03e4138>] (usb_hcd_irq) from [<c0059440>] (handle_irq_event_percpu+0x34/0x248)
Sep 16 20:05:55 plug01 kernel: [<c0059440>] (handle_irq_event_percpu) from [<c00596a4>] (handle_irq_event+0x50/0x84)
Sep 16 20:05:55 plug01 kernel: [<c00596a4>] (handle_irq_event) from [<c005bd18>] (handle_level_irq+0x98/0x16c)
Sep 16 20:05:55 plug01 kernel: [<c005bd18>] (handle_level_irq) from [<c0058d58>] (generic_handle_irq+0x20/0x34)
Sep 16 20:05:55 plug01 kernel: [<c0058d58>] (generic_handle_irq) from [<c0009db0>] (handle_IRQ+0x44/0xb0)
Sep 16 20:05:55 plug01 kernel: [<c0009db0>] (handle_IRQ) from [<c0531074>] (__irq_svc+0x34/0x98)
Sep 16 20:05:55 plug01 kernel: [<c0531074>] (__irq_svc) from [<c00271fc>] (run_timer_softirq+0x250/0x3d8)
Sep 16 20:05:55 plug01 kernel: [<c00271fc>] (run_timer_softirq) from [<c001fd88>] (__do_softirq+0xd4/0x2f8)
Sep 16 20:05:55 plug01 kernel: [<c001fd88>] (__do_softirq) from [<c002024c>] (irq_exit+0xb0/0xf8)
Sep 16 20:05:55 plug01 kernel: [<c002024c>] (irq_exit) from [<c0009db4>] (handle_IRQ+0x48/0xb0)
Sep 16 20:05:55 plug01 kernel: [<c0009db4>] (handle_IRQ) from [<c0531258>] (__irq_usr+0x38/0xa0)
Sep 16 20:05:55 plug01 kernel: ---[ end trace 50ee2f96a1337249 ]---
Sep 16 20:05:55 plug01 kernel: scsi 3:0:0:0: Direct-Access Seagate Expansion Desk 0739 PQ: 0 ANSI: 6
Sep 16 20:05:55 plug01 kernel: sd 3:0:0:0: [sdc] Spinning up disk...
Sep 16 20:05:55 plug01 kernel: sd 3:0:0:0: Attached scsi generic sg2 type 0
Sep 16 20:06:09 plug01 kernel: .........ready
Sep 16 20:06:09 plug01 kernel: sd 3:0:0:0: [sdc] 1220942645 4096-byte logical blocks: (5.00 TB/4.54 TiB)
Sep 16 20:06:09 plug01 kernel: sd 3:0:0:0: [sdc] Write Protect is off
Sep 16 20:06:09 plug01 kernel: sd 3:0:0:0: [sdc] Mode Sense: 2b 00 10 08
Sep 16 20:06:09 plug01 kernel: sd 3:0:0:0: [sdc] Write cache: enabled, read cache: enabled, supports DPO and FUA
Sep 16 20:06:09 plug01 kernel: sd 3:0:0:0: [sdc] 1220942645 4096-byte logical blocks: (5.00 TB/4.54 TiB)
Sep 16 20:06:09 plug01 kernel: sdc: sdc1
Sep 16 20:06:09 plug01 kernel: sd 3:0:0:0: [sdc] 1220942645 4096-byte logical blocks: (5.00 TB/4.54 TiB)
Sep 16 20:06:09 plug01 kernel: sd 3:0:0:0: [sdc] Attached SCSI disk
Sep 16 20:06:09 plug01 devmon[129]: ===========================================
Sep 16 20:06:09 plug01 devmon[129]: Monitoring activity from the disks daemon. Press Ctrl+C to cancel.
Sep 16 20:06:09 plug01 devmon[129]: ===========================================
Sep 16 20:06:09 plug01 devmon[129]: added: /org/freedesktop/UDisks/devices/sdc
Sep 16 20:06:10 plug01 devmon[129]: ===========================================
Sep 16 20:06:10 plug01 devmon[129]: added: /org/freedesktop/UDisks/devices/sdc1
Sep 16 20:06:10 plug01 devmon[129]: device: [/dev/sdc1]
Sep 16 20:06:10 plug01 devmon[129]: systeminternal: [0]
Sep 16 20:06:10 plug01 devmon[129]: usage: [filesystem]
Sep 16 20:06:10 plug01 devmon[129]: type: [ntfs]
Sep 16 20:06:10 plug01 devmon[129]: label: [5TB]
Sep 16 20:06:10 plug01 devmon[129]: ismounted: [0]
Sep 16 20:06:10 plug01 devmon[129]: nopolicy: [0]
Sep 16 20:06:10 plug01 devmon[129]: hasmedia: [1]
Sep 16 20:06:10 plug01 devmon[129]: opticaldisc: []
Sep 16 20:06:10 plug01 devmon[129]: numaudiotracks: []
Sep 16 20:06:10 plug01 devmon[129]: blank: []
Sep 16 20:06:10 plug01 devmon[129]: media: []
Sep 16 20:06:10 plug01 devmon[129]: partition: [1]
Sep 16 20:06:10 plug01 devmon[129]: devmon: /usr/sbin/udevil --mount /dev/sdc1 --mount-options "noexec,nosuid,nodev,noatime" # (5TB)
Sep 16 20:06:14 plug01 ntfs-3g[6530]: Version 2014.2.15 external FUSE 29
Sep 16 20:06:14 plug01 ntfs-3g[6530]: Mounted /dev/sdc1 (Read-Write, label "5TB", NTFS 3.1)
Sep 16 20:06:14 plug01 ntfs-3g[6530]: Cmdline options: rw,nosuid,noexec,nodev,noatime,fmask=0133,big_writes,uid=0,gid=0,utf8
Sep 16 20:06:14 plug01 ntfs-3g[6530]: Mount options: rw,nosuid,noexec,nodev,utf8,allow_other,nonempty,noatime,default_permissions,fsname=/dev/sdc1,blkdev,blksize=4096
Sep 16 20:06:14 plug01 ntfs-3g[6530]: Global ownership and permissions enforced, configuration type 7
Sep 16 20:06:15 plug01 devmon[129]: Mounted /dev/sdc1 at /media/5TB
Sep 16 20:06:15 plug01 devmon[129]: ===========================================
Sep 16 20:06:15 plug01 devmon[129]: changed: /org/freedesktop/UDisks/devices/sdc1
Sep 16 20:06:15 plug01 devmon[129]: device: [/dev/sdc1]
Sep 16 20:06:15 plug01 devmon[129]: systeminternal: [0]
Sep 16 20:06:15 plug01 devmon[129]: usage: [filesystem]
Sep 16 20:06:15 plug01 devmon[129]: type: [ntfs]
Sep 16 20:06:15 plug01 devmon[129]: label: [5TB]
Sep 16 20:06:15 plug01 devmon[129]: ismounted: [1]
Sep 16 20:06:15 plug01 devmon[129]: nopolicy: [0]
Sep 16 20:06:15 plug01 devmon[129]: hasmedia: [1]
Sep 16 20:06:15 plug01 devmon[129]: opticaldisc: []
Sep 16 20:06:15 plug01 devmon[129]: numaudiotracks: []
Sep 16 20:06:15 plug01 devmon[129]: blank: []
Sep 16 20:06:15 plug01 devmon[129]: media: []
Sep 16 20:06:15 plug01 devmon[129]: partition: [1]')

Everything worked, the drive was online and accessible.
So, why not plug into one of the USB 3.0 ports on the HUB for fun?

$this->bbcode_second_pass_code('', 'Sep 16 20:24:00 plug01 sshd[333]: pam_unix(sshd:session): session opened for user root by (uid=0)
Sep 16 20:24:00 plug01 systemd-logind[147]: New session c2 of user root.
Sep 16 20:24:00 plug01 systemd[1]: Starting Session c2 of user root.
Sep 16 20:24:00 plug01 systemd[1]: Started Session c2 of user root.
Sep 16 20:24:37 plug01 kernel: usb 2-2.2: USB disconnect, device number 3
Sep 16 20:24:38 plug01 kernel: sd 3:0:0:0: [sdc] Synchronizing SCSI cache
Sep 16 20:24:38 plug01 kernel: sd 3:0:0:0: [sdc]
Sep 16 20:24:38 plug01 kernel: Result: hostbyte=0x07 driverbyte=0x00
Sep 16 20:24:38 plug01 devmon[121]: ===========================================
Sep 16 20:24:38 plug01 devmon[121]: Monitoring activity from the disks daemon. Press Ctrl+C to cancel.
Sep 16 20:24:38 plug01 devmon[121]: ===========================================
Sep 16 20:24:38 plug01 devmon[121]: removed: /org/freedesktop/UDisks/devices/sdc1
Sep 16 20:24:38 plug01 ntfs-3g[296]: Unmounting /dev/sdc1 (5TB)
Sep 16 20:24:38 plug01 ntfs-3g[296]: Failed to sync device /dev/sdc1: No such device
Sep 16 20:24:38 plug01 ntfs-3g[296]: Failed to close volume /dev/sdc1: No such device
Sep 16 20:24:38 plug01 devmon[121]: devmon: /usr/sbin/udevil --unmount /dev/sdc1
Sep 16 20:24:38 plug01 devmon[121]: udevil: success running umount as current user
Sep 16 20:24:38 plug01 devmon[121]: ===========================================
Sep 16 20:24:38 plug01 devmon[121]: removed: /org/freedesktop/UDisks/devices/sdc
Sep 16 20:24:38 plug01 devmon[121]: devmon: /usr/sbin/udevil clean
Sep 16 20:24:44 plug01 kernel: usb 3-2.3: new SuperSpeed USB device number 4 using xhci_hcd
Sep 16 20:24:44 plug01 kernel: scsi4 : uas
Sep 16 20:24:44 plug01 kernel: scsi 4:0:0:0: Direct-Access Seagate Expansion Desk 0739 PQ: 0 ANSI: 6
Sep 16 20:24:44 plug01 kernel: sd 4:0:0:0: [sdc] Spinning up disk...
Sep 16 20:24:44 plug01 kernel: sd 4:0:0:0: Attached scsi generic sg2 type 0
Sep 16 20:24:50 plug01 kernel: ..ready
Sep 16 20:24:50 plug01 kernel: sd 4:0:0:0: [sdc] 1220942645 4096-byte logical blocks: (5.00 TB/4.54 TiB)
Sep 16 20:24:50 plug01 kernel: sd 4:0:0:0: [sdc] Write Protect is off
Sep 16 20:24:50 plug01 kernel: sd 4:0:0:0: [sdc] Mode Sense: 2b 00 10 08
Sep 16 20:24:50 plug01 kernel: sd 4:0:0:0: [sdc] Write cache: enabled, read cache: enabled, supports DPO and FUA
Sep 16 20:25:21 plug01 kernel: sd 4:0:0:0: uas_eh_abort_handler c71a7380 tag 0, inflight: IN
')

Boom, Device crashes and is unresponsive.
Palmore
 
Posts: 10
Joined: Sun Sep 14, 2014 7:57 pm

Next

Return to Marvell Kirkwood

Who is online

Users browsing this forum: No registered users and 11 guests