Welcome! Log In Create A New Profile

Advanced

USB drive causes booting problem with Linux Kernel 4.4.89 Oxnas

Posted by schnee 
USB drive causes booting problem with Linux Kernel 4.4.89 Oxnas
February 15, 2018 02:46PM
Hi bodhi,

first of all thanks for the latest rootfs and kernel files! I just had time now to start upgrading my systems and i want(ed) to give them a fresh start. I rebuilt my sata disk builder script with the new rootfs and kernel 4.4.89. It works fine and the pogo boots with some warning messages but generally works fine. But when i boot with my USB to SATA device plugged the boot ends up with kernel panic (this worked for years). I made some tests with other devices. Using USB stick with FAT partition works fine, but if it is formatted to ext4 it produces the same error.

Here is the boot log for the console:
IDE device 0: Model: Samsung SSD 840 PRO Series Firm: DXM06B0Q Ser#: S1ATNSAF50 
            Type: Hard Disk                                                     
            Supports 48-bit addressing                                          
            Capacity: 244198.3 MB = 238.4 GB (500118192 x 512)                  
4100416 bytes read in 558 ms (7 MiB/s)                                          
4280781 bytes read in 583 ms (7 MiB/s)                                          
7075 bytes read in 15 ms (460 KiB/s)                                            
## Booting kernel from Legacy Image at 60500000 ...                             
   Image Name:   Linux-4.4.89-oxnas-tld-1                                       
   Image Type:   ARM Linux Kernel Image (uncompressed)                          
   Data Size:    4100352 Bytes = 3.9 MiB                                        
   Load Address: 60008000                                                       
   Entry Point:  60008000                                                       
   Verifying Checksum ... OK                                                    
## Loading init Ramdisk from Legacy Image at 60e00000 ...                       
   Image Name:   initramfs-4.4.89-oxnas-tld-1                                   
   Image Type:   ARM Linux RAMDisk Image (gzip compressed)                      
   Data Size:    4280717 Bytes = 4.1 MiB                                        
   Load Address: 60000000                                                       
   Entry Point:  60000000                                                       
   Verifying Checksum ... OK                                                    
## Flattened Device Tree blob at 62c00000                                       
   Booting using the fdt blob at 0x62c00000                                     
   Loading Kernel Image ... OK                                                  
   Loading Ramdisk to 67a16000, end 67e2b18d ... OK                             
   Loading Device Tree to 67a11000, end 67a15ba2 ... OK                         
                                                                                
Starting kernel ...                                                             
                                                                                
Uncompressing Linux... done, booting the kernel.                                
[    0.000000] Booting Linux on physical CPU 0x0                                
[    0.000000] Linux version 4.4.89-oxnas-tld-1 (root@tldDebian) (gcc version 67
[    0.000000] CPU: ARMv6-compatible processor [410fb025] revision 5 (ARMv7), cd
[    0.000000] CPU: PIPT / VIPT nonaliasing data cache, VIPT aliasing instructie
[    0.000000] Machine model: Pogoplug V3 Classic                               
[    0.000000] Memory policy: Data cache writealloc                             
[    0.000000] DT missing boot CPU MPIDR[23:0], fall back to default cpu_logicap
[    0.000000] PERCPU: Embedded 12 pages/cpu @c7eda000 s17676 r8192 d23284 u4912
[    0.000000] Built 1 zonelists in Zone order, mobility grouping on.  Total pa2
[    0.000000] Kernel command line: console=ttyS0,115200 root=LABEL=rootfs root0
[    0.000000] PID hash table entries: 512 (order: -1, 2048 bytes)              
[    0.000000] Dentry cache hash table entries: 16384 (order: 4, 65536 bytes)   
[    0.000000] Inode-cache hash table entries: 8192 (order: 3, 32768 bytes)     
[    0.000000] Memory: 117604K/131072K available (5664K kernel code, 285K rwdat)
[    0.000000] Virtual kernel memory layout:                                    
[    0.000000]     vector  : 0xffff0000 - 0xffff1000   (   4 kB)                
[    0.000000]     fixmap  : 0xffc00000 - 0xfff00000   (3072 kB)                
[    0.000000]     vmalloc : 0xc8800000 - 0xff800000   ( 880 MB)                
[    0.000000]     lowmem  : 0xc0000000 - 0xc8000000   ( 128 MB)                
[    0.000000]     modules : 0xbf000000 - 0xc0000000   (  16 MB)                
[    0.000000]       .text : 0xc0008000 - 0xc07185bc   (7234 kB)                
[    0.000000]       .init : 0xc0719000 - 0xc0750000   ( 220 kB)                
[    0.000000]       .data : 0xc0750000 - 0xc0797478   ( 286 kB)                
[    0.000000]        .bss : 0xc0797478 - 0xc07d4298   ( 244 kB)                
[    0.000000] SLUB: HWalign=32, Order=0-3, MinObjects=0, CPUs=2, Nodes=1       
[    0.000000] Preemptible hierarchical RCU implementation.                     
[    0.000000] NR_IRQS:160                                                      
[    0.000000] clocksource: rps_clocksource_timer: mask: 0xffffff max_cycles: 0s
[    0.000007] sched_clock: 24 bits at 390kHz, resolution 2560ns, wraps every 2s
[    0.000163] Console: colour dummy device 80x30                               
[    0.000197] Calibrating delay loop... 319.48 BogoMIPS (lpj=1597440)          
[    0.050027] pid_max: default: 32768 minimum: 301                             
[    0.050206] Mount-cache hash table entries: 1024 (order: 0, 4096 bytes)      
[    0.050227] Mountpoint-cache hash table entries: 1024 (order: 0, 4096 bytes) 
[    0.051123] Initializing cgroup subsys net_cls                               
[    0.051212] CPU: Testing write buffer coherency: ok                          
[    0.051660] Setting up static identity map for 0x60008220 - 0x60008258       
[    0.190156] Brought up 2 CPUs                                                
[    0.190179] SMP: Total of 2 processors activated (638.97 BogoMIPS).          
[    0.191027] devtmpfs: initialized                                            
[    0.196526] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, ms
[    0.196569] futex hash table entries: 512 (order: 2, 16384 bytes)            
[    0.196889] prandom: seed boundary self test passed                          
[    0.201576] prandom: 100 self tests passed                                   
[    0.201610] pinctrl core: initialized pinctrl subsystem                      
[    0.202483] NET: Registered protocol family 16                               
[    0.203043] DMA: preallocated 256 KiB pool for atomic coherent allocations   
[    0.230018] cpuidle: using governor ladder                                   
[    0.260011] cpuidle: using governor menu                                     
[    0.265223] gpio-oxnas 44000000.gpio: at address c8848000                    
[    0.265658] gpio-oxnas 44100000.gpio: at address c884a000                    
[    0.266240] pinctrl-oxnas pinctrl: initialized OX820 pinctrl driver          
[    0.278881] vgaarb: loaded                                                   
[    0.279395] SCSI subsystem initialized                                       
[    0.280363] usbcore: registered new interface driver usbfs                   
[    0.280486] usbcore: registered new interface driver hub                     
[    0.280652] usbcore: registered new device driver usb                        
[    0.280855] pps_core: LinuxPPS API ver. 1 registered                         
[    0.280867] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giom>
[    0.280934] PTP clock support registered                                     
[    0.282314] clocksource: Switched to clocksource rps_clocksource_timer       
[    0.301639] NET: Registered protocol family 2                                
[    0.302745] TCP established hash table entries: 1024 (order: 0, 4096 bytes)  
[    0.302791] TCP bind hash table entries: 1024 (order: 1, 8192 bytes)         
[    0.302827] TCP: Hash tables configured (established 1024 bind 1024)         
[    0.302942] UDP hash table entries: 256 (order: 1, 8192 bytes)               
[    0.302988] UDP-Lite hash table entries: 256 (order: 1, 8192 bytes)          
[    0.303275] NET: Registered protocol family 1                                
[    0.303797] RPC: Registered named UNIX socket transport module.              
[    0.303818] RPC: Registered udp transport module.                            
[    0.303831] RPC: Registered tcp transport module.                            
[    0.303841] RPC: Registered tcp NFSv4.1 backchannel transport module.        
[    0.304245] Unpacking initramfs...                                           
[    0.774561] Freeing initrd memory: 4184K                                     
[    0.776122] Initialise system trusted keyring                                
[    0.803816] zbud: loaded                                                     
[    0.821498] NFS: Registering the id_resolver key type                        
[    0.821570] Key type id_resolver registered                                  
[    0.821585] Key type id_legacy registered                                    
[    0.821629] nfs4filelayout_init: NFSv4 File Layout Driver Registering...     
[    0.821652] Installing knfsd (copyright (C) 1996 okir@monad.swb.de).         
[    0.822663] fuse init (API version 7.23)                                     
[    0.824844] Key type big_key registered                                      
[    0.924495] Key type asymmetric registered                                   
[    0.924531] Asymmetric key parser 'x509' registered                          
[    0.924672] Block layer SCSI generic (bsg) driver version 0.4 loaded (major )
[    0.924702] io scheduler noop registered                                     
[    0.924725] io scheduler deadline registered                                 
[    0.924792] io scheduler cfq registered (default)                            
[    0.925455] Serial: 8250/16550 driver, 1 ports, IRQ sharing disabled         
[    0.926958] console [ttyS0] disabled                                         
[    0.927057] 44200000.uart: ttyS0 at MMIO 0x44200000 (irq = 6, base_baud = 39A
[    1.447595] console [ttyS0] enabled                                          
[    1.463301] loop: module loaded                                              
[    1.468485] scsi host0: sata_oxnas                                           
[    1.472430] ata1: SATA max UDMA/133 irq 7                                    
[    1.476526] sata_oxnas: resetting SATA core                                  
[    1.477570] nand: Could not find valid ONFI parameter page; aborting         
[    1.477614] nand: device found, Manufacturer ID: 0xad, Chip ID: 0xf1         
[    1.477621] nand: Hynix NAND 128MiB 3,3V 8-bit                               
[    1.477632] nand: 128 MiB, SLC, erase size: 128 KiB, page size: 2048, OOB si4
[    1.477665] Scanning device for bad blocks                                   
[    1.494766] Bad eraseblock 208 at 0x000001a00000                             
[    1.554278] Bad eraseblock 937 at 0x000007520000                             
[    1.565870] 2 ofpart partitions found on MTD device 41000000.nand            
[    1.571960] Creating 2 MTD partitions on "41000000.nand":                    
[    1.577354] 0x000000000000-0x000000e00000 : "boot"                           
[    1.584179] 0x000000e00000-0x000008000000 : "data"                           
[    1.591741] oxnas-gmac 40400000.ethernet: no reset control found             
[    1.597729] stmmac - user ID: 0x12, Synopsys ID: 0x35                        
[    1.602803]  Ring mode enabled                                               
[    1.605841]  DMA HW capability register supported                            
[    1.610365]  Enhanced/Alternate descriptors                                  
[    1.614696]  Enabled extended descriptors                                    
[    1.618680]  RX Checksum Offload Engine supported (type 2)                   
[    1.624151]  TX Checksum insertion supported                                 
[    1.628392]  Wake-Up On Lan supported                                        
[    1.632048]  Enable RX Mitigation via HW Watchdog Timer                      
[    1.665239] libphy: stmmac: probed                                           
[    1.668633] eth%d: PHY ID 001cc914 at 0 IRQ POLL (stmmac-0:00) active        
[    1.675102] eth%d: PHY ID 001cc914 at 3 IRQ POLL (stmmac-0:03)               
[    1.681845] PPP generic driver version 2.4.2                                 
[    1.686438] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver       
[    1.693007] ehci-pci: EHCI PCI platform driver                               
[    1.697963] oxnas-ehci 40200100.ehci: EHCI Host Controller                   
[    1.703587] oxnas-ehci 40200100.ehci: new USB bus registered, assigned bus n1
[    1.711452] oxnas-ehci 40200100.ehci: irq 10, io mem 0x40200100              
[    1.730150] oxnas-ehci 40200100.ehci: USB 2.0 started, EHCI 1.00             
[    1.736476] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002    
[    1.743270] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber1
[    1.750479] usb usb1: Product: EHCI Host Controller                          
[    1.755333] usb usb1: Manufacturer: Linux 4.4.89-oxnas-tld-1 ehci_hcd        
[    1.761758] usb usb1: SerialNumber: 40200100.ehci                            
[    1.767431] hub 1-0:1.0: USB hub found                                       
[    1.771294] hub 1-0:1.0: 2 ports detected                                    
[    1.776401] usbcore: registered new interface driver cdc_acm                 
[    1.782077] cdc_acm: USB Abstract Control Model driver for USB modems and ISs
[    1.790184] usbcore: registered new interface driver cdc_wdm                 
[    1.796016] usbcore: registered new interface driver usb-storage             
[    1.802219] usbcore: registered new interface driver usbserial               
[    1.808089] usbcore: registered new interface driver usbserial_generic       
[    1.814702] usbserial: USB Serial support registered for generic             
[    1.821153] mousedev: PS/2 mouse device common for all mice                  
[    1.826887] i2c /dev entries driver                                          
[    1.831746] hidraw: raw HID events driver (C) Jiri Kosina                    
[    1.837368] usbcore: registered new interface driver usbhid                  
[    1.842954] usbhid: USB HID core driver                                      
[    1.849064] NET: Registered protocol family 10                               
[    1.854996] sit: IPv6 over IPv4 tunneling driver                             
[    1.860789] NET: Registered protocol family 17                               
[    1.865397] Key type dns_resolver registered                                 
[    1.871308] registered taskstats version 1                                   
[    1.875435] Loading compiled-in X.509 certificates                           
[    1.880363] zswap: loaded using pool lzo/zbud                                
[    1.899816] Key type encrypted registered                                    
[    2.030010] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 310)           
[    2.036897] ata1.00: ATA-9: Samsung SSD 840 PRO Series, DXM06B0Q, max UDMA/13
[    2.044134] ata1.00: 500118192 sectors, multi 16: LBA48 NCQ (depth 0/32)     
[    2.051153] ata1.00: configured for UDMA/133                                 
[    2.070702] scsi 0:0:0:0: Direct-Access     ATA      Samsung SSD 840  6B0Q P5
[    2.080189] ata1.00: Enabling discard_zeroes_data                            
[    2.084979] sd 0:0:0:0: [sda] 500118192 512-byte logical blocks: (256 GB/238)
[    2.093913] sd 0:0:0:0: [sda] Write Protect is off                           
[    2.098923] sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doeA
[    2.100172] usb 1-1: new high-speed USB device number 2 using oxnas-ehci     
[    2.115645] ata1.00: Enabling discard_zeroes_data                            
[    2.121779]  sda: sda1 sda2 sda3                                             
[    2.126955] ata1.00: Enabling discard_zeroes_data                            
[    2.132695] sd 0:0:0:0: [sda] Attached SCSI disk                             
[    2.137658] Freeing unused kernel memory: 220K                               
Loading, please wait...                                                         
[    2.262133] usb 1-1: New USB device found, idVendor=05e3, idProduct=0608     
[    2.268823] usb 1-1: New USB device strings: Mfr=0, Product=1, SerialNumber=0
[    2.276008] usb 1-1: Product: USB2.0 Hub                                     
[    2.281415] hub 1-1:1.0: USB hub found                                       
[    2.285629] hub 1-1:1.0: 4 ports detected                                    
starting version 232                                                            
[    2.328465] random: systemd-udevd: uninitialized urandom read (16 bytes read)
[    2.339911] random: systemd-udevd: uninitialized urandom read (16 bytes read)
[    2.341184] random: udevadm: uninitialized urandom read (16 bytes read, 0 bi)
[    2.343713] random: udevadm: uninitialized urandom read (16 bytes read, 0 bi)
[    2.343889] random: udevadm: uninitialized urandom read (16 bytes read, 0 bi)
[    2.344972] random: udevadm: uninitialized urandom read (16 bytes read, 0 bi)
[    2.346094] random: udevadm: uninitialized urandom read (16 bytes read, 0 bi)
[    2.347141] random: udevadm: uninitialized urandom read (16 bytes read, 0 bi)
[    2.353131] random: udevadm: uninitialized urandom read (16 bytes read, 0 bi)
[    2.354127] random: udevadm: uninitialized urandom read (16 bytes read, 0 bi)
[    2.600240] usb 1-1.1: new high-speed USB device number 3 using oxnas-ehci   
[    2.865986] usb 1-1.1: New USB device found, idVendor=046d, idProduct=0991   
[    2.872977] usb 1-1.1: New USB device strings: Mfr=0, Product=0, SerialNumbe2
[    2.880450] usb 1-1.1: SerialNumber: 2CCCCCE2                                
[    2.970283] usb 1-1.2: new high-speed USB device number 4 using oxnas-ehci   
[    3.227970] usb 1-1.2: New USB device found, idVendor=046d, idProduct=0991   
[    3.234862] usb 1-1.2: New USB device strings: Mfr=0, Product=0, SerialNumbe2
[    3.242155] usb 1-1.2: SerialNumber: 516F6CE2                                
[    3.330140] usb 1-1.3: new high-speed USB device number 5 using oxnas-ehci   
[    3.442094] usb 1-1.3: New USB device found, idVendor=05e3, idProduct=0608   
[    3.448947] usb 1-1.3: New USB device strings: Mfr=0, Product=1, SerialNumbe0
[    3.456256] usb 1-1.3: Product: USB2.0 Hub                                   
[    3.461844] hub 1-1.3:1.0: USB hub found                                     
[    3.466124] hub 1-1.3:1.0: 4 ports detected                                  
[    3.550141] usb 1-1.4: new high-speed USB device number 6 using oxnas-ehci   
[    3.671590] usb 1-1.4: New USB device found, idVendor=152d, idProduct=2329   
[    3.678440] usb 1-1.4: New USB device strings: Mfr=1, Product=2, SerialNumbe5
[    3.685752] usb 1-1.4: Product: USB to ATA/ATAPI bridge                      
[    3.691038] usb 1-1.4: Manufacturer: JMicron                                 
[    3.695349] usb 1-1.4: SerialNumber: F000A6DFFFFF                            
[    3.701975] usb-storage 1-1.4:1.0: USB Mass Storage device detected          
[    3.708684] usb-storage 1-1.4:1.0: Quirks match for vid 152d pid 2329: 8020  
[    3.715863] scsi host1: usb-storage 1-1.4:1.0                                
[    3.755563] usbcore: registered new interface driver uas                     
[    3.770234] usb 1-1.3.1: new high-speed USB device number 7 using oxnas-ehci 
[    4.030973] usb 1-1.3.1: New USB device found, idVendor=046d, idProduct=0819 
[    4.038000] usb 1-1.3.1: New USB device strings: Mfr=0, Product=0, SerialNum2
[    4.045478] usb 1-1.3.1: SerialNumber: 439AA2F0                              
[    4.130142] usb 1-1.3.2: new full-speed USB device number 8 using oxnas-ehci 
[    4.247349] usb 1-1.3.2: New USB device found, idVendor=0403, idProduct=6001 
[    4.254415] usb 1-1.3.2: New USB device strings: Mfr=1, Product=2, SerialNum3
[    4.261882] usb 1-1.3.2: Product: FT232R USB UART                            
[    4.266567] usb 1-1.3.2: Manufacturer: FTDI                                  
[    4.270748] usb 1-1.3.2: SerialNumber: A10125LI                              
[    4.360140] usb 1-1.3.3: new low-speed USB device number 9 using oxnas-ehci  
[    4.574348] usb 1-1.3.3: New USB device found, idVendor=051d, idProduct=0002 
[    4.581404] usb 1-1.3.3: New USB device strings: Mfr=3, Product=1, SerialNum2
[    4.588853] usb 1-1.3.3: Product: Back-UPS CS 650 FW:817.v6.I USB FW:v6      
[    4.595458] usb 1-1.3.3: Manufacturer: American Power Conversion             
[    4.601456] usb 1-1.3.3: SerialNumber: 4B1037P54836                          
[    4.721351] scsi 1:0:0:0: Direct-Access     Hitachi  HDS721010KLA330       PS
[    4.735242] sd 1:0:0:0: [sdb] 1953525168 512-byte logical blocks: (1.00 TB/9)
[    4.748157] sd 1:0:0:0: [sdb] Write Protect is off                           
[    4.754659] sd 1:0:0:0: [sdb] No Caching mode page found                     
[    4.759964] sd 1:0:0:0: [sdb] Assuming drive cache: write through            
[    4.791713]  sdb: sdb1                                                       
[    4.808714] sd 1:0:0:0: [sdb] Attached SCSI disk                             
[    5.291456] EXT4-fs (sdb1): couldn't mount as ext3 due to feature incompatibs
[    5.541404] EXT4-fs (sdb1): mounted filesystem with ordered data mode. Opts: (null)
[    6.150656] hid-generic 0003:051D:0002.0001: hiddev0,hidraw0: USB HID v1.10 0
Begin: Loading essential drivers ... done.                                      
Begin: Running /scripts/init-premount ... done.                                 
Begin: Mounting root file system ... Begin: Running /scripts/local-top ... done.
Begin: Running /scripts/local-premount ... done.                                
Begin: Will now check root file system ... fsck from util-linux 2.29.2          
fsck: error 2 (No such file or directory) while executing fsck.ext3 for /dev/sda2
fsck exited with status code 8                                                  
done.                                                                           
Warning: File system check failed but did not detect errors                     
[   21.418388] EXT4-fs (sda2): mounting ext3 file system using the ext4 subsystm
[   21.440691] EXT4-fs (sda2): mounted filesystem with ordered data mode. Opts: (null)
done.                                                                           
Begin: Running /scripts/local-bottom ... done.                                  
Begin: Running /scripts/init-bottom ... done.                                   
run-init: nuking initramfs contents: Directory not empty                        
[   21.580188] Kernel panic - not syncing: Attempted to kill init! exitcode=0x00
[   21.580188]                                                                  
[   21.589291] CPU: 0 PID: 1 Comm: run-init Not tainted 4.4.89-oxnas-tld-1 #1   
[   21.596131] Hardware name: PLXTECH NAS782X SoC (Flattened Device Tree)       
[   21.602652] [<c0010ce8>] (unwind_backtrace) from [<c000cf90>] (show_stack+0x)
[   21.610368] [<c000cf90>] (show_stack) from [<c02d48b8>] (dump_stack+0x80/0x9)
[   21.617561] [<c02d48b8>] (dump_stack) from [<c0093e74>] (panic+0x8c/0x1ec)   
[   21.624412] [<c0093e74>] (panic) from [<c001a790>] (do_exit+0x974/0x9d8)     
[   21.631086] [<c001a790>] (do_exit) from [<c001b788>] (SyS_exit+0x10/0x14)    
[   21.637847] [<c001b788>] (SyS_exit) from [<c000a440>] (ret_fast_syscall+0x0/)
[   21.645299] CPU1: stopping                                                   
[   21.648005] CPU: 1 PID: 0 Comm: swapper/1 Not tainted 4.4.89-oxnas-tld-1 #1  
[   21.654932] Hardware name: PLXTECH NAS782X SoC (Flattened Device Tree)       
[   21.661445] [<c0010ce8>] (unwind_backtrace) from [<c000cf90>] (show_stack+0x)
[   21.669155] [<c000cf90>] (show_stack) from [<c02d48b8>] (dump_stack+0x80/0x9)
[   21.676349] [<c02d48b8>] (dump_stack) from [<c000fbfc>] (handle_IPI+0x190/0x)
[   21.683714] [<c000fbfc>] (handle_IPI) from [<c0009494>] (gic_handle_irq+0x78)
[   21.691251] [<c0009494>] (gic_handle_irq) from [<c000da00>] (__irq_svc+0x40/)
[   21.698693] Exception stack(0xc7469f88 to 0xc7469fd0)                        
[   21.703723] 9f80:                   00000001 00000000 00000000 00000000 c746c
[   21.711864] 9fa0: 00000000 c7469fe0 c074b2c4 c074e208 00000000 c07586ac 00008
[   21.720000] 9fc0: c000aef8 c000aefc 60000013 ffffffff                        
[   21.725030] [<c000da00>] (__irq_svc) from [<c000aefc>] (arch_cpu_idle+0x28/0)
[   21.732398] [<c000aefc>] (arch_cpu_idle) from [<c004e2a8>] (cpu_startup_entr)
[   21.740628] [<c004e2a8>] (cpu_startup_entry) from [<60009528>] (0x60009528)  
[   21.747563] ---[ end Kernel panic - not syncing: Attempted to kill init! exi0
[   21.747563]

This is the boot when no usb drive attached:
IDE device 0: Model: Samsung SSD 840 PRO Series Firm: DXM06B0Q Ser#: S1ATNSAF50 
            Type: Hard Disk                                                     
            Supports 48-bit addressing                                          
            Capacity: 244198.3 MB = 238.4 GB (500118192 x 512)                  
4100416 bytes read in 558 ms (7 MiB/s)                                          
4280781 bytes read in 583 ms (7 MiB/s)                                          
7075 bytes read in 14 ms (493.2 KiB/s)                                          
## Booting kernel from Legacy Image at 60500000 ...                             
   Image Name:   Linux-4.4.89-oxnas-tld-1                                       
   Image Type:   ARM Linux Kernel Image (uncompressed)                          
   Data Size:    4100352 Bytes = 3.9 MiB                                        
   Load Address: 60008000                                                       
   Entry Point:  60008000                                                       
   Verifying Checksum ... OK                                                    
## Loading init Ramdisk from Legacy Image at 60e00000 ...                       
   Image Name:   initramfs-4.4.89-oxnas-tld-1                                   
   Image Type:   ARM Linux RAMDisk Image (gzip compressed)                      
   Data Size:    4280717 Bytes = 4.1 MiB                                        
   Load Address: 60000000                                                       
   Entry Point:  60000000                                                       
   Verifying Checksum ... OK                                                    
## Flattened Device Tree blob at 62c00000                                       
   Booting using the fdt blob at 0x62c00000                                     
   Loading Kernel Image ... OK                                                  
   Loading Ramdisk to 67a16000, end 67e2b18d ... OK                             
   Loading Device Tree to 67a11000, end 67a15ba2 ... OK                         
                                                                                
Starting kernel ...                                                             
                                                                                
Uncompressing Linux... done, booting the kernel.                                
[    0.000000] Booting Linux on physical CPU 0x0                                
[    0.000000] Linux version 4.4.89-oxnas-tld-1 (root@tldDebian) (gcc version 67
[    0.000000] CPU: ARMv6-compatible processor [410fb025] revision 5 (ARMv7), cd
[    0.000000] CPU: PIPT / VIPT nonaliasing data cache, VIPT aliasing instructie
[    0.000000] Machine model: Pogoplug V3 Classic                               
[    0.000000] Memory policy: Data cache writealloc                             
[    0.000000] DT missing boot CPU MPIDR[23:0], fall back to default cpu_logicap
[    0.000000] PERCPU: Embedded 12 pages/cpu @c7eda000 s17676 r8192 d23284 u4912
[    0.000000] Built 1 zonelists in Zone order, mobility grouping on.  Total pa2
[    0.000000] Kernel command line: console=ttyS0,115200 root=LABEL=rootfs root0
[    0.000000] PID hash table entries: 512 (order: -1, 2048 bytes)              
[    0.000000] Dentry cache hash table entries: 16384 (order: 4, 65536 bytes)   
[    0.000000] Inode-cache hash table entries: 8192 (order: 3, 32768 bytes)     
[    0.000000] Memory: 117604K/131072K available (5664K kernel code, 285K rwdat)
[    0.000000] Virtual kernel memory layout:                                    
[    0.000000]     vector  : 0xffff0000 - 0xffff1000   (   4 kB)                
[    0.000000]     fixmap  : 0xffc00000 - 0xfff00000   (3072 kB)                
[    0.000000]     vmalloc : 0xc8800000 - 0xff800000   ( 880 MB)                
[    0.000000]     lowmem  : 0xc0000000 - 0xc8000000   ( 128 MB)                
[    0.000000]     modules : 0xbf000000 - 0xc0000000   (  16 MB)                
[    0.000000]       .text : 0xc0008000 - 0xc07185bc   (7234 kB)                
[    0.000000]       .init : 0xc0719000 - 0xc0750000   ( 220 kB)                
[    0.000000]       .data : 0xc0750000 - 0xc0797478   ( 286 kB)                
[    0.000000]        .bss : 0xc0797478 - 0xc07d4298   ( 244 kB)                
[    0.000000] SLUB: HWalign=32, Order=0-3, MinObjects=0, CPUs=2, Nodes=1       
[    0.000000] Preemptible hierarchical RCU implementation.                     
[    0.000000] NR_IRQS:160                                                      
[    0.000000] clocksource: rps_clocksource_timer: mask: 0xffffff max_cycles: 0s
[    0.000010] sched_clock: 24 bits at 390kHz, resolution 2560ns, wraps every 2s
[    0.000163] Console: colour dummy device 80x30                               
[    0.000197] Calibrating delay loop... 319.48 BogoMIPS (lpj=1597440)          
[    0.050024] pid_max: default: 32768 minimum: 301                             
[    0.050201] Mount-cache hash table entries: 1024 (order: 0, 4096 bytes)      
[    0.050222] Mountpoint-cache hash table entries: 1024 (order: 0, 4096 bytes) 
[    0.051120] Initializing cgroup subsys net_cls                               
[    0.051207] CPU: Testing write buffer coherency: ok                          
[    0.051650] Setting up static identity map for 0x60008220 - 0x60008258       
[    0.190154] Brought up 2 CPUs                                                
[    0.190177] SMP: Total of 2 processors activated (638.97 BogoMIPS).          
[    0.191032] devtmpfs: initialized                                            
[    0.196321] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, ms
[    0.196367] futex hash table entries: 512 (order: 2, 16384 bytes)            
[    0.196689] prandom: seed boundary self test passed                          
[    0.201379] prandom: 100 self tests passed                                   
[    0.201413] pinctrl core: initialized pinctrl subsystem                      
[    0.202283] NET: Registered protocol family 16                               
[    0.202841] DMA: preallocated 256 KiB pool for atomic coherent allocations   
[    0.230018] cpuidle: using governor ladder                                   
[    0.260011] cpuidle: using governor menu                                     
[    0.265231] gpio-oxnas 44000000.gpio: at address c8848000                    
[    0.265676] gpio-oxnas 44100000.gpio: at address c884a000                    
[    0.266286] pinctrl-oxnas pinctrl: initialized OX820 pinctrl driver          
[    0.279055] vgaarb: loaded                                                   
[    0.279674] SCSI subsystem initialized                                       
[    0.280629] usbcore: registered new interface driver usbfs                   
[    0.280744] usbcore: registered new interface driver hub                     
[    0.280911] usbcore: registered new device driver usb                        
[    0.281111] pps_core: LinuxPPS API ver. 1 registered                         
[    0.281123] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giom>
[    0.281190] PTP clock support registered                                     
[    0.282621] clocksource: Switched to clocksource rps_clocksource_timer       
[    0.302179] NET: Registered protocol family 2                                
[    0.303306] TCP established hash table entries: 1024 (order: 0, 4096 bytes)  
[    0.303354] TCP bind hash table entries: 1024 (order: 1, 8192 bytes)         
[    0.303390] TCP: Hash tables configured (established 1024 bind 1024)         
[    0.303500] UDP hash table entries: 256 (order: 1, 8192 bytes)               
[    0.303546] UDP-Lite hash table entries: 256 (order: 1, 8192 bytes)          
[    0.303828] NET: Registered protocol family 1                                
[    0.304335] RPC: Registered named UNIX socket transport module.              
[    0.304355] RPC: Registered udp transport module.                            
[    0.304366] RPC: Registered tcp transport module.                            
[    0.304378] RPC: Registered tcp NFSv4.1 backchannel transport module.        
[    0.304791] Unpacking initramfs...                                           
[    0.777295] Freeing initrd memory: 4184K                                     
[    0.778831] Initialise system trusted keyring                                
[    0.804044] zbud: loaded                                                     
[    0.821501] NFS: Registering the id_resolver key type                        
[    0.821573] Key type id_resolver registered                                  
[    0.821591] Key type id_legacy registered                                    
[    0.821632] nfs4filelayout_init: NFSv4 File Layout Driver Registering...     
[    0.821655] Installing knfsd (copyright (C) 1996 okir@monad.swb.de).         
[    0.822673] fuse init (API version 7.23)                                     
[    0.824808] Key type big_key registered                                      
[    0.924367] Key type asymmetric registered                                   
[    0.924405] Asymmetric key parser 'x509' registered                          
[    0.924546] Block layer SCSI generic (bsg) driver version 0.4 loaded (major )
[    0.924577] io scheduler noop registered                                     
[    0.924600] io scheduler deadline registered                                 
[    0.924664] io scheduler cfq registered (default)                            
[    0.925347] Serial: 8250/16550 driver, 1 ports, IRQ sharing disabled         
[    0.926848] console [ttyS0] disabled                                         
[    0.926945] 44200000.uart: ttyS0 at MMIO 0x44200000 (irq = 6, base_baud = 39A
[    1.447439] console [ttyS0] enabled                                          
[    1.463096] loop: module loaded                                              
[    1.468239] scsi host0: sata_oxnas                                           
[    1.472161] ata1: SATA max UDMA/133 irq 7                                    
[    1.476252] sata_oxnas: resetting SATA core                                  
[    1.477350] nand: Could not find valid ONFI parameter page; aborting         
[    1.477393] nand: device found, Manufacturer ID: 0xad, Chip ID: 0xf1         
[    1.477399] nand: Hynix NAND 128MiB 3,3V 8-bit                               
[    1.477406] nand: 128 MiB, SLC, erase size: 128 KiB, page size: 2048, OOB si4
[    1.477440] Scanning device for bad blocks                                   
[    1.494361] Bad eraseblock 208 at 0x000001a00000                             
[    1.553267] Bad eraseblock 937 at 0x000007520000                             
[    1.564858] 2 ofpart partitions found on MTD device 41000000.nand            
[    1.570944] Creating 2 MTD partitions on "41000000.nand":                    
[    1.576335] 0x000000000000-0x000000e00000 : "boot"                           
[    1.583188] 0x000000e00000-0x000008000000 : "data"                           
[    1.590755] oxnas-gmac 40400000.ethernet: no reset control found             
[    1.596748] stmmac - user ID: 0x12, Synopsys ID: 0x35                        
[    1.601817]  Ring mode enabled                                               
[    1.604858]  DMA HW capability register supported                            
[    1.609364]  Enhanced/Alternate descriptors                                  
[    1.613716]  Enabled extended descriptors                                    
[    1.617702]  RX Checksum Offload Engine supported (type 2)                   
[    1.623173]  TX Checksum insertion supported                                 
[    1.627420]  Wake-Up On Lan supported                                        
[    1.631075]  Enable RX Mitigation via HW Watchdog Timer                      
[    1.665482] libphy: stmmac: probed                                           
[    1.668879] eth%d: PHY ID 001cc914 at 0 IRQ POLL (stmmac-0:00) active        
[    1.675345] eth%d: PHY ID 001cc914 at 3 IRQ POLL (stmmac-0:03)               
[    1.682099] PPP generic driver version 2.4.2                                 
[    1.686691] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver       
[    1.693260] ehci-pci: EHCI PCI platform driver                               
[    1.698209] oxnas-ehci 40200100.ehci: EHCI Host Controller                   
[    1.703823] oxnas-ehci 40200100.ehci: new USB bus registered, assigned bus n1
[    1.711713] oxnas-ehci 40200100.ehci: irq 10, io mem 0x40200100              
[    1.730150] oxnas-ehci 40200100.ehci: USB 2.0 started, EHCI 1.00             
[    1.736499] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002    
[    1.743293] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber1
[    1.750497] usb usb1: Product: EHCI Host Controller                          
[    1.755353] usb usb1: Manufacturer: Linux 4.4.89-oxnas-tld-1 ehci_hcd        
[    1.761781] usb usb1: SerialNumber: 40200100.ehci                            
[    1.767467] hub 1-0:1.0: USB hub found                                       
[    1.771340] hub 1-0:1.0: 2 ports detected                                    
[    1.776430] usbcore: registered new interface driver cdc_acm                 
[    1.782105] cdc_acm: USB Abstract Control Model driver for USB modems and ISs
[    1.790220] usbcore: registered new interface driver cdc_wdm                 
[    1.796055] usbcore: registered new interface driver usb-storage             
[    1.802257] usbcore: registered new interface driver usbserial               
[    1.808128] usbcore: registered new interface driver usbserial_generic       
[    1.814735] usbserial: USB Serial support registered for generic             
[    1.821178] mousedev: PS/2 mouse device common for all mice                  
[    1.826918] i2c /dev entries driver                                          
[    1.831761] hidraw: raw HID events driver (C) Jiri Kosina                    
[    1.837381] usbcore: registered new interface driver usbhid                  
[    1.842972] usbhid: USB HID core driver                                      
[    1.848929] NET: Registered protocol family 10                               
[    1.854845] sit: IPv6 over IPv4 tunneling driver                             
[    1.860615] NET: Registered protocol family 17                               
[    1.865208] Key type dns_resolver registered                                 
[    1.871162] registered taskstats version 1                                   
[    1.875294] Loading compiled-in X.509 certificates                           
[    1.880225] zswap: loaded using pool lzo/zbud                                
[    1.900147] Key type encrypted registered                                    
[    2.030010] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 310)           
[    2.036840] ata1.00: ATA-9: Samsung SSD 840 PRO Series, DXM06B0Q, max UDMA/13
[    2.044080] ata1.00: 500118192 sectors, multi 16: LBA48 NCQ (depth 0/32)     
[    2.051100] ata1.00: configured for UDMA/133                                 
[    2.070704] scsi 0:0:0:0: Direct-Access     ATA      Samsung SSD 840  6B0Q P5
[    2.080207] ata1.00: Enabling discard_zeroes_data                            
[    2.084994] sd 0:0:0:0: [sda] 500118192 512-byte logical blocks: (256 GB/238)
[    2.093957] sd 0:0:0:0: [sda] Write Protect is off                           
[    2.098967] sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doeA
[    2.100172] usb 1-1: new high-speed USB device number 2 using oxnas-ehci     
[    2.115699] ata1.00: Enabling discard_zeroes_data                            
[    2.121804]  sda: sda1 sda2 sda3                                             
[    2.126988] ata1.00: Enabling discard_zeroes_data                            
[    2.132728] sd 0:0:0:0: [sda] Attached SCSI disk                             
[    2.137692] Freeing unused kernel memory: 220K                               
Loading, please wait...                                                         
[    2.262141] usb 1-1: New USB device found, idVendor=05e3, idProduct=0608     
[    2.268830] usb 1-1: New USB device strings: Mfr=0, Product=1, SerialNumber=0
[    2.276019] usb 1-1: Product: USB2.0 Hub                                     
[    2.281456] hub 1-1:1.0: USB hub found                                       
[    2.285655] hub 1-1:1.0: 4 ports detected                                    
starting version 232                                                            
[    2.326750] random: systemd-udevd: uninitialized urandom read (16 bytes read)
[    2.338058] random: systemd-udevd: uninitialized urandom read (16 bytes read)
[    2.339950] random: udevadm: uninitialized urandom read (16 bytes read, 0 bi)
[    2.342438] random: udevadm: uninitialized urandom read (16 bytes read, 0 bi)
[    2.342617] random: udevadm: uninitialized urandom read (16 bytes read, 0 bi)
[    2.343705] random: udevadm: uninitialized urandom read (16 bytes read, 0 bi)
[    2.344801] random: udevadm: uninitialized urandom read (16 bytes read, 0 bi)
[    2.345894] random: udevadm: uninitialized urandom read (16 bytes read, 0 bi)
[    2.352064] random: udevadm: uninitialized urandom read (16 bytes read, 0 bi)
[    2.353098] random: udevadm: uninitialized urandom read (16 bytes read, 0 bi)
[    2.600279] usb 1-1.1: new high-speed USB device number 3 using oxnas-ehci   
[    2.849643] usb 1-1.1: New USB device found, idVendor=046d, idProduct=0991   
[    2.856896] usb 1-1.1: New USB device strings: Mfr=0, Product=0, SerialNumbe2
[    2.865937] usb 1-1.1: SerialNumber: 2CCCCCE2                                
[    2.960261] usb 1-1.2: new high-speed USB device number 4 using oxnas-ehci   
[    3.217986] usb 1-1.2: New USB device found, idVendor=046d, idProduct=0991   
[    3.224878] usb 1-1.2: New USB device strings: Mfr=0, Product=0, SerialNumbe2
[    3.232179] usb 1-1.2: SerialNumber: 516F6CE2                                
[    3.320143] usb 1-1.3: new high-speed USB device number 5 using oxnas-ehci   
[    3.431982] usb 1-1.3: New USB device found, idVendor=05e3, idProduct=0608   
[    3.438832] usb 1-1.3: New USB device strings: Mfr=0, Product=1, SerialNumbe0
[    3.446141] usb 1-1.3: Product: USB2.0 Hub                                   
[    3.451581] hub 1-1.3:1.0: USB hub found                                     
[    3.455884] hub 1-1.3:1.0: 4 ports detected                                  
Begin: Loading essential drivers ... done.                                      
[    3.740142] usb 1-1.3.1: new high-speed USB device number 6 using oxnas-ehci 
[    4.000614] usb 1-1.3.1: New USB device found, idVendor=046d, idProduct=0819 
[    4.007639] usb 1-1.3.1: New USB device strings: Mfr=0, Product=0, SerialNum2
[    4.015124] usb 1-1.3.1: SerialNumber: 439AA2F0                              
[    4.100175] usb 1-1.3.2: new full-speed USB device number 7 using oxnas-ehci 
[    4.217105] usb 1-1.3.2: New USB device found, idVendor=0403, idProduct=6001 
[    4.224163] usb 1-1.3.2: New USB device strings: Mfr=1, Product=2, SerialNum3
[    4.231631] usb 1-1.3.2: Product: FT232R USB UART                            
[    4.236311] usb 1-1.3.2: Manufacturer: FTDI                                  
[    4.240494] usb 1-1.3.2: SerialNumber: A10125LI                              
[    4.330147] usb 1-1.3.3: new low-speed USB device number 8 using oxnas-ehci  
[    4.549237] usb 1-1.3.3: New USB device found, idVendor=051d, idProduct=0002 
[    4.556295] usb 1-1.3.3: New USB device strings: Mfr=3, Product=1, SerialNum2
[    4.563763] usb 1-1.3.3: Product: Back-UPS CS 650 FW:817.v6.I USB FW:v6      
[    4.570368] usb 1-1.3.3: Manufacturer: American Power Conversion             
[    4.576345] usb 1-1.3.3: SerialNumber: 4B1037P54836                          
[    6.125742] hid-generic 0003:051D:0002.0001: hiddev0,hidraw0: USB HID v1.10 0
Begin: Running /scripts/init-premount ... done.                                 
Begin: Mounting root file system ... Begin: Running /scripts/local-top ... done.
Begin: Running /scripts/local-premount ... done.                                
Begin: Will now check root file system ... fsck from util-linux 2.29.2          
fsck: error 2 (No such file or directory) while executing fsck.ext3 for /dev/sda2
fsck exited with status code 8                                                  
done.                                                                           
Warning: File system check failed but did not detect errors                     
[   18.708513] EXT4-fs (sda2): mounting ext3 file system using the ext4 subsystm
[   18.721121] EXT4-fs (sda2): mounted filesystem with ordered data mode. Opts:)
done.                                                                           
Begin: Running /scripts/local-bottom ... done.                                  
Begin: Running /scripts/init-bottom ... done.                                   
INIT: version 2.88 booting                                                      
[info] Using makefile-style concurrent boot in runlevel S.                      
[....] Starting the hotplug events dispatcher: systemd-udevdstarting version 232
. ok                                                                            
[....] Synthesizing the initial hotplug events...[   20.811404] media: Linux me0
[ ok [   20.849226] Linux video capture interface: v2.00                        
[   20.897013] usbcore: registered new interface driver ftdi_sio                
[   20.927155] uvcvideo: Found UVC 1.00 device <unnamed> (046d:0991)            
                                                 [   20.970503] usbserial: USB e
done.                                                                           
[   21.007859] input: UVC Camera (046d:0991) as /devices/platform/40200100.ehci0
[   21.023956] ftdi_sio 1-1.3.2:1.0: FTDI USB Serial Device converter detected  
[....] Waiting for /dev[   21.050611] uvcvideo: Found UVC 1.00 device <unnamed>)
 to be fully populated...[   21.066145] usb 1-1.3.2: Detected FT232RL           
[   21.106268] usb 1-1.3.2: FTDI USB Serial Device converter now attached to tt0
[   21.164904] input: UVC Camera (046d:0991) as /devices/platform/40200100.ehci1
[   21.213130] uvcvideo: Found UVC 1.00 device <unnamed> (046d:0819)            
[   21.351157] input: UVC Camera (046d:0819) as /devices/platform/40200100.ehci2
[   21.382927] usbcore: registered new interface driver uvcvideo                
[   21.390586] USB Video Class driver (1.1.1)                                   
[   21.436428] usb 1-1.1: set resolution quirk: cval->res = 384                 
[   21.846113] usb 1-1.2: set resolution quirk: cval->res = 384                 
[   22.366113] usb 1-1.3.1: set resolution quirk: cval->res = 384               
[   22.376947] usbcore: registered new interface driver snd-usb-audio           
done.                                                                           
[   23.218347] EXT4-fs (sda2): re-mounted. Opts: (null)                         
[....] Checking root file system...fsck from util-linux 2.29.2                  
e2fsck 1.43.4 (31-Jan-2017)                                                     
rootfs: clean, 66572/262144 files, 560280/1048576 blocks                        
done.                                                                           
[   23.471447] EXT4-fs (sda2): re-mounted. Opts: errors=remount-ro              
[....] Activating lvm and md swap...[   25.484715] Adding 524284k swap on /swapS
done.                                                                           
[....] Checking file systems...fsck from util-linux 2.29.2                      
e2fsck 1.43.4 (31-Jan-2017)                                                     
home: clean, 1212/15368192 files, 32931415/61463382 blocks                      
done.                                                                           
[....] Loading kernel modules...done.                                           
[....] Cleaning up temporary files... /tmp. ok                                  
/etc/init.d/mountall.sh: 4: /etc/default/locale: LC_TIME: not found             
/etc/init.d/mountall.sh: 5: /etc/default/locale: LC_MONETARY: not found         
/etc/init.d/mountall.sh: 6: /etc/default/locale: LC_ADDRESS: not found          
/etc/init.d/mountall.sh: 7: /etc/default/locale: LC_TELEPHONE: not found        
/etc/init.d/mountall.sh: 8: /etc/default/locale: LC_NAME: not found             
/etc/init.d/mountall.sh: 9: /etc/default/locale: LC_MEASUREMENT: not found      
/etc/init.d/mountall.sh: 10: /etc/default/locale: LC_IDENTIFICATION: not found  
/etc/init.d/mountall.sh: 11: /etc/default/locale: LC_NUMERIC: not found         
/etc/init.d/mountall.sh: 12: /etc/default/locale: LC_PAPER: not found           
/etc/init.d/mountall.sh: 13: /etc/default/locale: LANG: not found               
[....] Mounting local filesystems...done.                                       
[....] Activating swapfile swap...done.                                         
[....] Cleaning up temporary files.... ok                                       
[....] Setting kernel variables...done.                                         
[   31.270297] oxnas-gmac 40400000.ethernet eth0: Link is Up - 1Gbps/Full - flox
[....] Configuring network interfaces...Internet Systems Consortium DHCP Client5
Copyright 2004-2016 Internet Systems Consortium.                                
All rights reserved.                                                            
For info, please visit https://www.isc.org/software/dhcp/                       
                                                                                
Listening on LPF/eth0/00:25:31:02:98:3b                                         
Sending on   LPF/eth0/00:25:31:02:98:3b                                         
Sending on   Socket/fallback                                                    
DHCPREQUEST of 192.168.0.86 on eth0 to 255.255.255.255 port 67                  
DHCPREQUEST of 192.168.0.86 on eth0 to 255.255.255.255 port 67                  
DHCPNAK from 192.168.0.254                                                      
DHCPDISCOVER on eth0 to 255.255.255.255 port 67 interval 7                      
DHCPREQUEST of 192.168.0.10 on eth0 to 255.255.255.255 port 67                  
DHCPOFFER of 192.168.0.10 from 192.168.0.254                                    
DHCPACK of 192.168.0.10 from 192.168.0.254                                      
invoke-rc.d: could not determine current runlevel                               
Reloading /etc/samba/smb.conf: smbd.                                            
bound to 192.168.0.10 -- renewal in 19834 seconds.                              
done.                                                                           
[....] Starting RPC port mapper daemon: rpcbind. ok                             
[....] Starting NFS common utilities: statd idmapd. ok                          
[....] Cleaning up temporary files.... ok                                       
INIT: Entering runlevel: 2                                                      
[info] Using makefile-style concurrent boot in runlevel 2.                      
[....] Starting busybox' syslogd implementation : syslogdStarting /sbin/syslogd.
1285 (syslogd)                                                                  
. ok                                                                            
[....] Starting bittorrent daemon: transmission-daemon. ok                      
[....] Starting NFS common utilities: statd idmapd. ok                          
[....] Starting periodic command scheduler: cron. ok                            
[....] Starting busybox' klogd implementation : klogdStarting /sbin/klogd...    
1283 (klogd)                                                                    
. ok                                                                            
[....] Starting MTA:[....] Starting system message bus: dbus exim4. ok          
. ok                                                                            
[....] Starting Avahi mDNS/DNS-SD Daemon: avahi-daemon. ok                      
[....] Starting network daemon:: mosquitto. ok                                  
[....] Starting NetBIOS name server: nmbd. ok                                   
[....] Not starting NFS kernel daemon: no exports. ... (warning).               
[....] Starting NTP server: ntpd. ok                                            
[....] Starting OpenBSD Secure Shell server: sshd. ok                           
Starting TLS tunnels: /etc/stunnel/cam.conf: started /etc/stunnel/espserial.cond
[....] Starting SMB/CIFS daemon: smbd. ok                                       
                                                                                
Debian GNU/Linux 9 sch ttyS0                                             
                                                                                
sch login:

If i plug the drive after boot in mounted fine:
  433.830144] usb 1-1.4: new high-speed USB device number 9 using oxnas-ehci   
[  433.941995] usb 1-1.4: New USB device found, idVendor=152d, idProduct=2329   
[  433.949719] usb 1-1.4: New USB device strings: Mfr=1, Product=2, SerialNumbe5
[  433.970160] usb 1-1.4: Product: USB to ATA/ATAPI bridge                      
[  433.976017] usb 1-1.4: Manufacturer: JMicron                                 
[  433.981552] usb 1-1.4: SerialNumber: F000A6DFFFFF                            
[  433.989647] usb-storage 1-1.4:1.0: USB Mass Storage device detected          
[  434.010344] usb-storage 1-1.4:1.0: Quirks match for vid 152d pid 2329: 8020  
[  434.030530] scsi host1: usb-storage 1-1.4:1.0                                
[  434.125870] usbcore: registered new interface driver uas                     
[  435.031800] scsi 1:0:0:0: Direct-Access     Hitachi  HDS721010KLA330       PS
[  435.049505] sd 1:0:0:0: [sdb] 1953525168 512-byte logical blocks: (1.00 TB/9)
[  435.061772] sd 1:0:0:0: [sdb] Write Protect is off                           
[  435.068500] sd 1:0:0:0: [sdb] No Caching mode page found                     
[  435.074567] sd 1:0:0:0: [sdb] Assuming drive cache: write through            
[  435.115115]  sdb: sdb1                                                       
[  435.125032] sd 1:0:0:0: [sdb] Attached SCSI disk                             
[  436.093004] EXT4-fs (sdb1): recovery complete                                
[  436.097794] EXT4-fs (sdb1): mounted filesystem with ordered data mode. Opts:)

I looked at the boot log and see the following in both cases. /dev/sda2 is my root partition this was LABEL=rootfs in /etc/fstab, but with that it always fell to emergency prompt at boot.
Begin: Will now check root file system ... fsck from util-linux 2.29.2          
fsck: error 2 (No such file or directory) while executing fsck.ext3 for /dev/sda2
fsck exited with status code 8                                                  
done.                                                                           
Warning: File system check failed but did not detect errors                     
[   18.708513] EXT4-fs (sda2): mounting ext3 file system using the ext4 subsystm
[   18.721121] EXT4-fs (sda2): mounted filesystem with ordered data mode. Opts:)
done.

the other is about the USB attached drive:
[    4.721351] scsi 1:0:0:0: Direct-Access     Hitachi  HDS721010KLA330       PS
[    4.735242] sd 1:0:0:0: [sdb] 1953525168 512-byte logical blocks: (1.00 TB/9)
[    4.748157] sd 1:0:0:0: [sdb] Write Protect is off                           
[    4.754659] sd 1:0:0:0: [sdb] No Caching mode page found                     
[    4.759964] sd 1:0:0:0: [sdb] Assuming drive cache: write through            
[    4.791713]  sdb: sdb1                                                       
[    4.808714] sd 1:0:0:0: [sdb] Attached SCSI disk                             
[    5.291456] EXT4-fs (sdb1): couldn't mount as ext3 due to feature incompatibs
[    5.541404] EXT4-fs (sdb1): mounted filesystem with ordered data mode. Opts:)
[    6.150656] hid-generic 0003:051D:0002.0001: hiddev0,hidraw0: USB HID v1.10 0
Begin: Loading essential drivers ... done.                                      
Begin: Running /scripts/init-premount ... done.

Here is the boot line for the kernel from uboot:
ide_set_bootargs=setenv bootargs console=ttyS0,115200 root=LABEL=rootfs rootdelay=10

Now i am bit stuck as i use the USB harddrive, but i can't boot the device with it attached....

ps: as it can be seen from the log i have quite a few USB devices attached. I tried to boot without them only the harddrive attached, but it ended with the same result.



Edited 2 time(s). Last edit at 02/16/2018 02:18PM by bodhi.
Re: USB drive causes booting problem with Linux Kernel 4.4.89 Oxnas
February 16, 2018 09:48PM
schnee,

I suspect this is a power problem. Usually, if you can plug in the USB HDD after boot and it works, then perhaps it does not have enough juice to bring up the drive during boot. We know that Ext4 works during boot, so it's likely a power thing.

-bodhi
===========================
Forum Wiki
bodhi's corner
Re: USB drive causes booting problem with Linux Kernel 4.4.89 Oxnas
February 17, 2018 04:31AM
bodhi,

i don't think this problem is related to the power. The drive has a external power supply. Also i tried with a standard 8G USB stick. If the stick format was FAT32 than it booted, if the format was ext4 it caused the same issue.

As i changed both the kernel and the root filesystem at the same time, i will do some more testing next week:
- downgrade the kernel to 4.4.54 and test also with 4.4.79
- try to boot with systemd

Do you have any idea what causing this issue in the boot process:
Begin: Will now check root file system ... fsck from util-linux 2.29.2          
fsck: error 2 (No such file or directory) while executing fsck.ext3 for /dev/sda2
fsck exited with status code 8                                                  
done.                                                                           
Warning: File system check failed but did not detect errors                     
[   18.708513] EXT4-fs (sda2): mounting ext3 file system using the ext4 subsystm
[   18.721121] EXT4-fs (sda2): mounted filesystem with ordered data mode. Opts:)
done.

It is always shown, but with the original fstab (from the rootfs) at this point do boot stops with an emergency shell.

Thanks
Re: USB drive causes booting problem with Linux Kernel 4.4.89 Oxnas
February 17, 2018 03:00PM
schnee,

This is suspect:

Quote

[ 5.291456] EXT4-fs (sdb1): couldn't mount as ext3 due to feature incompatibs


Quote

Begin: Will now check root file system ... fsck from util-linux 2.29.2
fsck: error 2 (No such file or directory) while executing fsck.ext3 for /dev/sda2
fsck exited with status code 8
done.
Warning: File system check failed but did not detect errors
[ 18.708513] EXT4-fs (sda2): mounting ext3 file system using the ext4 subsystm
[ 18.721121] EXT4-fs (sda2): mounted filesystem with ordered data mode. Opts:)
done.

The above error is OK, if your fstab contains LABEL=rootfs for the root device, because it will be checked later. Fstab should contain this.

One more thing to check. Which Debian version? the behavior for fstab has changed from jessie to stretch. The old format /dev/root will cause the system to stop and kick out to initramfs shell.

cat  /etc/debian_version

-bodhi
===========================
Forum Wiki
bodhi's corner
Re: USB drive causes booting problem with Linux Kernel 4.4.89 Oxnas
February 18, 2018 02:17AM
I used you latest rootfs image to create drive.

~$ cat  /etc/debian_version
9.3

This is the fstab file:
# /etc/fstab: static file system information.
#
# <file system> <mount point>   <type>  <options>       <dump>  <pass>
/dev/sda2      /               ext4    noatime,errors=remount-ro 0 1
tmpfs          /tmp            tmpfs   defaults          0       0
/dev/sda3      /home           ext2    noatime,errors=remount-ro 0 1
/swapfile1     none            swap    sw                0       0

The /dev/sda2 line is modified from the original as the original dropped to emergency shell during boot.

You mention above the this is a suspect:
[ 5.291456] EXT4-fs (sdb1): couldn't mount as ext3 due to feature incompatibs
This happens quite early in the boot process, before the check for the root filesystem. Can this be the reason?
Re: USB drive causes booting problem with Linux Kernel 4.4.89 Oxnas
February 18, 2018 03:52AM
schnee,

I see. The fstab definition is not every good. You must have LABEL=rootfs to boot consistenly. You are relying on the fact that the rootfs drive is always sda and the rootfs partition is sda2. You can't move the rootfs to sda3,4,5... without changing bootargs and fstab. OTOH, if you use LABEL=rootfs then you are free to re-organize your disk with any layout.

With your Pogo configuration (many drives/partitions), the rootfs label is a must have.

/dev/sda2      /               ext4    noatime,errors=remount-ro 0 1
tmpfs          /tmp            tmpfs   defaults          0       0
/dev/sda3      /home           ext2    noatime,errors=remount-ro 0 1

I would move the "home" partition to EXT3 (Ext2 is prone to corruption), label it "home" partition, and then use this fstab:

LABEL=rootfs        /               ext4    noatime,errors=remount-ro 0 1
tmpfs                     /tmp            tmpfs   defaults          0       0
LABEL=home      /home           ext3    noatime,errors=remount-ro 0 1

=============

Note:

1. Assuming you want to keep /dev/sda2 in fstab, then your bootargs should specify root=/dev/sda2, not root=LABEL=rootfs.
2. With all that said, for this Pogo V3, if you connect the HDD to the internal SATA port, it will be sda. Because the internal SATA is the first drive to be spun up.

-bodhi
===========================
Forum Wiki
bodhi's corner
Re: USB drive causes booting problem with Linux Kernel 4.4.89 Oxnas
February 18, 2018 04:35PM
schnee ,

Also look at this post:

https://forum.doozan.com/read.php?2,51369

-bodhi
===========================
Forum Wiki
bodhi's corner
Re: USB drive causes booting problem with Linux Kernel 4.4.89 Oxnas
February 20, 2018 01:36PM
Tryed a few things tonight, but issue is still not solved:
- tried booting with systemd instead of init -> sam kernel panic
- tried booting with fat, ntfs, ext and ext4 drive (ext4 created with the extra options -O ^64bit)

with fat and ntfs drive attached on boot system booted, with ext2 and ext4 it failed.
example with fat
IDE device 0: Model: Samsung SSD 840 PRO Series Firm: DXM06B0Q Ser#: S1ATNSAF507409D 
            Type: Hard Disk
            Supports 48-bit addressing
            Capacity: 244198.3 MB = 238.4 GB (500118192 x 512)
4100416 bytes read in 557 ms (7 MiB/s)
4280781 bytes read in 582 ms (7 MiB/s)
7075 bytes read in 14 ms (493.2 KiB/s)
## Booting kernel from Legacy Image at 60500000 ...
   Image Name:   Linux-4.4.89-oxnas-tld-1
   Image Type:   ARM Linux Kernel Image (uncompressed)
   Data Size:    4100352 Bytes = 3.9 MiB
   Load Address: 60008000
   Entry Point:  60008000
   Verifying Checksum ... OK
## Loading init Ramdisk from Legacy Image at 60e00000 ...
   Image Name:   initramfs-4.4.89-oxnas-tld-1
   Image Type:   ARM Linux RAMDisk Image (gzip compressed)
   Data Size:    4280717 Bytes = 4.1 MiB
   Load Address: 60000000
   Entry Point:  60000000
   Verifying Checksum ... OK
## Flattened Device Tree blob at 62c00000
   Booting using the fdt blob at 0x62c00000
   Loading Kernel Image ... OK
   Loading Ramdisk to 67a16000, end 67e2b18d ... OK
   Loading Device Tree to 67a11000, end 67a15ba2 ... OK

Starting kernel ...

Uncompressing Linux... done, booting the kernel.
[    0.000000] Booting Linux on physical CPU 0x0
[    0.000000] Linux version 4.4.89-oxnas-tld-1 (root@tldDebian) (gcc version 6.3.0 20170516 (Debian 6.3.0-18) ) #1 S7
[    0.000000] CPU: ARMv6-compatible processor [410fb025] revision 5 (ARMv7), cr=00c5787d
[    0.000000] CPU: PIPT / VIPT nonaliasing data cache, VIPT aliasing instruction cache
[    0.000000] Machine model: Pogoplug V3 Classic
[    0.000000] Memory policy: Data cache writealloc
[    0.000000] DT missing boot CPU MPIDR[23:0], fall back to default cpu_logical_map
[    0.000000] PERCPU: Embedded 12 pages/cpu @c7eda000 s17676 r8192 d23284 u49152
[    0.000000] Built 1 zonelists in Zone order, mobility grouping on.  Total pages: 32512
[    0.000000] Kernel command line: console=ttyS0,115200 root=LABEL=rootfs rootdelay=10
[    0.000000] PID hash table entries: 512 (order: -1, 2048 bytes)
[    0.000000] Dentry cache hash table entries: 16384 (order: 4, 65536 bytes)
[    0.000000] Inode-cache hash table entries: 8192 (order: 3, 32768 bytes)
[    0.000000] Memory: 117604K/131072K available (5664K kernel code, 285K rwdata, 1568K rodata, 220K init, 243K bss, )
[    0.000000] Virtual kernel memory layout:
[    0.000000]     vector  : 0xffff0000 - 0xffff1000   (   4 kB)
[    0.000000]     fixmap  : 0xffc00000 - 0xfff00000   (3072 kB)
[    0.000000]     vmalloc : 0xc8800000 - 0xff800000   ( 880 MB)
[    0.000000]     lowmem  : 0xc0000000 - 0xc8000000   ( 128 MB)
[    0.000000]     modules : 0xbf000000 - 0xc0000000   (  16 MB)
[    0.000000]       .text : 0xc0008000 - 0xc07185bc   (7234 kB)
[    0.000000]       .init : 0xc0719000 - 0xc0750000   ( 220 kB)
[    0.000000]       .data : 0xc0750000 - 0xc0797478   ( 286 kB)
[    0.000000]        .bss : 0xc0797478 - 0xc07d4298   ( 244 kB)
[    0.000000] SLUB: HWalign=32, Order=0-3, MinObjects=0, CPUs=2, Nodes=1
[    0.000000] Preemptible hierarchical RCU implementation.
[    0.000000] NR_IRQS:160
[    0.000000] clocksource: rps_clocksource_timer: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 19112603332 ns
[    0.000010] sched_clock: 24 bits at 390kHz, resolution 2560ns, wraps every 21474835200ns
[    0.000163] Console: colour dummy device 80x30
[    0.000197] Calibrating delay loop... 319.48 BogoMIPS (lpj=1597440)
[    0.050027] pid_max: default: 32768 minimum: 301
[    0.050199] Mount-cache hash table entries: 1024 (order: 0, 4096 bytes)
[    0.050222] Mountpoint-cache hash table entries: 1024 (order: 0, 4096 bytes)
[    0.051118] Initializing cgroup subsys net_cls
[    0.051207] CPU: Testing write buffer coherency: ok
[    0.051655] Setting up static identity map for 0x60008220 - 0x60008258
[    0.190154] Brought up 2 CPUs
[    0.190177] SMP: Total of 2 processors activated (638.97 BogoMIPS).
[    0.191016] devtmpfs: initialized
[    0.196377] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
[    0.196423] futex hash table entries: 512 (order: 2, 16384 bytes)
[    0.196741] prandom: seed boundary self test passed
[    0.201428] prandom: 100 self tests passed
[    0.201461] pinctrl core: initialized pinctrl subsystem
[    0.202406] NET: Registered protocol family 16
[    0.202964] DMA: preallocated 256 KiB pool for atomic coherent allocations
[    0.230016] cpuidle: using governor ladder
[    0.260008] cpuidle: using governor menu
[    0.265054] gpio-oxnas 44000000.gpio: at address c8848000
[    0.265507] gpio-oxnas 44100000.gpio: at address c884a000
[    0.266094] pinctrl-oxnas pinctrl: initialized OX820 pinctrl driver
[    0.278727] vgaarb: loaded
[    0.279237] SCSI subsystem initialized
[    0.280225] usbcore: registered new interface driver usbfs
[    0.280343] usbcore: registered new interface driver hub
[    0.280506] usbcore: registered new device driver usb
[    0.280709] pps_core: LinuxPPS API ver. 1 registered
[    0.280724] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
[    0.280791] PTP clock support registered
[    0.282204] clocksource: Switched to clocksource rps_clocksource_timer
[    0.301460] NET: Registered protocol family 2
[    0.302574] TCP established hash table entries: 1024 (order: 0, 4096 bytes)
[    0.302622] TCP bind hash table entries: 1024 (order: 1, 8192 bytes)
[    0.302658] TCP: Hash tables configured (established 1024 bind 1024)
[    0.302771] UDP hash table entries: 256 (order: 1, 8192 bytes)
[    0.302817] UDP-Lite hash table entries: 256 (order: 1, 8192 bytes)
[    0.303101] NET: Registered protocol family 1
[    0.303631] RPC: Registered named UNIX socket transport module.
[    0.303651] RPC: Registered udp transport module.
[    0.303662] RPC: Registered tcp transport module.
[    0.303672] RPC: Registered tcp NFSv4.1 backchannel transport module.
[    0.304081] Unpacking initramfs...
[    0.774968] Freeing initrd memory: 4184K
[    0.776537] Initialise system trusted keyring
[    0.803745] zbud: loaded
[    0.821529] NFS: Registering the id_resolver key type
[    0.821598] Key type id_resolver registered
[    0.821614] Key type id_legacy registered
[    0.821657] nfs4filelayout_init: NFSv4 File Layout Driver Registering...
[    0.821678] Installing knfsd (copyright (C) 1996 okir@monad.swb.de).
[    0.822656] fuse init (API version 7.23)
[    0.824780] Key type big_key registered
[    0.934717] Key type asymmetric registered
[    0.934755] Asymmetric key parser 'x509' registered
[    0.934896] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 252)
[    0.934924] io scheduler noop registered
[    0.934947] io scheduler deadline registered
[    0.935011] io scheduler cfq registered (default)
[    0.935674] Serial: 8250/16550 driver, 1 ports, IRQ sharing disabled
[    0.937203] console [ttyS0] disabled
[    0.937303] 44200000.uart: ttyS0 at MMIO 0x44200000 (irq = 6, base_baud = 390625) is a 16550A
[    1.457679] console [ttyS0] enabled
[    1.473241] loop: module loaded
[    1.478400] scsi host0: sata_oxnas
[    1.482309] ata1: SATA max UDMA/133 irq 7
[    1.486402] sata_oxnas: resetting SATA core
[    1.487447] nand: Could not find valid ONFI parameter page; aborting
[    1.487490] nand: device found, Manufacturer ID: 0xad, Chip ID: 0xf1
[    1.487498] nand: Hynix NAND 128MiB 3,3V 8-bit
[    1.487505] nand: 128 MiB, SLC, erase size: 128 KiB, page size: 2048, OOB size: 64
[    1.487541] Scanning device for bad blocks
[    1.504686] Bad eraseblock 208 at 0x000001a00000
[    1.564377] Bad eraseblock 937 at 0x000007520000
[    1.576061] 2 ofpart partitions found on MTD device 41000000.nand
[    1.582144] Creating 2 MTD partitions on "41000000.nand":
[    1.587537] 0x000000000000-0x000000e00000 : "boot"
[    1.594357] 0x000000e00000-0x000008000000 : "data"
[    1.601932] oxnas-gmac 40400000.ethernet: no reset control found
[    1.607923] stmmac - user ID: 0x12, Synopsys ID: 0x35
[    1.612997]  Ring mode enabled
[    1.616038]  DMA HW capability register supported
[    1.620564]  Enhanced/Alternate descriptors
[    1.624893]  Enabled extended descriptors
[    1.628876]  RX Checksum Offload Engine supported (type 2)
[    1.634347]  TX Checksum insertion supported
[    1.638592]  Wake-Up On Lan supported
[    1.642245]  Enable RX Mitigation via HW Watchdog Timer
[    1.675494] libphy: stmmac: probed
[    1.678894] eth%d: PHY ID 001cc914 at 0 IRQ POLL (stmmac-0:00) active
[    1.685360] eth%d: PHY ID 001cc914 at 3 IRQ POLL (stmmac-0:03)
[    1.692144] PPP generic driver version 2.4.2
[    1.696734] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
[    1.703301] ehci-pci: EHCI PCI platform driver
[    1.708254] oxnas-ehci 40200100.ehci: EHCI Host Controller
[    1.713863] oxnas-ehci 40200100.ehci: new USB bus registered, assigned bus number 1
[    1.721728] oxnas-ehci 40200100.ehci: irq 10, io mem 0x40200100
[    1.740147] oxnas-ehci 40200100.ehci: USB 2.0 started, EHCI 1.00
[    1.746462] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002
[    1.753262] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    1.760471] usb usb1: Product: EHCI Host Controller
[    1.765322] usb usb1: Manufacturer: Linux 4.4.89-oxnas-tld-1 ehci_hcd
[    1.771745] usb usb1: SerialNumber: 40200100.ehci
[    1.777408] hub 1-0:1.0: USB hub found
[    1.781278] hub 1-0:1.0: 2 ports detected
[    1.786403] usbcore: registered new interface driver cdc_acm
[    1.792081] cdc_acm: USB Abstract Control Model driver for USB modems and ISDN adapters
[    1.800197] usbcore: registered new interface driver cdc_wdm
[    1.806036] usbcore: registered new interface driver usb-storage
[    1.812241] usbcore: registered new interface driver usbserial
[    1.818112] usbcore: registered new interface driver usbserial_generic
[    1.824721] usbserial: USB Serial support registered for generic
[    1.831168] mousedev: PS/2 mouse device common for all mice
[    1.836902] i2c /dev entries driver
[    1.841784] hidraw: raw HID events driver (C) Jiri Kosina
[    1.847403] usbcore: registered new interface driver usbhid
[    1.852992] usbhid: USB HID core driver
[    1.859089] NET: Registered protocol family 10
[    1.880773] sit: IPv6 over IPv4 tunneling driver
[    1.886471] NET: Registered protocol family 17
[    1.891507] Key type dns_resolver registered
[    1.897108] registered taskstats version 1
[    1.901273] Loading compiled-in X.509 certificates
[    1.906165] zswap: loaded using pool lzo/zbud
[    1.925639] Key type encrypted registered
[    2.040002] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 310)
[    2.046917] ata1.00: ATA-9: Samsung SSD 840 PRO Series, DXM06B0Q, max UDMA/133
[    2.054159] ata1.00: 500118192 sectors, multi 16: LBA48 NCQ (depth 0/32)
[    2.061178] ata1.00: configured for UDMA/133
[    2.080704] scsi 0:0:0:0: Direct-Access     ATA      Samsung SSD 840  6B0Q PQ: 0 ANSI: 5
[    2.090188] ata1.00: Enabling discard_zeroes_data
[    2.094978] sd 0:0:0:0: [sda] 500118192 512-byte logical blocks: (256 GB/238 GiB)
[    2.104002] sd 0:0:0:0: [sda] Write Protect is off
[    2.109015] sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
[    2.118909] ata1.00: Enabling discard_zeroes_data
[    2.120163] usb 1-1: new high-speed USB device number 2 using oxnas-ehci
[    2.131788]  sda: sda1 sda2 sda3
[    2.136972] ata1.00: Enabling discard_zeroes_data
[    2.142725] sd 0:0:0:0: [sda] Attached SCSI disk
[    2.147686] Freeing unused kernel memory: 220K
Loading, please wait...
[    2.282155] usb 1-1: New USB device found, idVendor=05e3, idProduct=0608
[    2.289753] usb 1-1: New USB device strings: Mfr=0, Product=1, SerialNumber=0
[    2.296954] usb 1-1: Product: USB2.0 Hub
[    2.303175] hub 1-1:1.0: USB hub found
[    2.307289] hub 1-1:1.0: 4 ports detected
starting version 232
[    2.349066] random: systemd-udevd: uninitialized urandom read (16 bytes read, 0 bits of entropy available)
[    2.360627] random: systemd-udevd: uninitialized urandom read (16 bytes read, 0 bits of entropy available)
[    2.361569] random: udevadm: uninitialized urandom read (16 bytes read, 0 bits of entropy available)
[    2.364062] random: udevadm: uninitialized urandom read (16 bytes read, 0 bits of entropy available)
[    2.364229] random: udevadm: uninitialized urandom read (16 bytes read, 0 bits of entropy available)
[    2.365286] random: udevadm: uninitialized urandom read (16 bytes read, 0 bits of entropy available)
[    2.366384] random: udevadm: uninitialized urandom read (16 bytes read, 0 bits of entropy available)
[    2.367449] random: udevadm: uninitialized urandom read (16 bytes read, 0 bits of entropy available)
[    2.373416] random: udevadm: uninitialized urandom read (16 bytes read, 0 bits of entropy available)
[    2.374430] random: udevadm: uninitialized urandom read (16 bytes read, 0 bits of entropy available)
[    2.622366] usb 1-1.4: new high-speed USB device number 3 using oxnas-ehci
[    2.753789] usb 1-1.4: New USB device found, idVendor=0951, idProduct=1665
[    2.763596] usb 1-1.4: New USB device strings: Mfr=1, Product=2, SerialNumber=3
[    2.809597] usb 1-1.4: Product: DataTraveler 2.0
[    2.823874] usb 1-1.4: Manufacturer: Kingston
[    2.842542] usb 1-1.4: SerialNumber: 001CC0EC33B0FD11C70C4AB3
[    2.897123] usb-storage 1-1.4:1.0: USB Mass Storage device detected
[    2.926750] scsi host1: usb-storage 1-1.4:1.0
[    3.132444] usbcore: registered new interface driver uas
Begin: Loading essential drivers ... done.
[    3.956254] scsi 1:0:0:0: Direct-Access     Kingston DataTraveler 2.0 1.00 PQ: 0 ANSI: 4
[    3.971637] sd 1:0:0:0: [sdb] 15131636 512-byte logical blocks: (7.75 GB/7.21 GiB)
[    3.980887] sd 1:0:0:0: [sdb] Write Protect is off
[    3.990740] sd 1:0:0:0: [sdb] Write cache: disabled, read cache: enabled, doesn't support DPO or FUA
[    4.016849]  sdb: sdb1
[    4.024222] sd 1:0:0:0: [sdb] Attached SCSI removable disk
[    4.342589] FAT-fs (sdb1): codepage cp437 not found
[    4.352609] F2FS-fs (sdb1): Magic Mismatch, valid(0xf2f52010) - read(0x0)
[    4.359377] F2FS-fs (sdb1): Can't find valid F2FS filesystem in 1th superblock
[    4.367610] F2FS-fs (sdb1): Magic Mismatch, valid(0xf2f52010) - read(0x0)
[    4.374576] F2FS-fs (sdb1): Can't find valid F2FS filesystem in 2th superblock
[    4.381946] F2FS-fs (sdb1): Magic Mismatch, valid(0xf2f52010) - read(0x0)
[    4.388707] F2FS-fs (sdb1): Can't find valid F2FS filesystem in 1th superblock
[    4.396011] F2FS-fs (sdb1): Magic Mismatch, valid(0xf2f52010) - read(0x0)
[    4.402856] F2FS-fs (sdb1): Can't find valid F2FS filesystem in 2th superblock
Begin: Running /scripts/init-premount ... done.
Begin: Mounting root file system ... Begin: Running /scripts/local-top ... done.
Begin: Running /scripts/local-premount ... done.
Begin: Will now check root file system ... fsck from util-linux 2.29.2
fsck: error 2 (No such file or directory) while executing fsck.ext3 for /dev/sda2
fsck exited with status code 8
done.
Warning: File system check failed but did not detect errors
[   18.378455] EXT4-fs (sda2): mounting ext3 file system using the ext4 subsystem
[   18.411005] EXT4-fs (sda2): mounted filesystem with ordered data mode. Opts: (null)
done.
Begin: Running /scripts/local-bottom ... done.
Begin: Running /scripts/init-bottom ... done.
INIT: version 2.88 booting
[info] Using makefile-style concurrent boot in runlevel S.
[....] Starting the hotplug events dispatcher: systemd-udevdstarting version 232
. ok 
[....] Synthesizing the initial hotplug events...done.
[....] Waiting for /dev to be fully populated...done.
[   22.119761] EXT4-fs (sda2): re-mounted. Opts: (null)
[....] Checking root file system...fsck from util-linux 2.29.2
e2fsck 1.43.4 (31-Jan-2017)
rootfs: clean, 66840/262144 files, 561866/1048576 blocks
done.
[   22.375595] EXT4-fs (sda2): re-mounted. Opts: errors=remount-ro
[....] Activating lvm and md swap...[   24.363827] Adding 524284k swap on /swapfile1.  Priority:-1 extents:13 across:S
done.
[....] Checking file systems...fsck from util-linux 2.29.2
e2fsck 1.43.4 (31-Jan-2017)
home: clean, 1276/15368192 files, 33145926/61463382 blocks
done.
[....] Loading kernel modules...done.
[....] Cleaning up temporary files... /tmp. ok 
[....] Mounting local filesystems...done.
[....] Activating swapfile swap...done.
[....] Cleaning up temporary files.... ok 
[....] Setting kernel variables...done.
[....] Configuring network interfaces...Internet Systems Consortium DHCP Client 4.3.5
Copyright 2004-2016 Internet Systems Consortium.
All rights reserved.
For info, please visit https://www.isc.org/software/dhcp/

Listening on LPF/eth0/00:25:31:02:98:3b
Sending on   LPF/eth0/00:25:31:02:98:3b
Sending on   Socket/fallback
DHCPREQUEST of 192.168.0.10 on eth0 to 255.255.255.255 port 67
[   32.100167] oxnas-gmac 40400000.ethernet eth0: Link is Up - 1Gbps/Full - flow control rx/tx
DHCPREQUEST of 192.168.0.10 on eth0 to 255.255.255.255 port 67
DHCPACK of 192.168.0.10 from 192.168.0.254
invoke-rc.d: could not determine current runlevel
Reloading /etc/samba/smb.conf: smbd.
bound to 192.168.0.10 -- renewal in 18157 seconds.
done.
[....] Starting RPC port mapper daemon: rpcbind. ok 
[....] Starting NFS common utilities: statd idmapd. ok 
[....] Cleaning up temporary files.... ok 
INIT: Entering runlevel: 2
[info] Using makefile-style concurrent boot in runlevel 2.
[....] Starting busybox' syslogd implementation : syslogdStarting /sbin/syslogd...
1296 (syslogd)
. ok 
[....] Starting NFS common utilities: statd idmapd. ok 
[+] Starting mjpg_streamer
ls: cannot access '/dev/video*': No such file or directory
[+] mjpg_streamer started
[....] Starting busybox' klogd implementation : klogdStarting /sbin/klogd...
1297 (klogd)
. ok 
[....] Starting periodic command scheduler: cron. ok 
[....] Starting system message bus: dbus. ok 
[....] Starting Avahi mDNS/DNS-SD Daemon: avahi-daemon. ok 
[....] Starting network daemon:: mosquitto. ok 
[....] Starting MTA:[....] Starting NetBIOS name server: nmbd[....] Starting NTP server: ntpd exim4. ok 
[....] Not starting NFS kernel daemon: no exports. ... (warning).
. ok 
. ok 
[....] Starting OpenBSD Secure Shell server: sshd. ok 
[....] Starting bittorrent daemon: transmission-daemon. ok 
Starting TLS tunnels: /etc/stunnel/cam.conf: started /etc/stunnel/espserial.conf: started /etc/stunnel/transmission.cd
[....] Starting SMB/CIFS daemon: smbd. ok 
[   49.218140] random: nonblocking pool is initialized

Debian GNU/Linux 9 schtorrent ttyS0

sch login: root
Password: 
Last login: Tue Feb 20 20:37:55 CET 2018 on ttyS0
Linux sch 4.4.89-oxnas-tld-1 #1 SMP PREEMPT Wed Oct 4 22:22:09 PDT 2017 armv6l

The programs included with the Debian GNU/Linux system are free software;
the exact distribution terms for each program are described in the
individual files in /usr/share/doc/*/copyright.

Debian GNU/Linux comes with ABSOLUTELY NO WARRANTY, to the extent
permitted by applicable law.
root@sch:~# df -h
Filesystem      Size  Used Avail Use% Mounted on
udev             58M     0   58M   0% /dev
tmpfs            12M  368K   12M   4% /run
/dev/sda2       3.9G  2.1G  1.7G  55% /
tmpfs           5.0M     0  5.0M   0% /run/lock
/dev/sdb1       7.2G  4.0K  7.2G   1% /media/test-fat
tmpfs            24M     0   24M   0% /run/shm
tmpfs            60M   20K   60M   1% /tmp
/dev/sda3       231G  123G   97G  57% /home

Format ext2:
Starting kernel ...

Uncompressing Linux... done, booting the kernel.
[    0.000000] Booting Linux on physical CPU 0x0
[    0.000000] Linux version 4.4.89-oxnas-tld-1 (root@tldDebian) (gcc version 6.3.0 20170516 (Debian 6.3.0-18) ) #1 S7
[    0.000000] CPU: ARMv6-compatible processor [410fb025] revision 5 (ARMv7), cr=00c5787d
[    0.000000] CPU: PIPT / VIPT nonaliasing data cache, VIPT aliasing instruction cache
[    0.000000] Machine model: Pogoplug V3 Classic
[    0.000000] Memory policy: Data cache writealloc
[    0.000000] DT missing boot CPU MPIDR[23:0], fall back to default cpu_logical_map
[    0.000000] PERCPU: Embedded 12 pages/cpu @c7eda000 s17676 r8192 d23284 u49152
[    0.000000] Built 1 zonelists in Zone order, mobility grouping on.  Total pages: 32512
[    0.000000] Kernel command line: console=ttyS0,115200 root=LABEL=rootfs rootdelay=10
[    0.000000] PID hash table entries: 512 (order: -1, 2048 bytes)
[    0.000000] Dentry cache hash table entries: 16384 (order: 4, 65536 bytes)
[    0.000000] Inode-cache hash table entries: 8192 (order: 3, 32768 bytes)
[    0.000000] Memory: 117604K/131072K available (5664K kernel code, 285K rwdata, 1568K rodata, 220K init, 243K bss, )
[    0.000000] Virtual kernel memory layout:
[    0.000000]     vector  : 0xffff0000 - 0xffff1000   (   4 kB)
[    0.000000]     fixmap  : 0xffc00000 - 0xfff00000   (3072 kB)
[    0.000000]     vmalloc : 0xc8800000 - 0xff800000   ( 880 MB)
[    0.000000]     lowmem  : 0xc0000000 - 0xc8000000   ( 128 MB)
[    0.000000]     modules : 0xbf000000 - 0xc0000000   (  16 MB)
[    0.000000]       .text : 0xc0008000 - 0xc07185bc   (7234 kB)
[    0.000000]       .init : 0xc0719000 - 0xc0750000   ( 220 kB)
[    0.000000]       .data : 0xc0750000 - 0xc0797478   ( 286 kB)
[    0.000000]        .bss : 0xc0797478 - 0xc07d4298   ( 244 kB)
[    0.000000] SLUB: HWalign=32, Order=0-3, MinObjects=0, CPUs=2, Nodes=1
[    0.000000] Preemptible hierarchical RCU implementation.
[    0.000000] NR_IRQS:160
[    0.000000] clocksource: rps_clocksource_timer: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 19112603332 ns
[    0.000010] sched_clock: 24 bits at 390kHz, resolution 2560ns, wraps every 21474835200ns
[    0.000163] Console: colour dummy device 80x30
[    0.000197] Calibrating delay loop... 319.48 BogoMIPS (lpj=1597440)
[    0.050024] pid_max: default: 32768 minimum: 301
[    0.050201] Mount-cache hash table entries: 1024 (order: 0, 4096 bytes)
[    0.050224] Mountpoint-cache hash table entries: 1024 (order: 0, 4096 bytes)
[    0.051100] Initializing cgroup subsys net_cls
[    0.051192] CPU: Testing write buffer coherency: ok
[    0.051632] Setting up static identity map for 0x60008220 - 0x60008258
[    0.190156] Brought up 2 CPUs
[    0.190182] SMP: Total of 2 processors activated (638.97 BogoMIPS).
[    0.191032] devtmpfs: initialized
[    0.196334] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
[    0.196380] futex hash table entries: 512 (order: 2, 16384 bytes)
[    0.196700] prandom: seed boundary self test passed
[    0.201390] prandom: 100 self tests passed
[    0.201423] pinctrl core: initialized pinctrl subsystem
[    0.202321] NET: Registered protocol family 16
[    0.202877] DMA: preallocated 256 KiB pool for atomic coherent allocations
[    0.230013] cpuidle: using governor ladder
[    0.260011] cpuidle: using governor menu
[    0.265128] gpio-oxnas 44000000.gpio: at address c8848000
[    0.265569] gpio-oxnas 44100000.gpio: at address c884a000
[    0.266145] pinctrl-oxnas pinctrl: initialized OX820 pinctrl driver
[    0.278935] vgaarb: loaded
[    0.279493] SCSI subsystem initialized
[    0.280445] usbcore: registered new interface driver usbfs
[    0.280560] usbcore: registered new interface driver hub
[    0.280721] usbcore: registered new device driver usb
[    0.280916] pps_core: LinuxPPS API ver. 1 registered
[    0.280931] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
[    0.280995] PTP clock support registered
[    0.282339] clocksource: Switched to clocksource rps_clocksource_timer
[    0.301713] NET: Registered protocol family 2
[    0.302822] TCP established hash table entries: 1024 (order: 0, 4096 bytes)
[    0.302868] TCP bind hash table entries: 1024 (order: 1, 8192 bytes)
[    0.302906] TCP: Hash tables configured (established 1024 bind 1024)
[    0.303019] UDP hash table entries: 256 (order: 1, 8192 bytes)
[    0.303070] UDP-Lite hash table entries: 256 (order: 1, 8192 bytes)
[    0.303357] NET: Registered protocol family 1
[    0.303877] RPC: Registered named UNIX socket transport module.
[    0.303897] RPC: Registered udp transport module.
[    0.303907] RPC: Registered tcp transport module.
[    0.303920] RPC: Registered tcp NFSv4.1 backchannel transport module.
[    0.304332] Unpacking initramfs...
[    0.776212] Freeing initrd memory: 4184K
[    0.777745] Initialise system trusted keyring
[    0.804116] zbud: loaded
[    0.821516] NFS: Registering the id_resolver key type
[    0.821583] Key type id_resolver registered
[    0.821598] Key type id_legacy registered
[    0.821642] nfs4filelayout_init: NFSv4 File Layout Driver Registering...
[    0.821662] Installing knfsd (copyright (C) 1996 okir@monad.swb.de).
[    0.822607] fuse init (API version 7.23)
[    0.824757] Key type big_key registered
[    0.924254] Key type asymmetric registered
[    0.924290] Asymmetric key parser 'x509' registered
[    0.924433] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 252)
[    0.924464] io scheduler noop registered
[    0.924487] io scheduler deadline registered
[    0.924556] io scheduler cfq registered (default)
[    0.925227] Serial: 8250/16550 driver, 1 ports, IRQ sharing disabled
[    0.926748] console [ttyS0] disabled
[    0.926845] 44200000.uart: ttyS0 at MMIO 0x44200000 (irq = 6, base_baud = 390625) is a 16550A
[    1.447357] console [ttyS0] enabled
[    1.463060] loop: module loaded
[    1.468180] scsi host0: sata_oxnas
[    1.472112] ata1: SATA max UDMA/133 irq 7
[    1.476206] sata_oxnas: resetting SATA core
[    1.477286] nand: Could not find valid ONFI parameter page; aborting
[    1.477329] nand: device found, Manufacturer ID: 0xad, Chip ID: 0xf1
[    1.477335] nand: Hynix NAND 128MiB 3,3V 8-bit
[    1.477345] nand: 128 MiB, SLC, erase size: 128 KiB, page size: 2048, OOB size: 64
[    1.477378] Scanning device for bad blocks
[    1.494533] Bad eraseblock 208 at 0x000001a00000
[    1.553845] Bad eraseblock 937 at 0x000007520000
[    1.565483] 2 ofpart partitions found on MTD device 41000000.nand
[    1.571568] Creating 2 MTD partitions on "41000000.nand":
[    1.576960] 0x000000000000-0x000000e00000 : "boot"
[    1.583810] 0x000000e00000-0x000008000000 : "data"
[    1.591324] oxnas-gmac 40400000.ethernet: no reset control found
[    1.597314] stmmac - user ID: 0x12, Synopsys ID: 0x35
[    1.602385]  Ring mode enabled
[    1.605424]  DMA HW capability register supported
[    1.609932]  Enhanced/Alternate descriptors
[    1.614284]  Enabled extended descriptors
[    1.618273]  RX Checksum Offload Engine supported (type 2)
[    1.623741]  TX Checksum insertion supported
[    1.627985]  Wake-Up On Lan supported
[    1.631641]  Enable RX Mitigation via HW Watchdog Timer
[    1.665482] libphy: stmmac: probed
[    1.668879] eth%d: PHY ID 001cc914 at 0 IRQ POLL (stmmac-0:00) active
[    1.675345] eth%d: PHY ID 001cc914 at 3 IRQ POLL (stmmac-0:03)
[    1.682076] PPP generic driver version 2.4.2
[    1.686661] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
[    1.693230] ehci-pci: EHCI PCI platform driver
[    1.698186] oxnas-ehci 40200100.ehci: EHCI Host Controller
[    1.703795] oxnas-ehci 40200100.ehci: new USB bus registered, assigned bus number 1
[    1.711685] oxnas-ehci 40200100.ehci: irq 10, io mem 0x40200100
[    1.730150] oxnas-ehci 40200100.ehci: USB 2.0 started, EHCI 1.00
[    1.736468] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002
[    1.743265] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    1.750474] usb usb1: Product: EHCI Host Controller
[    1.755328] usb usb1: Manufacturer: Linux 4.4.89-oxnas-tld-1 ehci_hcd
[    1.761753] usb usb1: SerialNumber: 40200100.ehci
[    1.767449] hub 1-0:1.0: USB hub found
[    1.771320] hub 1-0:1.0: 2 ports detected
[    1.776448] usbcore: registered new interface driver cdc_acm
[    1.782123] cdc_acm: USB Abstract Control Model driver for USB modems and ISDN adapters
[    1.790243] usbcore: registered new interface driver cdc_wdm
[    1.796080] usbcore: registered new interface driver usb-storage
[    1.802280] usbcore: registered new interface driver usbserial
[    1.808153] usbcore: registered new interface driver usbserial_generic
[    1.814763] usbserial: USB Serial support registered for generic
[    1.821219] mousedev: PS/2 mouse device common for all mice
[    1.826959] i2c /dev entries driver
[    1.831802] hidraw: raw HID events driver (C) Jiri Kosina
[    1.837422] usbcore: registered new interface driver usbhid
[    1.843013] usbhid: USB HID core driver
[    1.849085] NET: Registered protocol family 10
[    1.855006] sit: IPv6 over IPv4 tunneling driver
[    1.860776] NET: Registered protocol family 17
[    1.865372] Key type dns_resolver registered
[    1.871270] registered taskstats version 1
[    1.875402] Loading compiled-in X.509 certificates
[    1.880335] zswap: loaded using pool lzo/zbud
[    1.900226] Key type encrypted registered
[    2.030013] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 310)
[    2.036897] ata1.00: ATA-9: Samsung SSD 840 PRO Series, DXM06B0Q, max UDMA/133
[    2.044139] ata1.00: 500118192 sectors, multi 16: LBA48 NCQ (depth 0/32)
[    2.051164] ata1.00: configured for UDMA/133
[    2.070702] scsi 0:0:0:0: Direct-Access     ATA      Samsung SSD 840  6B0Q PQ: 0 ANSI: 5
[    2.080176] ata1.00: Enabling discard_zeroes_data
[    2.084971] sd 0:0:0:0: [sda] 500118192 512-byte logical blocks: (256 GB/238 GiB)
[    2.093900] sd 0:0:0:0: [sda] Write Protect is off
[    2.098910] sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
[    2.100172] usb 1-1: new high-speed USB device number 2 using oxnas-ehci
[    2.115645] ata1.00: Enabling discard_zeroes_data
[    2.121761]  sda: sda1 sda2 sda3
[    2.126955] ata1.00: Enabling discard_zeroes_data
[    2.132705] sd 0:0:0:0: [sda] Attached SCSI disk
[    2.137666] Freeing unused kernel memory: 220K
Loading, please wait...
[    2.262120] usb 1-1: New USB device found, idVendor=05e3, idProduct=0608
[    2.268805] usb 1-1: New USB device strings: Mfr=0, Product=1, SerialNumber=0
[    2.275996] usb 1-1: Product: USB2.0 Hub
[    2.281387] hub 1-1:1.0: USB hub found
[    2.285621] hub 1-1:1.0: 4 ports detected
starting version 232
[    2.327823] random: systemd-udevd: uninitialized urandom read (16 bytes read, 0 bits of entropy available)
[    2.339141] random: udevadm: uninitialized urandom read (16 bytes read, 0 bits of entropy available)
[    2.339159] random: systemd-udevd: uninitialized urandom read (16 bytes read, 0 bits of entropy available)
[    2.339399] random: systemd-udevd: uninitialized urandom read (16 bytes read, 0 bits of entropy available)
[    2.370219] random: udevadm: uninitialized urandom read (16 bytes read, 0 bits of entropy available)
[    2.379489] random: udevadm: uninitialized urandom read (16 bytes read, 0 bits of entropy available)
[    2.389726] random: udevadm: uninitialized urandom read (16 bytes read, 0 bits of entropy available)
[    2.400663] random: udevadm: uninitialized urandom read (16 bytes read, 0 bits of entropy available)
[    2.410982] random: udevadm: uninitialized urandom read (16 bytes read, 0 bits of entropy available)
[    2.426109] random: udevadm: uninitialized urandom read (16 bytes read, 0 bits of entropy available)
[    2.580224] usb 1-1.4: new high-speed USB device number 3 using oxnas-ehci
[    2.713533] usb 1-1.4: New USB device found, idVendor=0951, idProduct=1665
[    2.725639] usb 1-1.4: New USB device strings: Mfr=1, Product=2, SerialNumber=3
[    2.779325] usb 1-1.4: Product: DataTraveler 2.0
[    2.797393] usb 1-1.4: Manufacturer: Kingston
[    2.805166] usb 1-1.4: SerialNumber: 001CC0EC33B0FD11C70C4AB3
[    2.815872] usb-storage 1-1.4:1.0: USB Mass Storage device detected
[    2.877470] scsi host1: usb-storage 1-1.4:1.0
[    3.191900] usbcore: registered new interface driver uas
Begin: Loading essential drivers ... done.
[    3.906439] scsi 1:0:0:0: Direct-Access     Kingston DataTraveler 2.0 1.00 PQ: 0 ANSI: 4
[    3.919918] sd 1:0:0:0: [sdb] 15131636 512-byte logical blocks: (7.75 GB/7.21 GiB)
[    3.931704] sd 1:0:0:0: [sdb] Write Protect is off
[    3.941335] sd 1:0:0:0: [sdb] Write cache: disabled, read cache: enabled, doesn't support DPO or FUA
[    3.961420]  sdb: sdb1
[    3.975454] sd 1:0:0:0: [sdb] Attached SCSI removable disk
[    4.269565] EXT4-fs (sdb1): couldn't mount as ext3 due to feature incompatibilities
[    4.295902] EXT4-fs (sdb1): mounted filesystem without journal. Opts: (null)
Begin: Running /scripts/init-premount ... done.
Begin: Mounting root file system ... Begin: Running /scripts/local-top ... done.
Begin: Running /scripts/local-premount ... done.
Begin: Will now check root file system ... fsck from util-linux 2.29.2
fsck: error 2 (No such file or directory) while executing fsck.ext3 for /dev/sda2
fsck exited with status code 8
done.
Warning: File system check failed but did not detect errors
[   18.418060] EXT4-fs (sda2): mounting ext3 file system using the ext4 subsystem
[   18.440545] EXT4-fs (sda2): mounted filesystem with ordered data mode. Opts: (null)
done.
Begin: Running /scripts/local-bottom ... done.
Begin: Running /scripts/init-bottom ... done.
run-init: nuking initramfs contents: Directory not empty
[   18.580382] Kernel panic - not syncing: Attempted to kill init! exitcode=0x00000100
[   18.580382] 
[   18.589486] CPU: 1 PID: 1 Comm: run-init Not tainted 4.4.89-oxnas-tld-1 #1
[   18.596326] Hardware name: PLXTECH NAS782X SoC (Flattened Device Tree)
[   18.602846] [<c0010ce8>] (unwind_backtrace) from [<c000cf90>] (show_stack+0x10/0x14)
[   18.610562] [<c000cf90>] (show_stack) from [<c02d48b8>] (dump_stack+0x80/0x9c)
[   18.617761] [<c02d48b8>] (dump_stack) from [<c0093e74>] (panic+0x8c/0x1ec)
[   18.624611] [<c0093e74>] (panic) from [<c001a790>] (do_exit+0x974/0x9d8)
[   18.631283] [<c001a790>] (do_exit) from [<c001b788>] (SyS_exit+0x10/0x14)
[   18.638046] [<c001b788>] (SyS_exit) from [<c000a440>] (ret_fast_syscall+0x0/0x34)
[   18.645501] CPU0: stopping
[   18.648209] CPU: 0 PID: 0 Comm: swapper/0 Not tainted 4.4.89-oxnas-tld-1 #1
[   18.655134] Hardware name: PLXTECH NAS782X SoC (Flattened Device Tree)
[   18.661647] [<c0010ce8>] (unwind_backtrace) from [<c000cf90>] (show_stack+0x10/0x14)
[   18.669358] [<c000cf90>] (show_stack) from [<c02d48b8>] (dump_stack+0x80/0x9c)
[   18.676551] [<c02d48b8>] (dump_stack) from [<c000fbfc>] (handle_IPI+0x190/0x1c4)
[   18.683919] [<c000fbfc>] (handle_IPI) from [<c0009494>] (gic_handle_irq+0x78/0x84)
[   18.691456] [<c0009494>] (gic_handle_irq) from [<c000da00>] (__irq_svc+0x40/0x74)
[   18.698900] Exception stack(0xc0751f50 to 0xc0751f98)
[   18.703928] 1f40:                                     00000001 00000000 00000000 00000000
[   18.712071] 1f60: c0750000 c056962c 00000000 c0751fa8 c074b2c4 c074e208 00000000 c07586ac
[   18.720212] 1f80: 00000000 c0751fa0 c000aef8 c000aefc 60000013 ffffffff
[   18.726799] [<c000da00>] (__irq_svc) from [<c000aefc>] (arch_cpu_idle+0x28/0x30)
[   18.734167] [<c000aefc>] (arch_cpu_idle) from [<c004e2a8>] (cpu_startup_entry+0x144/0x258)
[   18.742407] [<c004e2a8>] (cpu_startup_entry) from [<c0719c14>] (start_kernel+0x33c/0x3b0)
[   18.750553] ---[ end Kernel panic - not syncing: Attempted to kill init! exitcode=0x00000100
[   18.750553]

The difference i see in the logs are around here:
When system boots:
Begin: Running /scripts/local-bottom ... done.
Begin: Running /scripts/init-bottom ... done.
INIT: version 2.88 booting
When system fails to boot:
Begin: Running /scripts/local-bottom ... done.
Begin: Running /scripts/init-bottom ... done.
run-init: nuking initramfs contents: Directory not empty
[   18.580382] Kernel panic - not syncing: Attempted to kill init! exitcode=0x00000100

tried to search for some realted bugs, but did not find anything useful so far



Edited 1 time(s). Last edit at 02/20/2018 01:55PM by schnee.
Re: USB drive causes booting problem with Linux Kernel 4.4.89 Oxnas
February 20, 2018 02:48PM
schnee,

I think problem might be with this drive/partition. Have you try

1. recreate the partition from scratch and reformat it?
or
2. Use a different USB thumb drive?

If you do that and still having problem, I will do a test on my box to verify if I see anything strange.

-bodhi
===========================
Forum Wiki
bodhi's corner
Re: USB drive causes booting problem with Linux Kernel 4.4.89 Oxnas
February 20, 2018 04:56PM
bodhi,

the USB drive was created for the test.... The initial issue was with my 1TB harddrive. I used the same USB drive, same partition with different filesystems. With fat and ntfs it was booted fine, with ext[2,4] it caused kernel panic on boot....

The system was working fine with the previous rootfs, the problem started after creating the new Debian 9 rootfs.
Re: USB drive causes booting problem with Linux Kernel 4.4.89 Oxnas
February 21, 2018 05:30PM
schnee,


> The system was working fine with the previous
> rootfs, the problem started after creating the new
> Debian 9 rootfs.

Are you booting directly from SATA? or just use SATA as a normal rootfs drive?

If booting directly from SATA, check you uImage and uInitrd to see if they are in sync, and if they are updated in the SATA /boot folder.

-bodhi
===========================
Forum Wiki
bodhi's corner
Re: USB drive causes booting problem with Linux Kernel 4.4.89 Oxnas
February 22, 2018 01:21PM
bodhi,

yes i boot directly from SATA and yes my uImage and uInitrd are correct. I had some time and i downgraded the kernel to 4.4.79, recreated uImage and uInitrd and magic everything works fine. To double check i downloaded the 4.4.89 image again and reinstalled it, generated new uImage and uInitrd files, but the result was sam kernel panic.

For reference here is the 4.4.79 boot log

IDE device 0: Model: Samsung SSD 840 PRO Series Firm: DXM06B0Q Ser#: S1ATNSAF507409D 
            Type: Hard Disk
            Supports 48-bit addressing
            Capacity: 244198.3 MB = 238.4 GB (500118192 x 512)
4619104 bytes read in 700 ms (6.3 MiB/s)
4587119 bytes read in 693 ms (6.3 MiB/s)
7075 bytes read in 16 ms (431.6 KiB/s)
## Booting kernel from Legacy Image at 60500000 ...
   Image Name:   Linux-4.4.79-oxnas-tld-1
   Image Type:   ARM Linux Kernel Image (uncompressed)
   Data Size:    4619040 Bytes = 4.4 MiB
   Load Address: 60008000
   Entry Point:  60008000
   Verifying Checksum ... OK
## Loading init Ramdisk from Legacy Image at 60e00000 ...
   Image Name:   initramfs-4.4.79-oxnas-tld-1
   Image Type:   ARM Linux RAMDisk Image (gzip compressed)
   Data Size:    4587055 Bytes = 4.4 MiB
   Load Address: 60000000
   Entry Point:  60000000
   Verifying Checksum ... OK
## Flattened Device Tree blob at 62c00000
   Booting using the fdt blob at 0x62c00000
   Loading Kernel Image ... OK
   Loading Ramdisk to 679cb000, end 67e2ae2f ... OK
   Loading Device Tree to 679c6000, end 679caba2 ... OK

Starting kernel ...

Uncompressing Linux... done, booting the kernel.
[    0.000000] Booting Linux on physical CPU 0x0
[    0.000000] Linux version 4.4.79-oxnas-tld-1 (root@tldDebian) (gcc version 6.3.0 20170516 (Debian 6.37
[    0.000000] CPU: ARMv6-compatible processor [410fb025] revision 5 (ARMv7), cr=00c5787d
[    0.000000] CPU: PIPT / VIPT nonaliasing data cache, VIPT aliasing instruction cache
[    0.000000] Machine model: Pogoplug V3 Classic
[    0.000000] Memory policy: Data cache writealloc
[    0.000000] DT missing boot CPU MPIDR[23:0], fall back to default cpu_logical_map
[    0.000000] PERCPU: Embedded 12 pages/cpu @c7eda000 s17676 r8192 d23284 u49152
[    0.000000] Built 1 zonelists in Zone order, mobility grouping on.  Total pages: 32512
[    0.000000] Kernel command line: console=ttyS0,115200 root=LABEL=rootfs rootdelay=10
[    0.000000] PID hash table entries: 512 (order: -1, 2048 bytes)
[    0.000000] Dentry cache hash table entries: 16384 (order: 4, 65536 bytes)
[    0.000000] Inode-cache hash table entries: 8192 (order: 3, 32768 bytes)
[    0.000000] Memory: 116196K/131072K available (6427K kernel code, 289K rwdata, 1908K rodata, 224K ini)
[    0.000000] Virtual kernel memory layout:
[    0.000000]     vector  : 0xffff0000 - 0xffff1000   (   4 kB)
[    0.000000]     fixmap  : 0xffc00000 - 0xfff00000   (3072 kB)
[    0.000000]     vmalloc : 0xc8800000 - 0xff800000   ( 880 MB)
[    0.000000]     lowmem  : 0xc0000000 - 0xc8000000   ( 128 MB)
[    0.000000]     modules : 0xbf000000 - 0xc0000000   (  16 MB)
[    0.000000]       .text : 0xc0008000 - 0xc082be58   (8336 kB)
[    0.000000]       .init : 0xc082c000 - 0xc0864000   ( 224 kB)
[    0.000000]       .data : 0xc0864000 - 0xc08ac418   ( 290 kB)
[    0.000000]        .bss : 0xc08ac418 - 0xc08ea378   ( 248 kB)
[    0.000000] SLUB: HWalign=32, Order=0-3, MinObjects=0, CPUs=2, Nodes=1
[    0.000000] Preemptible hierarchical RCU implementation.
[    0.000000] NR_IRQS:160
[    0.000000] clocksource: rps_clocksource_timer: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 191s
[    0.000012] sched_clock: 24 bits at 390kHz, resolution 2560ns, wraps every 21474835200ns
[    0.000163] Console: colour dummy device 80x30
[    0.000197] Calibrating delay loop... 319.48 BogoMIPS (lpj=1597440)
[    0.050030] pid_max: default: 32768 minimum: 301
[    0.050211] Mount-cache hash table entries: 1024 (order: 0, 4096 bytes)
[    0.050229] Mountpoint-cache hash table entries: 1024 (order: 0, 4096 bytes)
[    0.051112] Initializing cgroup subsys net_cls
[    0.051202] CPU: Testing write buffer coherency: ok
[    0.051645] Setting up static identity map for 0x60008220 - 0x60008258
[    0.190156] Brought up 2 CPUs
[    0.190179] SMP: Total of 2 processors activated (638.97 BogoMIPS).
[    0.191042] devtmpfs: initialized
[    0.196203] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275s
[    0.196249] futex hash table entries: 512 (order: 2, 16384 bytes)
[    0.196564] prandom: seed boundary self test passed
[    0.200970] prandom: 100 self tests passed
[    0.200998] pinctrl core: initialized pinctrl subsystem
[    0.201935] NET: Registered protocol family 16
[    0.202503] DMA: preallocated 256 KiB pool for atomic coherent allocations
[    0.230023] cpuidle: using governor ladder
[    0.260016] cpuidle: using governor menu
[    0.265149] gpio-oxnas 44000000.gpio: at address c8848000
[    0.265587] gpio-oxnas 44100000.gpio: at address c884a000
[    0.266173] pinctrl-oxnas pinctrl: initialized OX820 pinctrl driver
[    0.280097] vgaarb: loaded
[    0.280619] SCSI subsystem initialized
[    0.281512] usbcore: registered new interface driver usbfs
[    0.281625] usbcore: registered new interface driver hub
[    0.281792] usbcore: registered new device driver usb
[    0.281986] pps_core: LinuxPPS API ver. 1 registered
[    0.281999] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
[    0.282060] PTP clock support registered
[    0.284336] clocksource: Switched to clocksource rps_clocksource_timer
[    0.303994] NET: Registered protocol family 2
[    0.304942] TCP established hash table entries: 1024 (order: 0, 4096 bytes)
[    0.304990] TCP bind hash table entries: 1024 (order: 1, 8192 bytes)
[    0.305029] TCP: Hash tables configured (established 1024 bind 1024)
[    0.305134] UDP hash table entries: 256 (order: 1, 8192 bytes)
[    0.305180] UDP-Lite hash table entries: 256 (order: 1, 8192 bytes)
[    0.305461] NET: Registered protocol family 1
[    0.306019] RPC: Registered named UNIX socket transport module.
[    0.306040] RPC: Registered udp transport module.
[    0.306050] RPC: Registered tcp transport module.
[    0.306060] RPC: Registered tcp NFSv4.1 backchannel transport module.
[    0.306462] Unpacking initramfs...
[    0.807984] Freeing initrd memory: 4480K (c79cb000 - c7e2b000)
[    0.809510] Initialise system trusted keyring
[    0.823892] zbud: loaded
[    0.829393] NFS: Registering the id_resolver key type
[    0.829470] Key type id_resolver registered
[    0.829486] Key type id_legacy registered
[    0.829532] nfs4filelayout_init: NFSv4 File Layout Driver Registering...
[    0.829552] Installing knfsd (copyright (C) 1996 okir@monad.swb.de).
[    0.830638] fuse init (API version 7.23)
[    0.832693] Key type big_key registered
[    0.919001] Key type asymmetric registered
[    0.919037] Asymmetric key parser 'x509' registered
[    0.919201] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 252)
[    0.919237] io scheduler noop registered
[    0.919260] io scheduler deadline registered
[    0.919326] io scheduler cfq registered (default)
[    0.920058] Serial: 8250/16550 driver, 1 ports, IRQ sharing disabled
[    0.921646] console [ttyS0] disabled
[    0.921743] 44200000.uart: ttyS0 at MMIO 0x44200000 (irq = 6, base_baud = 390625) is a 16550A
[    1.444428] console [ttyS0] enabled
[    1.459906] loop: module loaded
[    1.465152] scsi host0: sata_oxnas
[    1.469002] ata1: SATA max UDMA/133 irq 7
[    1.473159] sata_oxnas: resetting SATA core
[    1.474291] nand: Could not find valid ONFI parameter page; aborting
[    1.474332] nand: device found, Manufacturer ID: 0xad, Chip ID: 0xf1
[    1.474337] nand: Hynix NAND 128MiB 3,3V 8-bit
[    1.474347] nand: 128 MiB, SLC, erase size: 128 KiB, page size: 2048, OOB size: 64
[    1.474365] Scanning device for bad blocks
[    1.491635] Bad eraseblock 208 at 0x000001a00000
[    1.551733] Bad eraseblock 937 at 0x000007520000
[    1.563420] 2 ofpart partitions found on MTD device 41000000.nand
[    1.569484] Creating 2 MTD partitions on "41000000.nand":
[    1.574891] 0x000000000000-0x000000e00000 : "boot"
[    1.581813] 0x000000e00000-0x000008000000 : "data"
[    1.589204] oxnas-gmac 40400000.ethernet: no reset control found
[    1.595248] stmmac - user ID: 0x12, Synopsys ID: 0x35
[    1.600309]  Ring mode enabled
[    1.603348]  DMA HW capability register supported
[    1.607848]  Enhanced/Alternate descriptors
[    1.612206]  Enabled extended descriptors
[    1.616194]  RX Checksum Offload Engine supported (type 2)
[    1.621670]  TX Checksum insertion supported
[    1.625917]  Wake-Up On Lan supported
[    1.629555]  Enable RX Mitigation via HW Watchdog Timer
[    1.665684] libphy: stmmac: probed
[    1.669081] eth%d: PHY ID 001cc914 at 0 IRQ POLL (stmmac-0:00) active
[    1.675543] eth%d: PHY ID 001cc914 at 3 IRQ POLL (stmmac-0:03)
[    1.682286] PPP generic driver version 2.4.2
[    1.687150] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
[    1.693726] ehci-pci: EHCI PCI platform driver
[    1.698690] oxnas-ehci 40200100.ehci: EHCI Host Controller
[    1.704266] oxnas-ehci 40200100.ehci: new USB bus registered, assigned bus number 1
[    1.712115] oxnas-ehci 40200100.ehci: irq 10, io mem 0x40200100
[    1.730004] oxnas-ehci 40200100.ehci: USB 2.0 started, EHCI 1.00
[    1.736322] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002
[    1.743144] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    1.750369] usb usb1: Product: EHCI Host Controller
[    1.755225] usb usb1: Manufacturer: Linux 4.4.79-oxnas-tld-1 ehci_hcd
[    1.761679] usb usb1: SerialNumber: 40200100.ehci
[    1.767390] hub 1-0:1.0: USB hub found
[    1.771264] hub 1-0:1.0: 2 ports detected
[    1.776437] usbcore: registered new interface driver cdc_acm
[    1.782146] cdc_acm: USB Abstract Control Model driver for USB modems and ISDN adapters
[    1.790256] usbcore: registered new interface driver cdc_wdm
[    1.796062] usbcore: registered new interface driver usb-storage
[    1.802293] usbcore: registered new interface driver usbserial
[    1.808189] usbcore: registered new interface driver usbserial_generic
[    1.814801] usbserial: USB Serial support registered for generic
[    1.821283] mousedev: PS/2 mouse device common for all mice
[    1.827008] i2c /dev entries driver
[    1.831864] hidraw: raw HID events driver (C) Jiri Kosina
[    1.837506] usbcore: registered new interface driver usbhid
[    1.843120] usbhid: USB HID core driver
[    1.849105] NET: Registered protocol family 10
[    1.855011] sit: IPv6 over IPv4 tunneling driver
[    1.860846] NET: Registered protocol family 17
[    1.865459] Key type dns_resolver registered
[    1.871482] registered taskstats version 1
[    1.875619] Loading compiled-in X.509 certificates
[    1.880576] zswap: loaded using pool lzo/zbud
[    1.900387] Key type encrypted registered
[    2.020160] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 310)
[    2.027230] ata1.00: ATA-9: Samsung SSD 840 PRO Series, DXM06B0Q, max UDMA/133
[    2.034462] ata1.00: 500118192 sectors, multi 16: LBA48 NCQ (depth 0/32)
[    2.041482] ata1.00: configured for UDMA/133
[    2.046469] scsi 0:0:0:0: Direct-Access     ATA      Samsung SSD 840  6B0Q PQ: 0 ANSI: 5
[    2.056081] ata1.00: Enabling discard_zeroes_data
[    2.061071] sd 0:0:0:0: [sda] 500118192 512-byte logical blocks: (256 GB/238 GiB)
[    2.069263] sd 0:0:0:0: [sda] Write Protect is off
[    2.074370] sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
[    2.084295] ata1.00: Enabling discard_zeroes_data
[    2.090053] usb 1-1: new high-speed USB device number 2 using oxnas-ehci
[    2.097062]  sda: sda1 sda2 sda3
[    2.102184] ata1.00: Enabling discard_zeroes_data
[    2.107814] sd 0:0:0:0: [sda] Attached SCSI disk
[    2.112875] Freeing unused kernel memory: 224K (c082c000 - c0864000)
Loading, please wait...
[    2.242055] usb 1-1: New USB device found, idVendor=05e3, idProduct=0608
[    2.248750] usb 1-1: New USB device strings: Mfr=0, Product=1, SerialNumber=0
[    2.256017] usb 1-1: Product: USB2.0 Hub
[    2.261488] hub 1-1:1.0: USB hub found
[    2.265702] hub 1-1:1.0: 4 ports detected
starting version 232
[    2.302256] random: systemd-udevd: uninitialized urandom read (16 bytes read, 0 bits of entropy avail)
[    2.313648] random: systemd-udevd: uninitialized urandom read (16 bytes read, 0 bits of entropy avail)
[    2.314316] random: udevadm: uninitialized urandom read (16 bytes read, 0 bits of entropy available)
[    2.316792] random: udevadm: uninitialized urandom read (16 bytes read, 0 bits of entropy available)
[    2.316971] random: udevadm: uninitialized urandom read (16 bytes read, 0 bits of entropy available)
[    2.318056] random: udevadm: uninitialized urandom read (16 bytes read, 0 bits of entropy available)
[    2.319155] random: udevadm: uninitialized urandom read (16 bytes read, 0 bits of entropy available)
[    2.320343] random: udevadm: uninitialized urandom read (16 bytes read, 0 bits of entropy available)
[    2.326476] random: udevadm: uninitialized urandom read (16 bytes read, 0 bits of entropy available)
[    2.327493] random: udevadm: uninitialized urandom read (16 bytes read, 0 bits of entropy available)
[    2.570165] usb 1-1.4: new high-speed USB device number 3 using oxnas-ehci
[    2.706214] usb 1-1.4: New USB device found, idVendor=0951, idProduct=1665
[    2.719457] usb 1-1.4: New USB device strings: Mfr=1, Product=2, SerialNumber=3
[    2.728611] usb 1-1.4: Product: DataTraveler 2.0
[    2.734932] usb 1-1.4: Manufacturer: Kingston
[    2.739297] usb 1-1.4: SerialNumber: 001CC0EC33B0FD11C70C4AB3
[    2.746764] usb-storage 1-1.4:1.0: USB Mass Storage device detected
[    2.772994] scsi host1: usb-storage 1-1.4:1.0
[    3.095590] usbcore: registered new interface driver uas
Begin: Loading essential drivers ... done.
[    3.794652] scsi 1:0:0:0: Direct-Access     Kingston DataTraveler 2.0 1.00 PQ: 0 ANSI: 4
[    3.810437] sd 1:0:0:0: [sdb] 15131636 512-byte logical blocks: (7.75 GB/7.21 GiB)
[    3.819893] sd 1:0:0:0: [sdb] Write Protect is off
[    3.826009] sd 1:0:0:0: [sdb] Write cache: disabled, read cache: enabled, doesn't support DPO or FUA
[    3.851663]  sdb: sdb1
[    3.860856] sd 1:0:0:0: [sdb] Attached SCSI removable disk
Begin: Running /scripts/init-premount ... done.
Begin: Mounting root file system ... Begin: Running /scripts/local-top ... done.
Begin: Running /scripts/local-premount ... done.
Begin: Will now check root file system ... fsck from util-linux 2.29.2
[/sbin/fsck.ext3 (1) -- /dev/sda2] fsck.ext3 -a -C0 /dev/sda2 
rootfs: clean, 68357/262144 files, 598068/1048576 blocks
done.
[   13.670379] EXT4-fs (sda2): mounting ext3 file system using the ext4 subsystem
[   13.683287] EXT4-fs (sda2): mounted filesystem with ordered data mode. Opts: (null)
done.
Begin: Running /scripts/local-bottom ... done.
Begin: Running /scripts/init-bottom ... done.
INIT: version 2.88 booting
[info] Using makefile-style concurrent boot in runlevel S.
[....] Starting the hotplug events dispatcher: systemd-udevdstarting version 232
. ok 
[....] Synthesizing the initial hotplug events...done.
[....] Waiting for /dev to be fully populated...[   16.625208] EXT4-fs (sdb1): recovery complete
[   16.629568] EXT4-fs (sdb1): mounted filesystem with ordered data mode. Opts: (null)
done.
[   17.420715] EXT4-fs (sda2): re-mounted. Opts: errors=remount-ro
[....] Activating lvm and md swap...[   19.433971] Adding 524284k swap on /swapfile1.  Priority:-1 extenS
done.
[....] Checking file systems...fsck from util-linux 2.29.2
e2fsck 1.43.4 (31-Jan-2017)
home: clean, 1501/15368192 files, 34343378/61463382 blocks
done.
[....] Loading kernel modules...done.
[....] Cleaning up temporary files... /tmp. ok 
[....] Mounting local filesystems...done.
[....] Activating swapfile swap...done.
[....] Cleaning up temporary files.... ok 
[....] Setting kernel variables...done.
[....] Configuring network interfaces...Internet Systems Consortium DHCP Client 4.3.5
Copyright 2004-2016 Internet Systems Consortium.
All rights reserved.
For info, please visit https://www.isc.org/software/dhcp/

Listening on LPF/eth0/00:25:31:02:98:3b
Sending on   LPF/eth0/00:25:31:02:98:3b
Sending on   Socket/fallback
DHCPREQUEST of 192.168.0.10 on eth0 to 255.255.255.255 port 67
[   29.670164] oxnas-gmac 40400000.ethernet eth0: Link is Up - 1Gbps/Full - flow control rx/tx
DHCPREQUEST of 192.168.0.10 on eth0 to 255.255.255.255 port 67
DHCPACK of 192.168.0.10 from 192.168.0.254
invoke-rc.d: could not determine current runlevel
Reloading /etc/samba/smb.conf: smbd.
bound to 192.168.0.10 -- renewal in 16223 seconds.
done.
[....] Starting RPC port mapper daemon: rpcbind. ok 
[....] Starting NFS common utilities: statd idmapd. ok 
[....] Cleaning up temporary files.... ok 
INIT: Entering runlevel: 2
[info] Using makefile-style concurrent boot in runlevel 2.
[....] Starting busybox' syslogd implementation : syslogdStarting /sbin/syslogd...
1272 (syslogd)
. ok 
[....] Starting NFS common utilities: statd idmapd. ok 
[+] Starting mjpg_streamer
ls: cannot access '/dev/video*': No such file or directory
[+] mjpg_streamer started
[....] Starting busybox' klogd implementation : klogdStarting /sbin/klogd...
1267 (klogd)
. ok 
[....] Starting periodic command scheduler: cron. ok 
[....] Starting system message bus: dbus. ok 
[....] Starting network daemon:: mosquitto. ok 
[....] Starting Avahi mDNS/DNS-SD Daemon: avahi-daemon. ok 
[....] Not starting NFS kernel daemon: no exports. ... (warning).
[....] Starting OpenBSD Secure Shell server: sshd. ok 
[....] Starting MTA:[....] Starting NTP server: ntpd[....] Starting NetBIOS name server: nmbd exim4. ok 
. ok 
. ok 
Starting TLS tunnels: /etc/stunnel/cam.conf: started /etc/stunnel/espserial.conf: started /etc/stunnel/td
[....] Starting bittorrent daemon: transmission-daemon. ok 
[....] Starting SMB/CIFS daemon: smbd. ok 

Debian GNU/Linux 9 sch ttyS0

sch login:

I don't know what changed between 4.4.79 and 4.4.89 but is see that the rootfs check is succesfull in 4.4.79 and it always failed in 4.4.89
4.4.79
Begin: Will now check root file system ... fsck from util-linux 2.29.2
[/sbin/fsck.ext3 (1) -- /dev/sda2] fsck.ext3 -a -C0 /dev/sda2 
rootfs: clean, 68357/262144 files, 598068/1048576 blocks
done.

4.4.89
Begin: Will now check root file system ... fsck from util-linux 2.29.2
fsck: error 2 (No such file or directory) while executing fsck.ext3 for /dev/sda2
fsck exited with status code 8
done.
Warning: File system check failed but did not detect errors

Thanks
Re: USB drive causes booting problem with Linux Kernel 4.4.89 Oxnas
February 22, 2018 11:51PM
schnee,

Here is my boot log where the rootfs is Ext4 and the 2nd USB drive is also formatted as Ext4.

You can use this log as a guide to compare with your configuration and boot log to see what might have been causing problem in your box.

U-Boot SPL 2013.10-tld-4 (Sep 07 2014 - 14:10:12)
  Boot device: NAND
Attempting to set PLLA to 850 MHz ...
  plla_ctrl0 : 0000020a
  plla_ctrl1 : 00330000
  plla_ctrl2 : 0065008b
  plla_ctrl3 : 000000f1

PLLA Set


U-Boot 2015.10-tld-2 (Oct 21 2017 - 22:00:02 -0700)
OXNAS OX820

DRAM:  128 MiB
NAND:  128 MiB
In:    serial
Out:   serial
Err:   serial
Net:   dwmac.40400000
Hit any key to stop autoboot:  0 
starting USB...
USB0:   USB EHCI 1.00
scanning bus 0 for devices... 4 USB Device(s) found
       scanning usb for storage devices... 2 Storage Device(s) found

Reset IDE: SATA PHY not ready for device 0
ide_preinit failed

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

Part	Start Sector	Num Sectors	UUID		Type
  1	2048      	15631360  	505eb50d-01	83 Boot


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

Part	Start Sector	Num Sectors	UUID		Type
  1	2048      	15630336  	17f04d84-01	83 Boot
loading envs from usb 0 ...
** File not found /boot/uEnv.txt **

no IDE devices available
running scan_disk ...
Scan device usb
device usb 0:1
** File not found /boot/uImage **
device usb 1:1
1 bytes read in 942 ms (0 Bytes/s)
Found bootable drive on usb 1
loading uImage ...
4100416 bytes read in 1240 ms (3.2 MiB/s)
loading uInitrd ...
4838035 bytes read in 1397 ms (3.3 MiB/s)
loading DTB /boot/dts/ox820-pogoplug-classic.dtb ...
7075 bytes read in 948 ms (6.8 KiB/s)
## Booting kernel from Legacy Image at 60500000 ...
   Image Name:   Linux-4.4.89-oxnas-tld-1
   Created:      2017-10-05   8:02:28 UTC
   Image Type:   ARM Linux Kernel Image (uncompressed)
   Data Size:    4100352 Bytes = 3.9 MiB
   Load Address: 60008000
   Entry Point:  60008000
   Verifying Checksum ... OK
## Loading init Ramdisk from Legacy Image at 60e00000 ...
   Image Name:   initramfs-4.4.89-oxnas-tld-1
   Created:      2017-10-05   8:03:15 UTC
   Image Type:   ARM Linux RAMDisk Image (gzip compressed)
   Data Size:    4837971 Bytes = 4.6 MiB
   Load Address: 60000000
   Entry Point:  60000000
   Verifying Checksum ... OK
## Flattened Device Tree blob at 62c00000
   Booting using the fdt blob at 0x62c00000
   Loading Kernel Image ... OK
   Loading Ramdisk to 67989000, end 67e26253 ... OK
   Loading Device Tree to 67984000, end 67988ba2 ... OK

Starting kernel ...

Uncompressing Linux... done, booting the kernel.
[    0.000000] Booting Linux on physical CPU 0x0
[    0.000000] Linux version 4.4.89-oxnas-tld-1 (root@tldDebian) (gcc version 6.3.0 20170516 (Debian 6.3.0-18) ) #1 SMP PREEMPT Wed Oct 4 22:22:09 PDT 2017
[    0.000000] CPU: ARMv6-compatible processor [410fb025] revision 5 (ARMv7), cr=00c5787d
[    0.000000] CPU: PIPT / VIPT nonaliasing data cache, VIPT aliasing instruction cache
[    0.000000] Machine model: Pogoplug V3 Classic
[    0.000000] Memory policy: Data cache writealloc
[    0.000000] DT missing boot CPU MPIDR[23:0], fall back to default cpu_logical_map
[    0.000000] PERCPU: Embedded 12 pages/cpu @c7eda000 s17676 r8192 d23284 u49152
[    0.000000] Built 1 zonelists in Zone order, mobility grouping on.  Total pages: 32512
[    0.000000] Kernel command line: console=ttyS0,115200 root=LABEL=rootfs rootdelay=10 mtdparts=41000000.nand:14m(boot),-(data)
[    0.000000] PID hash table entries: 512 (order: -1, 2048 bytes)
[    0.000000] Dentry cache hash table entries: 16384 (order: 4, 65536 bytes)
[    0.000000] Inode-cache hash table entries: 8192 (order: 3, 32768 bytes)
[    0.000000] Memory: 117060K/131072K available (5664K kernel code, 285K rwdata, 1568K rodata, 220K init, 243K bss, 14012K 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 : 0xc8800000 - 0xff800000   ( 880 MB)
[    0.000000]     lowmem  : 0xc0000000 - 0xc8000000   ( 128 MB)
[    0.000000]     modules : 0xbf000000 - 0xc0000000   (  16 MB)
[    0.000000]       .text : 0xc0008000 - 0xc07185bc   (7234 kB)
[    0.000000]       .init : 0xc0719000 - 0xc0750000   ( 220 kB)
[    0.000000]       .data : 0xc0750000 - 0xc0797478   ( 286 kB)
[    0.000000]        .bss : 0xc0797478 - 0xc07d4298   ( 244 kB)
[    0.000000] SLUB: HWalign=32, Order=0-3, MinObjects=0, CPUs=2, Nodes=1
[    0.000000] Preemptible hierarchical RCU implementation.
[    0.000000] NR_IRQS:160
[    0.000000] clocksource: rps_clocksource_timer: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 19112603332 ns
[    0.000010] sched_clock: 24 bits at 390kHz, resolution 2560ns, wraps every 21474835200ns
[    0.000156] Console: colour dummy device 80x30
[    0.000186] Calibrating delay loop... 339.14 BogoMIPS (lpj=1695744)
[    0.050014] pid_max: default: 32768 minimum: 301
[    0.050188] Mount-cache hash table entries: 1024 (order: 0, 4096 bytes)
[    0.050206] Mountpoint-cache hash table entries: 1024 (order: 0, 4096 bytes)
[    0.051048] Initializing cgroup subsys net_cls
[    0.051133] CPU: Testing write buffer coherency: ok
[    0.051553] Setting up static identity map for 0x60008220 - 0x60008258
[    0.190133] Brought up 2 CPUs
[    0.190154] SMP: Total of 2 processors activated (678.29 BogoMIPS).
[    0.190947] devtmpfs: initialized
[    0.195978] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
[    0.196019] futex hash table entries: 512 (order: 2, 16384 bytes)
[    0.196311] prandom: seed boundary self test passed
[    0.200724] prandom: 100 self tests passed
[    0.200752] pinctrl core: initialized pinctrl subsystem
[    0.201574] NET: Registered protocol family 16
[    0.202117] DMA: preallocated 256 KiB pool for atomic coherent allocations
[    0.230013] cpuidle: using governor ladder
[    0.260006] cpuidle: using governor menu
[    0.264791] gpio-oxnas 44000000.gpio: at address c8848000
[    0.265200] gpio-oxnas 44100000.gpio: at address c884a000
[    0.265745] pinctrl-oxnas pinctrl: initialized OX820 pinctrl driver
[    0.277614] vgaarb: loaded
[    0.278100] SCSI subsystem initialized
[    0.278958] usbcore: registered new interface driver usbfs
[    0.279070] usbcore: registered new interface driver hub
[    0.279224] usbcore: registered new device driver usb
[    0.279413] pps_core: LinuxPPS API ver. 1 registered
[    0.279426] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
[    0.279490] PTP clock support registered
[    0.280855] clocksource: Switched to clocksource rps_clocksource_timer
[    0.298595] NET: Registered protocol family 2
[    0.299635] TCP established hash table entries: 1024 (order: 0, 4096 bytes)
[    0.299681] TCP bind hash table entries: 1024 (order: 1, 8192 bytes)
[    0.299717] TCP: Hash tables configured (established 1024 bind 1024)
[    0.299824] UDP hash table entries: 256 (order: 1, 8192 bytes)
[    0.299870] UDP-Lite hash table entries: 256 (order: 1, 8192 bytes)
[    0.300211] NET: Registered protocol family 1
[    0.300707] RPC: Registered named UNIX socket transport module.
[    0.300725] RPC: Registered udp transport module.
[    0.300738] RPC: Registered tcp transport module.
[    0.300748] RPC: Registered tcp NFSv4.1 backchannel transport module.
[    0.301127] Unpacking initramfs...
[    0.796792] Freeing initrd memory: 4728K
[    0.798184] Initialise system trusted keyring
[    0.812733] zbud: loaded
[    0.817930] NFS: Registering the id_resolver key type
[    0.817984] Key type id_resolver registered
[    0.817996] Key type id_legacy registered
[    0.818040] nfs4filelayout_init: NFSv4 File Layout Driver Registering...
[    0.818060] Installing knfsd (copyright (C) 1996 okir@monad.swb.de).
[    0.818972] fuse init (API version 7.23)
[    0.821066] Key type big_key registered
[    0.922677] Key type asymmetric registered
[    0.922711] Asymmetric key parser 'x509' registered
[    0.922841] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 252)
[    0.922867] io scheduler noop registered
[    0.922887] io scheduler deadline registered
[    0.922949] io scheduler cfq registered (default)
[    0.923607] Serial: 8250/16550 driver, 1 ports, IRQ sharing disabled
[    0.925032] console [ttyS0] disabled
[    0.925130] 44200000.uart: ttyS0 at MMIO 0x44200000 (irq = 6, base_baud = 390625) is a 16550A
[    1.449333] console [ttyS0] enabled
[    1.551045] loop: module loaded
[    1.556039] scsi host0: sata_oxnas
[    1.559889] ata1: SATA max UDMA/133 irq 7
[    1.564026] sata_oxnas: resetting SATA core
[    1.565099] nand: Could not find valid ONFI parameter page; aborting
[    1.565140] nand: device found, Manufacturer ID: 0xad, Chip ID: 0xf1
[    1.565148] nand: Hynix NAND 128MiB 3,3V 8-bit
[    1.565155] nand: 128 MiB, SLC, erase size: 128 KiB, page size: 2048, OOB size: 64
[    1.565171] Scanning device for bad blocks
[    1.647416] 2 cmdlinepart partitions found on MTD device 41000000.nand
[    1.653941] Creating 2 MTD partitions on "41000000.nand":
[    1.659320] 0x000000000000-0x000000e00000 : "boot"
[    1.666145] 0x000000e00000-0x000008000000 : "data"
[    1.673459] oxnas-gmac 40400000.ethernet: no reset control found
[    1.679444] stmmac - user ID: 0x12, Synopsys ID: 0x35
[    1.684515]  Ring mode enabled
[    1.687552]  DMA HW capability register supported
[    1.692080]  Enhanced/Alternate descriptors
[    1.696412] 	Enabled extended descriptors
[    1.700413]  RX Checksum Offload Engine supported (type 2)
[    1.705866]  TX Checksum insertion supported
[    1.710133]  Wake-Up On Lan supported
[    1.713774]  Enable RX Mitigation via HW Watchdog Timer
[    1.734126] libphy: stmmac: probed
[    1.737520] eth%d: PHY ID 001cc914 at 0 IRQ POLL (stmmac-0:00) active
[    1.743979] eth%d: PHY ID 001cc914 at 3 IRQ POLL (stmmac-0:03)
[    1.750730] PPP generic driver version 2.4.2
[    1.755335] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
[    1.761937] ehci-pci: EHCI PCI platform driver
[    1.766860] oxnas-ehci 40200100.ehci: EHCI Host Controller
[    1.772428] oxnas-ehci 40200100.ehci: new USB bus registered, assigned bus number 1
[    1.780346] oxnas-ehci 40200100.ehci: irq 10, io mem 0x40200100
[    1.800012] oxnas-ehci 40200100.ehci: USB 2.0 started, EHCI 1.00
[    1.806328] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002
[    1.813150] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    1.820364] usb usb1: Product: EHCI Host Controller
[    1.825216] usb usb1: Manufacturer: Linux 4.4.89-oxnas-tld-1 ehci_hcd
[    1.831662] usb usb1: SerialNumber: 40200100.ehci
[    1.837286] hub 1-0:1.0: USB hub found
[    1.841141] hub 1-0:1.0: 2 ports detected
[    1.846243] usbcore: registered new interface driver cdc_acm
[    1.851937] cdc_acm: USB Abstract Control Model driver for USB modems and ISDN adapters
[    1.860042] usbcore: registered new interface driver cdc_wdm
[    1.865838] usbcore: registered new interface driver usb-storage
[    1.872048] usbcore: registered new interface driver usbserial
[    1.877921] usbcore: registered new interface driver usbserial_generic
[    1.884523] usbserial: USB Serial support registered for generic
[    1.890972] mousedev: PS/2 mouse device common for all mice
[    1.896704] i2c /dev entries driver
[    1.901470] hidraw: raw HID events driver (C) Jiri Kosina
[    1.907077] usbcore: registered new interface driver usbhid
[    1.912688] usbhid: USB HID core driver
[    1.918464] NET: Registered protocol family 10
[    1.924252] sit: IPv6 over IPv4 tunneling driver
[    1.929884] NET: Registered protocol family 17
[    1.934568] Key type dns_resolver registered
[    1.940167] ata1: SATA link down (SStatus 0 SControl 310)
[    1.945835] registered taskstats version 1
[    1.950016] Loading compiled-in X.509 certificates
[    1.954908] zswap: loaded using pool lzo/zbud
[    1.973941] Key type encrypted registered
[    1.992711] Freeing unused kernel memory: 220K
Loading, please wait...
[    2.160051] usb 1-1: new high-speed USB device number 2 using oxnas-ehci
starting version 232
[    2.176832] random: systemd-udevd: uninitialized urandom read (16 bytes read, 0 bits of entropy available)
[    2.187412] random: udevadm: uninitialized urandom read (16 bytes read, 0 bits of entropy available)
[    2.187939] random: systemd-udevd: uninitialized urandom read (16 bytes read, 0 bits of entropy available)
[    2.188162] random: systemd-udevd: uninitialized urandom read (16 bytes read, 0 bits of entropy available)
[    2.218181] random: udevadm: uninitialized urandom read (16 bytes read, 0 bits of entropy available)
[    2.227566] random: udevadm: uninitialized urandom read (16 bytes read, 0 bits of entropy available)
[    2.237762] random: udevadm: uninitialized urandom read (16 bytes read, 0 bits of entropy available)
[    2.250903] random: udevadm: uninitialized urandom read (16 bytes read, 0 bits of entropy available)
[    2.261009] random: udevadm: uninitialized urandom read (16 bytes read, 0 bits of entropy available)
[    2.271088] random: udevadm: uninitialized urandom read (16 bytes read, 0 bits of entropy available)
[    2.312017] usb 1-1: New USB device found, idVendor=05e3, idProduct=0608
[    2.318707] usb 1-1: New USB device strings: Mfr=0, Product=1, SerialNumber=0
[    2.325949] usb 1-1: Product: USB2.0 Hub
[    2.337259] hub 1-1:1.0: USB hub found
[    2.347184] hub 1-1:1.0: 4 ports detected
[    2.630256] usb 1-1.2: new high-speed USB device number 3 using oxnas-ehci
[    2.751759] usb 1-1.2: New USB device found, idVendor=0781, idProduct=5571
[    2.758612] usb 1-1.2: New USB device strings: Mfr=1, Product=2, SerialNumber=3
[    2.780211] usb 1-1.2: Product: Firebird USB Flash Drive
[    2.785512] usb 1-1.2: Manufacturer: SanDisk
[    2.789762] usb 1-1.2: SerialNumber: xxxxxxxxxxxxxxxxxxxxx
[    2.821219] usb-storage 1-1.2:1.0: USB Mass Storage device detected
[    2.838412] scsi host1: usb-storage 1-1.2:1.0
[    2.898990] usbcore: registered new interface driver uas
Begin: Loading essential drivers ... [    2.930163] usb 1-1.4: new high-speed USB device number 4 using oxnas-ehci
[    2.957662] netpoll: netconsole: local port 6666
[    2.962872] netpoll: netconsole: local IPv4 address 192.168.0.222
[    2.969315] netpoll: netconsole: interface 'eth0'
[    2.974471] netpoll: netconsole: remote port 6666
[    2.979159] netpoll: netconsole: remote IPv4 address 192.168.0.220
[    2.985574] netpoll: netconsole: remote ethernet address ff:ff:ff:ff:ff:ff
[    2.992519] netpoll: netconsole: device eth0 not up yet, forcing it
[    3.003809] netpoll: netconsole: carrier detect appears untrustworthy, waiting 4 seconds
[    3.041121] usb 1-1.4: New USB device found, idVendor=0781, idProduct=5571
[    3.047976] usb 1-1.4: New USB device strings: Mfr=1, Product=2, SerialNumber=3
[    3.055354] usb 1-1.4: Product: Cruzer Fit
[    3.059432] usb 1-1.4: Manufacturer: SanDisk
[    3.063818] usb 1-1.4: SerialNumber: xxxxxxxxxxxxxxx
[    3.075502] usb-storage 1-1.4:1.0: USB Mass Storage device detected
[    3.082352] scsi host2: usb-storage 1-1.4:1.0
[    3.841233] scsi 1:0:0:0: Direct-Access     SanDisk  Cruzer Fit       1.26 PQ: 0 ANSI: 5
[    3.856550] sd 1:0:0:0: [sda] 15633408 512-byte logical blocks: (8.00 GB/7.45 GiB)
[    3.867269] sd 1:0:0:0: [sda] Write Protect is off
[    3.875939] sd 1:0:0:0: [sda] Write cache: disabled, read cache: enabled, doesn't support DPO or FUA
[    3.901780]  sda: sda1
[    3.914083] sd 1:0:0:0: [sda] Attached SCSI removable disk
[    4.081333] scsi 2:0:0:0: Direct-Access     SanDisk  Cruzer Fit       1.22 PQ: 0 ANSI: 5
[    4.095987] sd 2:0:0:0: [sdb] 15633408 512-byte logical blocks: (8.00 GB/7.45 GiB)
[    4.107238] sd 2:0:0:0: [sdb] Write Protect is off
[    4.116049] sd 2:0:0:0: [sdb] Write cache: disabled, read cache: enabled, doesn't support DPO or FUA
[    4.147681]  sdb: sdb1
[    4.163829] sd 2:0:0:0: [sdb] Attached SCSI removable disk
[    4.990144] oxnas-gmac 40400000.ethernet eth0: Link is Up - 1Gbps/Full - flow control rx/tx
[    7.032785] console [netcon0] enabled
[    7.036439] netconsole: network logging started
done.
Begin: Running /scripts/init-premount ... done.
Begin: Mounting root file system ... Begin: Running /scripts/local-top ... done.
Begin: Running /scripts/local-premount ... done.
Begin: Will now check root file system ... fsck from util-linux 2.29.2
[/sbin/fsck.ext4 (1) -- /dev/sdb1] fsck.ext4 -a -C0 /dev/sdb1 
rootfs: clean, 151096/488640 files, 1695694/1953792 blocks
done.
[   17.371317] EXT4-fs (sdb1): mounted filesystem with ordered data mode. Opts: (null)
done.
Begin: Running /scripts/local-bottom ... done.
Begin: Running /scripts/init-bottom ... done.
INIT: version 2.88 booting
[info] Using makefile-style concurrent boot in runlevel S.
[ ok ] Setting hostname to 'PogoPro'...done.
[....] Starting the hotplug events dispatcher: systemd-udevdstarting version 232
. ok 
[ ok ] Synthesizing the initial hotplug events...done.
[   20.330869] random: nonblocking pool is initialized
[....] Waiting for /dev to be fully populated...[   21.223280] EXT4-fs (sda1): mounted filesystem with ordered data mode. Opts: errors=remount-ro
done.
[warn] Not activating swap on swapfile. ... (warning).
[   22.293987] EXT4-fs (sdb1): re-mounted. Opts: errors=remount-ro
[....] Will now activate lvm and md swap:swapon: /swapfile1: found signature [pagesize=4096, signature=swap]
swapon: /swapfile1: pagesize=4096, swapsize=1073741824, devsize=1073741824
swapon /swapfile1
[   23.882598] Adding 1048572k swap on /swapfile1.  Priority:-1 extents:20 across:1458172k FS
done.
[info] Will now check all file systems.
fsck from util-linux 2.29.2
Checking all file systems.
LABEL=rootfs is mounted
[ ok ] Done checking file systems. A log is being saved in /var/log/fsck/checkfs if that location is writable..
[ ok ] Cleaning up temporary files...[....] Cleaning /tmp...done.
[ ok .
[ ok ] Will now mount local filesystems:.
[....] Will now activate swapfile swap:swapon: /swapfile1: already active -- ignored
done.
[ ok ] Checking minimum space in /tmp...done.
[ ok ] Cleaning up temporary files....
[ ok ] Setting kernel variables...done.
[ ok ] Initializing random number generator...done.
[ ok ] Configuring network interfaces...done.
[ ok ] Starting RPC port mapper daemon: rpcbind.
[ ok ] Starting NFS common utilities: statd idmapd.
[ ok ] Cleaning up temporary files....
[ ok ] Starting filesystem in userspace: fuse.
[ ok ] Setting up X socket directories... /tmp/.X11-unix /tmp/.ICE-unix.
INIT: Entering runlevel: 2
[info] Using makefile-style concurrent boot in runlevel 2.
[....] Starting busybox' syslogd implementation : syslogdStarting /sbin/syslogd...
1542 (syslogd)
. ok 
[   32.978721] NFSD: starting 90-second grace period (net c078eda0)
[ ok ] Exporting directories for NFS kernel daemon....
[ ok ] Starting NFS kernel daemon: nfsd mountd.
[....] Starting busybox' klogd implementation : klogdStarting /sbin/klogd...
1541 (klogd)
. ok 
[ ok ] Starting automount....
[ ok ] Starting periodic command scheduler: cron.
[ ok ] Starting system message bus: dbus.
[ ok ] Starting NTP server: ntpd[....] Starting Avahi mDNS/DNS-SD Daemon: avahi-daemon.
. ok 
[ ok ] Starting OpenBSD Secure Shell server: sshd.
[ ok ] Starting Remote Desktop Protocol server: xrdp-sesman xrdp.
[ ok ] Starting NetBIOS name server: nmbd.
[ ok ] Starting SMB/CIFS daemon: smbd.
[....] Running local boot scripts (/etc/rc.local)<13>Feb 22 22:31:43 root[1970]: Starting rc.local
<13>Feb 22 22:31:43 root[1973]: Export all NFS shares
<13>Feb 22 22:31:43 root[1975]: exporting 192.168.0.0/255.255.255.0:/localdisk
<13>Feb 22 22:31:43 root[1977]: exporting 192.168.0.0/255.255.255.0:/media
<13>Feb 22 22:31:43 root[1978]: Done exporting NFS shares
<13>Feb 22 22:31:43 root[1980]: Pmounting all unmounted USB drives
<13>Feb 22 22:31:43 root[1983]: Mounting all unmounted USB disks: ROOTFS
rootfs (skip rootfs)
<13>Feb 22 22:31:43 root[1985]: Done Pmounting USB drives
<13>Feb 22 22:31:43 root[1988]: Mounting all unmounted disks with label: ROOTFS
rootfs (skip rootfs label and EFI label)
<13>Feb 22 22:31:43 root[1990]: Done mounting disks with label
<13>Feb 22 22:31:43 root: Turned led Green (logger)
<13>Feb 22 22:31:43 root[1994]: End of rc.local
. ok 

Debian GNU/Linux 9 PogoPro ttyS0

PogoPro login:

And logged in with SSH.

root@PogoPro:~# uname -a
Linux PogoPro 4.4.89-oxnas-tld-1 #1 SMP PREEMPT Wed Oct 4 22:22:09 PDT 2017 armv6l GNU/Linux


root@PogoPro:~# cat /etc/debian_version
9.1


root@PogoPro:~# mount
sysfs on /sys type sysfs (rw,nosuid,nodev,noexec,relatime)
proc on /proc type proc (rw,nosuid,nodev,noexec,relatime)
udev on /dev type devtmpfs (rw,nosuid,relatime,size=58528k,nr_inodes=14632,mode=755)
devpts on /dev/pts type devpts (rw,nosuid,noexec,relatime,gid=5,mode=620)
tmpfs on /run type tmpfs (rw,nosuid,noexec,relatime,size=12204k,mode=755)
/dev/sdb1 on / type ext4 (rw,noatime,errors=remount-ro,data=ordered)
tmpfs on /run/lock type tmpfs (rw,nosuid,nodev,noexec,relatime,size=5120k)
configfs on /sys/kernel/config type configfs (rw,relatime)
/dev/sda1 on /media/ROOTFS type ext4 (rw,nosuid,nodev,noexec,noatime,errors=remount-ro,data=ordered,user)
tmpfs on /run/shm type tmpfs (rw,nosuid,nodev,noexec,relatime,size=24400k)
tmpfs on /tmp type tmpfs (rw,relatime)
rpc_pipefs on /run/rpc_pipefs type rpc_pipefs (rw,relatime)
fusectl on /sys/fs/fuse/connections type fusectl (rw,relatime)
nfsd on /proc/fs/nfsd type nfsd (rw,relatime)
/etc/auto.nfs on /mnt/nfs type autofs (rw,relatime,fd=6,pgrp=1658,timeout=300,minproto=5,maxproto=5,indirect)

Note that the 2nd drive was plugged next to the Ethermet port, so it spun up faster and assigned sda1. My rootfs is sdb1.

root@PogoPro:~# fw_printenv
bootcmd_exec=run load_uimage; if run load_initrd; then if run load_dtb; then bootm 0x60500000 0x60e00000 0x62c00000; else bootm 0x60500000 0x60e00000; fi; else if run load_dtb; then bootm 0x60500000 - 0x62c00000; else bootm 0x60500000; fi; fi
bootcmd=run bootcmd_uenv; run scan_disk; run set_bootargs; run bootcmd_exec; reset
bootcmd_uenv=run uenv_load; if test $uenv_loaded -eq 1; then run uenv_import; fi
bootdelay=10
bootdev=usb
usb_custom_params=zswap.enabled=1
device=0:1
devices=usb ide
disks=0 1 2 3
ethact=mii0
if_netconsole=ping $serverip
load_dtb=echo loading DTB $dtb_file ...; load $bootdev $device 0x62c00000 $dtb_file
load_initrd=echo loading uInitrd ...; load $bootdev $device 0x60e00000 /boot/uInitrd
load_uimage=echo loading uImage ...; load $bootdev $device 0x60500000 /boot/uImage
mainlineLinux=yes
mtdids=nand0=41000000.nand
mtdparts=mtdparts=41000000.nand:14m(boot),-(data)
preboot_nc=setenv nc_ready 0; for pingstat in 1 2 3 4 5; do; sleep 1; if run if_netconsole; then setenv nc_ready 1; fi; done; if test $nc_ready -eq 1; then run start_netconsole; fi
scan_disk=echo running scan_disk ...; scan_done=0; setenv scan_usb "usb start";  setenv scan_ide "ide reset";  setenv scan_mmc "mmc rescan"; for dev in $devices; do if test $scan_done -eq 0; then echo Scan device $dev; run scan_$dev; for disknum in $disks; do if test $scan_done -eq 0; then echo device $dev $disknum:1; if load $dev $disknum:1 0x60500000 /boot/uImage 1; then scan_done=1; echo Found bootable drive on $dev $disknum; setenv device $disknum:1; setenv bootdev $dev; fi; fi; done; fi; done
set_bootargs=setenv bootargs console=ttyS0,115200 root=LABEL=rootfs rootdelay=10 $mtdparts $custom_params
start_netconsole=setenv ncip $serverip; setenv bootdelay 10; setenv stdin nc; setenv stdout nc; setenv stderr nc; version;
stderr=serial
stdin=serial
stdout=serial
uenv_import=echo importing envs ...; env import -t 0x60510000  $filesize
uenv_init_devices=setenv init_usb "usb start";  setenv init_ide "ide reset";  setenv init_mmc "mmc rescan"; for devtype in $devices; do run init_$devtype; done;
uenv_load=run uenv_init_devices; setenv uenv_loaded 0; for devtype in $devices;  do for disknum in 0; do run uenv_read_disk; done; done;
uenv_read_disk=if test $devtype -eq mmc; then if $devtype part; then run uenv_read;  fi; else if $devtype part $disknum; then run uenv_read; fi;  fi
uenv_read=echo loading envs from $devtype $disknum ...; if load $devtype $disknum:1 0x60500000 /boot/uEnv.txt; then setenv uenv_loaded 1; fi
usb_ready_retry=15
serverip=192.168.0.220
ipaddr=192.168.0.222
dtb_file=/boot/dts/ox820-pogoplug-classic.dtb
uboot_spl_version=U-Boot SPL 2013.10-tld-4 (Sep 07 2014 - 14:10:12)
uboot_version=U-Boot 2015.10-tld-2 (Oct 21 2017 - 22:00:02 -0700)
cesvcid=xxxxxxxxxxxxxx
ceboardver=PPV3
ethaddr=xxxxxxxx

root@PogoPro:~# fdisk -l
Disk /dev/mtdblock0: 14 MiB, 14680064 bytes, 28672 sectors
Units: sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disklabel type: dos
Disk identifier: 0xaaaaaaaa

Device           Boot      Start        End    Sectors   Size Id Type
/dev/mtdblock0p1      2863311530 5726623059 2863311530   1.3T aa unknown
/dev/mtdblock0p2      2863311530 5726623059 2863311530   1.3T aa unknown
/dev/mtdblock0p3      2863311530 4294989139 1431677610 682.7G aa unknown
/dev/mtdblock0p4      2863311530 5721052499 2857740970   1.3T aa unknown


Disk /dev/mtdblock1: 114 MiB, 119537664 bytes, 233472 sectors
Units: sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes


Disk /dev/sda: 7.5 GiB, 8004304896 bytes, 15633408 sectors
Units: sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disklabel type: dos
Disk identifier: 0x505eb50d

Device     Boot Start      End  Sectors  Size Id Type
/dev/sda1  *     2048 15633407 15631360  7.5G 83 Linux


Disk /dev/sdb: 7.5 GiB, 8004304896 bytes, 15633408 sectors
Units: sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disklabel type: dos
Disk identifier: 0x17f04d84

Device     Boot Start      End  Sectors  Size Id Type
/dev/sdb1  *     2048 15632383 15630336  7.5G 83 Linux

-bodhi
===========================
Forum Wiki
bodhi's corner
Re: USB drive causes booting problem with Linux Kernel 4.4.89 Oxnas
February 26, 2018 01:31PM
Just tested with the latest 4.4.117 but have the same result as with 4.4.89
IDE device 0: Model: Samsung SSD 840 PRO Series Firm: DXM06B0Q Ser#: S1ATNSAF507409D 
            Type: Hard Disk
            Supports 48-bit addressing
            Capacity: 244198.3 MB = 238.4 GB (500118192 x 512)
4124792 bytes read in 560 ms (7 MiB/s)
4595317 bytes read in 626 ms (7 MiB/s)
7075 bytes read in 14 ms (493.2 KiB/s)
## Booting kernel from Legacy Image at 60500000 ...
   Image Name:   Linux-4.4.117-oxnas-tld-1
   Image Type:   ARM Linux Kernel Image (uncompressed)
   Data Size:    4124728 Bytes = 3.9 MiB
   Load Address: 60008000
   Entry Point:  60008000
   Verifying Checksum ... OK
## Loading init Ramdisk from Legacy Image at 60e00000 ...
   Image Name:   initramfs-4.4.117-oxnas-tld-1
   Image Type:   ARM Linux RAMDisk Image (gzip compressed)
   Data Size:    4595253 Bytes = 4.4 MiB
   Load Address: 60000000
   Entry Point:  60000000
   Verifying Checksum ... OK
## Flattened Device Tree blob at 62c00000
   Booting using the fdt blob at 0x62c00000
   Loading Kernel Image ... OK
   Loading Ramdisk to 679c9000, end 67e2ae35 ... OK
   Loading Device Tree to 679c4000, end 679c8ba2 ... OK

Starting kernel ...

Uncompressing Linux... done, booting the kernel.
[    0.000000] Booting Linux on physical CPU 0x0
[    0.000000] Linux version 4.4.117-oxnas-tld-1 (root@tldDebian) (gcc version 6.3.0 20170516 (Debian 6.3.0-18+deb9u1) ) #2 SMP PREEMPT Sun Feb 25 01:33:22 PST 2018
[    0.000000] CPU: ARMv6-compatible processor [410fb025] revision 5 (ARMv7), cr=00c5787d
[    0.000000] CPU: PIPT / VIPT nonaliasing data cache, VIPT aliasing instruction cache
[    0.000000] Machine model: Pogoplug V3 Classic
[    0.000000] Memory policy: Data cache writealloc
[    0.000000] DT missing boot CPU MPIDR[23:0], fall back to default cpu_logical_map
[    0.000000] PERCPU: Embedded 12 pages/cpu @c7eda000 s17612 r8192 d23348 u49152
[    0.000000] Built 1 zonelists in Zone order, mobility grouping on.  Total pages: 32512
[    0.000000] Kernel command line: console=ttyS0,115200 root=LABEL=rootfs rootdelay=10
[    0.000000] PID hash table entries: 512 (order: -1, 2048 bytes)
[    0.000000] Dentry cache hash table entries: 16384 (order: 4, 65536 bytes)
[    0.000000] Inode-cache hash table entries: 8192 (order: 3, 32768 bytes)
[    0.000000] Memory: 117260K/131072K available (5701K kernel code, 285K rwdata, 1572K rodata, 220K init, 243K bss, 13812K 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 : 0xc8800000 - 0xff800000   ( 880 MB)
[    0.000000]     lowmem  : 0xc0000000 - 0xc8000000   ( 128 MB)
[    0.000000]     modules : 0xbf000000 - 0xc0000000   (  16 MB)
[    0.000000]       .text : 0xc0008000 - 0xc07228d8   (7275 kB)
[    0.000000]       .init : 0xc0723000 - 0xc075a000   ( 220 kB)
[    0.000000]       .data : 0xc075a000 - 0xc07a1698   ( 286 kB)
[    0.000000]        .bss : 0xc07a1698 - 0xc07de558   ( 244 kB)
[    0.000000] SLUB: HWalign=32, Order=0-3, MinObjects=0, CPUs=2, Nodes=1
[    0.000000] Preemptible hierarchical RCU implementation.
[    0.000000] NR_IRQS:160
[    0.000000] clocksource: rps_clocksource_timer: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 19112603332 ns
[    0.000007] sched_clock: 24 bits at 390kHz, resolution 2560ns, wraps every 21474835200ns
[    0.000161] Console: colour dummy device 80x30
[    0.000194] Calibrating delay loop... 319.48 BogoMIPS (lpj=1597440)
[    0.050035] pid_max: default: 32768 minimum: 301
[    0.050206] Mount-cache hash table entries: 1024 (order: 0, 4096 bytes)
[    0.050224] Mountpoint-cache hash table entries: 1024 (order: 0, 4096 bytes)
[    0.051092] Initializing cgroup subsys net_cls
[    0.051176] CPU: Testing write buffer coherency: ok
[    0.051632] Setting up static identity map for 0x60008220 - 0x60008258
[    0.190161] Brought up 2 CPUs
[    0.190184] SMP: Total of 2 processors activated (638.97 BogoMIPS).
[    0.191034] devtmpfs: initialized
[    0.196224] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
[    0.196270] futex hash table entries: 512 (order: 2, 16384 bytes)
[    0.196592] prandom: seed boundary self test passed
[    0.201277] prandom: 100 self tests passed
[    0.201305] pinctrl core: initialized pinctrl subsystem
[    0.202181] NET: Registered protocol family 16
[    0.202739] DMA: preallocated 256 KiB pool for atomic coherent allocations
[    0.230026] cpuidle: using governor ladder
[    0.260016] cpuidle: using governor menu
[    0.264919] gpio-oxnas 44000000.gpio: at address c8848000
[    0.265344] gpio-oxnas 44100000.gpio: at address c884a000
[    0.265920] pinctrl-oxnas pinctrl: initialized OX820 pinctrl driver
[    0.278256] vgaarb: loaded
[    0.278763] SCSI subsystem initialized
[    0.279654] usbcore: registered new interface driver usbfs
[    0.279767] usbcore: registered new interface driver hub
[    0.279923] usbcore: registered new device driver usb
[    0.280248] pps_core: LinuxPPS API ver. 1 registered
[    0.280266] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
[    0.280317] PTP clock support registered
[    0.281661] clocksource: Switched to clocksource rps_clocksource_timer
[    0.301143] NET: Registered protocol family 2
[    0.302269] TCP established hash table entries: 1024 (order: 0, 4096 bytes)
[    0.302312] TCP bind hash table entries: 1024 (order: 1, 8192 bytes)
[    0.302348] TCP: Hash tables configured (established 1024 bind 1024)
[    0.302466] UDP hash table entries: 256 (order: 1, 8192 bytes)
[    0.302512] UDP-Lite hash table entries: 256 (order: 1, 8192 bytes)
[    0.302791] NET: Registered protocol family 1
[    0.303321] RPC: Registered named UNIX socket transport module.
[    0.303342] RPC: Registered udp transport module.
[    0.303354] RPC: Registered tcp transport module.
[    0.303365] RPC: Registered tcp NFSv4.1 backchannel transport module.
[    0.303764] Unpacking initramfs...
[    0.805442] Freeing initrd memory: 4488K
[    0.806919] Initialise system trusted keyring
[    0.833546] zbud: loaded
[    0.851432] NFS: Registering the id_resolver key type
[    0.851509] Key type id_resolver registered
[    0.851525] Key type id_legacy registered
[    0.851571] nfs4filelayout_init: NFSv4 File Layout Driver Registering...
[    0.851591] Installing knfsd (copyright (C) 1996 okir@monad.swb.de).
[    0.852564] fuse init (API version 7.23)
[    0.854571] Key type big_key registered
[    0.939553] Key type asymmetric registered
[    0.939589] Asymmetric key parser 'x509' registered
[    0.939719] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251)
[    0.939750] io scheduler noop registered
[    0.939770] io scheduler deadline registered
[    0.939829] io scheduler cfq registered (default)
[    0.940572] Serial: 8250/16550 driver, 1 ports, IRQ sharing disabled
[    0.942092] console [ttyS0] disabled
[    0.942190] 44200000.uart: ttyS0 at MMIO 0x44200000 (irq = 6, base_baud = 390625) is a 16550A
[    1.463669] console [ttyS0] enabled
[    1.478932] loop: module loaded
[    1.484014] scsi host0: sata_oxnas
[    1.487866] ata1: SATA max UDMA/133 irq 7
[    1.492011] sata_oxnas: resetting SATA core
[    1.493099] nand: Could not find valid ONFI parameter page; aborting
[    1.493143] nand: device found, Manufacturer ID: 0xad, Chip ID: 0xf1
[    1.493150] nand: Hynix NAND 128MiB 3,3V 8-bit
[    1.493158] nand: 128 MiB, SLC, erase size: 128 KiB, page size: 2048, OOB size: 64
[    1.493191] Scanning device for bad blocks
[    1.510128] Bad eraseblock 208 at 0x000001a00000
[    1.569111] Bad eraseblock 937 at 0x000007520000
[    1.580728] 2 ofpart partitions found on MTD device 41000000.nand
[    1.586790] Creating 2 MTD partitions on "41000000.nand":
[    1.592199] 0x000000000000-0x000000e00000 : "boot"
[    1.598932] 0x000000e00000-0x000008000000 : "data"
[    1.606415] oxnas-gmac 40400000.ethernet: no reset control found
[    1.612441] stmmac - user ID: 0x12, Synopsys ID: 0x35
[    1.617464]  Ring mode enabled
[    1.620533]  DMA HW capability register supported
[    1.625039]  Enhanced/Alternate descriptors
[    1.629370]  Enabled extended descriptors
[    1.633374]  RX Checksum Offload Engine supported (type 2)
[    1.638830]  TX Checksum insertion supported
[    1.643089]  Wake-Up On Lan supported
[    1.646730]  Enable RX Mitigation via HW Watchdog Timer
[    1.667571] libphy: stmmac: probed
[    1.671022] eth%d: PHY ID 001cc914 at 0 IRQ POLL (stmmac-0:00) active
[    1.677434] eth%d: PHY ID 001cc914 at 3 IRQ POLL (stmmac-0:03)
[    1.684134] PPP generic driver version 2.4.2
[    1.688737] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
[    1.695303] ehci-pci: EHCI PCI platform driver
[    1.700352] oxnas-ehci 40200100.ehci: EHCI Host Controller
[    1.705853] oxnas-ehci 40200100.ehci: new USB bus registered, assigned bus number 1
[    1.713733] oxnas-ehci 40200100.ehci: irq 10, io mem 0x40200100
[    1.730155] oxnas-ehci 40200100.ehci: USB 2.0 started, EHCI 1.00
[    1.736468] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002
[    1.743267] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    1.750476] usb usb1: Product: EHCI Host Controller
[    1.755330] usb usb1: Manufacturer: Linux 4.4.117-oxnas-tld-1 ehci_hcd
[    1.761845] usb usb1: SerialNumber: 40200100.ehci
[    1.767521] hub 1-0:1.0: USB hub found
[    1.771394] hub 1-0:1.0: 2 ports detected
[    1.776463] usbcore: registered new interface driver cdc_acm
[    1.782144] cdc_acm: USB Abstract Control Model driver for USB modems and ISDN adapters
[    1.790272] usbcore: registered new interface driver cdc_wdm
[    1.796096] usbcore: registered new interface driver usb-storage
[    1.802301] usbcore: registered new interface driver usbserial
[    1.808171] usbcore: registered new interface driver usbserial_generic
[    1.814781] usbserial: USB Serial support registered for generic
[    1.821235] mousedev: PS/2 mouse device common for all mice
[    1.826972] i2c /dev entries driver
[    1.831774] hidraw: raw HID events driver (C) Jiri Kosina
[    1.837386] usbcore: registered new interface driver usbhid
[    1.842972] usbhid: USB HID core driver
[    1.848893] NET: Registered protocol family 10
[    1.854791] sit: IPv6 over IPv4 tunneling driver
[    1.860551] NET: Registered protocol family 17
[    1.865146] Key type dns_resolver registered
[    1.871027] registered taskstats version 1
[    1.875148] Loading compiled-in X.509 certificates
[    1.880071] zswap: loaded using pool lzo/zbud
[    1.899537] Key type encrypted registered
[    1.920632] hctosys: unable to open rtc device (rtc0)
[    2.040015] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 310)
[    2.046858] ata1.00: ATA-9: Samsung SSD 840 PRO Series, DXM06B0Q, max UDMA/133
[    2.054095] ata1.00: 500118192 sectors, multi 16: LBA48 NCQ (depth 0/32)
[    2.061114] ata1.00: configured for UDMA/133
[    2.080696] scsi 0:0:0:0: Direct-Access     ATA      Samsung SSD 840  6B0Q PQ: 0 ANSI: 5
[    2.090260] ata1.00: Enabling discard_zeroes_data
[    2.095045] usb 1-1: new high-speed USB device number 2 using oxnas-ehci
[    2.101944] sd 0:0:0:0: [sda] 500118192 512-byte logical blocks: (256 GB/238 GiB)
[    2.110402] sd 0:0:0:0: [sda] Write Protect is off
[    2.115409] sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
[    2.125250] ata1.00: Enabling discard_zeroes_data
[    2.131376]  sda: sda1 sda2 sda3
[    2.136504] ata1.00: Enabling discard_zeroes_data
[    2.142174] sd 0:0:0:0: [sda] Attached SCSI disk
[    2.147138] Freeing unused kernel memory: 220K
Loading, please wait...
[    2.262174] usb 1-1: New USB device found, idVendor=05e3, idProduct=0608
[    2.268858] usb 1-1: New USB device strings: Mfr=0, Product=1, SerialNumber=0
[    2.276044] usb 1-1: Product: USB2.0 Hub
[    2.281469] hub 1-1:1.0: USB hub found
[    2.285672] hub 1-1:1.0: 4 ports detected
starting version 232
[    2.333511] random: systemd-udevd: uninitialized urandom read (16 bytes read, 0 bits of entropy available)
[    2.346572] random: systemd-udevd: uninitialized urandom read (16 bytes read, 0 bits of entropy available)
[    2.356725] random: systemd-udevd: uninitialized urandom read (16 bytes read, 0 bits of entropy available)
[    2.360942] random: udevadm: uninitialized urandom read (16 bytes read, 0 bits of entropy available)
[    2.363484] random: udevadm: uninitialized urandom read (16 bytes read, 0 bits of entropy available)
[    2.363660] random: udevadm: uninitialized urandom read (16 bytes read, 0 bits of entropy available)
[    2.364743] random: udevadm: uninitialized urandom read (16 bytes read, 0 bits of entropy available)
[    2.365829] random: udevadm: uninitialized urandom read (16 bytes read, 0 bits of entropy available)
[    2.366942] random: udevadm: uninitialized urandom read (16 bytes read, 0 bits of entropy available)
[    2.373035] random: udevadm: uninitialized urandom read (16 bytes read, 0 bits of entropy available)
[    2.610327] usb 1-1.1: new high-speed USB device number 3 using oxnas-ehci
[    2.868725] usb 1-1.1: New USB device found, idVendor=046d, idProduct=0991
[    2.880348] usb 1-1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=2
[    2.887690] usb 1-1.1: SerialNumber: 2CCCCCE2
[    3.000358] usb 1-1.2: new high-speed USB device number 4 using oxnas-ehci
[    3.258764] usb 1-1.2: New USB device found, idVendor=046d, idProduct=0991
[    3.265653] usb 1-1.2: New USB device strings: Mfr=0, Product=0, SerialNumber=2
[    3.272947] usb 1-1.2: SerialNumber: 516F6CE2
[    3.360148] usb 1-1.3: new high-speed USB device number 5 using oxnas-ehci
[    3.472017] usb 1-1.3: New USB device found, idVendor=05e3, idProduct=0608
[    3.478865] usb 1-1.3: New USB device strings: Mfr=0, Product=1, SerialNumber=0
[    3.486174] usb 1-1.3: Product: USB2.0 Hub
[    3.494323] hub 1-1.3:1.0: USB hub found
[    3.498644] hub 1-1.3:1.0: 4 ports detected
[    3.580147] usb 1-1.4: new high-speed USB device number 6 using oxnas-ehci
[    3.701634] usb 1-1.4: New USB device found, idVendor=152d, idProduct=2329
[    3.708487] usb 1-1.4: New USB device strings: Mfr=1, Product=2, SerialNumber=5
[    3.715793] usb 1-1.4: Product: USB to ATA/ATAPI bridge
[    3.721077] usb 1-1.4: Manufacturer: JMicron
[    3.725388] usb 1-1.4: SerialNumber: F000A6DFFFFF
[    3.734760] usb-storage 1-1.4:1.0: USB Mass Storage device detected
[    3.741555] usb-storage 1-1.4:1.0: Quirks match for vid 152d pid 2329: 8020
[    3.748633] scsi host1: usb-storage 1-1.4:1.0
[    3.790225] usb 1-1.3.1: new high-speed USB device number 7 using oxnas-ehci
[    3.805355] usbcore: registered new interface driver uas
[    4.050649] usb 1-1.3.1: New USB device found, idVendor=046d, idProduct=0819
[    4.057671] usb 1-1.3.1: New USB device strings: Mfr=0, Product=0, SerialNumber=2
[    4.065149] usb 1-1.3.1: SerialNumber: 439AA2F0
[    4.160176] usb 1-1.3.2: new full-speed USB device number 8 using oxnas-ehci
[    4.277137] usb 1-1.3.2: New USB device found, idVendor=0403, idProduct=6001
[    4.284198] usb 1-1.3.2: New USB device strings: Mfr=1, Product=2, SerialNumber=3
[    4.291665] usb 1-1.3.2: Product: FT232R USB UART
[    4.296343] usb 1-1.3.2: Manufacturer: FTDI
[    4.300523] usb 1-1.3.2: SerialNumber: A10125LI
[    4.390146] usb 1-1.3.3: new low-speed USB device number 9 using oxnas-ehci
[    4.600768] usb 1-1.3.3: New USB device found, idVendor=051d, idProduct=0002
[    4.607790] usb 1-1.3.3: New USB device strings: Mfr=3, Product=1, SerialNumber=2
[    4.615267] usb 1-1.3.3: Product: Back-UPS CS 650 FW:817.v6.I USB FW:v6
[    4.621872] usb 1-1.3.3: Manufacturer: American Power Conversion
[    4.627852] usb 1-1.3.3: SerialNumber: 4B1037P54836  
[    4.751395] scsi 1:0:0:0: Direct-Access     Hitachi  HDS721010KLA330       PQ: 0 ANSI: 2 CCS
[    4.765816] sd 1:0:0:0: [sdb] 1953525168 512-byte logical blocks: (1.00 TB/932 GiB)
[    4.778117] sd 1:0:0:0: [sdb] Write Protect is off
[    4.784104] sd 1:0:0:0: [sdb] No Caching mode page found
[    4.789404] sd 1:0:0:0: [sdb] Assuming drive cache: write through
[    4.815009]  sdb: sdb1
[    4.823728] sd 1:0:0:0: [sdb] Attached SCSI disk
[    5.322124] EXT4-fs (sdb1): couldn't mount as ext3 due to feature incompatibilities
[    5.571146] EXT4-fs (sdb1): mounted filesystem with ordered data mode. Opts: (null)
[    6.181703] hid-generic 0003:051D:0002.0001: hiddev0,hidraw0: USB HID v1.10 Device [American Power Conversion Back-UPS CS 650 FW:817.v6.I USB FW:v6] on usb-40200100.ehci-1.3.3/input0
Begin: Loading essential drivers ... done.
Begin: Running /scripts/init-premount ... done.
Begin: Mounting root file system ... Begin: Running /scripts/local-top ... done.
Begin: Running /scripts/local-premount ... done.
Begin: Will now check root file system ... fsck from util-linux 2.29.2
[/sbin/fsck.ext3 (1) -- /dev/sda2] fsck.ext3 -a -C0 /dev/sda2 
rootfs: clean, 69877/262144 files, 622322/1048576 blocks
done.
[   16.467783] EXT4-fs (sda2): mounting ext3 file system using the ext4 subsystem
[   16.490713] EXT4-fs (sda2): mounted filesystem with ordered data mode. Opts: (null)
done.
Begin: Running /scripts/local-bottom ... done.
Begin: Running /scripts/init-bottom ... done.
run-init: nuking initramfs contents: Directory not empty
[   16.630213] Kernel panic - not syncing: Attempted to kill init! exitcode=0x00000100
[   16.630213] 
[   16.639321] CPU: 0 PID: 1 Comm: run-init Not tainted 4.4.117-oxnas-tld-1 #2
[   16.646246] Hardware name: PLXTECH NAS782X SoC (Flattened Device Tree)
[   16.652766] [<c0010cf4>] (unwind_backtrace) from [<c000cf90>] (show_stack+0x10/0x14)
[   16.660487] [<c000cf90>] (show_stack) from [<c02d8978>] (dump_stack+0x80/0x9c)
[   16.667681] [<c02d8978>] (dump_stack) from [<c0095200>] (panic+0x8c/0x1ec)
[   16.674531] [<c0095200>] (panic) from [<c001a78c>] (do_exit+0x974/0x9d8)
[   16.681203] [<c001a78c>] (do_exit) from [<c001b788>] (SyS_exit+0x10/0x14)
[   16.687964] [<c001b788>] (SyS_exit) from [<c000a420>] (ret_fast_syscall+0x0/0x40)
[   16.695418] CPU1: stopping
[   16.698122] CPU: 1 PID: 0 Comm: swapper/1 Not tainted 4.4.117-oxnas-tld-1 #2
[   16.705134] Hardware name: PLXTECH NAS782X SoC (Flattened Device Tree)
[   16.711649] [<c0010cf4>] (unwind_backtrace) from [<c000cf90>] (show_stack+0x10/0x14)
[   16.719365] [<c000cf90>] (show_stack) from [<c02d8978>] (dump_stack+0x80/0x9c)
[   16.726563] [<c02d8978>] (dump_stack) from [<c000fc08>] (handle_IPI+0x190/0x1c4)
[   16.733931] [<c000fc08>] (handle_IPI) from [<c0009494>] (gic_handle_irq+0x78/0x84)
[   16.741470] [<c0009494>] (gic_handle_irq) from [<c000da00>] (__irq_svc+0x40/0x74)
[   16.748915] Exception stack(0xc7469f88 to 0xc7469fd0)
[   16.753945] 9f80:                   00000001 00000000 00000000 00000000 c7468000 c057262c
[   16.762083] 9fa0: 00000000 c7469fe0 c07552c4 c0758210 00000000 c07626ac 00000000 c7469fd8
[   16.770219] 9fc0: c000aef8 c000aefc 60000013 ffffffff
[   16.775252] [<c000da00>] (__irq_svc) from [<c000aefc>] (arch_cpu_idle+0x28/0x30)
[   16.782620] [<c000aefc>] (arch_cpu_idle) from [<c004e5cc>] (cpu_startup_entry+0x144/0x258)
[   16.790850] [<c004e5cc>] (cpu_startup_entry) from [<60009528>] (0x60009528)
[   16.797785] ---[ end Kernel panic - not syncing: Attempted to kill init! exitcode=0x00000100
[   16.797785] 
Re: USB drive causes booting problem with Linux Kernel 4.4.89 Oxnas
February 26, 2018 02:14PM
bodhi,

thanks for posting your boot log. As the system works with 4.4.79 but not with 4.4.89 and 4.4.117 i am now trying to find the difference in the bootlog, if it helps to chase down the issue

My rootfs data (for the working 4.4.79)
root@sch:~# uname -a
Linux sch 4.4.117-oxnas-tld-1 #2 SMP PREEMPT Sun Feb 25 01:33:22 PST 2018 armv6l GNU/Linux
root@sch:~# cat /etc/debian_version 
9.3

So far i found this difference in the too boot:

kernel 4.4.89 bootlog snippet
[    4.751395] scsi 1:0:0:0: Direct-Access     Hitachi  HDS721010KLA330       PQ: 0 ANSI: 2 CCS
[    4.765816] sd 1:0:0:0: [sdb] 1953525168 512-byte logical blocks: (1.00 TB/932 GiB)
[    4.778117] sd 1:0:0:0: [sdb] Write Protect is off
[    4.784104] sd 1:0:0:0: [sdb] No Caching mode page found
[    4.789404] sd 1:0:0:0: [sdb] Assuming drive cache: write through
[    4.815009]  sdb: sdb1
[    4.823728] sd 1:0:0:0: [sdb] Attached SCSI disk
[    5.322124] EXT4-fs (sdb1): couldn't mount as ext3 due to feature incompatibilities
[    5.571146] EXT4-fs (sdb1): mounted filesystem with ordered data mode. Opts: (null)
[    6.181703] hid-generic 0003:051D:0002.0001:

kernel 4.4.79 bootlog snippet
[    4.761264] scsi 1:0:0:0: Direct-Access     Hitachi  HDS721010KLA330       PQ: 0 ANSI: 2 CCS
[    4.776053] sd 1:0:0:0: [sdb] 1953525168 512-byte logical blocks: (1.00 TB/932 GiB)
[    4.786467] sd 1:0:0:0: [sdb] Write Protect is off
[    4.792215] sd 1:0:0:0: [sdb] No Caching mode page found
[    4.797514] sd 1:0:0:0: [sdb] Assuming drive cache: write through
[    4.816378]  sdb: sdb1
[    4.824796] sd 1:0:0:0: [sdb] Attached SCSI disk
[    6.198405] hid-generic 0003:051D:0002.000

I think the difference is somewhere in the initrd, while in 4.4.79 it mounts the disk without error, in 4.4.89 and 4.4.117 it fails
Re: USB drive causes booting problem with Linux Kernel 4.4.89 Oxnas
February 26, 2018 02:43PM
I think i found the issue. Compared the initramfs from the working 4.4.79 and from 4.4.89 and found a diff, which relates to disk mounting:
-./lib/udev/rules.d/91-media-by-label-auto-mount.rules

The 4.4.79 initramfs does not have this line, while the non working 4.4.89 (and 4.4.117) has this line. If i remove the file from /etc/udev/rules.d folder and generate a new initrd.img and create a new uInitrd, then the system boots correctly with the new kernel (example 4.4.117). After the boot i can reinstall the udev file. What is not clear, why this udev file causes issues only from kernel 4.4.89? How can i generate a initramfs without moving the file from the rules.d directory? It seems that /usr/share/initramfs-tools/hooks/udev script is responsible to add these files to the initramfs

91-media-by-label-auto-mount.rules is providing automount function for drives plugged in. Here is the content:
KERNEL!="sd[b-z][0-9]", GOTO="media_by_label_auto_mount_end"
PROGRAM="/bin/grep -q ' %M:%m /[^ ]* /' /proc/self/mountinfo", PROGRAM!="/bin/grep -q ' %M:%m / /media/' /proc/self/mountinfo", GOTO="media_by_label_auto_mount_end"

# Import FS infos
IMPORT{program}="/sbin/blkid -o udev -p %N"

# Get a label if present, otherwise specify one
ENV{ID_FS_LABEL}!="", ENV{dir_name}="%E{ID_FS_LABEL}"
ENV{ID_FS_LABEL}=="", ENV{dir_name}="hd-%k"

# Global mount options
ACTION=="add", ENV{mount_options}="noatime"
# Filesystem-specific mount options
ACTION=="add", ENV{ID_FS_TYPE}=="vfat|ntfs", ENV{mount_options}="$env{mount_options},utf8,gid=100,umask=002"

# Mount the device
ACTION=="add", RUN+="/bin/mkdir -p /media/%E{dir_name}", RUN+="/bin/mount -o $env{mount_options} /dev/%k /media/%E{dir_name}", RUN+="/sbin/hdparm -S 120 /dev/%k"

# Clean up after removal
ACTION=="remove", ENV{dir_name}!="", RUN+="/bin/umount -l /media/%E{dir_name}", RUN+="/bin/rmdir /media/%E{dir_name}"

# Exit
LABEL="media_by_label_auto_mount_end"
Re: USB drive causes booting problem with Linux Kernel 4.4.89 Oxnas
February 26, 2018 11:30PM
schnee,

What a wild goose chase :) I don't know where you got that udev rules (not from my released rootfs). Apperently it did not work well.

Try this one in the Wiki thread:
https://forum.doozan.com/read.php?2,24139

Quote

udev

Automount USB drives with udev rules using disk label

-bodhi
===========================
Forum Wiki
bodhi's corner
Author:

Your Email:


Subject:


Spam prevention:
Please, enter the code that you see below in the input field. This is for blocking bots that try to post this form automatically. If the code is hard to read, then just try to guess it right. If you enter the wrong code, a new image is created and you get another chance to enter it right.
Message: