PogoPlug E02 boot issue with USB on latest Kernel/Systemd

[nathanh]

Ok. So I'm stuck on this one. On the latest Kernel 4.0.1-1 and latest Systemd I can't seem to get past boot when I have more than the "rootfs" usb plugged in the pogoplug. That is, if I try connect a second usb drive before booting up...boot never completes and ends at the same line:
$this->bbcode_second_pass_code('', '[ 38.794659] random: nonblocking pool is initialized')

If I remove all other USB drives from the pogoplug, and just leave the single rootfs usb drive...I can boot successfully and ssh into the device. It's only when I try to connect any other usb devices that boot fails---at the same point every time. I've commented out everything in /etc/fstab and its still the same result.

The full output from captured netconsole output is as follows:
$this->bbcode_second_pass_code('', 'U-Boot 2011.12 (Feb 20 2012 - 21:21:59)
Pogoplug E02
arm-none-linux-gnueabi-gcc (Sourcery G++ Lite 2009q3-67) 4.4.1
GNU ld (Sourcery G++ Lite 2009q3-67) 2.19.51.20090709
Hit any key to stop autoboot: 0
(Re)start USB...
USB: Register 10011 NbrPorts 1
USB EHCI 1.00
scanning bus for devices... 4 USB Device(s) found
scanning bus for storage devices... 2 Storage Device(s) found
Loading file "/rescueme" from usb device 0:1 (usbda1)
** File not found /rescueme
reading /rescueme.txt

** Unable to read "/rescueme.txt" from usb 0:1 **
Creating 1 MTD partitions on "nand0":
0x000002500000-0x000008000000 : "mtd=3"
UBI: attaching mtd1 to ubi0
UBI: physical eraseblock size: 131072 bytes (128 KiB)
UBI: logical eraseblock size: 129024 bytes
UBI: smallest flash I/O unit: 2048
UBI: sub-page size: 512
UBI: VID header offset: 512 (aligned 512)
UBI: data offset: 2048
UBI: attached mtd1 to ubi0
UBI: MTD device name: "mtd=3"
UBI: MTD device size: 91 MiB
UBI: number of good PEBs: 727
UBI: number of bad PEBs: 1
UBI: max. allowed volumes: 128
UBI: wear-leveling threshold: 4096
UBI: number of internal volumes: 1
UBI: number of user volumes: 0
UBI: available PEBs: 716
UBI: total number of reserved PEBs: 11
UBI: number of PEBs reserved for bad PEB handling: 7
UBI: max/mean erase counter: 1/1
UBIFS error (pid 0): ubifs_get_sb: cannot open "ubi:rootfs", error -19
Error reading superblock on volume 'ubi:rootfs'!
Loading file "/boot/uImage" from usb device 0:1 (usbda1)
1 bytes read
Found bootable drive on usb 0:1
Loading file "/boot/uImage" from usb device 0:1 (usbda1)
4073296 bytes read
Loading file "/boot/uInitrd" from usb device 0:1 (usbda1)
2581465 bytes read
## Booting kernel from Legacy Image at 00800000 ...
Image Name: Linux-4.0.1-1-ARCH
Image Type: ARM Linux Kernel Image (uncompressed)
Data Size: 4073232 Bytes = 3.9 MiB
Load Address: 00008000
Entry Point: 00008000
Verifying Checksum ... OK
## Loading init Ramdisk from Legacy Image at 01100000 ...
Image Name: uInitrd-4.0.1-1-ARCH
Image Type: ARM Linux RAMDisk Image (gzip compressed)
Data Size: 2581401 Bytes = 2.5 MiB
Load Address: 00000000
Entry Point: 00000000
Verifying Checksum ... OK
Loading Kernel Image ... OK
OK
Using machid 0xdd6 from environment

Starting kernel ...

[ 0.000000] Memory: 248236K/262144K available (5751K kernel code, 331K rwdata, 1756K rodata, 264K init, 698K bss, 13908K reserved, 0K cma-reserved)
[ 0.000000] Virtual kernel memory layout:
[ 0.000000] vector : 0xffff0000 - 0xffff1000 ( 4 kB)
[ 0.000000] fixmap : 0xffc00000 - 0xfff00000 (3072 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 - 0xc075d0f0 (7509 kB)
[ 0.000000] .init : 0xc075e000 - 0xc07a0000 ( 264 kB)
[ 0.000000] .data : 0xc07a0000 - 0xc07f2ec4 ( 332 kB)
[ 0.000000] .bss : 0xc07f2ec4 - 0xc08a1994 ( 699 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.000014] sched_clock: 32 bits at 200MHz, resolution 5ns, wraps every 21474836475ns
[ 3.740586] Console: colour dummy device 80x30
[ 3.740613] Calibrating delay loop... 1191.11 BogoMIPS (lpj=5955584)
[ 3.830484] pid_max: default: 32768 minimum: 301
[ 3.830572] Security Framework initialized
[ 3.830657] Mount-cache hash table entries: 1024 (order: 0, 4096 bytes)
[ 3.830678] Mountpoint-cache hash table entries: 1024 (order: 0, 4096 bytes)
[ 3.831372] Initializing cgroup subsys blkio
[ 3.831404] Initializing cgroup subsys memory
[ 3.831467] Initializing cgroup subsys devices
[ 3.831497] Initializing cgroup subsys freezer
[ 3.831518] Initializing cgroup subsys net_cls
[ 3.831537] Initializing cgroup subsys bfqio
[ 3.831555] Initializing cgroup subsys perf_event
[ 3.831576] Initializing cgroup subsys net_prio
[ 3.831667] CPU: Testing write buffer coherency: ok
[ 3.832111] Setting up static identity map for 0x57ac30 - 0x57ac88
[ 3.834012] devtmpfs: initialized
[ 3.836853] pinctrl core: initialized pinctrl subsystem
[ 3.837554] NET: Registered protocol family 16
[ 3.838069] DMA: preallocated 256 KiB pool for atomic coherent allocations
[ 3.860490] cpuidle: using governor ladder
[ 3.890489] cpuidle: using governor menu
[ 3.890691] Kirkwood: MV88F6281-A0, TCLK=200000000.
[ 3.890717] Feroceon L2: Enabling L2
[ 3.890757] Feroceon L2: Cache support initialised.
[ 3.891504] initial MPP regs: 01111111 11113322 00001111 00100000 00000000 00000000 00000000
[ 3.891533] final MPP regs: 01111111 11113322 00001111 00000000 00000000 00000000 00000000
[ 3.921978] vgaarb: loaded
[ 3.922295] SCSI subsystem initialized
[ 3.922476] libata version 3.00 loaded.
[ 3.922818] usbcore: registered new interface driver usbfs
[ 3.922895] usbcore: registered new interface driver hub
[ 3.922956] usbcore: registered new device driver usb
[ 3.923119] pps_core: LinuxPPS API ver. 1 registered
[ 3.923128] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
[ 3.923163] PTP clock support registered
[ 3.924243] Switched to clocksource orion_clocksource
[ 3.941629] FS-Cache: Loaded
[ 3.954733] NET: Registered protocol family 2
[ 3.955613] TCP established hash table entries: 2048 (order: 1, 8192 bytes)
[ 3.955652] TCP bind hash table entries: 2048 (order: 1, 8192 bytes)
[ 3.955683] TCP: Hash tables configured (established 2048 bind 2048)
[ 3.955749] TCP: reno registered
[ 3.955762] UDP hash table entries: 256 (order: 0, 4096 bytes)
[ 3.955784] UDP-Lite hash table entries: 256 (order: 0, 4096 bytes)
[ 3.955973] NET: Registered protocol family 1
[ 3.956028] PCI: CLS 0 bytes, default 32
[ 3.956286] Trying to unpack rootfs image as initramfs...
[ 4.161611] Freeing initrd memory: 2524K (c1100000 - c1377000)
[ 4.163146] futex hash table entries: 256 (order: -1, 3072 bytes)
[ 4.175977] VFS: Disk quotas dquot_6.5.2
[ 4.176251] VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
[ 4.178065] jffs2: version 2.2. (NAND) © 2001-2006 Red Hat, Inc.
[ 4.179138] fuse init (API version 7.23)
[ 4.181384] aufs 4.0-20150427
[ 4.183540] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250)
[ 4.183563] io scheduler noop registered
[ 4.183577] io scheduler deadline registered
[ 4.183854] io scheduler cfq registered
[ 4.184499] io scheduler bfq registered (default)
[ 4.184509] BFQ I/O-scheduler version: v7r7
[ 4.184732] mv_xor mv_xor.0: Marvell shared XOR driver
[ 4.224354] mv_xor mv_xor.0: Marvell XOR: ( xor cpy )
[ 4.264320] mv_xor mv_xor.0: Marvell XOR: ( xor cpy )
[ 4.264505] mv_xor mv_xor.1: Marvell shared XOR driver
[ 4.304318] mv_xor mv_xor.1: Marvell XOR: ( xor cpy )
[ 4.344316] mv_xor mv_xor.1: Marvell XOR: ( xor cpy )
[ 4.349776] Serial: 8250/16550 driver, 2 ports, IRQ sharing disabled
[ 4.350857] console [ttyS0] disabled
[ 4.370962] serial8250.0: ttyS0 at MMIO 0xf1012000 (irq = 33, base_baud = 12500000) is a 16550A
[ 4.957349] console [ttyS0] enabled
[ 4.974410] brd: module loaded
[ 4.983741] loop: module loaded
[ 4.988064] nand: Could not find valid ONFI parameter page; aborting
[ 4.994545] nand: device found, Manufacturer ID: 0xad, Chip ID: 0xf1
[ 5.000924] nand: Hynix NAND 128MiB 3,3V 8-bit
[ 5.005410] nand: 128 MiB, SLC, erase size: 128 KiB, page size: 2048, OOB size: 64
[ 5.013021] Scanning device for bad blocks
[ 5.021284] Bad eraseblock 54 at 0x0000006c0000
[ 5.037565] Bad eraseblock 209 at 0x000001a20000
[ 5.060219] Bad eraseblock 447 at 0x0000037e0000
[ 5.108559] 4 cmdlinepart partitions found on MTD device orion_nand
[ 5.114868] Creating 4 MTD partitions on "orion_nand":
[ 5.120037] 0x000000000000-0x000000100000 : "u-boot"
[ 5.125513] 0x000000100000-0x000000500000 : "uImage"
[ 5.130932] 0x000000500000-0x000002500000 : "rootfs"
[ 5.136416] 0x000002500000-0x000008000000 : "data"
[ 5.154562] libphy: orion_mdio_bus: probed
[ 5.158801] mv643xx_eth: MV-643xx 10/100/1000 ethernet driver version 1.4
[ 6.245298] mv643xx_eth_port mv643xx_eth_port.0 eth0: port 0 with MAC address 00:25:31:04:b1:36
[ 6.254282] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
[ 6.260848] ehci-pci: EHCI PCI platform driver
[ 6.265389] ehci-orion: EHCI orion driver
[ 6.269495] orion-ehci orion-ehci.0: EHCI Host Controller
[ 6.274966] orion-ehci orion-ehci.0: new USB bus registered, assigned bus number 1
[ 6.282805] orion-ehci orion-ehci.0: irq 19, io mem 0xf1050000
[ 6.304276] orion-ehci orion-ehci.0: USB 2.0 started, EHCI 1.00
[ 6.311162] hub 1-0:1.0: USB hub found
[ 6.315017] hub 1-0:1.0: 1 port detected
[ 6.319558] usbcore: registered new interface driver uas
[ 6.325081] usbcore: registered new interface driver usb-storage
[ 6.331170] usbcore: registered new interface driver ums-alauda
[ 6.337214] usbcore: registered new interface driver ums-cypress
[ 6.343297] usbcore: registered new interface driver ums-datafab
[ 6.349408] usbcore: registered new interface driver ums_eneub6250
[ 6.355692] usbcore: registered new interface driver ums-freecom
[ 6.361775] usbcore: registered new interface driver ums-isd200
[ 6.367796] usbcore: registered new interface driver ums-jumpshot
[ 6.373965] usbcore: registered new interface driver ums-karma
[ 6.379900] usbcore: registered new interface driver ums-onetouch
[ 6.386113] usbcore: registered new interface driver ums-realtek
[ 6.392207] usbcore: registered new interface driver ums-sddr09
[ 6.398237] usbcore: registered new interface driver ums-sddr55
[ 6.404234] usbcore: registered new interface driver ums-usbat
[ 6.410559] mousedev: PS/2 mouse device common for all mice
[ 6.634302] usb 1-1: new high-speed USB device number 2 using orion-ehci
[ 6.786803] hub 1-1:1.0: USB hub found
[ 6.790940] hub 1-1:1.0: 4 ports detected
[ 10.630141] console [netcon0] enabled
[ 10.633839] netconsole: network logging started
[ 10.638541] drivers/rtc/hctosys.c: unable to open rtc device (rtc0)
[ 10.646711] Freeing unused kernel memory: 264K (c075e000 - c07a0000)
[ 10.702576] random: systemd-tmpfile urandom read with 35 bits of entropy available
[ 38.794659] random: nonblocking pool is initialized
')

Here is my fw_printenv output:
$this->bbcode_second_pass_code('', '┌─[root@pogoplug]─[~]
└──> fw_printenv
ethact=egiga0
bootdelay=3
baudrate=115200
mainlineLinux=yes
console=ttyS0,115200
led_init=green blinking
led_exit=green off
led_error=orange blinking
mtdparts=mtdparts=orion_nand:1M(u-boot),4M(uImage),32M(rootfs),-(data)
mtdids=nand0=orion_nand
partition=nand0,2
stdin=serial
stdout=serial
stderr=serial
rescue_installed=0
rescue_set_bootargs=setenv bootargs console=$console ubi.mtd=2 root=ubi0:rootfs ro rootfstype=ubifs $mtdparts $rescue_custom_params
rescue_bootcmd=if test $rescue_installed -eq 1; then run rescue_set_bootargs; nand read.e 0x800000 0x100000 0x400000; bootm 0x800000; else run pogo_bootcmd; fi
pogo_bootcmd=if fsload uboot-original-mtd0.kwb; then go 0x800200; fi
force_rescue=0
force_rescue_bootcmd=if test $force_rescue -eq 1 || ext2load usb 0:1 0x1700000 /rescueme 1 || fatload usb 0:1 0x1700000 /rescueme.txt 1; then run rescue_bootcmd; fi
ubifs_mtd=3
ubifs_set_bootargs=setenv bootargs console=$console ubi.mtd=$ubifs_mtd root=ubi0:rootfs rootfstype=ubifs $mtdparts $ubifs_custom_params
ubifs_bootcmd=run ubifs_set_bootargs; if ubi part data && ubifsmount rootfs && ubifsload 0x800000 /boot/uImage && ubifsload 0x1100000 /boot/uInitrd; then bootm 0x800000 0x1100000; fi
usb_scan=usb_scan_done=0;for scan in $usb_scan_list; do run usb_scan_$scan; if test $usb_scan_done -eq 0 && ext2load usb $usb 0x800000 /boot/uImage 1; then usb_scan_done=1; echo "Found bootable drive on usb $usb"; setenv usb_device $usb; setenv usb_root /dev/$dev; fi; done
usb_scan_list=1 2 3 4
usb_scan_1=usb=0:1 dev=sda1
usb_scan_2=usb=1:1 dev=sdb1
usb_scan_3=usb=2:1 dev=sdc1
usb_scan_4=usb=3:1 dev=sdd1
usb_device=0:1
usb_root=/dev/sda1
usb_rootdelay=10
usb_set_bootargs=setenv bootargs console=$console root=$usb_root rootdelay=$usb_rootdelay rootfstype=$usb_rootfstype $mtdparts $usb_custom_params
usb_bootcmd=run usb_init; run usb_set_bootargs; run usb_boot
usb_boot=mw 0x800000 0 1; ext2load usb $usb_device 0x800000 /boot/uImage; if ext2load usb $usb_device 0x1100000 /boot/uInitrd; then bootm 0x800000 0x1100000; else bootm 0x800000; fi
bootcmd=usb start; run force_rescue_bootcmd; run ubifs_bootcmd; run usb_bootcmd; usb stop; run rescue_bootcmd; run pogo_bootcmd; reset
ethaddr=00:25:31:04:B1:36
arcNumber=3542
machid=dd6
usb_rootfstype=ext3
serverip=192.168.1.104
ipaddr=192.168.1.114
if_netconsole=ping $serverip
start_netconsole=setenv ncip $serverip; setenv bootdelay 10; setenv stdin nc; setenv stdout nc; setenv stderr nc; version;
preboot=run if_netconsole start_netconsole
usb_init=run usb_scan
usb_custom_params=ignore_loglevel netconsole=6666@192.168.1.114/eth0,6666@192.168.1.104/
')

Again, I can access the system with a successful boot only when the rootfs USB is attached alone. Multiple USB attachements result in the "random: nonblocking pool is initialized" error noted above.

Any ideas?

[nathanh]

Just for completeness. Here is a boot output with just the rootfs usb attached. After which I can SSH and access the system successfully:

$this->bbcode_second_pass_code('', 'U-Boot 2011.12 (Feb 20 2012 - 21:21:59)
Pogoplug E02
arm-none-linux-gnueabi-gcc (Sourcery G++ Lite 2009q3-67) 4.4.1
GNU ld (Sourcery G++ Lite 2009q3-67) 2.19.51.20090709
Hit any key to stop autoboot: 0
(Re)start USB...
USB: Register 10011 NbrPorts 1
USB EHCI 1.00
scanning bus for devices... 3 USB Device(s) found
scanning bus for storage devices... 1 Storage Device(s) found
Loading file "/rescueme" from usb device 0:1 (usbda1)
** File not found /rescueme
reading /rescueme.txt

** Unable to read "/rescueme.txt" from usb 0:1 **
Creating 1 MTD partitions on "nand0":
0x000002500000-0x000008000000 : "mtd=3"
UBI: attaching mtd1 to ubi0
UBI: physical eraseblock size: 131072 bytes (128 KiB)
UBI: logical eraseblock size: 129024 bytes
UBI: smallest flash I/O unit: 2048
UBI: sub-page size: 512
UBI: VID header offset: 512 (aligned 512)
UBI: data offset: 2048
UBI: attached mtd1 to ubi0
UBI: MTD device name: "mtd=3"
UBI: MTD device size: 91 MiB
UBI: number of good PEBs: 727
UBI: number of bad PEBs: 1
UBI: max. allowed volumes: 128
UBI: wear-leveling threshold: 4096
UBI: number of internal volumes: 1
UBI: number of user volumes: 0
UBI: available PEBs: 716
UBI: total number of reserved PEBs: 11
UBI: number of PEBs reserved for bad PEB handling: 7
UBI: max/mean erase counter: 1/1
UBIFS error (pid 0): ubifs_get_sb: cannot open "ubi:rootfs", error -19
Error reading superblock on volume 'ubi:rootfs'!
Loading file "/boot/uImage" from usb device 0:1 (usbda1)
1 bytes read
Found bootable drive on usb 0:1
Loading file "/boot/uImage" from usb device 0:1 (usbda1)
4073296 bytes read
Loading file "/boot/uInitrd" from usb device 0:1 (usbda1)
2581465 bytes read
## Booting kernel from Legacy Image at 00800000 ...
Image Name: Linux-4.0.1-1-ARCH
Image Type: ARM Linux Kernel Image (uncompressed)
Data Size: 4073232 Bytes = 3.9 MiB
Load Address: 00008000
Entry Point: 00008000
Verifying Checksum ... OK
## Loading init Ramdisk from Legacy Image at 01100000 ...
Image Name: uInitrd-4.0.1-1-ARCH
Image Type: ARM Linux RAMDisk Image (gzip compressed)
Data Size: 2581401 Bytes = 2.5 MiB
Load Address: 00000000
Entry Point: 00000000
Verifying Checksum ... OK
Loading Kernel Image ... OK
OK
Using machid 0xdd6 from environment

Starting kernel ...

[ 0.000000] PID hash table entries: 1024 (order: 0, 4096 bytes)
[ 0.000000] Dentry cache hash table entries: 32768 (order: 5, 131072 bytes)
[ 0.000000] Inode-cache hash table entries: 16384 (order: 4, 65536 bytes)
[ 0.000000] Memory: 248236K/262144K available (5751K kernel code, 331K rwdata, 1756K rodata, 264K init, 698K bss, 13908K reserved, 0K cma-reserved)
[ 0.000000] Virtual kernel memory layout:
[ 0.000000] vector : 0xffff0000 - 0xffff1000 ( 4 kB)
[ 0.000000] fixmap : 0xffc00000 - 0xfff00000 (3072 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 - 0xc075d0f0 (7509 kB)
[ 0.000000] .init : 0xc075e000 - 0xc07a0000 ( 264 kB)
[ 0.000000] .data : 0xc07a0000 - 0xc07f2ec4 ( 332 kB)
[ 0.000000] .bss : 0xc07f2ec4 - 0xc08a1994 ( 699 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.000012] sched_clock: 32 bits at 200MHz, resolution 5ns, wraps every 21474836475ns
[ 4.682354] Console: colour dummy device 80x30
[ 4.682382] Calibrating delay loop... 1191.11 BogoMIPS (lpj=5955584)
[ 4.772251] pid_max: default: 32768 minimum: 301
[ 4.772342] Security Framework initialized
[ 4.772427] Mount-cache hash table entries: 1024 (order: 0, 4096 bytes)
[ 4.772448] Mountpoint-cache hash table entries: 1024 (order: 0, 4096 bytes)
[ 4.773143] Initializing cgroup subsys blkio
[ 4.773174] Initializing cgroup subsys memory
[ 4.773237] Initializing cgroup subsys devices
[ 4.773264] Initializing cgroup subsys freezer
[ 4.773286] Initializing cgroup subsys net_cls
[ 4.773306] Initializing cgroup subsys bfqio
[ 4.773325] Initializing cgroup subsys perf_event
[ 4.773346] Initializing cgroup subsys net_prio
[ 4.773436] CPU: Testing write buffer coherency: ok
[ 4.773880] Setting up static identity map for 0x57ac30 - 0x57ac88
[ 4.775780] devtmpfs: initialized
[ 4.778623] pinctrl core: initialized pinctrl subsystem
[ 4.779323] NET: Registered protocol family 16
[ 4.779838] DMA: preallocated 256 KiB pool for atomic coherent allocations
[ 4.802256] cpuidle: using governor ladder
[ 4.832256] cpuidle: using governor menu
[ 4.832451] Kirkwood: MV88F6281-A0, TCLK=200000000.
[ 4.832477] Feroceon L2: Enabling L2
[ 4.832516] Feroceon L2: Cache support initialised.
[ 4.833263] initial MPP regs: 01111111 11113322 00001111 00100000 00000000 00000000 00000000
[ 4.833292] final MPP regs: 01111111 11113322 00001111 00000000 00000000 00000000 00000000
[ 4.863745] vgaarb: loaded
[ 4.864062] SCSI subsystem initialized
[ 4.864243] libata version 3.00 loaded.
[ 4.864585] usbcore: registered new interface driver usbfs
[ 4.864662] usbcore: registered new interface driver hub
[ 4.864722] usbcore: registered new device driver usb
[ 4.864885] pps_core: LinuxPPS API ver. 1 registered
[ 4.864895] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
[ 4.864929] PTP clock support registered
[ 4.866012] Switched to clocksource orion_clocksource
[ 4.883381] FS-Cache: Loaded
[ 4.896487] NET: Registered protocol family 2
[ 4.897367] TCP established hash table entries: 2048 (order: 1, 8192 bytes)
[ 4.897406] TCP bind hash table entries: 2048 (order: 1, 8192 bytes)
[ 4.897437] TCP: Hash tables configured (established 2048 bind 2048)
[ 4.897502] TCP: reno registered
[ 4.897516] UDP hash table entries: 256 (order: 0, 4096 bytes)
[ 4.897536] UDP-Lite hash table entries: 256 (order: 0, 4096 bytes)
[ 4.897729] NET: Registered protocol family 1
[ 4.897784] PCI: CLS 0 bytes, default 32
[ 4.898040] Trying to unpack rootfs image as initramfs...
[ 5.103380] Freeing initrd memory: 2524K (c1100000 - c1377000)
[ 5.104916] futex hash table entries: 256 (order: -1, 3072 bytes)
[ 5.117751] VFS: Disk quotas dquot_6.5.2
[ 5.118026] VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
[ 5.119835] jffs2: version 2.2. (NAND) © 2001-2006 Red Hat, Inc.
[ 5.120907] fuse init (API version 7.23)
[ 5.123155] aufs 4.0-20150427
[ 5.125312] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250)
[ 5.125335] io scheduler noop registered
[ 5.125349] io scheduler deadline registered
[ 5.125625] io scheduler cfq registered
[ 5.126270] io scheduler bfq registered (default)
[ 5.126281] BFQ I/O-scheduler version: v7r7
[ 5.126502] mv_xor mv_xor.0: Marvell shared XOR driver
[ 5.166123] mv_xor mv_xor.0: Marvell XOR: ( xor cpy )
[ 5.206089] mv_xor mv_xor.0: Marvell XOR: ( xor cpy )
[ 5.206276] mv_xor mv_xor.1: Marvell shared XOR driver
[ 5.246087] mv_xor mv_xor.1: Marvell XOR: ( xor cpy )
[ 5.286085] mv_xor mv_xor.1: Marvell XOR: ( xor cpy )
[ 5.291537] Serial: 8250/16550 driver, 2 ports, IRQ sharing disabled
[ 5.292615] console [ttyS0] disabled
[ 5.312721] serial8250.0: ttyS0 at MMIO 0xf1012000 (irq = 33, base_baud = 12500000) is a 16550A
[ 5.899085] console [ttyS0] enabled
[ 5.916134] brd: module loaded
[ 5.925462] loop: module loaded
[ 5.929783] nand: Could not find valid ONFI parameter page; aborting
[ 5.936273] nand: device found, Manufacturer ID: 0xad, Chip ID: 0xf1
[ 5.942651] nand: Hynix NAND 128MiB 3,3V 8-bit
[ 5.947137] nand: 128 MiB, SLC, erase size: 128 KiB, page size: 2048, OOB size: 64
[ 5.954748] Scanning device for bad blocks
[ 5.963012] Bad eraseblock 54 at 0x0000006c0000
[ 5.979291] Bad eraseblock 209 at 0x000001a20000
[ 6.001943] Bad eraseblock 447 at 0x0000037e0000
[ 6.050277] 4 cmdlinepart partitions found on MTD device orion_nand
[ 6.056585] Creating 4 MTD partitions on "orion_nand":
[ 6.061755] 0x000000000000-0x000000100000 : "u-boot"
[ 6.067229] 0x000000100000-0x000000500000 : "uImage"
[ 6.072641] 0x000000500000-0x000002500000 : "rootfs"
[ 6.078123] 0x000002500000-0x000008000000 : "data"
[ 6.096324] libphy: orion_mdio_bus: probed
[ 6.100563] mv643xx_eth: MV-643xx 10/100/1000 ethernet driver version 1.4
[ 7.187059] mv643xx_eth_port mv643xx_eth_port.0 eth0: port 0 with MAC address 00:25:31:04:b1:36
[ 7.196041] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
[ 7.202600] ehci-pci: EHCI PCI platform driver
[ 7.207142] ehci-orion: EHCI orion driver
[ 7.211248] orion-ehci orion-ehci.0: EHCI Host Controller
[ 7.216718] orion-ehci orion-ehci.0: new USB bus registered, assigned bus number 1
[ 7.224467] orion-ehci orion-ehci.0: irq 19, io mem 0xf1050000
[ 7.246045] orion-ehci orion-ehci.0: USB 2.0 started, EHCI 1.00
[ 7.252934] hub 1-0:1.0: USB hub found
[ 7.256788] hub 1-0:1.0: 1 port detected
[ 7.261328] usbcore: registered new interface driver uas
[ 7.266850] usbcore: registered new interface driver usb-storage
[ 7.272940] usbcore: registered new interface driver ums-alauda
[ 7.278986] usbcore: registered new interface driver ums-cypress
[ 7.285069] usbcore: registered new interface driver ums-datafab
[ 7.291177] usbcore: registered new interface driver ums_eneub6250
[ 7.297455] usbcore: registered new interface driver ums-freecom
[ 7.303536] usbcore: registered new interface driver ums-isd200
[ 7.309557] usbcore: registered new interface driver ums-jumpshot
[ 7.315726] usbcore: registered new interface driver ums-karma
[ 7.321660] usbcore: registered new interface driver ums-onetouch
[ 7.327875] usbcore: registered new interface driver ums-realtek
[ 7.333968] usbcore: registered new interface driver ums-sddr09
[ 7.340000] usbcore: registered new interface driver ums-sddr55
[ 7.345995] usbcore: registered new interface driver ums-usbat
[ 7.352324] mousedev: PS/2 mouse device common for all mice
[ 7.576071] usb 1-1: new high-speed USB device number 2 using orion-ehci
[ 7.728589] hub 1-1:1.0: USB hub found
[ 7.732729] hub 1-1:1.0: 4 ports detected
[ 11.221144] console [netcon0] enabled
[ 11.224845] netconsole: network logging started
[ 11.229546] drivers/rtc/hctosys.c: unable to open rtc device (rtc0)
[ 11.237707] Freeing unused kernel memory: 264K (c075e000 - c07a0000)
[ 11.294010] random: systemd-tmpfile urandom read with 24 bits of entropy available
[ 12.151148] EXT4-fs (sda1): mounting ext3 file system using the ext4 subsystem
[ 12.170289] EXT4-fs (sda1): mounted filesystem with ordered data mode. Opts: (null)
[ 12.674543] NET: Registered protocol family 10
[ 12.680343] systemd[1]: Inserted module 'ipv6'
[ 12.702886] systemd[1]: systemd 219 running in system mode. (+PAM -AUDIT -SELINUX -IMA -APPARMOR +SMACK -SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID -ELFUTILS +KMOD +IDN)
[ 12.721447] systemd[1]: Detected architecture 'arm'.
[ 12.755141] systemd[1]: Set hostname to <pogoplug>.
[ 13.130147] systemd[1]: Unit type .busname is not supported on this system.
[ 13.432935] systemd[1]: Cannot add dependency job for unit org.freedesktop.resolve1.busname, ignoring: Unit org.freedesktop.resolve1.busname failed to load: No such file or directory.
[ 13.449971] systemd[1]: Cannot add dependency job for unit ntpd.service, ignoring: Unit ntpd.service failed to load: No such file or directory.
[ 13.463871] systemd[1]: Cannot add dependency job for unit display-manager.service, ignoring: Unit display-manager.service failed to load: No such file or directory.
[ 13.506241] systemd[1]: Reached target Remote File Systems.
[ 13.512040] systemd[1]: Starting Remote File Systems.
[ 13.536178] systemd[1]: Reached target Encrypted Volumes.
[ 13.541764] systemd[1]: Starting Encrypted Volumes.
[ 13.566196] systemd[1]: Set up automount Arbitrary Executable File Formats File System Automount Point.
[ 13.575833] systemd[1]: Starting Arbitrary Executable File Formats File System Automount Point.
[ 13.585087] systemd[1]: Started Forward Password Requests to Wall Directory Watch.
[ 13.592910] systemd[1]: Starting Forward Password Requests to Wall Directory Watch.
[ 13.616191] systemd[1]: Created slice Root Slice.
[ 13.621090] systemd[1]: Starting Root Slice.
[ 13.651558] systemd[1]: Listening on Journal Audit Socket.
[ 13.676235] systemd[1]: Created slice User and Session Slice.
[ 13.682205] systemd[1]: Starting User and Session Slice.
[ 13.706188] systemd[1]: Listening on LVM2 metadata daemon socket.
[ 13.712475] systemd[1]: Starting LVM2 metadata daemon socket.
[ 13.736183] systemd[1]: Listening on udev Kernel Socket.
[ 13.741686] systemd[1]: Starting udev Kernel Socket.
[ 13.766180] systemd[1]: Listening on /dev/initctl Compatibility Named Pipe.
[ 13.773340] systemd[1]: Starting /dev/initctl Compatibility Named Pipe.
[ 13.796173] systemd[1]: Listening on Delayed Shutdown Socket.
[ 13.802102] systemd[1]: Starting Delayed Shutdown Socket.
[ 13.808009] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.
[ 13.816178] systemd[1]: Starting Dispatch Password Requests to Console Directory Watch.
[ 13.846163] systemd[1]: Reached target Paths.
[ 13.850697] systemd[1]: Starting Paths.
[ 13.876188] systemd[1]: Created slice System Slice.
[ 13.881319] systemd[1]: Starting System Slice.
[ 13.906192] systemd[1]: Created slice system-serial\x2dgetty.slice.
[ 13.912655] systemd[1]: Starting system-serial\x2dgetty.slice.
[ 13.936170] systemd[1]: Reached target Slices.
[ 13.940825] systemd[1]: Starting Slices.
[ 13.966183] systemd[1]: Listening on Journal Socket (/dev/log).
[ 13.972312] systemd[1]: Starting Journal Socket (/dev/log).
[ 13.996180] systemd[1]: Listening on Journal Socket.
[ 14.009733] systemd[1]: Mounting POSIX Message Queue File System...
[ 14.036821] systemd[1]: Mounted Huge Pages File System.
[ 14.072226] systemd[1]: Starting Load Kernel Modules...
[ 14.102834] systemd[1]: Mounting Debug File System...
[ 14.143269] systemd[1]: Mounting Temporary Directory...
[ 14.164898] systemd[1]: Starting Create list of required static device nodes for the current kernel...
[ 14.224984] RPC: Registered named UNIX socket transport module.
[ 14.235425] systemd[1]: Starting Setup Virtual Console...
[ 14.244829] RPC: Registered udp transport module.
[ 14.251174] RPC: Registered tcp transport module.
[ 14.256819] RPC: Registered tcp NFSv4.1 backchannel transport module.
[ 14.280663] systemd[1]: Starting Journal Service...
[ 14.302975] systemd[1]: Listening on udev Control Socket.
[ 14.316415] systemd[1]: Starting udev Control Socket.
[ 14.342470] systemd[1]: Starting udev Coldplug all Devices...
[ 14.351647] FS-Cache: Netfs 'nfs' registered for caching
[ 14.396296] systemd[1]: Created slice system-getty.slice.
[ 14.401970] systemd[1]: Starting system-getty.slice.
[ 14.426320] systemd[1]: Listening on Device-mapper event daemon FIFOs.
[ 14.437802] systemd[1]: Starting Device-mapper event daemon FIFOs.
[ 14.477631] systemd[1]: Starting File System Check on Root Device...
[ 14.528416] systemd[1]: Started Set Up Additional Binary Formats.
[ 14.541318] systemd[1]: Mounted POSIX Message Queue File System.
[ 14.549191] systemd[1]: Mounted Debug File System.
[ 14.555246] systemd[1]: Mounted Temporary Directory.
[ 14.574315] systemd[1]: Started Load Kernel Modules.
[ 14.591129] systemd[1]: Started Create list of required static device nodes for the current kernel.
[ 14.605445] systemd-journald[108]: Failed to set file attributes: Inappropriate ioctl for device
[ 14.629364] systemd[1]: Started Setup Virtual Console.
[ 14.916251] systemd[1]: Started File System Check on Root Device.
[ 14.936257] systemd[1]: Started udev Coldplug all Devices.
[ 15.016241] systemd[1]: Started Journal Service.
[ 15.336533] random: nonblocking pool is initialized
[ 15.387694] EXT4-fs (sda1): re-mounted. Opts: (null)
[ 15.901263] systemd-journald[108]: Received request to flush runtime journal from PID 1
[ 18.538965] systemd-journald[108]: Failed to open system journal: Invalid argument
[ 19.607654] systemd-journald[108]: Failed to set file attributes: Inappropriate ioctl for device
')

[nathanh]

If I then, AFTER a successful boot with just the rootfs usb attached, then attach my other USB drives--they mount correctly (this picks up from the preceding 2 lines of netconsole output directly above):

$this->bbcode_second_pass_code('', '[ 18.538965] systemd-journald[108]: Failed to open system journal: Invalid argument
[ 19.607654] systemd-journald[108]: Failed to set file attributes: Inappropriate ioctl for device
[ 1228.332363] usb 1-1.2: new full-speed USB device number 4 using orion-ehci
[ 1228.446351] usb-storage 1-1.2:1.0: USB Mass Storage device detected
[ 1228.455050] scsi host1: usb-storage 1-1.2:1.0
[ 1229.452825] scsi 1:0:0:0: Direct-Access USB 2.0 5.00 PQ: 0 ANSI: 2
[ 1229.469660] sd 1:0:0:0: [sdb] 125952 2048-byte logical blocks: (257 MB/246 MiB)
[ 1229.478308] sd 1:0:0:0: Attached scsi generic sg1 type 0
[ 1229.492664] sd 1:0:0:0: [sdb] Write Protect is off
[ 1229.497528] sd 1:0:0:0: [sdb] Mode Sense: 0b 00 00 08
[ 1229.508519] sd 1:0:0:0: [sdb] No Caching mode page found
[ 1229.515226] sd 1:0:0:0: [sdb] Assuming drive cache: write through
[ 1229.526145] sd 1:0:0:0: [sdb] 125952 2048-byte logical blocks: (257 MB/246 MiB)
[ 1229.545023] sdb: sdb1
[ 1229.556789] sd 1:0:0:0: [sdb] 125952 2048-byte logical blocks: (257 MB/246 MiB)
[ 1229.588288] sd 1:0:0:0: [sdb] Attached SCSI removable disk
[ 1230.930358] Adding 250876k swap on /dev/sdb1. Priority:2 extents:1 across:250876k
[ 1260.331487] usb 1-1.3: new high-speed USB device number 5 using orion-ehci
[ 1260.446111] usb-storage 1-1.3:1.0: USB Mass Storage device detected
[ 1260.454634] scsi host2: usb-storage 1-1.3:1.0
[ 1261.452961] scsi 2:0:0:0: Direct-Access ST1000DM 003-9YN162 0041 PQ: 0 ANSI: 0
[ 1261.469895] sd 2:0:0:0: [sdc] 1953525168 512-byte logical blocks: (1.00 TB/931 GiB)
[ 1261.480101] sd 2:0:0:0: Attached scsi generic sg2 type 0
[ 1261.496003] sd 2:0:0:0: [sdc] Write Protect is off
[ 1261.500899] sd 2:0:0:0: [sdc] Mode Sense: 03 00 00 00
[ 1261.509391] sd 2:0:0:0: [sdc] No Caching mode page found
[ 1261.516025] sd 2:0:0:0: [sdc] Assuming drive cache: write through
[ 1261.541102] sdc: sdc1
[ 1261.551018] sd 2:0:0:0: [sdc] Attached SCSI disk
[ 1261.820337] EXT4-fs (sdc1): mounting ext3 file system using the ext4 subsystem
[ 1261.969080] EXT4-fs (sdc1): mounted filesystem with ordered data mode. Opts: (null)
')

Here is "mount" output after attaching:
$this->bbcode_second_pass_code('', '┌─[root@pogoplug]─[~]
└──> mount
proc on /proc type proc (rw,nosuid,nodev,noexec,relatime)
sys on /sys type sysfs (rw,nosuid,nodev,noexec,relatime)
dev on /dev type devtmpfs (rw,nosuid,relatime,size=124116k,nr_inodes=31029,mode=755)
run on /run type tmpfs (rw,nosuid,nodev,relatime,mode=755)
/dev/sda1 on / type ext3 (rw,noatime,data=ordered)
securityfs on /sys/kernel/security type securityfs (rw,nosuid,nodev,noexec,relatime)
tmpfs on /dev/shm type tmpfs (rw,nosuid,nodev)
devpts on /dev/pts type devpts (rw,nosuid,noexec,relatime,gid=5,mode=620,ptmxmode=000)
tmpfs on /sys/fs/cgroup type tmpfs (ro,nosuid,nodev,noexec,mode=755)
cgroup on /sys/fs/cgroup/systemd type cgroup (rw,nosuid,nodev,noexec,relatime,xattr,release_agent=/usr/lib/systemd/systemd-cgroups-agent,name=systemd)
cgroup on /sys/fs/cgroup/net_cls,net_prio type cgroup (rw,nosuid,nodev,noexec,relatime,net_cls,net_prio)
cgroup on /sys/fs/cgroup/freezer type cgroup (rw,nosuid,nodev,noexec,relatime,freezer)
cgroup on /sys/fs/cgroup/devices type cgroup (rw,nosuid,nodev,noexec,relatime,devices)
cgroup on /sys/fs/cgroup/blkio type cgroup (rw,nosuid,nodev,noexec,relatime,blkio)
cgroup on /sys/fs/cgroup/cpu,cpuacct type cgroup (rw,nosuid,nodev,noexec,relatime,cpu,cpuacct)
cgroup on /sys/fs/cgroup/memory type cgroup (rw,nosuid,nodev,noexec,relatime,memory)
cgroup on /sys/fs/cgroup/perf_event type cgroup (rw,nosuid,nodev,noexec,relatime,perf_event)
cgroup on /sys/fs/cgroup/cpuset type cgroup (rw,nosuid,nodev,noexec,relatime,cpuset)
cgroup on /sys/fs/cgroup/bfqio type cgroup (rw,nosuid,nodev,noexec,relatime,bfqio)
systemd-1 on /proc/sys/fs/binfmt_misc type autofs (rw,relatime,fd=22,pgrp=1,timeout=300,minproto=5,maxproto=5,direct)
mqueue on /dev/mqueue type mqueue (rw,relatime)
debugfs on /sys/kernel/debug type debugfs (rw,relatime)
tmpfs on /tmp type tmpfs (rw)
fusectl on /sys/fs/fuse/connections type fusectl (rw,relatime)
tmpfs on /run/user/0 type tmpfs (rw,nosuid,nodev,relatime,size=25104k,mode=700)
/dev/sdc1 on /media/usb type ext3 (rw,noatime,data=ordered)
')

Specifically, $this->bbcode_second_pass_code('', '/dev/sdb1') which is "swap" gets mounted correctly....and $this->bbcode_second_pass_code('', '/dev/sdc1') which is external USB hard drive storage also mounts correctly. As is evident from the following output:

$this->bbcode_second_pass_code('', '┌─[root@pogoplug]─[~]
└──> free -m
total used free shared buff/cache available
Mem: 245 31 127 8 86 193
Swap: 244 0 244
┌─[root@pogoplug]─[~]
└──> df -h
Filesystem Size Used Avail Use% Mounted on
dev 122M 0 122M 0% /dev
run 123M 8.4M 115M 7% /run
/dev/sda1 15G 3.5G 11G 25% /
tmpfs 123M 0 123M 0% /dev/shm
tmpfs 123M 0 123M 0% /sys/fs/cgroup
tmpfs 123M 0 123M 0% /tmp
tmpfs 25M 0 25M 0% /run/user/0
/dev/sdc1 917G 286G 585G 33% /media/usb
')